builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0207 starttime: 1480392447.92 results: success (0) buildid: 20161128180027 builduid: b75659fef1844ebd88399b33e9999f05 revision: 35d579c4ff1e15f81d3ef70a3137afc218a99353 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:27.916306) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:27.916763) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:27.917056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:27.944420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:27.944830) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-28 20:07:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-11-28 20:07:28 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:28.069258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:28.069576) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.023776 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:28.117963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 20:07:28.118485) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 35d579c4ff1e15f81d3ef70a3137afc218a99353 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 35d579c4ff1e15f81d3ef70a3137afc218a99353 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-28 20:07:28,215 truncating revision to first 12 chars 2016-11-28 20:07:28,215 Setting DEBUG logging. 2016-11-28 20:07:28,215 attempt 1/10 2016-11-28 20:07:28,215 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/35d579c4ff1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 20:07:29,173 unpacking tar archive at: mozilla-aurora-35d579c4ff1e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.233765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 20:07:29.376567) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:29.376911) ========= script_repo_revision: 35d579c4ff1e15f81d3ef70a3137afc218a99353 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:29.377312) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:29.377584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:29.397590) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-28 20:07:29.397958) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:07:29 INFO - MultiFileLogger online at 20161128 20:07:29 in /builds/slave/test 20:07:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:07:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:07:29 INFO - {'append_to_log': False, 20:07:29 INFO - 'base_work_dir': '/builds/slave/test', 20:07:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:07:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:07:29 INFO - 'branch': 'Mozilla-Aurora', 20:07:29 INFO - 'buildbot_json_path': 'buildprops.json', 20:07:29 INFO - 'config_files': ('talos/mac_config.py',), 20:07:29 INFO - 'default_actions': ('clobber', 20:07:29 INFO - 'read-buildbot-config', 20:07:29 INFO - 'download-and-extract', 20:07:29 INFO - 'populate-webroot', 20:07:29 INFO - 'create-virtualenv', 20:07:29 INFO - 'install', 20:07:29 INFO - 'run-tests'), 20:07:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:07:29 INFO - 'download_minidump_stackwalk': True, 20:07:29 INFO - 'download_symbols': 'ondemand', 20:07:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:07:29 INFO - 'tooltool.py': '/tools/tooltool.py', 20:07:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:07:29 INFO - '/tools/misc-python/virtualenv.py')}, 20:07:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:07:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:07:29 INFO - 'installer_path': 'installer.exe', 20:07:29 INFO - 'log_level': 'info', 20:07:29 INFO - 'log_name': 'talos', 20:07:29 INFO - 'log_to_console': True, 20:07:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:07:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:07:29 INFO - 'opt_config_files': (), 20:07:29 INFO - 'pip_index': False, 20:07:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:29 INFO - 'cmd': ('bash', 20:07:29 INFO - '-c', 20:07:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:29 INFO - 'enabled': True, 20:07:29 INFO - 'halt_on_failure': False, 20:07:29 INFO - 'name': 'check_screen_resolution'},), 20:07:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:29 INFO - 'cmd': ('bash', 20:07:29 INFO - '-c', 20:07:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:29 INFO - 'enabled': True, 20:07:29 INFO - 'halt_on_failure': False, 20:07:29 INFO - 'name': 'check_screen_resolution'},), 20:07:29 INFO - 'run_cmd_checks_enabled': True, 20:07:29 INFO - 'sps_profile': False, 20:07:29 INFO - 'sps_profile_interval': 0, 20:07:29 INFO - 'suite': 'dromaeojs', 20:07:29 INFO - 'system_bits': '32', 20:07:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:07:29 INFO - 'title': 't-yosemite-r7-0207', 20:07:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:07:29 INFO - 'use_talos_json': True, 20:07:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:07:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:07:29 INFO - 'work_dir': 'build'} 20:07:29 INFO - [mozharness: 2016-11-29 04:07:29.597873Z] Running clobber step. 20:07:29 INFO - Running pre-action listener: _resource_record_pre_action 20:07:29 INFO - Running main action method: clobber 20:07:29 INFO - rmtree: /builds/slave/test/build 20:07:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:07:31 INFO - Running post-action listener: _resource_record_post_action 20:07:31 INFO - [mozharness: 2016-11-29 04:07:31.848021Z] Finished clobber step (success) 20:07:31 INFO - [mozharness: 2016-11-29 04:07:31.848143Z] Running read-buildbot-config step. 20:07:31 INFO - Running pre-action listener: _resource_record_pre_action 20:07:31 INFO - Running main action method: read_buildbot_config 20:07:31 INFO - Using buildbot properties: 20:07:31 INFO - { 20:07:31 INFO - "project": "", 20:07:31 INFO - "product": "firefox", 20:07:31 INFO - "script_repo_revision": "production", 20:07:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:07:31 INFO - "repository": "", 20:07:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:07:31 INFO - "buildid": "20161128180027", 20:07:31 INFO - "pgo_build": "False", 20:07:31 INFO - "basedir": "/builds/slave/test", 20:07:31 INFO - "buildnumber": 2, 20:07:31 INFO - "slavename": "t-yosemite-r7-0207", 20:07:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 20:07:31 INFO - "platform": "yosemite_r7", 20:07:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:07:31 INFO - "branch": "mozilla-aurora", 20:07:31 INFO - "repo_path": "releases/mozilla-aurora", 20:07:31 INFO - "revision": "35d579c4ff1e15f81d3ef70a3137afc218a99353", 20:07:31 INFO - "stage_platform": "macosx64", 20:07:31 INFO - "builduid": "b75659fef1844ebd88399b33e9999f05", 20:07:31 INFO - "slavebuilddir": "test" 20:07:31 INFO - } 20:07:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:07:31 INFO - Running post-action listener: _resource_record_post_action 20:07:31 INFO - [mozharness: 2016-11-29 04:07:31.852462Z] Finished read-buildbot-config step (success) 20:07:31 INFO - [mozharness: 2016-11-29 04:07:31.852582Z] Running download-and-extract step. 20:07:31 INFO - Running pre-action listener: _resource_record_pre_action 20:07:31 INFO - Running main action method: download_and_extract 20:07:31 INFO - mkdir: /builds/slave/test/build/tests 20:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:31 INFO - trying https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:31 INFO - Downloading https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 20:07:33 INFO - Downloaded 1199 bytes. 20:07:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:33 INFO - Using the following test package requirements: 20:07:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:07:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:07:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:07:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'jsshell-mac.zip'], 20:07:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:07:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:07:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:07:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:07:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:07:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:07:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:07:33 INFO - Http code: 200 20:07:33 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 20:07:33 INFO - x-amz-cf-id: xSreC4Xb00weiJOn7UL52ME8r50S6sOxUoU4zfcGK_oPEDOKUdkOyw== 20:07:33 INFO - x-amz-version-id: v4K.qznqByYX3m_bsVRR9cLI5eOagXAM 20:07:34 INFO - Expected file size: 16283354 20:07:34 INFO - Obtained file size: 16283354 20:07:40 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:07:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:07:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:07:40 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:07:41 INFO - Http code: 200 20:07:41 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 20:07:41 INFO - x-amz-cf-id: ALqYkpRzxvcBu1-Vjy__C2nmoP51Q6xc_WDZZp-hyRLggp2KOihy4Q== 20:07:41 INFO - x-amz-version-id: v4K.qznqByYX3m_bsVRR9cLI5eOagXAM 20:07:41 INFO - Expected file size: 16283354 20:07:41 INFO - Obtained file size: 16283354 20:07:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:07:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:07:47 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:07:48 INFO - Http code: 200 20:07:48 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 20:07:48 INFO - x-amz-cf-id: vwZjHSGHXXnHLeEN5tGctNXbdhPstwNSOK9Ch91aqn5tgngzGV5xRQ== 20:07:48 INFO - x-amz-version-id: qzORJGFzoJE7nq5zKTqqUhh7E6fH4Hq8 20:07:50 INFO - Expected file size: 14409038 20:07:50 INFO - Obtained file size: 14409038 20:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:50 INFO - trying https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:50 INFO - Downloading https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 20:07:53 INFO - Downloaded 109810216 bytes. 20:07:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:53 INFO - mkdir: /builds/slave/test/properties 20:07:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:07:53 INFO - Writing to file /builds/slave/test/properties/build_url 20:07:53 INFO - Contents: 20:07:53 INFO - build_url:https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:54 INFO - Running post-action listener: _resource_record_post_action 20:07:54 INFO - Running post-action listener: set_extra_try_arguments 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.246694Z] Finished download-and-extract step (success) 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.246847Z] Running populate-webroot step. 20:07:54 INFO - Running pre-action listener: _resource_record_pre_action 20:07:54 INFO - Running main action method: populate_webroot 20:07:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:07:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:07:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:07:54 INFO - '--tppagecycles', 20:07:54 INFO - '7'], 20:07:54 INFO - 'tests': ['tsvgm']}}, 20:07:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:07:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:07:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:07:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:07:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['damp', 'tps']}, 20:07:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['damp', 'tps']}, 20:07:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['dromaeo_dom']}, 20:07:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:07:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['basic_compositor_video']}, 20:07:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:07:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tsvgx', 20:07:54 INFO - 'tsvgr_opacity', 20:07:54 INFO - 'tart', 20:07:54 INFO - 'tscrollx', 20:07:54 INFO - 'cart']}, 20:07:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:07:54 INFO - 'tsvgr_opacity', 20:07:54 INFO - 'tart', 20:07:54 INFO - 'tscrollx', 20:07:54 INFO - 'cart']}, 20:07:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tp5o']}, 20:07:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['tp5o']}, 20:07:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s', 20:07:54 INFO - '--xperf_path', 20:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:54 INFO - 'tests': ['tp5n']}, 20:07:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--xperf_path', 20:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:54 INFO - 'tests': ['tp5n']}}, 20:07:54 INFO - 'talos.zip': {'path': '', 20:07:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:07:54 INFO - Running post-action listener: _resource_record_post_action 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.265695Z] Finished populate-webroot step (success) 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.265836Z] Running create-virtualenv step. 20:07:54 INFO - Running pre-action listener: _resource_record_pre_action 20:07:54 INFO - Running main action method: create_virtualenv 20:07:54 INFO - Creating virtualenv /builds/slave/test/build/venv 20:07:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:07:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:07:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:07:54 INFO - Using real prefix '/tools/python27' 20:07:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:07:55 INFO - Installing distribute.............................................................................................................................................................................................done. 20:07:58 INFO - Installing pip.................done. 20:07:58 INFO - Return code: 0 20:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:07:58 INFO - Reading from file tmpfile_stdout 20:07:58 INFO - Output received: 20:07:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:07:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:58 INFO - 'HOME': '/Users/cltbld', 20:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:58 INFO - 'LOGNAME': 'cltbld', 20:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:58 INFO - 'MOZ_NO_REMOTE': '1', 20:07:58 INFO - 'NO_EM_RESTART': '1', 20:07:58 INFO - 'PAGER': '/bin/cat', 20:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:58 INFO - 'PWD': '/builds/slave/test', 20:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:58 INFO - 'SHELL': '/bin/bash', 20:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:07:58 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:58 INFO - 'USER': 'cltbld', 20:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:58 INFO - 'XPC_FLAGS': '0x0', 20:07:58 INFO - 'XPC_SERVICE_NAME': '0', 20:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:07:58 INFO - Cleaning up... 20:07:58 INFO - Return code: 0 20:07:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:58 INFO - 'HOME': '/Users/cltbld', 20:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:58 INFO - 'LOGNAME': 'cltbld', 20:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:58 INFO - 'MOZ_NO_REMOTE': '1', 20:07:58 INFO - 'NO_EM_RESTART': '1', 20:07:58 INFO - 'PAGER': '/bin/cat', 20:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:58 INFO - 'PWD': '/builds/slave/test', 20:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:58 INFO - 'SHELL': '/bin/bash', 20:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:07:58 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:58 INFO - 'USER': 'cltbld', 20:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:58 INFO - 'XPC_FLAGS': '0x0', 20:07:58 INFO - 'XPC_SERVICE_NAME': '0', 20:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:58 INFO - Downloading/unpacking psutil>=3.1.1 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:02 INFO - Installing collected packages: psutil 20:08:02 INFO - Running setup.py install for psutil 20:08:02 INFO - building 'psutil._psutil_osx' extension 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:08:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:08:03 INFO - building 'psutil._psutil_posix' extension 20:08:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:08:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:03 INFO - ^ 20:08:03 INFO - 1 warning generated. 20:08:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:03 INFO - Successfully installed psutil 20:08:03 INFO - Cleaning up... 20:08:03 INFO - Return code: 0 20:08:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:08:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:03 INFO - 'HOME': '/Users/cltbld', 20:08:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:03 INFO - 'LOGNAME': 'cltbld', 20:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:03 INFO - 'MOZ_NO_REMOTE': '1', 20:08:03 INFO - 'NO_EM_RESTART': '1', 20:08:03 INFO - 'PAGER': '/bin/cat', 20:08:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:03 INFO - 'PWD': '/builds/slave/test', 20:08:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:03 INFO - 'SHELL': '/bin/bash', 20:08:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:03 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:03 INFO - 'USER': 'cltbld', 20:08:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:03 INFO - 'XPC_FLAGS': '0x0', 20:08:03 INFO - 'XPC_SERVICE_NAME': '0', 20:08:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:06 INFO - Installing collected packages: mozsystemmonitor 20:08:06 INFO - Running setup.py install for mozsystemmonitor 20:08:06 INFO - Successfully installed mozsystemmonitor 20:08:06 INFO - Cleaning up... 20:08:06 INFO - Return code: 0 20:08:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:08:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:06 INFO - 'HOME': '/Users/cltbld', 20:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:06 INFO - 'LOGNAME': 'cltbld', 20:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:06 INFO - 'MOZ_NO_REMOTE': '1', 20:08:06 INFO - 'NO_EM_RESTART': '1', 20:08:06 INFO - 'PAGER': '/bin/cat', 20:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:06 INFO - 'PWD': '/builds/slave/test', 20:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:06 INFO - 'SHELL': '/bin/bash', 20:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:06 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:06 INFO - 'USER': 'cltbld', 20:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:06 INFO - 'XPC_FLAGS': '0x0', 20:08:06 INFO - 'XPC_SERVICE_NAME': '0', 20:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:06 INFO - Downloading/unpacking jsonschema==2.5.1 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:08:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - Downloading functools32-3.2.3-2.tar.gz 20:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:08:09 INFO - warning: no files found matching '*.txt' 20:08:09 INFO - no previously-included directories found matching 'build' 20:08:09 INFO - no previously-included directories found matching 'dist' 20:08:09 INFO - no previously-included directories found matching '.git*' 20:08:09 INFO - Installing collected packages: jsonschema, functools32 20:08:09 INFO - Running setup.py install for functools32 20:08:10 INFO - warning: no files found matching '*.txt' 20:08:10 INFO - no previously-included directories found matching 'build' 20:08:10 INFO - no previously-included directories found matching 'dist' 20:08:10 INFO - no previously-included directories found matching '.git*' 20:08:10 INFO - Successfully installed jsonschema functools32 20:08:10 INFO - Cleaning up... 20:08:10 INFO - Return code: 0 20:08:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:10 INFO - 'HOME': '/Users/cltbld', 20:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:10 INFO - 'LOGNAME': 'cltbld', 20:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:10 INFO - 'MOZ_NO_REMOTE': '1', 20:08:10 INFO - 'NO_EM_RESTART': '1', 20:08:10 INFO - 'PAGER': '/bin/cat', 20:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:10 INFO - 'PWD': '/builds/slave/test', 20:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:10 INFO - 'SHELL': '/bin/bash', 20:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:10 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:10 INFO - 'USER': 'cltbld', 20:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:10 INFO - 'XPC_FLAGS': '0x0', 20:08:10 INFO - 'XPC_SERVICE_NAME': '0', 20:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:08:10 INFO - Cleaning up... 20:08:10 INFO - Return code: 0 20:08:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:10 INFO - 'HOME': '/Users/cltbld', 20:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:10 INFO - 'LOGNAME': 'cltbld', 20:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:10 INFO - 'MOZ_NO_REMOTE': '1', 20:08:10 INFO - 'NO_EM_RESTART': '1', 20:08:10 INFO - 'PAGER': '/bin/cat', 20:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:10 INFO - 'PWD': '/builds/slave/test', 20:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:10 INFO - 'SHELL': '/bin/bash', 20:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:10 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:10 INFO - 'USER': 'cltbld', 20:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:10 INFO - 'XPC_FLAGS': '0x0', 20:08:10 INFO - 'XPC_SERVICE_NAME': '0', 20:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:10 INFO - Downloading/unpacking blobuploader==1.2.4 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - Downloading blobuploader-1.2.4.tar.gz 20:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:08:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:08:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:14 INFO - Downloading docopt-0.6.1.tar.gz 20:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:08:14 INFO - Installing collected packages: blobuploader, requests, docopt 20:08:14 INFO - Running setup.py install for blobuploader 20:08:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:08:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:08:14 INFO - Running setup.py install for requests 20:08:15 INFO - Running setup.py install for docopt 20:08:15 INFO - Successfully installed blobuploader requests docopt 20:08:15 INFO - Cleaning up... 20:08:15 INFO - Return code: 0 20:08:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:15 INFO - 'HOME': '/Users/cltbld', 20:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:15 INFO - 'LOGNAME': 'cltbld', 20:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:15 INFO - 'MOZ_NO_REMOTE': '1', 20:08:15 INFO - 'NO_EM_RESTART': '1', 20:08:15 INFO - 'PAGER': '/bin/cat', 20:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:15 INFO - 'PWD': '/builds/slave/test', 20:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:15 INFO - 'SHELL': '/bin/bash', 20:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:15 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:15 INFO - 'USER': 'cltbld', 20:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:15 INFO - 'XPC_FLAGS': '0x0', 20:08:15 INFO - 'XPC_SERVICE_NAME': '0', 20:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-eyUEer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-NzrCz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-SX19S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-jRgONh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Fo3hUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-BJWob8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-iaid5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-IDRbIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-pLS9K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-r2M_PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-hmENHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-3EASo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:17 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-AbeowC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:17 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-14to65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:17 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-xNJqO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:17 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ETqw66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:17 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-lpHlmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:08:17 INFO - Running setup.py install for manifestparser 20:08:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:08:17 INFO - Running setup.py install for mozcrash 20:08:17 INFO - Running setup.py install for mozdebug 20:08:18 INFO - Running setup.py install for mozdevice 20:08:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozfile 20:08:18 INFO - Running setup.py install for mozhttpd 20:08:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozinfo 20:08:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozInstall 20:08:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozleak 20:08:18 INFO - Running setup.py install for mozlog 20:08:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for moznetwork 20:08:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for mozprocess 20:08:19 INFO - Running setup.py install for mozprofile 20:08:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for mozrunner 20:08:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for mozscreenshot 20:08:19 INFO - Running setup.py install for moztest 20:08:20 INFO - Running setup.py install for mozversion 20:08:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:08:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:08:20 INFO - Cleaning up... 20:08:20 INFO - Return code: 0 20:08:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:20 INFO - 'HOME': '/Users/cltbld', 20:08:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:20 INFO - 'LOGNAME': 'cltbld', 20:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:20 INFO - 'MOZ_NO_REMOTE': '1', 20:08:20 INFO - 'NO_EM_RESTART': '1', 20:08:20 INFO - 'PAGER': '/bin/cat', 20:08:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:20 INFO - 'PWD': '/builds/slave/test', 20:08:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:20 INFO - 'SHELL': '/bin/bash', 20:08:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:20 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:20 INFO - 'USER': 'cltbld', 20:08:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:20 INFO - 'XPC_FLAGS': '0x0', 20:08:20 INFO - 'XPC_SERVICE_NAME': '0', 20:08:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-lQh253-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Vtfo9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_S14ZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Wwt6zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-WlEsCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-5COvc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-8S_T0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-2EHmbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-wNKe5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-L6AXGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-73ywpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-fDIIm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Mkd5CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-w_LWo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:21 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-DhYwF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:22 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-M8TyUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:22 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-uZp17W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:25 INFO - Downloading blessings-1.6.tar.gz 20:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:08:25 INFO - Installing collected packages: blessings 20:08:25 INFO - Running setup.py install for blessings 20:08:25 INFO - Successfully installed blessings 20:08:25 INFO - Cleaning up... 20:08:25 INFO - Return code: 0 20:08:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:08:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:25 INFO - Reading from file tmpfile_stdout 20:08:25 INFO - Current package versions: 20:08:25 INFO - blessings == 1.6 20:08:25 INFO - blobuploader == 1.2.4 20:08:25 INFO - docopt == 0.6.1 20:08:25 INFO - functools32 == 3.2.3-2 20:08:25 INFO - jsonschema == 2.5.1 20:08:25 INFO - manifestparser == 1.1 20:08:25 INFO - mozInstall == 1.12 20:08:25 INFO - mozcrash == 1.0 20:08:25 INFO - mozdebug == 0.1 20:08:25 INFO - mozdevice == 0.48 20:08:25 INFO - mozfile == 1.2 20:08:25 INFO - mozhttpd == 0.7 20:08:25 INFO - mozinfo == 0.9 20:08:25 INFO - mozleak == 0.1 20:08:25 INFO - mozlog == 3.4 20:08:25 INFO - moznetwork == 0.27 20:08:25 INFO - mozprocess == 0.23 20:08:25 INFO - mozprofile == 0.28 20:08:25 INFO - mozrunner == 6.13 20:08:25 INFO - mozscreenshot == 0.1 20:08:25 INFO - mozsystemmonitor == 0.3 20:08:25 INFO - moztest == 0.7 20:08:25 INFO - mozversion == 1.4 20:08:25 INFO - psutil == 3.1.1 20:08:25 INFO - requests == 1.2.3 20:08:25 INFO - wsgiref == 0.1.2 20:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:26 INFO - 'HOME': '/Users/cltbld', 20:08:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:26 INFO - 'LOGNAME': 'cltbld', 20:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:26 INFO - 'MOZ_NO_REMOTE': '1', 20:08:26 INFO - 'NO_EM_RESTART': '1', 20:08:26 INFO - 'PAGER': '/bin/cat', 20:08:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:26 INFO - 'PWD': '/builds/slave/test', 20:08:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:26 INFO - 'SHELL': '/bin/bash', 20:08:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:26 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:26 INFO - 'USER': 'cltbld', 20:08:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:26 INFO - 'XPC_FLAGS': '0x0', 20:08:26 INFO - 'XPC_SERVICE_NAME': '0', 20:08:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:08:26 INFO - Cleaning up... 20:08:26 INFO - Return code: 0 20:08:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f527570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1514740b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:08:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:26 INFO - 'HOME': '/Users/cltbld', 20:08:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:26 INFO - 'LOGNAME': 'cltbld', 20:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:26 INFO - 'MOZ_NO_REMOTE': '1', 20:08:26 INFO - 'NO_EM_RESTART': '1', 20:08:26 INFO - 'PAGER': '/bin/cat', 20:08:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:26 INFO - 'PWD': '/builds/slave/test', 20:08:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:26 INFO - 'SHELL': '/bin/bash', 20:08:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:26 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:26 INFO - 'USER': 'cltbld', 20:08:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:26 INFO - 'XPC_FLAGS': '0x0', 20:08:26 INFO - 'XPC_SERVICE_NAME': '0', 20:08:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:08:26 INFO - Cleaning up... 20:08:26 INFO - Return code: 0 20:08:26 INFO - Running post-action listener: _resource_record_post_action 20:08:26 INFO - Running post-action listener: _start_resource_monitoring 20:08:26 INFO - Starting resource monitoring. 20:08:26 INFO - [mozharness: 2016-11-29 04:08:26.712721Z] Finished create-virtualenv step (success) 20:08:26 INFO - [mozharness: 2016-11-29 04:08:26.713673Z] Running install step. 20:08:26 INFO - Running pre-action listener: _resource_record_pre_action 20:08:26 INFO - Running main action method: install 20:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:26 INFO - Reading from file tmpfile_stdout 20:08:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:08:27 INFO - Reading from file tmpfile_stdout 20:08:27 INFO - Output received: 20:08:27 INFO - Usage: mozinstall [options] installer 20:08:27 INFO - Options: 20:08:27 INFO - -h, --help show this help message and exit 20:08:27 INFO - -d DEST, --destination=DEST 20:08:27 INFO - Directory to install application into. [default: 20:08:27 INFO - "/builds/slave/test"] 20:08:27 INFO - --app=APP Application being installed. [default: firefox] 20:08:27 INFO - mkdir: /builds/slave/test/build/application 20:08:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:08:58 INFO - Reading from file tmpfile_stdout 20:08:58 INFO - Output received: 20:08:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:08:58 INFO - Running post-action listener: _resource_record_post_action 20:08:58 INFO - [mozharness: 2016-11-29 04:08:58.336619Z] Finished install step (success) 20:08:58 INFO - [mozharness: 2016-11-29 04:08:58.336767Z] Running run-tests step. 20:08:58 INFO - Running pre-action listener: _resource_record_pre_action 20:08:58 INFO - Running main action method: run_tests 20:08:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:08:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:08:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:08:58 INFO - 2016-11-28 20:08:58.351 screenresolution[1622:7502] starting screenresolution argv=screenresolution get 20:08:58 INFO - 2016-11-28 20:08:58.353 screenresolution[1622:7502] Display 0: 1600x1200x32@60 20:08:58 INFO - 2016-11-28 20:08:58.359 screenresolution[1623:7504] starting screenresolution argv=screenresolution list 20:08:58 INFO - Available Modes on Display 0 20:08:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:08:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:08:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:08:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:08:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:08:58 INFO - Intel Iris: 20:08:58 INFO - Chipset Model: Intel Iris 20:08:58 INFO - Type: GPU 20:08:58 INFO - Bus: Built-In 20:08:58 INFO - VRAM (Dynamic, Max): 1536 MB 20:08:58 INFO - Vendor: Intel (0x8086) 20:08:58 INFO - Device ID: 0x0a2e 20:08:58 INFO - Revision ID: 0x0009 20:08:58 INFO - Displays: 20:08:58 INFO - Display: 20:08:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:08:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:08:58 INFO - Display Serial Number: 1600x1200 60 20:08:58 INFO - Main Display: Yes 20:08:58 INFO - Mirror: Off 20:08:58 INFO - Online: Yes 20:08:58 INFO - Rotation: Supported 20:08:58 INFO - Return code: 0 20:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:08:58 INFO - Python 2.7.3 20:08:58 INFO - Return code: 0 20:08:58 INFO - grabbing minidump binary from tooltool 20:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3bdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:08:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:08:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:08:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:08:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:08:58 INFO - Return code: 0 20:08:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:08:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:08:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0207 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y66at5UXjg/Render', 20:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:58 INFO - 'HOME': '/Users/cltbld', 20:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:58 INFO - 'LOGNAME': 'cltbld', 20:08:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:08:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:58 INFO - 'MOZ_NO_REMOTE': '1', 20:08:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:08:58 INFO - 'NO_EM_RESTART': '1', 20:08:58 INFO - 'PAGER': '/bin/cat', 20:08:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:58 INFO - 'PWD': '/builds/slave/test', 20:08:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:58 INFO - 'SHELL': '/bin/bash', 20:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners', 20:08:58 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 20:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:58 INFO - 'USER': 'cltbld', 20:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:58 INFO - 'XPC_FLAGS': '0x0', 20:08:58 INFO - 'XPC_SERVICE_NAME': '0', 20:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:08:58 INFO - mozversion application_buildid: 20161128180027 20:08:58 INFO - mozversion application_changeset: 35d579c4ff1e15f81d3ef70a3137afc218a99353 20:08:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:08:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:08:58 INFO - mozversion application_name: Firefox 20:08:58 INFO - mozversion application_remotingname: firefox-dev 20:08:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:08:58 INFO - mozversion application_vendor: Mozilla 20:08:58 INFO - mozversion application_version: 52.0a2 20:08:58 INFO - mozversion platform_buildid: 20161128180027 20:08:58 INFO - mozversion platform_changeset: 35d579c4ff1e15f81d3ef70a3137afc218a99353 20:08:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:08:58 INFO - mozversion platform_version: 52.0a2 20:08:58 INFO - using testdate: 1480392538 20:08:58 INFO - actual date: 1480392538 20:08:58 INFO - starting webserver on 'localhost:49243' 20:08:58 INFO - SUITE-START | Running 2 tests 20:08:58 INFO - TEST-START | dromaeo_css 20:08:58 INFO - operating with platform_type : mac_ 20:08:58 INFO - Initialising browser for dromaeo_css test... 20:08:58 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpPEIyFa/profile http://localhost:49243/getInfo.html) 20:09:01 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 20:09:01 INFO - PROCESS | 1633 | colorDepth:24 20:09:01 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 20:09:01 INFO - PROCESS | 1633 | __metrics 20:09:01 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:09:01 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:02 INFO - TEST-INFO | 1633: exit 0 20:09:02 INFO - Browser initialized. 20:09:02 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:02 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpPEIyFa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:14 INFO - PROCESS | 1635 | RSS: Main: 223694848 20:09:14 INFO - PROCESS | 1635 | 20:10:06 INFO - PROCESS | 1635 | 1480392606303 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:12:19 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 20:12:20 INFO - PROCESS | 1635 | RSS: Main: 326541312 20:12:20 INFO - PROCESS | 1635 | 20:15:23 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 20:15:24 INFO - PROCESS | 1635 | RSS: Main: 347398144 20:15:24 INFO - PROCESS | 1635 | 20:18:26 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 20:18:27 INFO - PROCESS | 1635 | RSS: Main: 318308352 20:18:27 INFO - PROCESS | 1635 | 20:21:29 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 20:21:30 INFO - PROCESS | 1635 | RSS: Main: 317845504 20:21:30 INFO - PROCESS | 1635 | 20:24:33 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 20:24:34 INFO - PROCESS | 1635 | RSS: Main: 308547584 20:24:34 INFO - PROCESS | 1635 | 20:27:37 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 20:27:38 INFO - PROCESS | 1635 | RSS: Main: 416452608 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | __start_tp_report 20:27:38 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 20:27:38 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 20:27:38 INFO - PROCESS | 1635 | |i|pagename|runs| 20:27:38 INFO - PROCESS | 1635 | |0;dojo.html;2922.766178705703;3160.9051728448153;3224.1617179533323;3263.3473305338925;3314.7679662423625;4506;5660.575456840743;5688.317401911771;5695.914561281579;5708.457696518834;5660.632058916168;5677.48902598766;5677.801276955307;5705.030144875371;5706.828795136143;5137.152369858978;5165;5189;5236.502532259429;5293.682379057269;5071.1886098224295;5112.8977420451565;5136.5890728741615;5192;5241.528262456378;5116.667416617926;5183;5198.428172900978;5232.398274198459;5251.186066159751;5175.068487672217;5177.430482646906;5213.504717051874;5244.947550524522;5250.816221432232;5115.411727651331;5193.272941788153;5196.61025423091;5202.115640341151;5249.711265880375;5518.53092487135;5520.944790552124;5551.084071128259;5553.8333849984565;5645.5483561314995;6104.603200791978;6137.570370074097;6152.477039451652;6178.598391104609;6185.226846644169;6240.095186198021;6259.906101408483;6261.467775239064;6272.247330320391;6274.278457977345;5529.364123125812;5589.189567512688;5603.35561410443;5735.598508104435;5779.537636989031;6155.10750941111;6210.285817130997;6210.968945155245;6213.03697926822;6215.96892015537;5905.143754155623;5972.044472884405;6025.698715064317;6078.726457309432;6080.391960803884;6101;6165.568410211242;6206.565540412218;6210.161628180138;6217;5957.821265362046;5967.5524335675;6060.212172417558;6097;6108.053251745898;6061.636301821904;6093;6097.939020609826;6118.632882027093;6139.662318572432;4887.60899128076;4888.584470320063;4889.877753056202;4902.926456103161;4918.065567542156;4233.25917964355;4385.758783266951;4389.144116897174;4393.275109606943;4399.670024748157;5462.453754624506;5567.47109024642;5573.526250268773;5596.188552659923;5643.971780141155;5561.082421400504;5602.803901863415;6182.28903676083;6259.843503912348;6267;5649.209110724501;5650.576206784508;5664.037113690682;5669.773209071674;5673.3475650299915;5201.531862132426;5211.921821172686;5344;5350.143976963667;5353.7590808413715;5487.820118674505;6057.42454466825;6204.658743769136;6252.374762523802;6253.530985176039;5815.912761308668;5886.882262354815;6004.219451471456;6194.0708893666315;6203.16257305276;5229.137192363218;5853.560982926383;5895.14520394549;6100.786472473442;6110.847228819315;5656.547476201977;5784.94215057844;5845.912311315248;5846.473817356458;5859.033259512304;5938.138969849434;6005.339412664691;6005.549583781147;6052.576319657758;6116.031445914875;5976.491998180029;6056;6060.424259695322;6084;6087.4521293083835;5986.341502434816;5999.100134979788;6009.729562169626;6018.608790428783;6034.668093254913;5188.169892817131;5633.267675202362;5723.542116630742;5790.305163380422;5890.4993075589055;4857.805687772449;4920.92618610714;5892.705364731832;5912.674802885883;6027.457528822427;5879;5879.882402351844;5881.5588830838715;5891.734871930687;5905.35041145465 20:27:38 INFO - PROCESS | 1635 | |1;ext.html;439.59996403273016;465.257913627764;465.97204167749936;513.5249893848189;553.612471270111;29243.56134657979;30582;30725;30725;30744.07767766965;39115.60884391162;40410.19179616406;40443.19113617726;42400.36399454011;42431.78784106083;9183.86224206639;9218.58516366765;9354.672586459441;9361.67234146805;9370.016148304432;9292.070792920722;9352.298577606707;9364.438133711965;9367.250619950388;9371.859422108673;9351.906480935206;9406.5767040483;9410.858837117448;9428.717138485823;9477.573509192069;9292.721218363426;9365.34442589019;9368.531573421302;9380.765480862965;9400.341976061678;8165.3059489943425;9145.359824812265;9192.264618830477;9204.861927071099;9216.723498295061;37695.81152094222;37955.43066853999;38019;38051.42922856132;38054.80972595147;3217.034889533154;3309.5366648669205;3375.3080618473155;3734.7333187224813;3819;28665.14004579845;29314.70685293141;29414;29639.703602964124;29839.552406713916;3109.455845227099;3163.31988622445;3168.3980043791607;3178.2689981304197;3185.4106990206888;521169;529762.3511882455;530560;618639.9068004676;640170;56983.43016569864;57953.710231448575;58874.82250354969;58932.705336473045;58959.70520147458;34914.65085349165;62247.37752622416;62338.0649290261;62619;62648.68675656593;15074.397024119131;15401;15405.845941540441;15419.614509637124;15428.845711542965;11511.942440287912;11549.538018479334;11580.305181689126;11591.884081159249;11810.940945295219;17898.28406863711;18515.629687406446;18527.629447410975;18531.88808671484;18540.536486587942;6964.825879353057;7176.66285177395;7532.856928689199;7640.274173953466;7703.268189521987;815.8123631564773;927.4898805656861;935.5162044771143;990.9851352229722;1030.5465595138264;28091.297717557223;29349.559756603667;29647.851760741058;30127.69872301249;30368.17790932551;864.4078806017898;921.3228277216241;985.7979114281555;1007.969760907174;1061.193492945367;9835.262355323379;14687.118772873662;14947.402103915721;15216.847831521765;15602.375904963674;645865;648110;648221.5177848255;648298;650876;53072;53485.93028139493;53609.73195134077;53768.655783605725;53837;1212.9575464858688;1213.3144773203112;1215.4834195466892;1362.8160198373291;1383.4881093995084;28437.857810710815;29432.11703648894;30228.395432091675;30486.84756576203;30996;51585.22622160595;52316;52406.95186096333;52410;52430.21354679607;605614;610508;610830;612019;612168;24831.875840621404;25208;25782.96868125254;26570.468590628465;26616.866915665298;43873.34189987217;44315;44675;45214.54785452235;45554.31668524908;36797.08007299839;37293.81353093217;37353.62646373502;37485.62514374821;37535.81232093822;36819.356096001306;37331;38074.42888356732;38368;38386.80806595949;35615.287694246486;36079;36138.4579231317;36574.90275291746;36636.45045324269;31739.365212695157;33321;33398.833005834815;33495.16262093467;33702.662973370934;24441.51116977686;24788.38029049288;24794.38014049591;25218.495630087662;25328.493430131664 20:27:38 INFO - PROCESS | 1635 | |2;jquery.html;311.3321924472008;348.0602373591304;366.1413984207035;367.60482481332576;401.85131501344506;14444.277786110704;14764.187969661665;15233.238338083092;18208.453746387597;20035.39893803188;18734.999999999967;19068.04659767013;19622.803771962277;20046.59906801863;20274.29039983601;5754.108113242443;5792.594518383715;5955.689748255377;6008.331340189755;6028.4574388305045;5101.438841727407;5671.914921276163;5890.998530249846;6017.909731354033;6043.093535969618;6100.511959043266;6118.449339559439;6120.26556813183;6122.295935967354;6123.234595675541;6010.669413182273;6115.48018418435;6586.571872828251;6658.600483970978;6661.034150048265;4950.950490495121;5855.268091488564;5862.355140934494;5864.677442740647;5868.559858010666;22680.979355929023;23716.271992202623;23750.287491375286;24182.879085604636;24201.757982420128;2137.561799831031;2138.1661152150673;2231.263682984611;2484.4906794107164;2777.1390868830727;9948.602055917754;10133.746656333577;10353.430561319125;11973;15314.081155130727;1818.354484158118;1825.3246298869371;1859.3120545398153;1894.526368407898;1916.0419790104947;97795.0220497793;116013;116657.41671291675;116943.41528292389;117189;11261.887381126084;11285.040771534348;11372.943135284437;11462.911023452758;11549;11428.25716328436;13275.402606882713;14126.788098178535;14127.505537306144;14396.928015360065;7065.43476521877;7474.85050298991;7929.365650747926;8308.086110527836;8336.083030866599;8149.307308878811;8282.254597086292;8427.873581896276;8433.831323373499;8439.451435656663;4380.846670366522;4465.464144302705;4478.081993191388;4550.726956382628;4577.793999270041;1751.1244377811095;1788.910554472271;1790.873730776526;1832.395309547846;1902.4197619726117;823.4524041512447;841.2638940926689;922.8569571716395;930.418488444722;965.290511474066;16905;18879.90560047191;20372.879491627806;20477.590448190953;20781.58436831255;894.7941973346036;902.444996327254;918.237862574062;928.4986107501874;981.9705408837747;10194.337368071052;10771.299865508718;10783.029527342576;10794.460276986276;10819;600167;602568;609483;610673;614567;120143;126231.47537049207;130848;134146.65853341343;143753;960.0831206198064;997.8952210017843;1009.9394036357842;1010.9494525273707;1013.4881884648205;16180.676386471969;18178;18341;18519;18571.16429760691;35981.46027809533;38174.61825381822;54002.64993375197;55306.72346638241;56305.71847140738;75345;76453.61773191098;77549.44901101834;82351;83109.75185931532;6957.547759395623;7986.800329991796;7995.560244186626;8024.679012839421;8165.469244499089;10465.68602941913;11075.889241107487;12774.23354598727;12897.48410063587;13096.869031309565;10834.187435942493;10929.83605245906;10953.671389858317;11264.7747045057;11381.943090284496;8934.195922367018;10808.297460665031;10896.891031089588;11190.440477975906;11475.827862582235;11052.889471105185;11237.775244495228;11414.372209528556;11575.652730418102;11756.470958806703;7161.964190179224;7398.445116616168;7459.627018649155;7532.623368831427;7568.810779730551;10540.314879533113;11058.557657693602;11127.165462590176;11276.985071343619;11353.772924541629 20:27:38 INFO - PROCESS | 1635 | |3;mootools.html;1442.704245629646;1474.9557513274601;1618.182817677073;1802.7295905614164;1824.498262977681;7421.480496365243;7710.884336734954;7949.403794715397;8341.499510029402;8381.287590554803;7599.7340093096755;7669.041369828771;7677.3858091352695;7679.92320076799;7680.692772289116;1847.9353222637176;2293;2293.4266433391654;2294.403455101677;2296.678465014891;2015.2140665140607;2021.8786872787607;2255.883337747817;2278.2823410625683;2345.6833327500826;2031.9288824891132;2109.89450527473;2294.231432470116;2322.013144413626;2322.9767702323015;2227.6435770276757;2305.2853615379117;2305.6541518772065;2306.3657494189065;2313.618252988255;2291.5531471362924;2301.390131615123;2310.27226423676;2312.4681323295736;2336.5677349690363;8126.63430145639;8170.223828736261;8234.670613175465;8250.5462199579;8260.215279548433;4957.182064959277;5109.744512774347;5130.230465430215;5130.640855140142;5137.306463627438;3089.7991630544166;3212.1969507623094;3282.3599398117376;3365.1250674824723;3436.9484457733156;996.1632228927663;1035.2287545778368;1057.0592172966067;1063.7606538528769;1706.6160113974381;10527.894721052768;10648.361098334124;10653.25427220095;10655.680329590046;10668.293073108343;4157.397177409259;4350.32569951658;4359.04101097758;4364.978175109104;4423.845165419195;8491.980962284566;8495.150484951455;8496.107908669625;8507.53208573522;8509.149085091442;4993.550580447777;4993.950060499421;4996.600271978233;4998.475160108209;5005.89988200234;3989.3816458448987;4201.537830838605;4205.558416366299;4620.97689511557;4652.418447694038;3576.678098971105;3579.982100089483;3645.5989841117457;3686.7972261525874;3697.7226707997006;1119.356370087203;1123.7078359626391;1123.713453069462;1138.3511398502774;1139.3847322445786;828.4780588229377;853.7566793463833;856.8586183279814;904.7692838326317;910.8406028944908;5456.454354564591;5469.863253418696;5492.038893193675;5538.086215774352;5612.747426365823;413.1324219139842;414.21092818180813;422.8837069805828;428.1073960791722;430.3265389665164;3498.090496470885;3591.4612808078996;3822.6177382261553;3826.1199924017633;3831.578526362098;9549.140577348073;9795.510224488748;9997.150242229493;10102.797944041225;10159.187265018782;9790.853137202805;9812.558434870449;10104.69685909424;10115.988401159826;10126.987301269814;7414.740484083031;7766.068071831303;7840.13758486566;7843.176466471053;7848.646810893523;5307.6284660073425;5396.109641909041;5429.348478182485;5475.4524547544925;5614.242077319673;9258.39820411698;9316.6273349068;9391.718248452557;9392.530373481435;9488.762780930532;9469.14777670013;9475.763105922408;9484.52577371115;9552.808943821223;9586.712398628053;6172.475339596184;6392.488600911823;6397.200349956255;6408.743650253938;6454.870902582016;7536.284053014955;7826.373890088893;7842.686292548234;7918.722844700408;7922.920770792449;6946.340097690711;7084.858302834017;7101.644917754195;7145.9285407147345;7229.710811567478;6948.965255173692;6965.825854353681;6988.650567471708;7002.824929376602;7067.116610423697;6400.039994000751;6426.582272152295;6468.902966455601;6482.222133344029;6537.7058032389605;4715.457722361973;4760.119377915097;4763.785629646605;4816.590589799906;4843.200871856246;4374.884404476838;4676.158291507561;4690.671652984395;4835.226363781778;4924.089043526959 20:27:38 INFO - PROCESS | 1635 | |4;prototype.html;293.3912132325425;296.44712610980525;303.2842491719542;306.6396983544336;307.9091667957952;4300.677449191311;4769.618430525558;5066.594672426207;5084.949150508493;5122.590192784578;4125.64838042316;4895.951040489594;4972;5001.499850014997;5029.245613158028;3278.655741147181;3913.256481268564;3922.941155882657;3925.8233379497847;3927.7643341399466;3173.7783551513917;3284.4088064148445;3605.3415428902604;3930.4104384342436;3952.565217826037;3418.3676019936393;3780.81095945203;3874.9806250968704;3907.960920390788;3920.5491368492562;3431.9485207721905;3891.4746509221177;3905.511811023622;3907.6678482328753;3933.3509970854775;2995.9850200749074;3003.279212988889;3197.7921435106646;3200.3599280144063;3473.930521389583;3825.4261860720835;4023.818928148211;4522.66080043998;4919.975400123012;5023.547880690755;3702.4446333049987;4423.469183697976;4433.312836510346;4441.467023957113;4497.7526236057;5235.65968212065;5283.445238249967;5287;5294.126469132589;5317.9468205317835;3335.449650807614;3579.13884779913;3609.6390360963956;3765.437343502443;3772.0946972726624;4598.632109431237;4777.331173635694;5418.349798024223;5496.80761173361;5515.972420137874;3477.791332520057;3788.3559794834946;3797.3354662933875;3807.9809600951817;3817.541894972621;3313.7846040007803;3581.6239294874185;3604.981975090108;3753.7424962637274;3917.3536366499206;4184.602462766033;4311.611954924009;4320.762358070274;4342.305231162999;4402.8679139625865;5045.798168073309;5282.234076059027;5288.735563221823;5339.679619222781;5368.489993450616;1104.5857803323754;1129.9774004519863;1159.930404175752;1192.9582464613698;1209.9274043557414;752.5935994562984;811.5999900172155;834.4993004197554;837.2590257622048;845.8815765792793;3986.621270979252;4655.440427456772;4673.766311684402;4719.882002949954;4814.325994360793;4583.610393116555;5050.444451110375;5085.084684756779;5112.437631860493;5140.588752899759;4039.7576145431367;4339.609435150852;4633.308842272613;4664.813407463664;4684.250519916797;2170.4139882231707;2278.8404811663027;2309.1712652001165;2419.3346829621996;2601.80486463516;4262.42457268271;5147.922781158286;5179.493840358837;5199.128313807027;5224.60815438836;4463.620592249695;4754.97767979886;4924.778384972685;5219.817306394334;5232.476752324813;4757.9286310705365;5163.561097306771;5172.818951336685;5208.348956380452;5226.3205783248695;4062;4698.718076915396;4900.774806298426;5050.772715227897;5051.191809310492;4247.57706168437;4421.845235416745;4486.407325399567;5029.76770691183;5032.9550621874705;4274.658027357867;4964.67729597575;5181.637285390138;5195.506426889439;5199.620246839585;3343.5820522434697;3347.7489188310487;3460.7923524588605;3466.6533346665133;3476.209751977683;4229.513605935357;4743.288506723881;4974.403071631427;5056.317397151338;5078.5175408336145;3775.6979441645153;4470.87039072657;4757.075412784536;4853.883606770392;5298;5263.34208223972;5273.6044796639635;5278.8944221116135;5306.815910453697;5321.255024296524;4832.516748325139;4917.311576379381;5205.895882082413;5230.241614965885;5238.585581892944;4810.807567697253;5110.9233361500355;5215.76529056201;5272.894542109213;5309.017831701148;5147.382314122179;5165.66423182492;5170.819021334235;5214.270002199619;5221.738913054256 20:27:38 INFO - PROCESS | 1635 | |5;yui.html;619.7180282971249;684.3191024930195;747.8653842308386;754.3851760814939;758.325090669304;13827.446902123915;14190.858091419082;14648.926755366236;14836.480723174678;14921.92539037306;13094.934525327384;13134.277614731185;13220.867791322084;13323.400446979886;13744.450221991134;799.932005779508;828.1428721273484;828.8922440082781;829.0962850492962;830.0454477351064;650.2749434380655;671.4326394196896;797.5932274539997;822.1572887790034;825.5665775467897;851.3912552524944;868.053821334745;868.1752335281476;870.4559650218613;872.3370238618663;862.8964524257098;863.3222920007827;864.9178328058824;865.0786911938765;865.8657908024267;497.55443553392973;511.33015749368445;515.7756375976462;516.2540129512855;643.996780016097;13646.181229126285;14221.715565688732;14233.715325693533;14503.492377766834;14507.057041292283;13015.609531714064;13236.86763132366;13278.601641950758;13302.135361201492;13366.198028118246;9818.919918808926;11144.665660030212;11202.991730744272;11270.32378057311;11383.943080284545;3492.388831954398;3573.1424458130123;3611.416026344655;3680.7423480356388;3745.1198968242443;74896.6255168726;75796;81647.5917620414;88051.11948880494;93538;10457.68626941193;10585.41780202081;10697.946510267398;11013.669589912315;11018.669439916815;21061.684074738892;21324.680129798064;21556.245531406326;21720.78279217219;21830.126794928343;7660;7779.821649938026;7802.375809935305;7802.960985195038;7961.641726122339;4167.458230430025;4215.515215750167;4254.638355739735;4325.243082460557;4350.543192964757;3320.369129865318;3424.6232914379398;3427.4002049641213;3439.466882633196;3442.0018194723752;612.5344737999153;626.8213559135625;628.7987843890003;633.341325021972;633.4647223096568;662.3773652766384;663.5620490476263;675.4832553096885;677.096076737551;698.5284932670427;8316.542590157478;8408.915910840935;8732.525210146934;8923.866142007875;9107;601.954853385989;653.633964979613;657.6185812228896;663.2935923241732;679.5379142183363;4279.122779830127;4380.759058251763;4440.134173836071;4504.414426124583;4582.908341833145;86583.26833463296;107098.46450767697;109821.90178098116;114616;119927;104250;105408;107102;108865;108926.45536772265;87432.93984710505;91776;93966;96321;97225.5138724316;8621.396502244783;8733.432326898732;8822.911770882209;8840.557972101498;8898.42160259581;49036.75481622569;54156.458435415145;55601.721991389786;57746.133807993756;58235.544111397554;52322.95354092973;67785.66107169433;68245.31754682389;68914;69583.65208173927;12100.576479823163;12202;12211.961983231526;12290.692732681755;12402.25586464815;11239.719007024889;11648.767024659628;12009.459574319002;12316.753664926471;12523.93738031304;11345.432728363714;12155.635330940086;13058.934705326412;13304.334783260605;13406.128601640861;12043.638690839289;12138.514459421878;12365.319907405179;12603.243805371707;12799.872001279868;12500.374981251083;12555.623331300076;12730.23618582888;12887.613371598867;13524.797128043285;11060;11321.547138114383;11427.257228280136;11467.31196128235;11640.253961905782;9576.248045080942;10285.74285642865;10456.359759219207;10484.528196231035;10542.841857371992 20:27:38 INFO - PROCESS | 1635 | __end_tp_report 20:27:38 INFO - PROCESS | 1635 | __start_cc_report 20:27:38 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1714 20:27:38 INFO - PROCESS | 1635 | __end_cc_report 20:27:38 INFO - PROCESS | 1635 | __startTimestamp1480393658018__endTimestamp 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | ------- Summary: start ------- 20:27:38 INFO - PROCESS | 1635 | Number of tests: 6 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5606.86 Median:5723.54 stddev:632.75 (11.1%) stddev-sans-first:598.63 20:27:38 INFO - PROCESS | 1635 | Values: 2922.8 3160.9 3224.2 3263.3 3314.8 4506.0 5660.6 5688.3 5695.9 5708.5 5660.6 5677.5 5677.8 5705.0 5706.8 5137.2 5165.0 5189.0 5236.5 5293.7 5071.2 5112.9 5136.6 5192.0 5241.5 5116.7 5183.0 5198.4 5232.4 5251.2 5175.1 5177.4 5213.5 5244.9 5250.8 5115.4 5193.3 5196.6 5202.1 5249.7 5518.5 5520.9 5551.1 5553.8 5645.5 6104.6 6137.6 6152.5 6178.6 6185.2 6240.1 6259.9 6261.5 6272.2 6274.3 5529.4 5589.2 5603.4 5735.6 5779.5 6155.1 6210.3 6211.0 6213.0 6216.0 5905.1 5972.0 6025.7 6078.7 6080.4 6101.0 6165.6 6206.6 6210.2 6217.0 5957.8 5967.6 6060.2 6097.0 6108.1 6061.6 6093.0 6097.9 6118.6 6139.7 4887.6 4888.6 4889.9 4902.9 4918.1 4233.3 4385.8 4389.1 4393.3 4399.7 5462.5 5567.5 5573.5 5596.2 5644.0 5561.1 5602.8 6182.3 6259.8 6267.0 5649.2 5650.6 5664.0 5669.8 5673.3 5201.5 5211.9 5344.0 5350.1 5353.8 5487.8 6057.4 6204.7 6252.4 6253.5 5815.9 5886.9 6004.2 6194.1 6203.2 5229.1 5853.6 5895.1 6100.8 6110.8 5656.5 5784.9 5845.9 5846.5 5859.0 5938.1 6005.3 6005.5 6052.6 6116.0 5976.5 6056.0 6060.4 6084.0 6087.5 5986.3 5999.1 6009.7 6018.6 6034.7 5188.2 5633.3 5723.5 5790.3 5890.5 4857.8 4920.9 5892.7 5912.7 6027.5 5879.0 5879.9 5881.6 5891.7 5905.4 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:72477.60 Median:21093.50 stddev:163565.13 (775.4%) stddev-sans-first:163934.54 20:27:38 INFO - PROCESS | 1635 | Values: 439.6 465.3 466.0 513.5 553.6 29243.6 30582.0 30725.0 30725.0 30744.1 39115.6 40410.2 40443.2 42400.4 42431.8 9183.9 9218.6 9354.7 9361.7 9370.0 9292.1 9352.3 9364.4 9367.3 9371.9 9351.9 9406.6 9410.9 9428.7 9477.6 9292.7 9365.3 9368.5 9380.8 9400.3 8165.3 9145.4 9192.3 9204.9 9216.7 37695.8 37955.4 38019.0 38051.4 38054.8 3217.0 3309.5 3375.3 3734.7 3819.0 28665.1 29314.7 29414.0 29639.7 29839.6 3109.5 3163.3 3168.4 3178.3 3185.4 521169.0 529762.4 530560.0 618639.9 640170.0 56983.4 57953.7 58874.8 58932.7 58959.7 34914.7 62247.4 62338.1 62619.0 62648.7 15074.4 15401.0 15405.8 15419.6 15428.8 11511.9 11549.5 11580.3 11591.9 11810.9 17898.3 18515.6 18527.6 18531.9 18540.5 6964.8 7176.7 7532.9 7640.3 7703.3 815.8 927.5 935.5 991.0 1030.5 28091.3 29349.6 29647.9 30127.7 30368.2 864.4 921.3 985.8 1008.0 1061.2 9835.3 14687.1 14947.4 15216.8 15602.4 645865.0 648110.0 648221.5 648298.0 650876.0 53072.0 53485.9 53609.7 53768.7 53837.0 1213.0 1213.3 1215.5 1362.8 1383.5 28437.9 29432.1 30228.4 30486.8 30996.0 51585.2 52316.0 52407.0 52410.0 52430.2 605614.0 610508.0 610830.0 612019.0 612168.0 24831.9 25208.0 25783.0 26570.5 26616.9 43873.3 44315.0 44675.0 45214.5 45554.3 36797.1 37293.8 37353.6 37485.6 37535.8 36819.4 37331.0 38074.4 38368.0 38386.8 35615.3 36079.0 36138.5 36574.9 36636.5 31739.4 33321.0 33398.8 33495.2 33702.7 24441.5 24788.4 24794.4 25218.5 25328.5 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34830.86 Median:23966.58 stddev:101339.17 (422.8%) stddev-sans-first:101590.31 20:27:38 INFO - PROCESS | 1635 | Values: 311.3 348.1 366.1 367.6 401.9 14444.3 14764.2 15233.2 18208.5 20035.4 18735.0 19068.0 19622.8 20046.6 20274.3 5754.1 5792.6 5955.7 6008.3 6028.5 5101.4 5671.9 5891.0 6017.9 6043.1 6100.5 6118.4 6120.3 6122.3 6123.2 6010.7 6115.5 6586.6 6658.6 6661.0 4951.0 5855.3 5862.4 5864.7 5868.6 22681.0 23716.3 23750.3 24182.9 24201.8 2137.6 2138.2 2231.3 2484.5 2777.1 9948.6 10133.7 10353.4 11973.0 15314.1 1818.4 1825.3 1859.3 1894.5 1916.0 97795.0 116013.0 116657.4 116943.4 117189.0 11261.9 11285.0 11372.9 11462.9 11549.0 11428.3 13275.4 14126.8 14127.5 14396.9 7065.4 7474.9 7929.4 8308.1 8336.1 8149.3 8282.3 8427.9 8433.8 8439.5 4380.8 4465.5 4478.1 4550.7 4577.8 1751.1 1788.9 1790.9 1832.4 1902.4 823.5 841.3 922.9 930.4 965.3 16905.0 18879.9 20372.9 20477.6 20781.6 894.8 902.4 918.2 928.5 982.0 10194.3 10771.3 10783.0 10794.5 10819.0 600167.0 602568.0 609483.0 610673.0 614567.0 120143.0 126231.5 130848.0 134146.7 143753.0 960.1 997.9 1009.9 1010.9 1013.5 16180.7 18178.0 18341.0 18519.0 18571.2 35981.5 38174.6 54002.6 55306.7 56305.7 75345.0 76453.6 77549.4 82351.0 83109.8 6957.5 7986.8 7995.6 8024.7 8165.5 10465.7 11075.9 12774.2 12897.5 13096.9 10834.2 10929.8 10953.7 11264.8 11381.9 8934.2 10808.3 10896.9 11190.4 11475.8 11052.9 11237.8 11414.4 11575.7 11756.5 7162.0 7398.4 7459.6 7532.6 7568.8 10540.3 11058.6 11127.2 11277.0 11353.8 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5291.33 Median:4993.75 stddev:2938.38 (58.8%) stddev-sans-first:2932.38 20:27:38 INFO - PROCESS | 1635 | Values: 1442.7 1475.0 1618.2 1802.7 1824.5 7421.5 7710.9 7949.4 8341.5 8381.3 7599.7 7669.0 7677.4 7679.9 7680.7 1847.9 2293.0 2293.4 2294.4 2296.7 2015.2 2021.9 2255.9 2278.3 2345.7 2031.9 2109.9 2294.2 2322.0 2323.0 2227.6 2305.3 2305.7 2306.4 2313.6 2291.6 2301.4 2310.3 2312.5 2336.6 8126.6 8170.2 8234.7 8250.5 8260.2 4957.2 5109.7 5130.2 5130.6 5137.3 3089.8 3212.2 3282.4 3365.1 3436.9 996.2 1035.2 1057.1 1063.8 1706.6 10527.9 10648.4 10653.3 10655.7 10668.3 4157.4 4350.3 4359.0 4365.0 4423.8 8492.0 8495.2 8496.1 8507.5 8509.1 4993.6 4994.0 4996.6 4998.5 5005.9 3989.4 4201.5 4205.6 4621.0 4652.4 3576.7 3580.0 3645.6 3686.8 3697.7 1119.4 1123.7 1123.7 1138.4 1139.4 828.5 853.8 856.9 904.8 910.8 5456.5 5469.9 5492.0 5538.1 5612.7 413.1 414.2 422.9 428.1 430.3 3498.1 3591.5 3822.6 3826.1 3831.6 9549.1 9795.5 9997.2 10102.8 10159.2 9790.9 9812.6 10104.7 10116.0 10127.0 7414.7 7766.1 7840.1 7843.2 7848.6 5307.6 5396.1 5429.3 5475.5 5614.2 9258.4 9316.6 9391.7 9392.5 9488.8 9469.1 9475.8 9484.5 9552.8 9586.7 6172.5 6392.5 6397.2 6408.7 6454.9 7536.3 7826.4 7842.7 7918.7 7922.9 6946.3 7084.9 7101.6 7145.9 7229.7 6949.0 6965.8 6988.7 7002.8 7067.1 6400.0 6426.6 6468.9 6482.2 6537.7 4715.5 4760.1 4763.8 4816.6 4843.2 4374.9 4676.2 4690.7 4835.2 4924.1 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4145.02 Median:4644.37 stddev:1265.62 (27.3%) stddev-sans-first:1235.71 20:27:38 INFO - PROCESS | 1635 | Values: 293.4 296.4 303.3 306.6 307.9 4300.7 4769.6 5066.6 5084.9 5122.6 4125.6 4896.0 4972.0 5001.5 5029.2 3278.7 3913.3 3922.9 3925.8 3927.8 3173.8 3284.4 3605.3 3930.4 3952.6 3418.4 3780.8 3875.0 3908.0 3920.5 3431.9 3891.5 3905.5 3907.7 3933.4 2996.0 3003.3 3197.8 3200.4 3473.9 3825.4 4023.8 4522.7 4920.0 5023.5 3702.4 4423.5 4433.3 4441.5 4497.8 5235.7 5283.4 5287.0 5294.1 5317.9 3335.4 3579.1 3609.6 3765.4 3772.1 4598.6 4777.3 5418.3 5496.8 5516.0 3477.8 3788.4 3797.3 3808.0 3817.5 3313.8 3581.6 3605.0 3753.7 3917.4 4184.6 4311.6 4320.8 4342.3 4402.9 5045.8 5282.2 5288.7 5339.7 5368.5 1104.6 1130.0 1159.9 1193.0 1209.9 752.6 811.6 834.5 837.3 845.9 3986.6 4655.4 4673.8 4719.9 4814.3 4583.6 5050.4 5085.1 5112.4 5140.6 4039.8 4339.6 4633.3 4664.8 4684.3 2170.4 2278.8 2309.2 2419.3 2601.8 4262.4 5147.9 5179.5 5199.1 5224.6 4463.6 4755.0 4924.8 5219.8 5232.5 4757.9 5163.6 5172.8 5208.3 5226.3 4062.0 4698.7 4900.8 5050.8 5051.2 4247.6 4421.8 4486.4 5029.8 5033.0 4274.7 4964.7 5181.6 5195.5 5199.6 3343.6 3347.7 3460.8 3466.7 3476.2 4229.5 4743.3 4974.4 5056.3 5078.5 3775.7 4470.9 4757.1 4853.9 5298.0 5263.3 5273.6 5278.9 5306.8 5321.3 4832.5 4917.3 5205.9 5230.2 5238.6 4810.8 5110.9 5215.8 5272.9 5309.0 5147.4 5165.7 5170.8 5214.3 5221.7 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20574.84 Median:4302.18 stddev:30650.39 (712.4%) stddev-sans-first:30699.75 20:27:38 INFO - PROCESS | 1635 | Values: 619.7 684.3 747.9 754.4 758.3 13827.4 14190.9 14648.9 14836.5 14921.9 13094.9 13134.3 13220.9 13323.4 13744.5 799.9 828.1 828.9 829.1 830.0 650.3 671.4 797.6 822.2 825.6 851.4 868.1 868.2 870.5 872.3 862.9 863.3 864.9 865.1 865.9 497.6 511.3 515.8 516.3 644.0 13646.2 14221.7 14233.7 14503.5 14507.1 13015.6 13236.9 13278.6 13302.1 13366.2 9818.9 11144.7 11203.0 11270.3 11383.9 3492.4 3573.1 3611.4 3680.7 3745.1 74896.6 75796.0 81647.6 88051.1 93538.0 10457.7 10585.4 10697.9 11013.7 11018.7 21061.7 21324.7 21556.2 21720.8 21830.1 7660.0 7779.8 7802.4 7803.0 7961.6 4167.5 4215.5 4254.6 4325.2 4350.5 3320.4 3424.6 3427.4 3439.5 3442.0 612.5 626.8 628.8 633.3 633.5 662.4 663.6 675.5 677.1 698.5 8316.5 8408.9 8732.5 8923.9 9107.0 602.0 653.6 657.6 663.3 679.5 4279.1 4380.8 4440.1 4504.4 4582.9 86583.3 107098.5 109821.9 114616.0 119927.0 104250.0 105408.0 107102.0 108865.0 108926.5 87432.9 91776.0 93966.0 96321.0 97225.5 8621.4 8733.4 8822.9 8840.6 8898.4 49036.8 54156.5 55601.7 57746.1 58235.5 52323.0 67785.7 68245.3 68914.0 69583.7 12100.6 12202.0 12212.0 12290.7 12402.3 11239.7 11648.8 12009.5 12316.8 12523.9 11345.4 12155.6 13058.9 13304.3 13406.1 12043.6 12138.5 12365.3 12603.2 12799.9 12500.4 12555.6 12730.2 12887.6 13524.8 11060.0 11321.5 11427.3 11467.3 11640.3 9576.2 10285.7 10456.4 10484.5 10542.8 20:27:38 INFO - PROCESS | 1635 | -------- Summary: end -------- 20:27:38 INFO - PROCESS | 1635 | 20:27:38 INFO - TEST-INFO | 1635: exit 0 20:27:38 INFO - TEST-OK | dromaeo_css | took 1119740ms 20:27:38 INFO - TEST-START | kraken 20:27:38 INFO - operating with platform_type : mac_ 20:27:38 INFO - Initialising browser for kraken test... 20:27:38 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpuQngsV/profile http://localhost:49243/getInfo.html) 20:27:41 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 20:27:41 INFO - PROCESS | 1644 | colorDepth:24 20:27:41 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 20:27:41 INFO - PROCESS | 1644 | __metrics 20:27:41 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:27:41 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:27:42 INFO - TEST-INFO | 1644: exit 0 20:27:42 INFO - Browser initialized. 20:27:42 INFO - Running cycle 1/1 for kraken test... 20:27:42 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpuQngsV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:54 INFO - PROCESS | 1645 | RSS: Main: 223211520 20:27:54 INFO - PROCESS | 1645 | 20:27:57 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 20:27:58 INFO - PROCESS | 1645 | RSS: Main: 270635008 20:27:58 INFO - PROCESS | 1645 | 20:28:01 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 20:28:01 INFO - PROCESS | 1645 | RSS: Main: 284422144 20:28:01 INFO - PROCESS | 1645 | 20:28:04 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 20:28:05 INFO - PROCESS | 1645 | RSS: Main: 382066688 20:28:05 INFO - PROCESS | 1645 | 20:28:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 20:28:08 INFO - PROCESS | 1645 | RSS: Main: 282652672 20:28:08 INFO - PROCESS | 1645 | 20:28:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:10 INFO - PROCESS | 1645 | RSS: Main: 283762688 20:28:10 INFO - PROCESS | 1645 | 20:28:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 20:28:14 INFO - PROCESS | 1645 | RSS: Main: 326672384 20:28:14 INFO - PROCESS | 1645 | 20:28:17 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 20:28:17 INFO - PROCESS | 1645 | RSS: Main: 326197248 20:28:17 INFO - PROCESS | 1645 | 20:28:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 20:28:21 INFO - PROCESS | 1645 | RSS: Main: 323985408 20:28:21 INFO - PROCESS | 1645 | 20:28:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:28:23 INFO - PROCESS | 1645 | RSS: Main: 299966464 20:28:23 INFO - PROCESS | 1645 | 20:28:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:28:26 INFO - PROCESS | 1645 | RSS: Main: 305012736 20:28:26 INFO - PROCESS | 1645 | 20:28:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:28 INFO - PROCESS | 1645 | RSS: Main: 305729536 20:28:28 INFO - PROCESS | 1645 | 20:28:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:28:32 INFO - PROCESS | 1645 | RSS: Main: 321126400 20:28:32 INFO - PROCESS | 1645 | 20:28:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:28:36 INFO - PROCESS | 1645 | RSS: Main: 314728448 20:28:36 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 20:28:38 INFO - PROCESS | 1645 | RSS: Main: 358572032 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | __start_tp_report 20:28:38 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 20:28:38 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 20:28:38 INFO - PROCESS | 1645 | |i|pagename|runs| 20:28:38 INFO - PROCESS | 1645 | |0;ai-astar;106;116;116;114;114;116;115;115;116;114 20:28:38 INFO - PROCESS | 1645 | |1;audio-beat-detection;148;109;115;148;115;117;113;114;144;113 20:28:38 INFO - PROCESS | 1645 | |2;audio-dft;185;186;183;188;184;183;180;184;185;185 20:28:38 INFO - PROCESS | 1645 | |3;audio-fft;75;56;60;88;63;61;58;61;104;81 20:28:38 INFO - PROCESS | 1645 | |4;audio-oscillator;85;71;95;71;68;69;88;69;69;92 20:28:38 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;116;129;104;87;89;86;92;88;90;86 20:28:38 INFO - PROCESS | 1645 | |6;imaging-darkroom;92;107;149;93;95;95;94;94;94;113 20:28:38 INFO - PROCESS | 1645 | |7;imaging-desaturate;81;83;81;80;84;91;140;85;84;82 20:28:38 INFO - PROCESS | 1645 | |8;json-parse-financial;56;56;54;56;57;57;57;58;58;58 20:28:38 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;70;69;76;73;74;71;71;72;71;70 20:28:38 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;86;85;85;86;84;85;92;86;101;85 20:28:38 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;148;148;147;147;146;159;158;145;200;148 20:28:38 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;168;170;170;170;164;224;173;167;167;166 20:28:38 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;68;67;68;66;69;79;87;85;88;71 20:28:38 INFO - PROCESS | 1645 | __end_tp_report 20:28:38 INFO - PROCESS | 1645 | __start_cc_report 20:28:38 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1997 20:28:38 INFO - PROCESS | 1645 | __end_cc_report 20:28:38 INFO - PROCESS | 1645 | __startTimestamp1480393718264__endTimestamp 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | ------- Summary: start ------- 20:28:38 INFO - PROCESS | 1645 | Number of tests: 14 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:3.01 (2.6%) stddev-sans-first:0.93 20:28:38 INFO - PROCESS | 1645 | Values: 106.0 116.0 116.0 114.0 114.0 116.0 115.0 115.0 116.0 114.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:116.00 stddev:16.08 (13.9%) stddev-sans-first:14.43 20:28:38 INFO - PROCESS | 1645 | Values: 148.0 109.0 115.0 148.0 115.0 117.0 113.0 114.0 144.0 113.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:2.11 (1.1%) stddev-sans-first:2.22 20:28:38 INFO - PROCESS | 1645 | Values: 185.0 186.0 183.0 188.0 184.0 183.0 180.0 184.0 185.0 185.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:70.70 Median:62.00 stddev:15.89 (25.6%) stddev-sans-first:16.78 20:28:38 INFO - PROCESS | 1645 | Values: 75.0 56.0 60.0 88.0 63.0 61.0 58.0 61.0 104.0 81.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.00 stddev:10.92 (14.0%) stddev-sans-first:11.26 20:28:38 INFO - PROCESS | 1645 | Values: 85.0 71.0 95.0 71.0 68.0 69.0 88.0 69.0 69.0 92.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:87.50 stddev:14.89 (17.0%) stddev-sans-first:14.05 20:28:38 INFO - PROCESS | 1645 | Values: 116.0 129.0 104.0 87.0 89.0 86.0 92.0 88.0 90.0 86.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:94.00 stddev:17.71 (18.8%) stddev-sans-first:18.36 20:28:38 INFO - PROCESS | 1645 | Values: 92.0 107.0 149.0 93.0 95.0 95.0 94.0 94.0 94.0 113.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:83.50 stddev:18.15 (21.7%) stddev-sans-first:19.01 20:28:38 INFO - PROCESS | 1645 | Values: 81.0 83.0 81.0 80.0 84.0 91.0 140.0 85.0 84.0 82.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.25 (2.2%) stddev-sans-first:1.30 20:28:38 INFO - PROCESS | 1645 | Values: 56.0 56.0 54.0 56.0 57.0 57.0 57.0 58.0 58.0 58.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:2.11 (3.0%) stddev-sans-first:2.15 20:28:38 INFO - PROCESS | 1645 | Values: 70.0 69.0 76.0 73.0 74.0 71.0 71.0 72.0 71.0 70.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.50 stddev:5.23 (6.1%) stddev-sans-first:5.52 20:28:38 INFO - PROCESS | 1645 | Values: 86.0 85.0 85.0 86.0 84.0 85.0 92.0 86.0 101.0 85.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:148.00 stddev:16.68 (11.3%) stddev-sans-first:17.52 20:28:38 INFO - PROCESS | 1645 | Values: 148.0 148.0 147.0 147.0 146.0 159.0 158.0 145.0 200.0 148.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:170.00 stddev:17.79 (10.5%) stddev-sans-first:18.74 20:28:38 INFO - PROCESS | 1645 | Values: 168.0 170.0 170.0 170.0 164.0 224.0 173.0 167.0 167.0 166.0 20:28:38 INFO - PROCESS | 1645 | 20:28:38 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:75.00 stddev:8.97 (12.0%) stddev-sans-first:9.17 20:28:38 INFO - PROCESS | 1645 | Values: 68.0 67.0 68.0 66.0 69.0 79.0 87.0 85.0 88.0 71.0 20:28:38 INFO - PROCESS | 1645 | -------- Summary: end -------- 20:28:38 INFO - PROCESS | 1645 | 20:28:39 INFO - TEST-INFO | 1645: exit 0 20:28:39 INFO - TEST-OK | kraken | took 60648ms 20:28:39 INFO - SUITE-END | took 1180s 20:28:39 INFO - Completed test suite (00:19:40) 20:28:39 INFO - javascript benchmark 20:28:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2922.766178705703, 3160.9051728448153, 3224.1617179533323, 3263.3473305338925, 3314.7679662423625, 4506.0, 5660.575456840743, 5688.317401911771, 5695.914561281579, 5708.457696518834, 5660.632058916168, 5677.48902598766, 5677.801276955307, 5705.030144875371, 5706.828795136143, 5137.152369858978, 5165.0, 5189.0, 5236.502532259429, 5293.682379057269, 5071.1886098224295, 5112.8977420451565, 5136.5890728741615, 5192.0, 5241.528262456378, 5116.667416617926, 5183.0, 5198.428172900978, 5232.398274198459, 5251.186066159751, 5175.068487672217, 5177.430482646906, 5213.504717051874, 5244.947550524522, 5250.816221432232, 5115.411727651331, 5193.272941788153, 5196.61025423091, 5202.115640341151, 5249.711265880375, 5518.53092487135, 5520.944790552124, 5551.084071128259, 5553.8333849984565, 5645.5483561314995, 6104.603200791978, 6137.570370074097, 6152.477039451652, 6178.598391104609, 6185.226846644169, 6240.095186198021, 6259.906101408483, 6261.467775239064, 6272.247330320391, 6274.278457977345, 5529.364123125812, 5589.189567512688, 5603.35561410443, 5735.598508104435, 5779.537636989031, 6155.10750941111, 6210.285817130997, 6210.968945155245, 6213.03697926822, 6215.96892015537, 5905.143754155623, 5972.044472884405, 6025.698715064317, 6078.726457309432, 6080.391960803884, 6101.0, 6165.568410211242, 6206.565540412218, 6210.161628180138, 6217.0, 5957.821265362046, 5967.5524335675, 6060.212172417558, 6097.0, 6108.053251745898, 6061.636301821904, 6093.0, 6097.939020609826, 6118.632882027093, 6139.662318572432, 4887.60899128076, 4888.584470320063, 4889.877753056202, 4902.926456103161, 4918.065567542156, 4233.25917964355, 4385.758783266951, 4389.144116897174, 4393.275109606943, 4399.670024748157, 5462.453754624506, 5567.47109024642, 5573.526250268773, 5596.188552659923, 5643.971780141155, 5561.082421400504, 5602.803901863415, 6182.28903676083, 6259.843503912348, 6267.0, 5649.209110724501, 5650.576206784508, 5664.037113690682, 5669.773209071674, 5673.3475650299915, 5201.531862132426, 5211.921821172686, 5344.0, 5350.143976963667, 5353.7590808413715, 5487.820118674505, 6057.42454466825, 6204.658743769136, 6252.374762523802, 6253.530985176039, 5815.912761308668, 5886.882262354815, 6004.219451471456, 6194.0708893666315, 6203.16257305276, 5229.137192363218, 5853.560982926383, 5895.14520394549, 6100.786472473442, 6110.847228819315, 5656.547476201977, 5784.94215057844, 5845.912311315248, 5846.473817356458, 5859.033259512304, 5938.138969849434, 6005.339412664691, 6005.549583781147, 6052.576319657758, 6116.031445914875, 5976.491998180029, 6056.0, 6060.424259695322, 6084.0, 6087.4521293083835, 5986.341502434816, 5999.100134979788, 6009.729562169626, 6018.608790428783, 6034.668093254913, 5188.169892817131, 5633.267675202362, 5723.542116630742, 5790.305163380422, 5890.4993075589055, 4857.805687772449, 4920.92618610714, 5892.705364731832, 5912.674802885883, 6027.457528822427, 5879.0, 5879.882402351844, 5881.5588830838715, 5891.734871930687, 5905.35041145465], "value": 5566.632017632974, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.59996403273016, 465.257913627764, 465.97204167749936, 513.5249893848189, 553.612471270111, 29243.56134657979, 30582.0, 30725.0, 30725.0, 30744.07767766965, 39115.60884391162, 40410.19179616406, 40443.19113617726, 42400.36399454011, 42431.78784106083, 9183.86224206639, 9218.58516366765, 9354.672586459441, 9361.67234146805, 9370.016148304432, 9292.070792920722, 9352.298577606707, 9364.438133711965, 9367.250619950388, 9371.859422108673, 9351.906480935206, 9406.5767040483, 9410.858837117448, 9428.717138485823, 9477.573509192069, 9292.721218363426, 9365.34442589019, 9368.531573421302, 9380.765480862965, 9400.341976061678, 8165.3059489943425, 9145.359824812265, 9192.264618830477, 9204.861927071099, 9216.723498295061, 37695.81152094222, 37955.43066853999, 38019.0, 38051.42922856132, 38054.80972595147, 3217.034889533154, 3309.5366648669205, 3375.3080618473155, 3734.7333187224813, 3819.0, 28665.14004579845, 29314.70685293141, 29414.0, 29639.703602964124, 29839.552406713916, 3109.455845227099, 3163.31988622445, 3168.3980043791607, 3178.2689981304197, 3185.4106990206888, 521169.0, 529762.3511882455, 530560.0, 618639.9068004676, 640170.0, 56983.43016569864, 57953.710231448575, 58874.82250354969, 58932.705336473045, 58959.70520147458, 34914.65085349165, 62247.37752622416, 62338.0649290261, 62619.0, 62648.68675656593, 15074.397024119131, 15401.0, 15405.845941540441, 15419.614509637124, 15428.845711542965, 11511.942440287912, 11549.538018479334, 11580.305181689126, 11591.884081159249, 11810.940945295219, 17898.28406863711, 18515.629687406446, 18527.629447410975, 18531.88808671484, 18540.536486587942, 6964.825879353057, 7176.66285177395, 7532.856928689199, 7640.274173953466, 7703.268189521987, 815.8123631564773, 927.4898805656861, 935.5162044771143, 990.9851352229722, 1030.5465595138264, 28091.297717557223, 29349.559756603667, 29647.851760741058, 30127.69872301249, 30368.17790932551, 864.4078806017898, 921.3228277216241, 985.7979114281555, 1007.969760907174, 1061.193492945367, 9835.262355323379, 14687.118772873662, 14947.402103915721, 15216.847831521765, 15602.375904963674, 645865.0, 648110.0, 648221.5177848255, 648298.0, 650876.0, 53072.0, 53485.93028139493, 53609.73195134077, 53768.655783605725, 53837.0, 1212.9575464858688, 1213.3144773203112, 1215.4834195466892, 1362.8160198373291, 1383.4881093995084, 28437.857810710815, 29432.11703648894, 30228.395432091675, 30486.84756576203, 30996.0, 51585.22622160595, 52316.0, 52406.95186096333, 52410.0, 52430.21354679607, 605614.0, 610508.0, 610830.0, 612019.0, 612168.0, 24831.875840621404, 25208.0, 25782.96868125254, 26570.468590628465, 26616.866915665298, 43873.34189987217, 44315.0, 44675.0, 45214.54785452235, 45554.31668524908, 36797.08007299839, 37293.81353093217, 37353.62646373502, 37485.62514374821, 37535.81232093822, 36819.356096001306, 37331.0, 38074.42888356732, 38368.0, 38386.80806595949, 35615.287694246486, 36079.0, 36138.4579231317, 36574.90275291746, 36636.45045324269, 31739.365212695157, 33321.0, 33398.833005834815, 33495.16262093467, 33702.662973370934, 24441.51116977686, 24788.38029049288, 24794.38014049591, 25218.495630087662, 25328.493430131664], "value": 19280.919326913045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.3321924472008, 348.0602373591304, 366.1413984207035, 367.60482481332576, 401.85131501344506, 14444.277786110704, 14764.187969661665, 15233.238338083092, 18208.453746387597, 20035.39893803188, 18734.999999999967, 19068.04659767013, 19622.803771962277, 20046.59906801863, 20274.29039983601, 5754.108113242443, 5792.594518383715, 5955.689748255377, 6008.331340189755, 6028.4574388305045, 5101.438841727407, 5671.914921276163, 5890.998530249846, 6017.909731354033, 6043.093535969618, 6100.511959043266, 6118.449339559439, 6120.26556813183, 6122.295935967354, 6123.234595675541, 6010.669413182273, 6115.48018418435, 6586.571872828251, 6658.600483970978, 6661.034150048265, 4950.950490495121, 5855.268091488564, 5862.355140934494, 5864.677442740647, 5868.559858010666, 22680.979355929023, 23716.271992202623, 23750.287491375286, 24182.879085604636, 24201.757982420128, 2137.561799831031, 2138.1661152150673, 2231.263682984611, 2484.4906794107164, 2777.1390868830727, 9948.602055917754, 10133.746656333577, 10353.430561319125, 11973.0, 15314.081155130727, 1818.354484158118, 1825.3246298869371, 1859.3120545398153, 1894.526368407898, 1916.0419790104947, 97795.0220497793, 116013.0, 116657.41671291675, 116943.41528292389, 117189.0, 11261.887381126084, 11285.040771534348, 11372.943135284437, 11462.911023452758, 11549.0, 11428.25716328436, 13275.402606882713, 14126.788098178535, 14127.505537306144, 14396.928015360065, 7065.43476521877, 7474.85050298991, 7929.365650747926, 8308.086110527836, 8336.083030866599, 8149.307308878811, 8282.254597086292, 8427.873581896276, 8433.831323373499, 8439.451435656663, 4380.846670366522, 4465.464144302705, 4478.081993191388, 4550.726956382628, 4577.793999270041, 1751.1244377811095, 1788.910554472271, 1790.873730776526, 1832.395309547846, 1902.4197619726117, 823.4524041512447, 841.2638940926689, 922.8569571716395, 930.418488444722, 965.290511474066, 16905.0, 18879.90560047191, 20372.879491627806, 20477.590448190953, 20781.58436831255, 894.7941973346036, 902.444996327254, 918.237862574062, 928.4986107501874, 981.9705408837747, 10194.337368071052, 10771.299865508718, 10783.029527342576, 10794.460276986276, 10819.0, 600167.0, 602568.0, 609483.0, 610673.0, 614567.0, 120143.0, 126231.47537049207, 130848.0, 134146.65853341343, 143753.0, 960.0831206198064, 997.8952210017843, 1009.9394036357842, 1010.9494525273707, 1013.4881884648205, 16180.676386471969, 18178.0, 18341.0, 18519.0, 18571.16429760691, 35981.46027809533, 38174.61825381822, 54002.64993375197, 55306.72346638241, 56305.71847140738, 75345.0, 76453.61773191098, 77549.44901101834, 82351.0, 83109.75185931532, 6957.547759395623, 7986.800329991796, 7995.560244186626, 8024.679012839421, 8165.469244499089, 10465.68602941913, 11075.889241107487, 12774.23354598727, 12897.48410063587, 13096.869031309565, 10834.187435942493, 10929.83605245906, 10953.671389858317, 11264.7747045057, 11381.943090284496, 8934.195922367018, 10808.297460665031, 10896.891031089588, 11190.440477975906, 11475.827862582235, 11052.889471105185, 11237.775244495228, 11414.372209528556, 11575.652730418102, 11756.470958806703, 7161.964190179224, 7398.445116616168, 7459.627018649155, 7532.623368831427, 7568.810779730551, 10540.314879533113, 11058.557657693602, 11127.165462590176, 11276.985071343619, 11353.772924541629], "value": 9366.967676021444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.704245629646, 1474.9557513274601, 1618.182817677073, 1802.7295905614164, 1824.498262977681, 7421.480496365243, 7710.884336734954, 7949.403794715397, 8341.499510029402, 8381.287590554803, 7599.7340093096755, 7669.041369828771, 7677.3858091352695, 7679.92320076799, 7680.692772289116, 1847.9353222637176, 2293.0, 2293.4266433391654, 2294.403455101677, 2296.678465014891, 2015.2140665140607, 2021.8786872787607, 2255.883337747817, 2278.2823410625683, 2345.6833327500826, 2031.9288824891132, 2109.89450527473, 2294.231432470116, 2322.013144413626, 2322.9767702323015, 2227.6435770276757, 2305.2853615379117, 2305.6541518772065, 2306.3657494189065, 2313.618252988255, 2291.5531471362924, 2301.390131615123, 2310.27226423676, 2312.4681323295736, 2336.5677349690363, 8126.63430145639, 8170.223828736261, 8234.670613175465, 8250.5462199579, 8260.215279548433, 4957.182064959277, 5109.744512774347, 5130.230465430215, 5130.640855140142, 5137.306463627438, 3089.7991630544166, 3212.1969507623094, 3282.3599398117376, 3365.1250674824723, 3436.9484457733156, 996.1632228927663, 1035.2287545778368, 1057.0592172966067, 1063.7606538528769, 1706.6160113974381, 10527.894721052768, 10648.361098334124, 10653.25427220095, 10655.680329590046, 10668.293073108343, 4157.397177409259, 4350.32569951658, 4359.04101097758, 4364.978175109104, 4423.845165419195, 8491.980962284566, 8495.150484951455, 8496.107908669625, 8507.53208573522, 8509.149085091442, 4993.550580447777, 4993.950060499421, 4996.600271978233, 4998.475160108209, 5005.89988200234, 3989.3816458448987, 4201.537830838605, 4205.558416366299, 4620.97689511557, 4652.418447694038, 3576.678098971105, 3579.982100089483, 3645.5989841117457, 3686.7972261525874, 3697.7226707997006, 1119.356370087203, 1123.7078359626391, 1123.713453069462, 1138.3511398502774, 1139.3847322445786, 828.4780588229377, 853.7566793463833, 856.8586183279814, 904.7692838326317, 910.8406028944908, 5456.454354564591, 5469.863253418696, 5492.038893193675, 5538.086215774352, 5612.747426365823, 413.1324219139842, 414.21092818180813, 422.8837069805828, 428.1073960791722, 430.3265389665164, 3498.090496470885, 3591.4612808078996, 3822.6177382261553, 3826.1199924017633, 3831.578526362098, 9549.140577348073, 9795.510224488748, 9997.150242229493, 10102.797944041225, 10159.187265018782, 9790.853137202805, 9812.558434870449, 10104.69685909424, 10115.988401159826, 10126.987301269814, 7414.740484083031, 7766.068071831303, 7840.13758486566, 7843.176466471053, 7848.646810893523, 5307.6284660073425, 5396.109641909041, 5429.348478182485, 5475.4524547544925, 5614.242077319673, 9258.39820411698, 9316.6273349068, 9391.718248452557, 9392.530373481435, 9488.762780930532, 9469.14777670013, 9475.763105922408, 9484.52577371115, 9552.808943821223, 9586.712398628053, 6172.475339596184, 6392.488600911823, 6397.200349956255, 6408.743650253938, 6454.870902582016, 7536.284053014955, 7826.373890088893, 7842.686292548234, 7918.722844700408, 7922.920770792449, 6946.340097690711, 7084.858302834017, 7101.644917754195, 7145.9285407147345, 7229.710811567478, 6948.965255173692, 6965.825854353681, 6988.650567471708, 7002.824929376602, 7067.116610423697, 6400.039994000751, 6426.582272152295, 6468.902966455601, 6482.222133344029, 6537.7058032389605, 4715.457722361973, 4760.119377915097, 4763.785629646605, 4816.590589799906, 4843.200871856246, 4374.884404476838, 4676.158291507561, 4690.671652984395, 4835.226363781778, 4924.089043526959], "value": 4217.243263199835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3912132325425, 296.44712610980525, 303.2842491719542, 306.6396983544336, 307.9091667957952, 4300.677449191311, 4769.618430525558, 5066.594672426207, 5084.949150508493, 5122.590192784578, 4125.64838042316, 4895.951040489594, 4972.0, 5001.499850014997, 5029.245613158028, 3278.655741147181, 3913.256481268564, 3922.941155882657, 3925.8233379497847, 3927.7643341399466, 3173.7783551513917, 3284.4088064148445, 3605.3415428902604, 3930.4104384342436, 3952.565217826037, 3418.3676019936393, 3780.81095945203, 3874.9806250968704, 3907.960920390788, 3920.5491368492562, 3431.9485207721905, 3891.4746509221177, 3905.511811023622, 3907.6678482328753, 3933.3509970854775, 2995.9850200749074, 3003.279212988889, 3197.7921435106646, 3200.3599280144063, 3473.930521389583, 3825.4261860720835, 4023.818928148211, 4522.66080043998, 4919.975400123012, 5023.547880690755, 3702.4446333049987, 4423.469183697976, 4433.312836510346, 4441.467023957113, 4497.7526236057, 5235.65968212065, 5283.445238249967, 5287.0, 5294.126469132589, 5317.9468205317835, 3335.449650807614, 3579.13884779913, 3609.6390360963956, 3765.437343502443, 3772.0946972726624, 4598.632109431237, 4777.331173635694, 5418.349798024223, 5496.80761173361, 5515.972420137874, 3477.791332520057, 3788.3559794834946, 3797.3354662933875, 3807.9809600951817, 3817.541894972621, 3313.7846040007803, 3581.6239294874185, 3604.981975090108, 3753.7424962637274, 3917.3536366499206, 4184.602462766033, 4311.611954924009, 4320.762358070274, 4342.305231162999, 4402.8679139625865, 5045.798168073309, 5282.234076059027, 5288.735563221823, 5339.679619222781, 5368.489993450616, 1104.5857803323754, 1129.9774004519863, 1159.930404175752, 1192.9582464613698, 1209.9274043557414, 752.5935994562984, 811.5999900172155, 834.4993004197554, 837.2590257622048, 845.8815765792793, 3986.621270979252, 4655.440427456772, 4673.766311684402, 4719.882002949954, 4814.325994360793, 4583.610393116555, 5050.444451110375, 5085.084684756779, 5112.437631860493, 5140.588752899759, 4039.7576145431367, 4339.609435150852, 4633.308842272613, 4664.813407463664, 4684.250519916797, 2170.4139882231707, 2278.8404811663027, 2309.1712652001165, 2419.3346829621996, 2601.80486463516, 4262.42457268271, 5147.922781158286, 5179.493840358837, 5199.128313807027, 5224.60815438836, 4463.620592249695, 4754.97767979886, 4924.778384972685, 5219.817306394334, 5232.476752324813, 4757.9286310705365, 5163.561097306771, 5172.818951336685, 5208.348956380452, 5226.3205783248695, 4062.0, 4698.718076915396, 4900.774806298426, 5050.772715227897, 5051.191809310492, 4247.57706168437, 4421.845235416745, 4486.407325399567, 5029.76770691183, 5032.9550621874705, 4274.658027357867, 4964.67729597575, 5181.637285390138, 5195.506426889439, 5199.620246839585, 3343.5820522434697, 3347.7489188310487, 3460.7923524588605, 3466.6533346665133, 3476.209751977683, 4229.513605935357, 4743.288506723881, 4974.403071631427, 5056.317397151338, 5078.5175408336145, 3775.6979441645153, 4470.87039072657, 4757.075412784536, 4853.883606770392, 5298.0, 5263.34208223972, 5273.6044796639635, 5278.8944221116135, 5306.815910453697, 5321.255024296524, 4832.516748325139, 4917.311576379381, 5205.895882082413, 5230.241614965885, 5238.585581892944, 4810.807567697253, 5110.9233361500355, 5215.76529056201, 5272.894542109213, 5309.017831701148, 5147.382314122179, 5165.66423182492, 5170.819021334235, 5214.270002199619, 5221.738913054256], "value": 3744.9802860933737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.7180282971249, 684.3191024930195, 747.8653842308386, 754.3851760814939, 758.325090669304, 13827.446902123915, 14190.858091419082, 14648.926755366236, 14836.480723174678, 14921.92539037306, 13094.934525327384, 13134.277614731185, 13220.867791322084, 13323.400446979886, 13744.450221991134, 799.932005779508, 828.1428721273484, 828.8922440082781, 829.0962850492962, 830.0454477351064, 650.2749434380655, 671.4326394196896, 797.5932274539997, 822.1572887790034, 825.5665775467897, 851.3912552524944, 868.053821334745, 868.1752335281476, 870.4559650218613, 872.3370238618663, 862.8964524257098, 863.3222920007827, 864.9178328058824, 865.0786911938765, 865.8657908024267, 497.55443553392973, 511.33015749368445, 515.7756375976462, 516.2540129512855, 643.996780016097, 13646.181229126285, 14221.715565688732, 14233.715325693533, 14503.492377766834, 14507.057041292283, 13015.609531714064, 13236.86763132366, 13278.601641950758, 13302.135361201492, 13366.198028118246, 9818.919918808926, 11144.665660030212, 11202.991730744272, 11270.32378057311, 11383.943080284545, 3492.388831954398, 3573.1424458130123, 3611.416026344655, 3680.7423480356388, 3745.1198968242443, 74896.6255168726, 75796.0, 81647.5917620414, 88051.11948880494, 93538.0, 10457.68626941193, 10585.41780202081, 10697.946510267398, 11013.669589912315, 11018.669439916815, 21061.684074738892, 21324.680129798064, 21556.245531406326, 21720.78279217219, 21830.126794928343, 7660.0, 7779.821649938026, 7802.375809935305, 7802.960985195038, 7961.641726122339, 4167.458230430025, 4215.515215750167, 4254.638355739735, 4325.243082460557, 4350.543192964757, 3320.369129865318, 3424.6232914379398, 3427.4002049641213, 3439.466882633196, 3442.0018194723752, 612.5344737999153, 626.8213559135625, 628.7987843890003, 633.341325021972, 633.4647223096568, 662.3773652766384, 663.5620490476263, 675.4832553096885, 677.096076737551, 698.5284932670427, 8316.542590157478, 8408.915910840935, 8732.525210146934, 8923.866142007875, 9107.0, 601.954853385989, 653.633964979613, 657.6185812228896, 663.2935923241732, 679.5379142183363, 4279.122779830127, 4380.759058251763, 4440.134173836071, 4504.414426124583, 4582.908341833145, 86583.26833463296, 107098.46450767697, 109821.90178098116, 114616.0, 119927.0, 104250.0, 105408.0, 107102.0, 108865.0, 108926.45536772265, 87432.93984710505, 91776.0, 93966.0, 96321.0, 97225.5138724316, 8621.396502244783, 8733.432326898732, 8822.911770882209, 8840.557972101498, 8898.42160259581, 49036.75481622569, 54156.458435415145, 55601.721991389786, 57746.133807993756, 58235.544111397554, 52322.95354092973, 67785.66107169433, 68245.31754682389, 68914.0, 69583.65208173927, 12100.576479823163, 12202.0, 12211.961983231526, 12290.692732681755, 12402.25586464815, 11239.719007024889, 11648.767024659628, 12009.459574319002, 12316.753664926471, 12523.93738031304, 11345.432728363714, 12155.635330940086, 13058.934705326412, 13304.334783260605, 13406.128601640861, 12043.638690839289, 12138.514459421878, 12365.319907405179, 12603.243805371707, 12799.872001279868, 12500.374981251083, 12555.623331300076, 12730.23618582888, 12887.613371598867, 13524.797128043285, 11060.0, 11321.547138114383, 11427.257228280136, 11467.31196128235, 11640.253961905782, 9576.248045080942, 10285.74285642865, 10456.359759219207, 10484.528196231035, 10542.841857371992], "value": 7190.511150759696, "unit": "score"}], "name": "dromaeo_css", "value": 6965.2208588987705, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 114.0, 114.0, 116.0, 115.0, 115.0, 116.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 115.0, 148.0, 115.0, 117.0, 113.0, 114.0, 144.0, 113.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 188.0, 184.0, 183.0, 180.0, 184.0, 185.0, 185.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 56.0, 60.0, 88.0, 63.0, 61.0, 58.0, 61.0, 104.0, 81.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 95.0, 71.0, 68.0, 69.0, 88.0, 69.0, 69.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 129.0, 104.0, 87.0, 89.0, 86.0, 92.0, 88.0, 90.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 107.0, 149.0, 93.0, 95.0, 95.0, 94.0, 94.0, 94.0, 113.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 81.0, 80.0, 84.0, 91.0, 140.0, 85.0, 84.0, 82.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 56.0, 57.0, 57.0, 57.0, 58.0, 58.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 76.0, 73.0, 74.0, 71.0, 71.0, 72.0, 71.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 86.0, 84.0, 85.0, 92.0, 86.0, 101.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 146.0, 159.0, 158.0, 145.0, 200.0, 148.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 170.0, 170.0, 164.0, 224.0, 173.0, 167.0, 167.0, 166.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 69.0, 79.0, 87.0, 85.0, 88.0, 71.0], "value": 74.8, "unit": "score"}], "name": "kraken", "value": 1478.1000000000001, "alertThreshold": 2.0}]} 20:28:39 INFO - Return code: 0 20:28:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:28:39 INFO - # TBPL SUCCESS # 20:28:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:28:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:28:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:28:39 INFO - 2016-11-28 20:28:39.592 screenresolution[1649:9898] starting screenresolution argv=screenresolution get 20:28:39 INFO - 2016-11-28 20:28:39.595 screenresolution[1649:9898] Display 0: 1600x1200x32@60 20:28:39 INFO - 2016-11-28 20:28:39.601 screenresolution[1650:9901] starting screenresolution argv=screenresolution list 20:28:39 INFO - Available Modes on Display 0 20:28:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:28:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:28:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:28:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:28:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:28:39 INFO - Intel Iris: 20:28:39 INFO - Chipset Model: Intel Iris 20:28:39 INFO - Type: GPU 20:28:39 INFO - Bus: Built-In 20:28:39 INFO - VRAM (Dynamic, Max): 1536 MB 20:28:39 INFO - Vendor: Intel (0x8086) 20:28:39 INFO - Device ID: 0x0a2e 20:28:39 INFO - Revision ID: 0x0009 20:28:39 INFO - Displays: 20:28:39 INFO - Display: 20:28:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:28:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:28:39 INFO - Display Serial Number: 1600x1200 60 20:28:39 INFO - Main Display: Yes 20:28:39 INFO - Mirror: Off 20:28:39 INFO - Online: Yes 20:28:39 INFO - Rotation: Supported 20:28:39 INFO - Return code: 0 20:28:39 INFO - Running post-action listener: _resource_record_post_action 20:28:39 INFO - [mozharness: 2016-11-29 04:28:39.852657Z] Finished run-tests step (success) 20:28:39 INFO - Running post-run listener: _resource_record_post_run 20:28:39 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37643264; Write bytes: 655311872; Read time: 241; Write time: 1921 20:28:39 INFO - TinderboxPrint: CPU usage
53.6% 20:28:39 INFO - TinderboxPrint: I/O read bytes / time
37,643,264 / 241 20:28:39 INFO - TinderboxPrint: I/O write bytes / time
655,311,872 / 1,921 20:28:39 INFO - TinderboxPrint: CPU idle
2,251.1 (46.4%) 20:28:39 INFO - TinderboxPrint: CPU system
974.6 (20.1%) 20:28:39 INFO - TinderboxPrint: CPU user
1,624.0 (33.5%) 20:28:39 INFO - TinderboxPrint: Swap in / out
385,683,456 / 0 20:28:39 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 343505408; Write bytes: 336746496; Read time: 26831; Write time: 729 20:28:39 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35853312; Write bytes: 304163840; Read time: 226; Write time: 1145 20:28:40 INFO - Running post-run listener: _upload_blobber_files 20:28:40 INFO - Blob upload gear active. 20:28:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:28:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:28:40 INFO - (blobuploader) - INFO - Open directory for files ... 20:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:41 INFO - (blobuploader) - INFO - Done attempting. 20:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:43 INFO - (blobuploader) - INFO - Done attempting. 20:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:28:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:45 INFO - (blobuploader) - INFO - Done attempting. 20:28:45 INFO - (blobuploader) - INFO - Iteration through files over. 20:28:45 INFO - Return code: 0 20:28:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:28:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:28:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1ed6581bc9e302803f2bd704c269b6db869aa633dca6baeef2757339b20c19840263fcba8143f8c886282ba793842b6babc267bfcbd45e8bb8029c177094706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c8bb7a2ca8dd2eab43a57eee54b2f25952673bca9e58c670a7ac97f26176a3b003f873c1839a2be41df7157ef1be5a68fbe57ba6e5798535f3cdb56934877cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50e3bb58480e4631a721f31d286f446ec0d8159a8f798f9d682596c0558c07dc8ad29b9e783c3195c0f2efc714b4f10874247478f0700e27f94db32add25f6a0"} 20:28:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:28:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:28:45 INFO - Contents: 20:28:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1ed6581bc9e302803f2bd704c269b6db869aa633dca6baeef2757339b20c19840263fcba8143f8c886282ba793842b6babc267bfcbd45e8bb8029c177094706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c8bb7a2ca8dd2eab43a57eee54b2f25952673bca9e58c670a7ac97f26176a3b003f873c1839a2be41df7157ef1be5a68fbe57ba6e5798535f3cdb56934877cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50e3bb58480e4631a721f31d286f446ec0d8159a8f798f9d682596c0558c07dc8ad29b9e783c3195c0f2efc714b4f10874247478f0700e27f94db32add25f6a0"} 20:28:45 INFO - Running post-run listener: copy_logs_to_upload_dir 20:28:45 INFO - Copying logs to upload dir... 20:28:45 INFO - mkdir: /builds/slave/test/build/upload/logs 20:28:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.125525 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-28 20:28:45.575642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:45.579436) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1ed6581bc9e302803f2bd704c269b6db869aa633dca6baeef2757339b20c19840263fcba8143f8c886282ba793842b6babc267bfcbd45e8bb8029c177094706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c8bb7a2ca8dd2eab43a57eee54b2f25952673bca9e58c670a7ac97f26176a3b003f873c1839a2be41df7157ef1be5a68fbe57ba6e5798535f3cdb56934877cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50e3bb58480e4631a721f31d286f446ec0d8159a8f798f9d682596c0558c07dc8ad29b9e783c3195c0f2efc714b4f10874247478f0700e27f94db32add25f6a0"} build_url:https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010861 build_url: 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1ed6581bc9e302803f2bd704c269b6db869aa633dca6baeef2757339b20c19840263fcba8143f8c886282ba793842b6babc267bfcbd45e8bb8029c177094706", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c8bb7a2ca8dd2eab43a57eee54b2f25952673bca9e58c670a7ac97f26176a3b003f873c1839a2be41df7157ef1be5a68fbe57ba6e5798535f3cdb56934877cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50e3bb58480e4631a721f31d286f446ec0d8159a8f798f9d682596c0558c07dc8ad29b9e783c3195c0f2efc714b4f10874247478f0700e27f94db32add25f6a0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:45.613029) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:45.613355) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y66at5UXjg/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.MoLUkJHh01/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005260 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:45.661891) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 20:28:45.662218) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 20:28:45.662544) ========= ========= Total master_lag: 0.19 =========