builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0149 starttime: 1477485443.82 results: success (0) buildid: 20161025185822 builduid: 597bb44274da41c9a5d57fe3793b73d1 revision: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.817957) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.818324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.818585) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.921541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:23.921787) ========= 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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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-10-26 05:37:23-- 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% 155M=0s 2016-10-26 05:37:24 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.101401) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.101698) ========= 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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.019183 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:24.150396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 05:37:24.150692) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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-10-26 05:37:24,223 truncating revision to first 12 chars 2016-10-26 05:37:24,223 Setting DEBUG logging. 2016-10-26 05:37:24,223 attempt 1/10 2016-10-26 05:37:24,223 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b1b18f25c0ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 05:37:25,292 unpacking tar archive at: fx-team-b1b18f25c0ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 05:37:25.502414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.502717) ========= script_repo_revision: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.503129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.503394) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 05:37:25.521417) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-26 05:37:25.521702) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:37:25 INFO - MultiFileLogger online at 20161026 05:37:25 in /builds/slave/test 05:37:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:37:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:25 INFO - {'append_to_log': False, 05:37:25 INFO - 'base_work_dir': '/builds/slave/test', 05:37:25 INFO - 'blob_upload_branch': 'Fx-Team', 05:37:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:25 INFO - 'branch': 'Fx-Team', 05:37:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:25 INFO - 'config_files': ('talos/mac_config.py',), 05:37:25 INFO - 'default_actions': ('clobber', 05:37:25 INFO - 'read-buildbot-config', 05:37:25 INFO - 'download-and-extract', 05:37:25 INFO - 'populate-webroot', 05:37:25 INFO - 'create-virtualenv', 05:37:25 INFO - 'install', 05:37:25 INFO - 'run-tests'), 05:37:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:25 INFO - 'download_minidump_stackwalk': True, 05:37:25 INFO - 'download_symbols': 'ondemand', 05:37:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:25 INFO - 'installer_path': 'installer.exe', 05:37:25 INFO - 'log_level': 'info', 05:37:25 INFO - 'log_name': 'talos', 05:37:25 INFO - 'log_to_console': True, 05:37:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:37:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:37:25 INFO - 'opt_config_files': (), 05:37:25 INFO - 'pip_index': False, 05:37:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:25 INFO - 'cmd': ('bash', 05:37:25 INFO - '-c', 05:37:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:25 INFO - 'enabled': True, 05:37:25 INFO - 'halt_on_failure': False, 05:37:25 INFO - 'name': 'check_screen_resolution'},), 05:37:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:25 INFO - 'cmd': ('bash', 05:37:25 INFO - '-c', 05:37:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:25 INFO - 'enabled': True, 05:37:25 INFO - 'halt_on_failure': False, 05:37:25 INFO - 'name': 'check_screen_resolution'},), 05:37:25 INFO - 'run_cmd_checks_enabled': True, 05:37:25 INFO - 'sps_profile': False, 05:37:25 INFO - 'sps_profile_interval': 0, 05:37:25 INFO - 'suite': 'dromaeojs', 05:37:25 INFO - 'system_bits': '32', 05:37:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:25 INFO - 'title': 't-yosemite-r7-0149', 05:37:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:25 INFO - 'use_talos_json': True, 05:37:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:25 INFO - 'work_dir': 'build'} 05:37:25 INFO - [mozharness: 2016-10-26 12:37:25.722401Z] Running clobber step. 05:37:25 INFO - Running pre-action listener: _resource_record_pre_action 05:37:25 INFO - Running main action method: clobber 05:37:25 INFO - rmtree: /builds/slave/test/build 05:37:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:27 INFO - Running post-action listener: _resource_record_post_action 05:37:27 INFO - [mozharness: 2016-10-26 12:37:27.392073Z] Finished clobber step (success) 05:37:27 INFO - [mozharness: 2016-10-26 12:37:27.392263Z] Running read-buildbot-config step. 05:37:27 INFO - Running pre-action listener: _resource_record_pre_action 05:37:27 INFO - Running main action method: read_buildbot_config 05:37:27 INFO - Using buildbot properties: 05:37:27 INFO - { 05:37:27 INFO - "project": "", 05:37:27 INFO - "product": "firefox", 05:37:27 INFO - "script_repo_revision": "production", 05:37:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:37:27 INFO - "repository": "", 05:37:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:37:27 INFO - "buildid": "20161025185822", 05:37:27 INFO - "pgo_build": "False", 05:37:27 INFO - "basedir": "/builds/slave/test", 05:37:27 INFO - "buildnumber": 28, 05:37:27 INFO - "slavename": "t-yosemite-r7-0149", 05:37:27 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:37:27 INFO - "platform": "yosemite_r7", 05:37:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:37:27 INFO - "branch": "fx-team", 05:37:27 INFO - "repo_path": "integration/fx-team", 05:37:27 INFO - "revision": "b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1", 05:37:27 INFO - "stage_platform": "macosx64", 05:37:27 INFO - "builduid": "597bb44274da41c9a5d57fe3793b73d1", 05:37:27 INFO - "slavebuilddir": "test" 05:37:27 INFO - } 05:37:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:37:27 INFO - Running post-action listener: _resource_record_post_action 05:37:27 INFO - [mozharness: 2016-10-26 12:37:27.395579Z] Finished read-buildbot-config step (success) 05:37:27 INFO - [mozharness: 2016-10-26 12:37:27.395697Z] Running download-and-extract step. 05:37:27 INFO - Running pre-action listener: _resource_record_pre_action 05:37:27 INFO - Running main action method: download_and_extract 05:37:27 INFO - mkdir: /builds/slave/test/build/tests 05:37:27 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')]} 05:37:27 INFO - trying https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:37:28 INFO - Downloaded 1199 bytes. 05:37:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:28 INFO - Using the following test package requirements: 05:37:28 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:28 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:37:28 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:37:28 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'jsshell-mac.zip'], 05:37:28 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:37:28 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:28 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:37:28 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:37:28 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:37:28 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:28 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:37:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:37:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:29 INFO - Http code: 200 05:37:29 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 05:37:29 INFO - x-amz-cf-id: MDEAKFnjzIqrRQGyw1KDy7KCVelPA-ky5l8D8bL-hg_aXrcrwtdleQ== 05:37:29 INFO - x-amz-version-id: m0xOcBGqqv0MOgpPsX9fI9CpxiTvSkOZ 05:37:29 INFO - Expected file size: 16423362 05:37:29 INFO - Obtained file size: 16423362 05:37:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:37:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:36 INFO - Http code: 200 05:37:36 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 05:37:36 INFO - x-amz-cf-id: BcmY6MtCHVLz7NCmFFwFgc5ZzXyxV89s8hpMTaGCTbxrQbObVf-ycQ== 05:37:36 INFO - x-amz-version-id: m0xOcBGqqv0MOgpPsX9fI9CpxiTvSkOZ 05:37:37 INFO - Expected file size: 16423362 05:37:37 INFO - Obtained file size: 16423362 05:37:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:37:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:37:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:37:44 INFO - Http code: 200 05:37:44 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 05:37:44 INFO - x-amz-cf-id: rnLMvE2gCuPJGTO_Su3Uq2QJ6UAAik56I9IT7n6FZJoxMa6IHtAYWA== 05:37:44 INFO - x-amz-version-id: BBSq4.5gYhV_V2QsJwf74wEF2YXjLqJo 05:37:44 INFO - Expected file size: 14409028 05:37:44 INFO - Obtained file size: 14409028 05:37:44 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')]} 05:37:44 INFO - trying https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:37:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:37:47 INFO - Downloaded 111194726 bytes. 05:37:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:47 INFO - mkdir: /builds/slave/test/properties 05:37:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:47 INFO - Contents: 05:37:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - Running post-action listener: set_extra_try_arguments 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.213977Z] Finished download-and-extract step (success) 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.214135Z] Running populate-webroot step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: populate_webroot 05:37:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:48 INFO - '--tppagecycles', 05:37:48 INFO - '7'], 05:37:48 INFO - 'tests': ['tsvgm']}}, 05:37:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s', 05:37:48 INFO - '--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}, 05:37:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}}, 05:37:48 INFO - 'talos.zip': {'path': '', 05:37:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.233118Z] Finished populate-webroot step (success) 05:37:48 INFO - [mozharness: 2016-10-26 12:37:48.233263Z] Running create-virtualenv step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: create_virtualenv 05:37:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:48 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 05:37:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:48 INFO - Using real prefix '/tools/python27' 05:37:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:51 INFO - Installing pip.................done. 05:37:51 INFO - Return code: 0 05:37:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:37:52 INFO - Reading from file tmpfile_stdout 05:37:52 INFO - Output received: 05:37:52 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) 05:37:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:52 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')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:37:52 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 05:37:52 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 05:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:52 INFO - 'HOME': '/Users/cltbld', 05:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PAGER': '/bin/cat', 05:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:37:52 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - 'XPC_FLAGS': '0x0', 05:37:52 INFO - 'XPC_SERVICE_NAME': '0', 05:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:52 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 05:37:52 INFO - Cleaning up... 05:37:52 INFO - Return code: 0 05:37:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:52 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')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:37:52 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 05:37:52 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 05:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:52 INFO - 'HOME': '/Users/cltbld', 05:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PAGER': '/bin/cat', 05:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:37:52 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - 'XPC_FLAGS': '0x0', 05:37:52 INFO - 'XPC_SERVICE_NAME': '0', 05:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:52 INFO - Downloading/unpacking psutil>=3.1.1 05:37:52 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 05:37:52 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 05:37:52 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 05:37:52 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 05:37:52 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 05:37:52 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 05:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:56 INFO - Installing collected packages: psutil 05:37:56 INFO - Running setup.py install for psutil 05:37:56 INFO - building 'psutil._psutil_osx' extension 05:37:56 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 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 INFO - building 'psutil._psutil_posix' extension 05:37:57 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 05:37:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:37:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:37:57 INFO - ^ 05:37:57 INFO - 1 warning generated. 05:37:57 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 05:37:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:57 INFO - Successfully installed psutil 05:37:57 INFO - Cleaning up... 05:37:57 INFO - Return code: 0 05:37:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:57 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')]} 05:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:57 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')]} 05:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:57 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:37:57 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 05:37:57 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 05:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:57 INFO - 'HOME': '/Users/cltbld', 05:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:57 INFO - 'LOGNAME': 'cltbld', 05:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:57 INFO - 'MOZ_NO_REMOTE': '1', 05:37:57 INFO - 'NO_EM_RESTART': '1', 05:37:57 INFO - 'PAGER': '/bin/cat', 05:37:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:57 INFO - 'PWD': '/builds/slave/test', 05:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:57 INFO - 'SHELL': '/bin/bash', 05:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:37:57 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:57 INFO - 'USER': 'cltbld', 05:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:57 INFO - 'XPC_FLAGS': '0x0', 05:37:57 INFO - 'XPC_SERVICE_NAME': '0', 05:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 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 05:37:57 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 05:38:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:38:00 INFO - Installing collected packages: mozsystemmonitor 05:38:00 INFO - Running setup.py install for mozsystemmonitor 05:38:00 INFO - Successfully installed mozsystemmonitor 05:38:00 INFO - Cleaning up... 05:38:00 INFO - Return code: 0 05:38:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:38:00 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')]} 05:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:00 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')]} 05:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:00 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:00 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 05:38:00 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 05:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:00 INFO - 'HOME': '/Users/cltbld', 05:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:00 INFO - 'LOGNAME': 'cltbld', 05:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:00 INFO - 'MOZ_NO_REMOTE': '1', 05:38:00 INFO - 'NO_EM_RESTART': '1', 05:38:00 INFO - 'PAGER': '/bin/cat', 05:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:00 INFO - 'PWD': '/builds/slave/test', 05:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:00 INFO - 'SHELL': '/bin/bash', 05:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:00 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:00 INFO - 'USER': 'cltbld', 05:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:00 INFO - 'XPC_FLAGS': '0x0', 05:38:00 INFO - 'XPC_SERVICE_NAME': '0', 05:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:38:00 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 05:38:00 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 05:38:00 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 05:38:00 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 05:38:00 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 05:38:00 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 05:38:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:38:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:38: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 05:38: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 05:38: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 05:38: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 05:38: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 05:38: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 05:38:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:38:03 INFO - warning: no files found matching '*.txt' 05:38:03 INFO - no previously-included directories found matching 'build' 05:38:03 INFO - no previously-included directories found matching 'dist' 05:38:03 INFO - no previously-included directories found matching '.git*' 05:38:03 INFO - Installing collected packages: jsonschema, functools32 05:38:03 INFO - Running setup.py install for functools32 05:38:04 INFO - warning: no files found matching '*.txt' 05:38:04 INFO - no previously-included directories found matching 'build' 05:38:04 INFO - no previously-included directories found matching 'dist' 05:38:04 INFO - no previously-included directories found matching '.git*' 05:38:04 INFO - Successfully installed jsonschema functools32 05:38:04 INFO - Cleaning up... 05:38:04 INFO - Return code: 0 05:38:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:38:04 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')]} 05:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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')]} 05:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:04 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 05:38:04 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 05:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:04 INFO - 'HOME': '/Users/cltbld', 05:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:04 INFO - 'LOGNAME': 'cltbld', 05:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:04 INFO - 'MOZ_NO_REMOTE': '1', 05:38:04 INFO - 'NO_EM_RESTART': '1', 05:38:04 INFO - 'PAGER': '/bin/cat', 05:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:04 INFO - 'PWD': '/builds/slave/test', 05:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:04 INFO - 'SHELL': '/bin/bash', 05:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:04 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:04 INFO - 'USER': 'cltbld', 05:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:04 INFO - 'XPC_FLAGS': '0x0', 05:38:04 INFO - 'XPC_SERVICE_NAME': '0', 05:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:38:04 INFO - Cleaning up... 05:38:04 INFO - Return code: 0 05:38:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:38:04 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')]} 05:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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')]} 05:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:04 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 05:38:04 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 05:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:04 INFO - 'HOME': '/Users/cltbld', 05:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:04 INFO - 'LOGNAME': 'cltbld', 05:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:04 INFO - 'MOZ_NO_REMOTE': '1', 05:38:04 INFO - 'NO_EM_RESTART': '1', 05:38:04 INFO - 'PAGER': '/bin/cat', 05:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:04 INFO - 'PWD': '/builds/slave/test', 05:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:04 INFO - 'SHELL': '/bin/bash', 05:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:04 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:04 INFO - 'USER': 'cltbld', 05:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:04 INFO - 'XPC_FLAGS': '0x0', 05:38:04 INFO - 'XPC_SERVICE_NAME': '0', 05:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:07 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 05:38:07 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 05:38:07 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 05:38:07 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 05:38:07 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 05:38:07 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 05:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:38:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 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 05:38:08 INFO - Downloading docopt-0.6.1.tar.gz 05:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:08 INFO - Running setup.py install for blobuploader 05:38:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:08 INFO - Running setup.py install for requests 05:38:09 INFO - Running setup.py install for docopt 05:38:09 INFO - Successfully installed blobuploader requests docopt 05:38:09 INFO - Cleaning up... 05:38:09 INFO - Return code: 0 05:38:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:09 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')]} 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 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')]} 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:09 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 05:38:09 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 05:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:09 INFO - 'HOME': '/Users/cltbld', 05:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:09 INFO - 'LOGNAME': 'cltbld', 05:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:09 INFO - 'MOZ_NO_REMOTE': '1', 05:38:09 INFO - 'NO_EM_RESTART': '1', 05:38:09 INFO - 'PAGER': '/bin/cat', 05:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:09 INFO - 'PWD': '/builds/slave/test', 05:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:09 INFO - 'SHELL': '/bin/bash', 05:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:09 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:09 INFO - 'USER': 'cltbld', 05:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:09 INFO - 'XPC_FLAGS': '0x0', 05:38:09 INFO - 'XPC_SERVICE_NAME': '0', 05:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:09 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-1OY_6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:09 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-l7IamI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:09 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-zj6i50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:09 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-S7Rkkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-9DCrdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-DJ_cwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-RVpI_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-zkC2RD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-A3cuvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-wzOX5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-eFYmBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-UAgYT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-o02jZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:10 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-F2AvGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:11 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-V1Bi_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:11 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-SJThSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:11 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-_YSFn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:11 INFO - Running setup.py install for manifestparser 05:38:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:11 INFO - Running setup.py install for mozcrash 05:38:11 INFO - Running setup.py install for mozdebug 05:38:11 INFO - Running setup.py install for mozdevice 05:38:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:11 INFO - Running setup.py install for mozfile 05:38:12 INFO - Running setup.py install for mozhttpd 05:38:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for mozinfo 05:38:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for mozInstall 05:38:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for mozleak 05:38:12 INFO - Running setup.py install for mozlog 05:38:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:12 INFO - Running setup.py install for moznetwork 05:38:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozprocess 05:38:13 INFO - Running setup.py install for mozprofile 05:38:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozrunner 05:38:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Running setup.py install for mozscreenshot 05:38:13 INFO - Running setup.py install for moztest 05:38:13 INFO - Running setup.py install for mozversion 05:38:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:13 INFO - Cleaning up... 05:38:13 INFO - Return code: 0 05:38:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:13 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')]} 05:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:13 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')]} 05:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:13 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:13 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 05:38:13 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 05:38:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:13 INFO - 'HOME': '/Users/cltbld', 05:38:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:13 INFO - 'LOGNAME': 'cltbld', 05:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:13 INFO - 'MOZ_NO_REMOTE': '1', 05:38:13 INFO - 'NO_EM_RESTART': '1', 05:38:13 INFO - 'PAGER': '/bin/cat', 05:38:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:13 INFO - 'PWD': '/builds/slave/test', 05:38:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:13 INFO - 'SHELL': '/bin/bash', 05:38:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:13 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:13 INFO - 'USER': 'cltbld', 05:38:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:13 INFO - 'XPC_FLAGS': '0x0', 05:38:13 INFO - 'XPC_SERVICE_NAME': '0', 05:38:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-8iNBWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-DaIDQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-ybJil_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-JED6Zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4wMTFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-cndPdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-DlbHwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Ni6h8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-94q_OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-QN6NoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-h5QDuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-vJRQrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-YYGckd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-pabR58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-UcdNEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-X8nhpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:15 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)) 05:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-guIDJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:16 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)) 05:38:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:18 INFO - Downloading blessings-1.6.tar.gz 05:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:18 INFO - Installing collected packages: blessings 05:38:18 INFO - Running setup.py install for blessings 05:38:18 INFO - Successfully installed blessings 05:38:18 INFO - Cleaning up... 05:38:18 INFO - Return code: 0 05:38:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:19 INFO - Reading from file tmpfile_stdout 05:38:19 INFO - Current package versions: 05:38:19 INFO - blessings == 1.6 05:38:19 INFO - blobuploader == 1.2.4 05:38:19 INFO - docopt == 0.6.1 05:38:19 INFO - functools32 == 3.2.3-2 05:38:19 INFO - jsonschema == 2.5.1 05:38:19 INFO - manifestparser == 1.1 05:38:19 INFO - mozInstall == 1.12 05:38:19 INFO - mozcrash == 0.17 05:38:19 INFO - mozdebug == 0.1 05:38:19 INFO - mozdevice == 0.48 05:38:19 INFO - mozfile == 1.2 05:38:19 INFO - mozhttpd == 0.7 05:38:19 INFO - mozinfo == 0.9 05:38:19 INFO - mozleak == 0.1 05:38:19 INFO - mozlog == 3.4 05:38:19 INFO - moznetwork == 0.27 05:38:19 INFO - mozprocess == 0.23 05:38:19 INFO - mozprofile == 0.28 05:38:19 INFO - mozrunner == 6.12 05:38:19 INFO - mozscreenshot == 0.1 05:38:19 INFO - mozsystemmonitor == 0.3 05:38:19 INFO - moztest == 0.7 05:38:19 INFO - mozversion == 1.4 05:38:19 INFO - psutil == 3.1.1 05:38:19 INFO - requests == 1.2.3 05:38:19 INFO - wsgiref == 0.1.2 05:38:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:19 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')]} 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 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')]} 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:19 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 05:38:19 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 05:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:19 INFO - 'HOME': '/Users/cltbld', 05:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:19 INFO - 'LOGNAME': 'cltbld', 05:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:19 INFO - 'MOZ_NO_REMOTE': '1', 05:38:19 INFO - 'NO_EM_RESTART': '1', 05:38:19 INFO - 'PAGER': '/bin/cat', 05:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:19 INFO - 'PWD': '/builds/slave/test', 05:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:19 INFO - 'SHELL': '/bin/bash', 05:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:19 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:19 INFO - 'USER': 'cltbld', 05:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:19 INFO - 'XPC_FLAGS': '0x0', 05:38:19 INFO - 'XPC_SERVICE_NAME': '0', 05:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 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)) 05:38:19 INFO - Cleaning up... 05:38:19 INFO - Return code: 0 05:38:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:19 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')]} 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 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')]} 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 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 0x100c86490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91c9d66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, '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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 05:38:19 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 05:38:19 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 05:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:19 INFO - 'HOME': '/Users/cltbld', 05:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:19 INFO - 'LOGNAME': 'cltbld', 05:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:19 INFO - 'MOZ_NO_REMOTE': '1', 05:38:19 INFO - 'NO_EM_RESTART': '1', 05:38:19 INFO - 'PAGER': '/bin/cat', 05:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:19 INFO - 'PWD': '/builds/slave/test', 05:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:19 INFO - 'SHELL': '/bin/bash', 05:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:19 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:19 INFO - 'USER': 'cltbld', 05:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:19 INFO - 'XPC_FLAGS': '0x0', 05:38:19 INFO - 'XPC_SERVICE_NAME': '0', 05:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:19 INFO - Cleaning up... 05:38:19 INFO - Return code: 0 05:38:19 INFO - Running post-action listener: _resource_record_post_action 05:38:19 INFO - Running post-action listener: _start_resource_monitoring 05:38:19 INFO - Starting resource monitoring. 05:38:19 INFO - [mozharness: 2016-10-26 12:38:19.732959Z] Finished create-virtualenv step (success) 05:38:19 INFO - [mozharness: 2016-10-26 12:38:19.733952Z] Running install step. 05:38:19 INFO - Running pre-action listener: _resource_record_pre_action 05:38:19 INFO - Running main action method: install 05:38:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:19 INFO - Reading from file tmpfile_stdout 05:38:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:20 INFO - Reading from file tmpfile_stdout 05:38:20 INFO - Output received: 05:38:20 INFO - Usage: mozinstall [options] installer 05:38:20 INFO - Options: 05:38:20 INFO - -h, --help show this help message and exit 05:38:20 INFO - -d DEST, --destination=DEST 05:38:20 INFO - Directory to install application into. [default: 05:38:20 INFO - "/builds/slave/test"] 05:38:20 INFO - --app=APP Application being installed. [default: firefox] 05:38:20 INFO - mkdir: /builds/slave/test/build/application 05:38:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:38:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:38:54 INFO - Reading from file tmpfile_stdout 05:38:54 INFO - Output received: 05:38:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:38:54 INFO - Running post-action listener: _resource_record_post_action 05:38:54 INFO - [mozharness: 2016-10-26 12:38:54.602923Z] Finished install step (success) 05:38:54 INFO - [mozharness: 2016-10-26 12:38:54.603037Z] Running run-tests step. 05:38:54 INFO - Running pre-action listener: _resource_record_pre_action 05:38:54 INFO - Running main action method: run_tests 05:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:54 INFO - 2016-10-26 05:38:54.617 screenresolution[2364:28801] starting screenresolution argv=screenresolution get 05:38:54 INFO - 2016-10-26 05:38:54.620 screenresolution[2364:28801] Display 0: 1600x1200x32@60 05:38:54 INFO - 2016-10-26 05:38:54.625 screenresolution[2365:28803] starting screenresolution argv=screenresolution list 05:38:54 INFO - Available Modes on Display 0 05:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:54 INFO - Intel Iris: 05:38:54 INFO - Chipset Model: Intel Iris 05:38:54 INFO - Type: GPU 05:38:54 INFO - Bus: Built-In 05:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:54 INFO - Vendor: Intel (0x8086) 05:38:54 INFO - Device ID: 0x0a2e 05:38:54 INFO - Revision ID: 0x0009 05:38:54 INFO - Displays: 05:38:54 INFO - Display: 05:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:54 INFO - Display Serial Number: 1600x1200 60 05:38:54 INFO - Main Display: Yes 05:38:54 INFO - Mirror: Off 05:38:54 INFO - Online: Yes 05:38:54 INFO - Rotation: Supported 05:38:54 INFO - Return code: 0 05:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:54 INFO - Python 2.7.3 05:38:54 INFO - Return code: 0 05:38:54 INFO - grabbing minidump binary from tooltool 05:38:54 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')]} 05:38:54 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 0x100a9ad30>, '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 0x1008c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a92b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:54 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 05:38:54 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 05:38:54 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 05:38:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:54 INFO - Return code: 0 05:38:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:38:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:38:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 05:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0149 --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 05:38:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGrGhpDZgH/Render', 05:38:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:54 INFO - 'HOME': '/Users/cltbld', 05:38:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:54 INFO - 'LOGNAME': 'cltbld', 05:38:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:54 INFO - 'MOZ_NO_REMOTE': '1', 05:38:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:54 INFO - 'NO_EM_RESTART': '1', 05:38:54 INFO - 'PAGER': '/bin/cat', 05:38:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:54 INFO - 'PWD': '/builds/slave/test', 05:38:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:54 INFO - 'SHELL': '/bin/bash', 05:38:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sHMbq5D9h4/Listeners', 05:38:54 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 05:38:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:54 INFO - 'USER': 'cltbld', 05:38:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:54 INFO - 'XPC_FLAGS': '0x0', 05:38:54 INFO - 'XPC_SERVICE_NAME': '0', 05:38:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 05:38:55 INFO - mozversion application_buildid: 20161025185822 05:38:55 INFO - mozversion application_changeset: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 05:38:55 INFO - mozversion application_display_name: Nightly 05:38:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:55 INFO - mozversion application_name: Firefox 05:38:55 INFO - mozversion application_remotingname: firefox 05:38:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:38:55 INFO - mozversion application_vendor: Mozilla 05:38:55 INFO - mozversion application_version: 52.0a1 05:38:55 INFO - mozversion platform_buildid: 20161025185822 05:38:55 INFO - mozversion platform_changeset: b1b18f25c0ea69d9ee57c4198d577dfcd0129ce1 05:38:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:38:55 INFO - mozversion platform_version: 52.0a1 05:38:55 INFO - using testdate: 1477485535 05:38:55 INFO - actual date: 1477485535 05:38:55 INFO - starting webserver on 'localhost:53076' 05:38:55 INFO - SUITE-START | Running 2 tests 05:38:55 INFO - TEST-START | dromaeo_css 05:38:55 INFO - operating with platform_type : mac_ 05:38:55 INFO - Initialising browser for dromaeo_css test... 05:38:55 INFO - TEST-INFO | started process 2375 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmp12qzNU/profile http://localhost:53076/getInfo.html) 05:38:57 INFO - PROCESS | 2375 | __metrics Screen width/height:1600/1200 05:38:57 INFO - PROCESS | 2375 | colorDepth:24 05:38:57 INFO - PROCESS | 2375 | Browser inner width/height: 1024/689 05:38:57 INFO - PROCESS | 2375 | __metrics 05:38:57 INFO - PROCESS | 2375 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:38:57 INFO - PROCESS | 2375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:58 INFO - TEST-INFO | 2375: exit 0 05:38:58 INFO - Browser initialized. 05:38:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:58 INFO - TEST-INFO | started process 2377 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmp12qzNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:10 INFO - PROCESS | 2377 | RSS: Main: 230961152 05:39:10 INFO - PROCESS | 2377 | 05:40:01 INFO - PROCESS | 2377 | 1477485601334 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] 05:42:14 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53076/tests/dromaeo/cssquery-ext.html) 05:42:16 INFO - PROCESS | 2377 | RSS: Main: 362659840 05:42:16 INFO - PROCESS | 2377 | 05:45:19 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-ext.html (next: http://localhost:53076/tests/dromaeo/cssquery-jquery.html) 05:45:19 INFO - PROCESS | 2377 | RSS: Main: 371683328 05:45:19 INFO - PROCESS | 2377 | 05:48:22 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53076/tests/dromaeo/cssquery-mootools.html) 05:48:22 INFO - PROCESS | 2377 | RSS: Main: 331108352 05:48:22 INFO - PROCESS | 2377 | 05:51:25 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53076/tests/dromaeo/cssquery-prototype.html) 05:51:25 INFO - PROCESS | 2377 | RSS: Main: 354185216 05:51:25 INFO - PROCESS | 2377 | 05:54:29 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53076/tests/dromaeo/cssquery-yui.html) 05:54:29 INFO - PROCESS | 2377 | RSS: Main: 331010048 05:54:29 INFO - PROCESS | 2377 | 05:57:33 INFO - PROCESS | 2377 | Cycle 1(1): loaded http://localhost:53076/tests/dromaeo/cssquery-yui.html (next: http://localhost:53076/tests/dromaeo/cssquery-dojo.html) 05:57:34 INFO - PROCESS | 2377 | RSS: Main: 391409664 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | __start_tp_report 05:57:34 INFO - PROCESS | 2377 | _x_x_mozilla_page_load 05:57:34 INFO - PROCESS | 2377 | _x_x_mozilla_page_load_details 05:57:34 INFO - PROCESS | 2377 | |i|pagename|runs| 05:57:34 INFO - PROCESS | 2377 | |0;dojo.html;2730.740579644934;3309.420851351013;3314.0389287106727;3336.8331583420822;3401.234722187508;5415.702136382497;5490.615656904019;5726.284214473191;5736.512396446302;5802;5516.558675305976;5757.453041961017;5830.795922142725;5832.18349431079;5857.824265272038;5136.460671629481;5222.791088356461;5236.162214045753;5250.474952504757;5275.208718692188;5337.893242135155;5385.838424847261;5389.892202155955;5401.027814993299;5403.864903377427;5330.92003619946;5339.733013349356;5378.542823859957;5383.43473935237;5446.074167391533;5162.148245539519;5214.791408343662;5227.895442091137;5234.7120908350025;5282.102042652758;5185.585153187736;5235.633505654606;5261.394939581959;5261.6842989420375;5276.630635855492;5749.9425005750245;5768.278965129359;5778.104393818965;5832.475077243069;5834.5624078194305;6123.892747182667;6152.87694246117;6190.938090619081;6283.931731605638;6297.27581328144;6203.658798766066;6242.094896240065;6303.779367722153;6314.400131987454;6315.715792789336;5822.534197264251;5846.269216347957;5855.765769369177;5861.923334906732;5897.882042359129;6150.3849615039035;6256.843578910472;6279.434850863353;6301.054841773771;6308.179936608303;6147.477464415575;6222.097795819581;6232.065190221503;6268.968655156695;6290.685465726695;6312.368763123651;6314.368563143649;6317.052442133717;6331;6332.176817013758;6088.269407671108;6191.1641928339095;6232.688365581703;6301.212348456443;6327;6197.81406557804;6273.153124328249;6273.8745225095245;6296;6329.968350158312;4309.655227581786;4969.055879382906;4970.751462426864;4974.900501990012;5007.198848184273;3861.67175790061;4491.752953587584;4507.797149128297;4513.661475389359;4535.727856328631;5720.399358067343;5721.971390143106;5788.363280039192;5823.126531020381;5835.679037652885;6253.093301471261;6273.780417685359;6297.905531417033;6306.810795676137;6312.747490100344;5830.970845145832;5839.2992840858515;5852.15143804146;5852.4147585241135;5856;5454.427285135083;5461.644993075437;5467.699276539828;5470.890582188334;5473.589480788957;6256.624602523863;6262.655553944577;6269.247690277196;6285.905711414424;6295.9296919523795;6207.596506227081;6274.341194174546;6317.210348706411;6320.6839658016;6326.715297811702;6283;6337.6197428154455;6361.904571431525;6366.395192456709;6381.936180638134;6245.843853903507;6257.906131407942;6261.843453913506;6263.843403914756;6289.339619340087;6156.230471191101;6223.097650840693;6251.937480625135;6308.211473565804;6315.936840631719;6105.399096011559;6199.597026193283;6201.968990155201;6233.065040244;6240.282367527792;6039.75840966374;6089.117078023751;6217.036359364306;6225.128482012612;6245.9069662807915;5310.761015754223;5901.852453688693;6048.092786082123;6058.212432383762;6063.727132279147;6086.908696369646;6109.769069418215;6124.326324104434;6144.126041557368;6238.407351301619;6019.789307374221;6055.061465472859;6057.091436284592;6066.848328791815;6104.32852386246 05:57:34 INFO - PROCESS | 2377 | |1;ext.html;497.9145462890898;509.0760270109748;511.89250257445923;518.611041718711;530.0776648631382;29481;39382.80308598457;40500.797496012514;40562.39156412656;42949.78525107378;42180.156396872124;42583.787081064555;42585.57414425855;42629;42753;9487.430754154755;9669.709908702715;9673.03269673034;9677.080677335642;9685.128338449538;9516.666916657918;9635.277354198462;9638.228941684649;9638.807223855518;9648.276379271547;8486.405951583392;9176;9601.519924003807;9641.903580964206;9660.130588247057;9553.904460955406;9606.423614583147;9616.51917404127;9619;9622.903770962306;9470.668526601536;9531.428114313163;9532.571034303473;9533;9542.90457095434;39290.60709392927;39598.80200598978;39668;39805.800970995246;40031.199376012315;3370.3427831572853;3590.4434812604086;3870.767753934773;4050.8987275318354;4207.506335251003;28930.566041509395;30348.393032139455;30362.392752144835;30364.392712145633;30454.54318185229;3091.8299493527848;3147.244661281276;3182.0612919188893;3225.354929014183;3293.555370025064;448104;450062;452073;452984;465599;55795.72102139463;56182;56229.7188514063;56233.7188314064;56285.155722663374;60126.699366502886;60171.69914150489;60237.69881150566;60277;60365;15674.059556426651;15957.521274361789;15983.120928349052;16010.11944343049;16051.95662281948;12146.089043321786;12156.75686486283;12562.30907300089;12673.746525069631;12693.555725549562;14908.850911490963;15474;15511;15528.282550553608;15757.44848930282;6184.6598437086395;6269.65516896566;6853.109095817612;6882.7591034313555;6951.427971650421;875.5403413207989;887.2813021452644;889.5952341684518;1005.9849102263472;1099.2250463423268;24510.509789804106;27882.44235115327;29214.977475788244;29937;30037.699623003926;838.5849004742691;862.4480332587149;1046.2937517175876;1053.3679792124665;1132.116948779953;13103.60689179326;14798.926005369904;15030.699386012437;15260.465883694018;15544.378224871105;603019;607996;608118;609429;610350;56014.43985560174;56127.71936140293;56216.43783562194;56427;56495.71752141213;1149.7010777198157;1214.8299238106672;1251.5369313354117;1356.6065840906224;1375.5460697969645;29247.7075229245;30196.24509387283;30232;30438.446464455214;30789;46654.766726166155;46866.76566617282;46949;47004.7649761749;47072.939059141405;474591.15157514653;477181;478216;478227;479314;24910.12814551482;25102.874485627453;25480.980760769362;25991.74008259893;26415.867920661043;46031.53968460273;46080.308795367426;46128.53871461243;46278.53721462877;46757;32039.839800800848;38019;38145.2370952585;38344;38536.990191274956;34042.65957340494;38097.619023809406;38238.42642360311;38247.42628860625;38494.615053849106;36268.81865590655;37017;37192.628073718915;37644.247115057;37705.81147094247;32734;32736;33036.50445243371;34041.659583403845;34693.30613387768;23518.529629407658;24874.626880597167;24913.128040518495;25137.994480221318;25546.872265638554 05:57:34 INFO - PROCESS | 2377 | |2;jquery.html;347.3400538975947;369.4255432801992;369.7725898572378;376.1949428223602;396.6410398589278;14730.70538589229;14921.925390373046;15727.213639318032;20090.296839610615;20810.063547140377;19996;20119.698204526907;20760.169593216255;20975.685364719502;21199.470013249636;4875.63432742544;5787.074068149108;5995.111539864951;6034.728437220336;6054.576179667425;5805.535557155417;5885.646861188321;5929.229200203968;5940.61386009908;5943.762249510014;5626.662400255977;5827.825165245049;5878.000739874232;5888.823335299948;5891.263592050994;5827.980103481895;5888.646681199141;5895.970520147414;5896.882062358729;5897.056470964625;4996.200607902718;5077.720725360104;5101.821436249714;5135.717535535544;5161;15676.843231567653;23140.884295578584;23441.765582344127;25524.36189095269;25861.482770344486;2181.9236326728646;2187.5406164705432;2188.3106821351384;2267.455810605459;2378.595638741418;10307.690769276858;11929;12522.998160147166;14691.706165876623;16310.102944338058;1735.158448152645;1796.3353559183186;1844.3360390259495;1853.7497437845852;1874.372085351409;121539.36056416156;124238;126149.36925315314;128417;129849;10245.641402551024;10785.636474479938;10896.073833724222;11250.768392697435;11403.657890263305;14417.567472975827;14442.783358249635;14469.638259043608;14471.927640361731;14473.78289325661;7177.605231712306;7810.921890781133;8351.20663536963;8439.156084391634;8597.01134369543;8316.501009939422;8331.50010999342;8347.4156809024;8485.660573576988;8533.615987280587;4503;4508.143452744033;4515.2549829277805;4529.162105010583;4589.678722489461;1741.1207340293324;1741.3992172700398;1905.3617038292327;1922.961600735615;1940.9417717468498;731.7585196885016;828.5939889454124;843.4939036578127;871.70006361323;967.2600460647469;16159.596010099842;19894.90052549757;20202.494937626383;21723.456913577287;21815;914.2777206007313;924.7734305095295;968.6561270748782;1001.329109496639;1010.408910787183;7415.5179913305465;10609.469526523642;11235.550577976954;11344.886551134548;11467.426628668532;541323;562903;564828;576720;576959;84122.57938710267;121931.39034304772;126396.94412223592;126914;128211.35894320655;909.7088931541975;919.3518569408551;961.4471678784727;962.1484985787629;981.1562056631292;15092.622684432537;19309.51726206856;19358.806411936264;20010;20071.096800643714;53669.92660146697;54286.7427954324;54573.454265456836;55144;55323.723381382835;76052.43685378895;76783.8482422753;78110;78495;79925.20074799178;6685.565438246498;7060.117485314336;7939.801504962422;7972.123066462568;8457.069722330662;6672.366125218096;9235.707001019728;10579.206559508222;12091.39543022863;12148.939255303667;8894.822103558023;10026.546150807946;10062;10262.127719143658;10424.791504170025;4932.778024988956;5204.453532379197;9970.451625160686;9984.251181161588;10422.687319380128;10031.598736050477;10266.384016959006;10473.31923424975;10600.946995264974;10713.303635263683;6474.805755827333;6908.827279318058;6918.342757438036;7130.750423735144;7219.783406497813;7876.645550950107;9981.151602113901;10173.389596624227;10506.159507239556;10605 05:57:34 INFO - PROCESS | 2377 | |3;mootools.html;1497.9775303370452;1537.5694805454473;1743.9738403923936;1891.498752830501;1926.4509614759788;8200.589970501482;8685.91314086859;8693.608787604557;8702.129787021295;8703.52130632815;7262.709491620329;7491.925080749191;7754.147043825189;8421.410501264914;8441.746747597566;2046.9692954605694;2259.0737797503025;2274.4541310085624;2314.3982564533176;2316.224064938248;2034.9898250508727;2177.923772667957;2335.2994101769486;2337.6610391493223;2337.731160916491;2306.2927286338195;2318.234982455794;2327.8719670418122;2328.2665960222475;2329.6272596384583;2289.7710228977135;2293.71328583927;2307.976920230793;2310.0759696121518;2311.5839148953182;2294.2314324701247;2295.8737269450176;2295.91964281251;2297.954040919189;2300.861948283092;8454.365922555833;8535;8552.973643162799;8602.225799677997;8616.741497755014;5040.571551418134;5188.662736922088;5221.477852214786;5233.528982391565;5246.81636142737;3046.6191726034244;3228.8869889553753;3324.4680851063713;3548.095235714902;3553.200529880782;873.5370253765515;1001.5643195210107;1030.1912998296377;1042.5100202904698;1046.837740150278;10782.299150555267;11076.612318568812;11089.72275693106;11093.445327733662;11136.10911127116;4267.466566679165;4321.67587430944;4517.570830771071;4527.932081018787;4535.138323718483;8802.03177650458;8810.515421651742;8823.20591146787;8834.779130521789;8865.601047952858;5094.5160209780015;5096.05722941257;5165.845024649267;5169.612279079084;5183.663061900965;4115.847562682454;4289.914201715948;4352.173087113501;4851.539103785135;4851.830185943475;3587.515685382492;3588.8923332300074;3670.6696397324367;3693.963060369416;3707.1658876752513;1144.2333636463422;1148.5061423587938;1148.529103067738;1156.2311063143084;1160.22265082395;828.5996665635057;855.8446097767963;880.9691660791841;897.7979954510183;946.4605175050156;5427.185922111636;5672.801451949162;5816.88366232673;5847.122931560215;5925.170476133345;886.4636894678755;918.4626993209048;922.9861552076724;924.4453328003278;962.9037096290315;3608.260306637133;3696.926061478809;3776.2825063237897;3791.2038471921205;3903.882883513499;10201.693949181536;10563;10581.470926453647;10585.894141058645;10587.576496940035;10475.685729428129;10565.260431769702;10573.62992295266;10573.682789516326;10579.94710026445;7193.208747037717;8005.199480051948;8171.264586187152;8177.386695997705;8179.100298967109;4790.329353890528;5533.916991245215;5584.497395234448;5622.32532096151;5687.459691329317;9320.021397753138;9651.855222171813;9653.71038868835;9675.612975480903;9700.369475984038;9456.810863782548;9731.610735570497;9799.804003920024;9809.607615695293;9949.950250248703;7167.749128780676;7197.352238298578;7246.275372462712;7361.595112268877;7361.595112268877;8004.83990320202;8101.270885620087;8101.392395570238;8102.756917292255;8118.918810811816;6896.310368963063;7178.964105179441;7247.019435324421;7353.742619008309;7453.105627324756;7049.823754406181;7171.211166771546;7188.101487314086;7217.711291548279;7229.7469588564145;6708.530402871947;6838.7264509419065;6838.931610684029;6842.076319696777;6850.657467126524;4693.55411235932;4844.394450693663;4856.781444835062;4878.268259761065;4910.459849416633;4784.40194975628;4793.7376606282405;4894.657373983787;5051.545360917535;5074.137396642654 05:57:34 INFO - PROCESS | 2377 | |4;prototype.html;287.5341946047404;297.2316561688036;306.82510968747806;309.73053443504153;311.66496016781963;4938.283948827418;5123.12906805843;5155.3040339554145;5232.843014709555;5259.737013149342;3849.653531182193;4206.200821843855;4522.439758283387;5163.7159956202395;5190;3447.913802154954;3949.032487040679;3982.362821948489;4029.851492324688;4030.979845100785;3827.4832897558754;4019.959800402002;4021.1756589899146;4033.5764744701823;4051.7163798534116;3885.4754608127964;3911.393733971239;3928.3321835287925;3984.521857377119;3993.5207775067033;3962.0387907985732;3965.9291991162227;3990.9999999999854;3991.920161596781;4004.5594984551676;3165.9710594057024;3445.7415693822813;3452.430348992438;3467.9826600867086;3472.2013936794424;4915.852524424273;5011.724355160465;5022.6233032522705;5039.3196918415915;5107.693538387709;4502.324651302298;4510.188166130095;4521.422789010266;4521.615662668677;4537.342085397632;4526.977365113154;4557.498675145731;4740.739259340735;5403.78384864605;5482.615062447848;3675.6691897729065;3760.953627561684;3805.8582425272584;3813.48517950076;3829.463875057494;4839.669090999968;4859.032092002744;5182.922256166161;5354.839354819362;5639.887202255973;2973.44991176633;3042.558828969787;3703.6296370362747;3791.6018818024263;3943.585923478051;3464.7921124732597;3997.8001099944886;4005.619466150711;4020.3165461871554;4037.7577345359373;4556.817727290938;4577.901303687091;4610.54767748165;4617.699849509771;4651.395318608559;5244.895102098013;5367.463253674601;5482.081271554924;5495.368032676213;5503.889922201533;1246.3643541793751;1309.240640428549;1335.9866401336058;1341.9060665753498;1353.5668586052368;757.7499425189674;767.7044337929825;791.7862177212236;841.7769291137853;846.8560344741286;4187.811548480265;4189.350650649154;4245.8513952011535;4305.761004277821;4381.38660587518;4514.525974772601;4570.451545814523;4580.190799660343;4622.560856718606;4713.481517033124;3956.287868183697;4173.64524015456;4181.599164279939;4228.61942425177;4269.701120921506;2152.666336717811;2393.4136136646284;2561.718210996789;2929.384829185895;2956.4826155422716;5510.8071217507995;5789.507891829157;5812.3025236971835;5837.824865254049;5879;5563.5798427684085;5636.403561304721;5752.539796816244;5785.363610002896;5789.739461724233;4990.9750451248965;5728.770849165987;5758.337791153987;5761.884762304814;5801.796937107181;4284.614384705392;4810.350602668735;5368.409474957673;5543.196236545759;5586.318567087739;4448.599626033672;4654.9767251164885;4885.5847252983895;4998.925231075336;5477.260569823023;4653.371794807657;4759.381810184554;5466.72666366688;5776.884462310814;5801.76792928295;3121.5785868907406;3631;3760.191558814868;3765;3780.962190378061;5288.9735551322;5602.5517958562405;5629.114246727228;5646.463585959327;5684.602077854511;4617.607503362117;5328.946710532845;5388.811391601275;5425.453745682462;5468.207109968953;4224.345226489899;5123.436421993503;5378.677279363251;5393.8112166074;5408.72956352173;5004.299398084344;5005.1241032819835;5021.84934451967;5276.709780962084;5307.41618421981;4978.775955081957;5199.050356116456;5383.300170977748;5588.469095436089;5598;4662.230731929194;5419.214213939035;5435.2934118564335;5474.972625136849;5522.199281104297 05:57:34 INFO - PROCESS | 2377 | |5;yui.html;694.9930500694993;701.982450438739;758.7989182866538;799.4763429953379;801.1227705662297;15169.696606067886;15353.61615959603;15379.846201537981;15393.538193854174;15399;13869.959753018513;13871.098378605384;13888.166709997407;13896.583102506916;13897.374618142183;698.8707089188516;720.2113685514354;722.5664601239241;815.8857759913617;868.7133246028811;880.7665968518347;881.4534988307227;895.6596493332524;896.5114012862973;897.291139999403;943.8065196634708;954.0745476887408;954.5847556312991;954.9474778887159;956.4691596164126;726.836461796097;751.29003092078;947.625687853301;948.5257371314343;949.3971328206569;590.3181824992141;678.9287124851869;699.7061234281614;699.7970588529321;701.0850839654249;14706.411743530245;14744.778828317583;14750.04124731889;14771.852281477155;14772.55682329532;13251.271180085092;13301.933490332487;13391.263480508567;13404.664883377896;13550.390232439466;11970.34163121028;12092.637220883387;12112.454939527654;12361.938190309082;12669.366531673379;3715.386961151407;3944.3886197639413;4411.669124815619;4599.034202817413;4612;77914;79370.01574960614;79854.600726996;88371.99999999936;92814;10466.842997355046;10602.20483463728;10625.256232063835;10868.945655271673;10883.67348979532;17920.28318867257;20993.795651456683;21188.682169767464;21193.78806211918;21206.893965530075;7555.6977720890545;7644.678611165203;7839.33365663925;7908.01149856268;7935.722249721348;4118.897027574335;4185.414042034117;4276.187524370422;4307.913841723148;4323.740575565476;3410.2497450560836;3571.7856928583847;3652.7077833773233;3665.633436656313;3665.780053196817;638.5944924972583;641.415703213063;653.2096163641951;654.9279579246279;656.0585559721815;560.4451592923058;563.7773079633522;622.7508996401476;626.5050610018035;628.277480896963;8405.663773448994;8954.820903581893;9032;9036.683716069905;9428.057194280516;538.7979507684619;588.2646691635456;635.9904601430982;638.8307098618869;666.1040899989506;4217.493900731871;4400.207962566705;4423.137488189772;4481.305397663367;4497.595216430537;68665.65667171632;71203;99887.50056249817;105973.94026059796;113134.86865131243;80257.19742802613;83714.16285837063;102557.48721256346;102772;104296.47851760844;75088.62455687686;76254;85307.57346213353;88608.67086993829;90622.09377906268;8480.872786908078;8490.533020683923;8647.740567782977;8662.480251184948;8687.431585232413;52971.47028529819;53182.46817531775;54194.187087192935;54929.176062358296;57078;59869.700651498206;60820;71536.92694609481;79248.60375698315;82840;13163.868361316265;13350.933245333712;13375.598732038054;13604.455821767018;13744.931275343559;14400;14661.120332780067;14687.779683304545;14868.405263789327;15053.473128440452;12169.452374643339;13243.404046817725;13402.664933376354;13493.527726529524;13705.862941370458;13065.738685226052;13081.411336490071;13339.933300333436;13756.17462952186;13798.862011379757;12635.873641263837;12705.17416367933;12978.221306721627;13272.734545309233;13430;11215.71960700989;11224.438778060901;11270.323780573191;11282.717932051435;11474.251830627241;11247.66257012291;11347.943260283646;11386;11439.542418303176;11528.654140375802 05:57:34 INFO - PROCESS | 2377 | __end_tp_report 05:57:34 INFO - PROCESS | 2377 | __start_cc_report 05:57:34 INFO - PROCESS | 2377 | _x_x_mozilla_cycle_collect,1787 05:57:34 INFO - PROCESS | 2377 | __end_cc_report 05:57:34 INFO - PROCESS | 2377 | __startTimestamp1477486654001__endTimestamp 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | ------- Summary: start ------- 05:57:34 INFO - PROCESS | 2377 | Number of tests: 6 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#0] dojo.html Cycles:165 Average:5787.36 Median:6058.21 stddev:671.69 (11.1%) stddev-sans-first:629.50 05:57:34 INFO - PROCESS | 2377 | Values: 2730.7 3309.4 3314.0 3336.8 3401.2 5415.7 5490.6 5726.3 5736.5 5802.0 5516.6 5757.5 5830.8 5832.2 5857.8 5136.5 5222.8 5236.2 5250.5 5275.2 5337.9 5385.8 5389.9 5401.0 5403.9 5330.9 5339.7 5378.5 5383.4 5446.1 5162.1 5214.8 5227.9 5234.7 5282.1 5185.6 5235.6 5261.4 5261.7 5276.6 5749.9 5768.3 5778.1 5832.5 5834.6 6123.9 6152.9 6190.9 6283.9 6297.3 6203.7 6242.1 6303.8 6314.4 6315.7 5822.5 5846.3 5855.8 5861.9 5897.9 6150.4 6256.8 6279.4 6301.1 6308.2 6147.5 6222.1 6232.1 6269.0 6290.7 6312.4 6314.4 6317.1 6331.0 6332.2 6088.3 6191.2 6232.7 6301.2 6327.0 6197.8 6273.2 6273.9 6296.0 6330.0 4309.7 4969.1 4970.8 4974.9 5007.2 3861.7 4491.8 4507.8 4513.7 4535.7 5720.4 5722.0 5788.4 5823.1 5835.7 6253.1 6273.8 6297.9 6306.8 6312.7 5831.0 5839.3 5852.2 5852.4 5856.0 5454.4 5461.6 5467.7 5470.9 5473.6 6256.6 6262.7 6269.2 6285.9 6295.9 6207.6 6274.3 6317.2 6320.7 6326.7 6283.0 6337.6 6361.9 6366.4 6381.9 6245.8 6257.9 6261.8 6263.8 6289.3 6156.2 6223.1 6251.9 6308.2 6315.9 6105.4 6199.6 6202.0 6233.1 6240.3 6039.8 6089.1 6217.0 6225.1 6245.9 5310.8 5901.9 6048.1 6058.2 6063.7 6086.9 6109.8 6124.3 6144.1 6238.4 6019.8 6055.1 6057.1 6066.8 6104.3 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#1] ext.html Cycles:180 Average:64813.67 Median:22275.85 stddev:138010.49 (619.6%) stddev-sans-first:138313.16 05:57:34 INFO - PROCESS | 2377 | Values: 497.9 509.1 511.9 518.6 530.1 29481.0 39382.8 40500.8 40562.4 42949.8 42180.2 42583.8 42585.6 42629.0 42753.0 9487.4 9669.7 9673.0 9677.1 9685.1 9516.7 9635.3 9638.2 9638.8 9648.3 8486.4 9176.0 9601.5 9641.9 9660.1 9553.9 9606.4 9616.5 9619.0 9622.9 9470.7 9531.4 9532.6 9533.0 9542.9 39290.6 39598.8 39668.0 39805.8 40031.2 3370.3 3590.4 3870.8 4050.9 4207.5 28930.6 30348.4 30362.4 30364.4 30454.5 3091.8 3147.2 3182.1 3225.4 3293.6 448104.0 450062.0 452073.0 452984.0 465599.0 55795.7 56182.0 56229.7 56233.7 56285.2 60126.7 60171.7 60237.7 60277.0 60365.0 15674.1 15957.5 15983.1 16010.1 16052.0 12146.1 12156.8 12562.3 12673.7 12693.6 14908.9 15474.0 15511.0 15528.3 15757.4 6184.7 6269.7 6853.1 6882.8 6951.4 875.5 887.3 889.6 1006.0 1099.2 24510.5 27882.4 29215.0 29937.0 30037.7 838.6 862.4 1046.3 1053.4 1132.1 13103.6 14798.9 15030.7 15260.5 15544.4 603019.0 607996.0 608118.0 609429.0 610350.0 56014.4 56127.7 56216.4 56427.0 56495.7 1149.7 1214.8 1251.5 1356.6 1375.5 29247.7 30196.2 30232.0 30438.4 30789.0 46654.8 46866.8 46949.0 47004.8 47072.9 474591.2 477181.0 478216.0 478227.0 479314.0 24910.1 25102.9 25481.0 25991.7 26415.9 46031.5 46080.3 46128.5 46278.5 46757.0 32039.8 38019.0 38145.2 38344.0 38537.0 34042.7 38097.6 38238.4 38247.4 38494.6 36268.8 37017.0 37192.6 37644.2 37705.8 32734.0 32736.0 33036.5 34041.7 34693.3 23518.5 24874.6 24913.1 25138.0 25546.9 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#2] jquery.html Cycles:180 Average:33619.86 Median:2449.82 stddev:94659.83 (3863.9%) stddev-sans-first:94892.41 05:57:34 INFO - PROCESS | 2377 | Values: 347.3 369.4 369.8 376.2 396.6 14730.7 14921.9 15727.2 20090.3 20810.1 19996.0 20119.7 20760.2 20975.7 21199.5 4875.6 5787.1 5995.1 6034.7 6054.6 5805.5 5885.6 5929.2 5940.6 5943.8 5626.7 5827.8 5878.0 5888.8 5891.3 5828.0 5888.6 5896.0 5896.9 5897.1 4996.2 5077.7 5101.8 5135.7 5161.0 15676.8 23140.9 23441.8 25524.4 25861.5 2181.9 2187.5 2188.3 2267.5 2378.6 10307.7 11929.0 12523.0 14691.7 16310.1 1735.2 1796.3 1844.3 1853.7 1874.4 121539.4 124238.0 126149.4 128417.0 129849.0 10245.6 10785.6 10896.1 11250.8 11403.7 14417.6 14442.8 14469.6 14471.9 14473.8 7177.6 7810.9 8351.2 8439.2 8597.0 8316.5 8331.5 8347.4 8485.7 8533.6 4503.0 4508.1 4515.3 4529.2 4589.7 1741.1 1741.4 1905.4 1923.0 1940.9 731.8 828.6 843.5 871.7 967.3 16159.6 19894.9 20202.5 21723.5 21815.0 914.3 924.8 968.7 1001.3 1010.4 7415.5 10609.5 11235.6 11344.9 11467.4 541323.0 562903.0 564828.0 576720.0 576959.0 84122.6 121931.4 126396.9 126914.0 128211.4 909.7 919.4 961.4 962.1 981.2 15092.6 19309.5 19358.8 20010.0 20071.1 53669.9 54286.7 54573.5 55144.0 55323.7 76052.4 76783.8 78110.0 78495.0 79925.2 6685.6 7060.1 7939.8 7972.1 8457.1 6672.4 9235.7 10579.2 12091.4 12148.9 8894.8 10026.5 10062.0 10262.1 10424.8 4932.8 5204.5 9970.5 9984.3 10422.7 10031.6 10266.4 10473.3 10600.9 10713.3 6474.8 6908.8 6918.3 7130.8 7219.8 7876.6 9981.2 10173.4 10506.2 10605.0 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#3] mootools.html Cycles:180 Average:5495.17 Median:5084.33 stddev:3071.86 (60.4%) stddev-sans-first:3065.79 05:57:34 INFO - PROCESS | 2377 | Values: 1498.0 1537.6 1744.0 1891.5 1926.5 8200.6 8685.9 8693.6 8702.1 8703.5 7262.7 7491.9 7754.1 8421.4 8441.7 2047.0 2259.1 2274.5 2314.4 2316.2 2035.0 2177.9 2335.3 2337.7 2337.7 2306.3 2318.2 2327.9 2328.3 2329.6 2289.8 2293.7 2308.0 2310.1 2311.6 2294.2 2295.9 2295.9 2298.0 2300.9 8454.4 8535.0 8553.0 8602.2 8616.7 5040.6 5188.7 5221.5 5233.5 5246.8 3046.6 3228.9 3324.5 3548.1 3553.2 873.5 1001.6 1030.2 1042.5 1046.8 10782.3 11076.6 11089.7 11093.4 11136.1 4267.5 4321.7 4517.6 4527.9 4535.1 8802.0 8810.5 8823.2 8834.8 8865.6 5094.5 5096.1 5165.8 5169.6 5183.7 4115.8 4289.9 4352.2 4851.5 4851.8 3587.5 3588.9 3670.7 3694.0 3707.2 1144.2 1148.5 1148.5 1156.2 1160.2 828.6 855.8 881.0 897.8 946.5 5427.2 5672.8 5816.9 5847.1 5925.2 886.5 918.5 923.0 924.4 962.9 3608.3 3696.9 3776.3 3791.2 3903.9 10201.7 10563.0 10581.5 10585.9 10587.6 10475.7 10565.3 10573.6 10573.7 10579.9 7193.2 8005.2 8171.3 8177.4 8179.1 4790.3 5533.9 5584.5 5622.3 5687.5 9320.0 9651.9 9653.7 9675.6 9700.4 9456.8 9731.6 9799.8 9809.6 9950.0 7167.7 7197.4 7246.3 7361.6 7361.6 8004.8 8101.3 8101.4 8102.8 8118.9 6896.3 7179.0 7247.0 7353.7 7453.1 7049.8 7171.2 7188.1 7217.7 7229.7 6708.5 6838.7 6838.9 6842.1 6850.7 4693.6 4844.4 4856.8 4878.3 4910.5 4784.4 4793.7 4894.7 5051.5 5074.1 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#4] prototype.html Cycles:180 Average:4319.96 Median:4620.13 stddev:1325.64 (28.7%) stddev-sans-first:1294.34 05:57:34 INFO - PROCESS | 2377 | Values: 287.5 297.2 306.8 309.7 311.7 4938.3 5123.1 5155.3 5232.8 5259.7 3849.7 4206.2 4522.4 5163.7 5190.0 3447.9 3949.0 3982.4 4029.9 4031.0 3827.5 4020.0 4021.2 4033.6 4051.7 3885.5 3911.4 3928.3 3984.5 3993.5 3962.0 3965.9 3991.0 3991.9 4004.6 3166.0 3445.7 3452.4 3468.0 3472.2 4915.9 5011.7 5022.6 5039.3 5107.7 4502.3 4510.2 4521.4 4521.6 4537.3 4527.0 4557.5 4740.7 5403.8 5482.6 3675.7 3761.0 3805.9 3813.5 3829.5 4839.7 4859.0 5182.9 5354.8 5639.9 2973.4 3042.6 3703.6 3791.6 3943.6 3464.8 3997.8 4005.6 4020.3 4037.8 4556.8 4577.9 4610.5 4617.7 4651.4 5244.9 5367.5 5482.1 5495.4 5503.9 1246.4 1309.2 1336.0 1341.9 1353.6 757.7 767.7 791.8 841.8 846.9 4187.8 4189.4 4245.9 4305.8 4381.4 4514.5 4570.5 4580.2 4622.6 4713.5 3956.3 4173.6 4181.6 4228.6 4269.7 2152.7 2393.4 2561.7 2929.4 2956.5 5510.8 5789.5 5812.3 5837.8 5879.0 5563.6 5636.4 5752.5 5785.4 5789.7 4991.0 5728.8 5758.3 5761.9 5801.8 4284.6 4810.4 5368.4 5543.2 5586.3 4448.6 4655.0 4885.6 4998.9 5477.3 4653.4 4759.4 5466.7 5776.9 5801.8 3121.6 3631.0 3760.2 3765.0 3781.0 5289.0 5602.6 5629.1 5646.5 5684.6 4617.6 5328.9 5388.8 5425.5 5468.2 4224.3 5123.4 5378.7 5393.8 5408.7 5004.3 5005.1 5021.8 5276.7 5307.4 4978.8 5199.1 5383.3 5588.5 5598.0 4662.2 5419.2 5435.3 5475.0 5522.2 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - PROCESS | 2377 | [#5] yui.html Cycles:180 Average:19959.24 Median:4452.22 stddev:28238.92 (634.3%) stddev-sans-first:28281.09 05:57:34 INFO - PROCESS | 2377 | Values: 695.0 702.0 758.8 799.5 801.1 15169.7 15353.6 15379.8 15393.5 15399.0 13870.0 13871.1 13888.2 13896.6 13897.4 698.9 720.2 722.6 815.9 868.7 880.8 881.5 895.7 896.5 897.3 943.8 954.1 954.6 954.9 956.5 726.8 751.3 947.6 948.5 949.4 590.3 678.9 699.7 699.8 701.1 14706.4 14744.8 14750.0 14771.9 14772.6 13251.3 13301.9 13391.3 13404.7 13550.4 11970.3 12092.6 12112.5 12361.9 12669.4 3715.4 3944.4 4411.7 4599.0 4612.0 77914.0 79370.0 79854.6 88372.0 92814.0 10466.8 10602.2 10625.3 10868.9 10883.7 17920.3 20993.8 21188.7 21193.8 21206.9 7555.7 7644.7 7839.3 7908.0 7935.7 4118.9 4185.4 4276.2 4307.9 4323.7 3410.2 3571.8 3652.7 3665.6 3665.8 638.6 641.4 653.2 654.9 656.1 560.4 563.8 622.8 626.5 628.3 8405.7 8954.8 9032.0 9036.7 9428.1 538.8 588.3 636.0 638.8 666.1 4217.5 4400.2 4423.1 4481.3 4497.6 68665.7 71203.0 99887.5 105973.9 113134.9 80257.2 83714.2 102557.5 102772.0 104296.5 75088.6 76254.0 85307.6 88608.7 90622.1 8480.9 8490.5 8647.7 8662.5 8687.4 52971.5 53182.5 54194.2 54929.2 57078.0 59869.7 60820.0 71536.9 79248.6 82840.0 13163.9 13350.9 13375.6 13604.5 13744.9 14400.0 14661.1 14687.8 14868.4 15053.5 12169.5 13243.4 13402.7 13493.5 13705.9 13065.7 13081.4 13339.9 13756.2 13798.9 12635.9 12705.2 12978.2 13272.7 13430.0 11215.7 11224.4 11270.3 11282.7 11474.3 11247.7 11347.9 11386.0 11439.5 11528.7 05:57:34 INFO - PROCESS | 2377 | -------- Summary: end -------- 05:57:34 INFO - PROCESS | 2377 | 05:57:34 INFO - TEST-INFO | 2377: exit 0 05:57:34 INFO - TEST-OK | dromaeo_css | took 1119552ms 05:57:34 INFO - TEST-START | kraken 05:57:34 INFO - operating with platform_type : mac_ 05:57:34 INFO - Initialising browser for kraken test... 05:57:34 INFO - TEST-INFO | started process 2380 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpQ3_s9Q/profile http://localhost:53076/getInfo.html) 05:57:37 INFO - PROCESS | 2380 | __metrics Screen width/height:1600/1200 05:57:37 INFO - PROCESS | 2380 | colorDepth:24 05:57:37 INFO - PROCESS | 2380 | Browser inner width/height: 1024/689 05:57:37 INFO - PROCESS | 2380 | __metrics 05:57:37 INFO - PROCESS | 2380 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:57:37 INFO - PROCESS | 2380 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:37 INFO - TEST-INFO | 2380: exit 0 05:57:37 INFO - Browser initialized. 05:57:37 INFO - Running cycle 1/1 for kraken test... 05:57:38 INFO - TEST-INFO | started process 2381 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpQ3_s9Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:49 INFO - PROCESS | 2381 | RSS: Main: 231583744 05:57:49 INFO - PROCESS | 2381 | 05:57:52 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53076/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:53 INFO - PROCESS | 2381 | RSS: Main: 289681408 05:57:53 INFO - PROCESS | 2381 | 05:57:56 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53076/tests/kraken/driver.html?testName=audio-dft) 05:57:56 INFO - PROCESS | 2381 | RSS: Main: 291282944 05:57:56 INFO - PROCESS | 2381 | 05:58:00 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53076/tests/kraken/driver.html?testName=audio-fft) 05:58:00 INFO - PROCESS | 2381 | RSS: Main: 381456384 05:58:00 INFO - PROCESS | 2381 | 05:58:02 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53076/tests/kraken/driver.html?testName=audio-oscillator) 05:58:03 INFO - PROCESS | 2381 | RSS: Main: 284631040 05:58:03 INFO - PROCESS | 2381 | 05:58:05 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53076/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:58:06 INFO - PROCESS | 2381 | RSS: Main: 288817152 05:58:06 INFO - PROCESS | 2381 | 05:58:08 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53076/tests/kraken/driver.html?testName=imaging-darkroom) 05:58:09 INFO - PROCESS | 2381 | RSS: Main: 326537216 05:58:09 INFO - PROCESS | 2381 | 05:58:12 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53076/tests/kraken/driver.html?testName=imaging-desaturate) 05:58:13 INFO - PROCESS | 2381 | RSS: Main: 326479872 05:58:13 INFO - PROCESS | 2381 | 05:58:16 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53076/tests/kraken/driver.html?testName=json-parse-financial) 05:58:16 INFO - PROCESS | 2381 | RSS: Main: 327045120 05:58:16 INFO - PROCESS | 2381 | 05:58:18 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53076/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:58:18 INFO - PROCESS | 2381 | RSS: Main: 301932544 05:58:18 INFO - PROCESS | 2381 | 05:58:20 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:58:21 INFO - PROCESS | 2381 | RSS: Main: 305405952 05:58:21 INFO - PROCESS | 2381 | 05:58:23 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:58:24 INFO - PROCESS | 2381 | RSS: Main: 310308864 05:58:24 INFO - PROCESS | 2381 | 05:58:27 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:58:28 INFO - PROCESS | 2381 | RSS: Main: 322854912 05:58:28 INFO - PROCESS | 2381 | 05:58:31 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:31 INFO - PROCESS | 2381 | RSS: Main: 316493824 05:58:31 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:53076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53076/tests/kraken/driver.html?testName=ai-astar) 05:58:33 INFO - PROCESS | 2381 | RSS: Main: 360255488 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | __start_tp_report 05:58:33 INFO - PROCESS | 2381 | _x_x_mozilla_page_load 05:58:33 INFO - PROCESS | 2381 | _x_x_mozilla_page_load_details 05:58:33 INFO - PROCESS | 2381 | |i|pagename|runs| 05:58:33 INFO - PROCESS | 2381 | |0;ai-astar;104;113;113;113;112;113;115;160;110;110 05:58:33 INFO - PROCESS | 2381 | |1;audio-beat-detection;150;115;114;164;114;113;114;114;141;111 05:58:33 INFO - PROCESS | 2381 | |2;audio-dft;184;188;185;186;186;184;181;180;184;184 05:58:33 INFO - PROCESS | 2381 | |3;audio-fft;82;59;60;87;62;62;61;58;82;59 05:58:33 INFO - PROCESS | 2381 | |4;audio-oscillator;85;72;87;56;89;54;89;88;98;122 05:58:33 INFO - PROCESS | 2381 | |5;imaging-gaussian-blur;90;86;89;87;89;86;89;87;89;85 05:58:33 INFO - PROCESS | 2381 | |6;imaging-darkroom;150;132;97;98;98;97;98;99;98;99 05:58:33 INFO - PROCESS | 2381 | |7;imaging-desaturate;102;65;122;127;123;85;82;83;82;80 05:58:33 INFO - PROCESS | 2381 | |8;json-parse-financial;56;55;55;54;53;51;54;55;54;53 05:58:33 INFO - PROCESS | 2381 | |9;json-stringify-tinderbox;64;74;69;72;75;71;69;69;69;70 05:58:33 INFO - PROCESS | 2381 | |10;stanford-crypto-aes;110;97;107;106;85;86;87;90;90;94 05:58:33 INFO - PROCESS | 2381 | |11;stanford-crypto-ccm;148;133;168;157;156;150;146;145;177;146 05:58:33 INFO - PROCESS | 2381 | |12;stanford-crypto-pbkdf2;174;174;171;173;171;234;220;184;173;172 05:58:33 INFO - PROCESS | 2381 | |13;stanford-crypto-sha256-iterative;68;68;68;69;68;68;67;69;68;67 05:58:33 INFO - PROCESS | 2381 | __end_tp_report 05:58:33 INFO - PROCESS | 2381 | __start_cc_report 05:58:33 INFO - PROCESS | 2381 | _x_x_mozilla_cycle_collect,2131 05:58:33 INFO - PROCESS | 2381 | __end_cc_report 05:58:33 INFO - PROCESS | 2381 | __startTimestamp1477486713801__endTimestamp 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | ------- Summary: start ------- 05:58:33 INFO - PROCESS | 2381 | Number of tests: 14 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#0] ai-astar Cycles:10 Average:116.30 Median:113.00 stddev:15.65 (13.8%) stddev-sans-first:15.95 05:58:33 INFO - PROCESS | 2381 | Values: 104.0 113.0 113.0 113.0 112.0 113.0 115.0 160.0 110.0 110.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:114.50 stddev:19.22 (16.8%) stddev-sans-first:18.14 05:58:33 INFO - PROCESS | 2381 | Values: 150.0 115.0 114.0 164.0 114.0 113.0 114.0 114.0 141.0 111.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:2.35 (1.3%) stddev-sans-first:2.49 05:58:33 INFO - PROCESS | 2381 | Values: 184.0 188.0 185.0 186.0 186.0 184.0 181.0 180.0 184.0 184.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#3] audio-fft Cycles:10 Average:67.20 Median:62.00 stddev:11.52 (18.6%) stddev-sans-first:10.90 05:58:33 INFO - PROCESS | 2381 | Values: 82.0 59.0 60.0 87.0 62.0 62.0 61.0 58.0 82.0 59.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:87.50 stddev:19.84 (22.7%) stddev-sans-first:21.04 05:58:33 INFO - PROCESS | 2381 | Values: 85.0 72.0 87.0 56.0 89.0 54.0 89.0 88.0 98.0 122.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.70 (1.9%) stddev-sans-first:1.59 05:58:33 INFO - PROCESS | 2381 | Values: 90.0 86.0 89.0 87.0 89.0 86.0 89.0 87.0 89.0 85.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#6] imaging-darkroom Cycles:10 Average:106.60 Median:98.00 stddev:18.63 (19.0%) stddev-sans-first:11.36 05:58:33 INFO - PROCESS | 2381 | Values: 150.0 132.0 97.0 98.0 98.0 97.0 98.0 99.0 98.0 99.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:81.00 stddev:21.84 (27.0%) stddev-sans-first:23.02 05:58:33 INFO - PROCESS | 2381 | Values: 102.0 65.0 122.0 127.0 123.0 85.0 82.0 83.0 82.0 80.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.41 (2.6%) stddev-sans-first:1.30 05:58:33 INFO - PROCESS | 2381 | Values: 56.0 55.0 55.0 54.0 53.0 51.0 54.0 55.0 54.0 53.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:3.08 (4.4%) stddev-sans-first:2.32 05:58:33 INFO - PROCESS | 2381 | Values: 64.0 74.0 69.0 72.0 75.0 71.0 69.0 69.0 69.0 70.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:88.50 stddev:9.37 (10.6%) stddev-sans-first:8.26 05:58:33 INFO - PROCESS | 2381 | Values: 110.0 97.0 107.0 106.0 85.0 86.0 87.0 90.0 90.0 94.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:153.00 stddev:12.56 (8.2%) stddev-sans-first:13.21 05:58:33 INFO - PROCESS | 2381 | Values: 148.0 133.0 168.0 157.0 156.0 150.0 146.0 145.0 177.0 146.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:174.00 stddev:22.89 (13.2%) stddev-sans-first:23.96 05:58:33 INFO - PROCESS | 2381 | Values: 174.0 174.0 171.0 173.0 171.0 234.0 220.0 184.0 173.0 172.0 05:58:33 INFO - PROCESS | 2381 | 05:58:33 INFO - PROCESS | 2381 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 05:58:33 INFO - PROCESS | 2381 | Values: 68.0 68.0 68.0 69.0 68.0 68.0 67.0 69.0 68.0 67.0 05:58:33 INFO - PROCESS | 2381 | -------- Summary: end -------- 05:58:33 INFO - PROCESS | 2381 | 05:58:34 INFO - TEST-INFO | 2381: exit 0 05:58:34 INFO - TEST-OK | kraken | took 59930ms 05:58:34 INFO - SUITE-END | took 1179s 05:58:34 INFO - Completed test suite (00:19:39) 05:58:34 INFO - javascript benchmark 05:58:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2730.740579644934, 3309.420851351013, 3314.0389287106727, 3336.8331583420822, 3401.234722187508, 5415.702136382497, 5490.615656904019, 5726.284214473191, 5736.512396446302, 5802.0, 5516.558675305976, 5757.453041961017, 5830.795922142725, 5832.18349431079, 5857.824265272038, 5136.460671629481, 5222.791088356461, 5236.162214045753, 5250.474952504757, 5275.208718692188, 5337.893242135155, 5385.838424847261, 5389.892202155955, 5401.027814993299, 5403.864903377427, 5330.92003619946, 5339.733013349356, 5378.542823859957, 5383.43473935237, 5446.074167391533, 5162.148245539519, 5214.791408343662, 5227.895442091137, 5234.7120908350025, 5282.102042652758, 5185.585153187736, 5235.633505654606, 5261.394939581959, 5261.6842989420375, 5276.630635855492, 5749.9425005750245, 5768.278965129359, 5778.104393818965, 5832.475077243069, 5834.5624078194305, 6123.892747182667, 6152.87694246117, 6190.938090619081, 6283.931731605638, 6297.27581328144, 6203.658798766066, 6242.094896240065, 6303.779367722153, 6314.400131987454, 6315.715792789336, 5822.534197264251, 5846.269216347957, 5855.765769369177, 5861.923334906732, 5897.882042359129, 6150.3849615039035, 6256.843578910472, 6279.434850863353, 6301.054841773771, 6308.179936608303, 6147.477464415575, 6222.097795819581, 6232.065190221503, 6268.968655156695, 6290.685465726695, 6312.368763123651, 6314.368563143649, 6317.052442133717, 6331.0, 6332.176817013758, 6088.269407671108, 6191.1641928339095, 6232.688365581703, 6301.212348456443, 6327.0, 6197.81406557804, 6273.153124328249, 6273.8745225095245, 6296.0, 6329.968350158312, 4309.655227581786, 4969.055879382906, 4970.751462426864, 4974.900501990012, 5007.198848184273, 3861.67175790061, 4491.752953587584, 4507.797149128297, 4513.661475389359, 4535.727856328631, 5720.399358067343, 5721.971390143106, 5788.363280039192, 5823.126531020381, 5835.679037652885, 6253.093301471261, 6273.780417685359, 6297.905531417033, 6306.810795676137, 6312.747490100344, 5830.970845145832, 5839.2992840858515, 5852.15143804146, 5852.4147585241135, 5856.0, 5454.427285135083, 5461.644993075437, 5467.699276539828, 5470.890582188334, 5473.589480788957, 6256.624602523863, 6262.655553944577, 6269.247690277196, 6285.905711414424, 6295.9296919523795, 6207.596506227081, 6274.341194174546, 6317.210348706411, 6320.6839658016, 6326.715297811702, 6283.0, 6337.6197428154455, 6361.904571431525, 6366.395192456709, 6381.936180638134, 6245.843853903507, 6257.906131407942, 6261.843453913506, 6263.843403914756, 6289.339619340087, 6156.230471191101, 6223.097650840693, 6251.937480625135, 6308.211473565804, 6315.936840631719, 6105.399096011559, 6199.597026193283, 6201.968990155201, 6233.065040244, 6240.282367527792, 6039.75840966374, 6089.117078023751, 6217.036359364306, 6225.128482012612, 6245.9069662807915, 5310.761015754223, 5901.852453688693, 6048.092786082123, 6058.212432383762, 6063.727132279147, 6086.908696369646, 6109.769069418215, 6124.326324104434, 6144.126041557368, 6238.407351301619, 6019.789307374221, 6055.061465472859, 6057.091436284592, 6066.848328791815, 6104.32852386246], "value": 5740.957611120217, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.9145462890898, 509.0760270109748, 511.89250257445923, 518.611041718711, 530.0776648631382, 29481.0, 39382.80308598457, 40500.797496012514, 40562.39156412656, 42949.78525107378, 42180.156396872124, 42583.787081064555, 42585.57414425855, 42629.0, 42753.0, 9487.430754154755, 9669.709908702715, 9673.03269673034, 9677.080677335642, 9685.128338449538, 9516.666916657918, 9635.277354198462, 9638.228941684649, 9638.807223855518, 9648.276379271547, 8486.405951583392, 9176.0, 9601.519924003807, 9641.903580964206, 9660.130588247057, 9553.904460955406, 9606.423614583147, 9616.51917404127, 9619.0, 9622.903770962306, 9470.668526601536, 9531.428114313163, 9532.571034303473, 9533.0, 9542.90457095434, 39290.60709392927, 39598.80200598978, 39668.0, 39805.800970995246, 40031.199376012315, 3370.3427831572853, 3590.4434812604086, 3870.767753934773, 4050.8987275318354, 4207.506335251003, 28930.566041509395, 30348.393032139455, 30362.392752144835, 30364.392712145633, 30454.54318185229, 3091.8299493527848, 3147.244661281276, 3182.0612919188893, 3225.354929014183, 3293.555370025064, 448104.0, 450062.0, 452073.0, 452984.0, 465599.0, 55795.72102139463, 56182.0, 56229.7188514063, 56233.7188314064, 56285.155722663374, 60126.699366502886, 60171.69914150489, 60237.69881150566, 60277.0, 60365.0, 15674.059556426651, 15957.521274361789, 15983.120928349052, 16010.11944343049, 16051.95662281948, 12146.089043321786, 12156.75686486283, 12562.30907300089, 12673.746525069631, 12693.555725549562, 14908.850911490963, 15474.0, 15511.0, 15528.282550553608, 15757.44848930282, 6184.6598437086395, 6269.65516896566, 6853.109095817612, 6882.7591034313555, 6951.427971650421, 875.5403413207989, 887.2813021452644, 889.5952341684518, 1005.9849102263472, 1099.2250463423268, 24510.509789804106, 27882.44235115327, 29214.977475788244, 29937.0, 30037.699623003926, 838.5849004742691, 862.4480332587149, 1046.2937517175876, 1053.3679792124665, 1132.116948779953, 13103.60689179326, 14798.926005369904, 15030.699386012437, 15260.465883694018, 15544.378224871105, 603019.0, 607996.0, 608118.0, 609429.0, 610350.0, 56014.43985560174, 56127.71936140293, 56216.43783562194, 56427.0, 56495.71752141213, 1149.7010777198157, 1214.8299238106672, 1251.5369313354117, 1356.6065840906224, 1375.5460697969645, 29247.7075229245, 30196.24509387283, 30232.0, 30438.446464455214, 30789.0, 46654.766726166155, 46866.76566617282, 46949.0, 47004.7649761749, 47072.939059141405, 474591.15157514653, 477181.0, 478216.0, 478227.0, 479314.0, 24910.12814551482, 25102.874485627453, 25480.980760769362, 25991.74008259893, 26415.867920661043, 46031.53968460273, 46080.308795367426, 46128.53871461243, 46278.53721462877, 46757.0, 32039.839800800848, 38019.0, 38145.2370952585, 38344.0, 38536.990191274956, 34042.65957340494, 38097.619023809406, 38238.42642360311, 38247.42628860625, 38494.615053849106, 36268.81865590655, 37017.0, 37192.628073718915, 37644.247115057, 37705.81147094247, 32734.0, 32736.0, 33036.50445243371, 34041.659583403845, 34693.30613387768, 23518.529629407658, 24874.626880597167, 24913.128040518495, 25137.994480221318, 25546.872265638554], "value": 19257.07786787309, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3400538975947, 369.4255432801992, 369.7725898572378, 376.1949428223602, 396.6410398589278, 14730.70538589229, 14921.925390373046, 15727.213639318032, 20090.296839610615, 20810.063547140377, 19996.0, 20119.698204526907, 20760.169593216255, 20975.685364719502, 21199.470013249636, 4875.63432742544, 5787.074068149108, 5995.111539864951, 6034.728437220336, 6054.576179667425, 5805.535557155417, 5885.646861188321, 5929.229200203968, 5940.61386009908, 5943.762249510014, 5626.662400255977, 5827.825165245049, 5878.000739874232, 5888.823335299948, 5891.263592050994, 5827.980103481895, 5888.646681199141, 5895.970520147414, 5896.882062358729, 5897.056470964625, 4996.200607902718, 5077.720725360104, 5101.821436249714, 5135.717535535544, 5161.0, 15676.843231567653, 23140.884295578584, 23441.765582344127, 25524.36189095269, 25861.482770344486, 2181.9236326728646, 2187.5406164705432, 2188.3106821351384, 2267.455810605459, 2378.595638741418, 10307.690769276858, 11929.0, 12522.998160147166, 14691.706165876623, 16310.102944338058, 1735.158448152645, 1796.3353559183186, 1844.3360390259495, 1853.7497437845852, 1874.372085351409, 121539.36056416156, 124238.0, 126149.36925315314, 128417.0, 129849.0, 10245.641402551024, 10785.636474479938, 10896.073833724222, 11250.768392697435, 11403.657890263305, 14417.567472975827, 14442.783358249635, 14469.638259043608, 14471.927640361731, 14473.78289325661, 7177.605231712306, 7810.921890781133, 8351.20663536963, 8439.156084391634, 8597.01134369543, 8316.501009939422, 8331.50010999342, 8347.4156809024, 8485.660573576988, 8533.615987280587, 4503.0, 4508.143452744033, 4515.2549829277805, 4529.162105010583, 4589.678722489461, 1741.1207340293324, 1741.3992172700398, 1905.3617038292327, 1922.961600735615, 1940.9417717468498, 731.7585196885016, 828.5939889454124, 843.4939036578127, 871.70006361323, 967.2600460647469, 16159.596010099842, 19894.90052549757, 20202.494937626383, 21723.456913577287, 21815.0, 914.2777206007313, 924.7734305095295, 968.6561270748782, 1001.329109496639, 1010.408910787183, 7415.5179913305465, 10609.469526523642, 11235.550577976954, 11344.886551134548, 11467.426628668532, 541323.0, 562903.0, 564828.0, 576720.0, 576959.0, 84122.57938710267, 121931.39034304772, 126396.94412223592, 126914.0, 128211.35894320655, 909.7088931541975, 919.3518569408551, 961.4471678784727, 962.1484985787629, 981.1562056631292, 15092.622684432537, 19309.51726206856, 19358.806411936264, 20010.0, 20071.096800643714, 53669.92660146697, 54286.7427954324, 54573.454265456836, 55144.0, 55323.723381382835, 76052.43685378895, 76783.8482422753, 78110.0, 78495.0, 79925.20074799178, 6685.565438246498, 7060.117485314336, 7939.801504962422, 7972.123066462568, 8457.069722330662, 6672.366125218096, 9235.707001019728, 10579.206559508222, 12091.39543022863, 12148.939255303667, 8894.822103558023, 10026.546150807946, 10062.0, 10262.127719143658, 10424.791504170025, 4932.778024988956, 5204.453532379197, 9970.451625160686, 9984.251181161588, 10422.687319380128, 10031.598736050477, 10266.384016959006, 10473.31923424975, 10600.946995264974, 10713.303635263683, 6474.805755827333, 6908.827279318058, 6918.342757438036, 7130.750423735144, 7219.783406497813, 7876.645550950107, 9981.151602113901, 10173.389596624227, 10506.159507239556, 10605.0], "value": 9150.367841461686, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.9775303370452, 1537.5694805454473, 1743.9738403923936, 1891.498752830501, 1926.4509614759788, 8200.589970501482, 8685.91314086859, 8693.608787604557, 8702.129787021295, 8703.52130632815, 7262.709491620329, 7491.925080749191, 7754.147043825189, 8421.410501264914, 8441.746747597566, 2046.9692954605694, 2259.0737797503025, 2274.4541310085624, 2314.3982564533176, 2316.224064938248, 2034.9898250508727, 2177.923772667957, 2335.2994101769486, 2337.6610391493223, 2337.731160916491, 2306.2927286338195, 2318.234982455794, 2327.8719670418122, 2328.2665960222475, 2329.6272596384583, 2289.7710228977135, 2293.71328583927, 2307.976920230793, 2310.0759696121518, 2311.5839148953182, 2294.2314324701247, 2295.8737269450176, 2295.91964281251, 2297.954040919189, 2300.861948283092, 8454.365922555833, 8535.0, 8552.973643162799, 8602.225799677997, 8616.741497755014, 5040.571551418134, 5188.662736922088, 5221.477852214786, 5233.528982391565, 5246.81636142737, 3046.6191726034244, 3228.8869889553753, 3324.4680851063713, 3548.095235714902, 3553.200529880782, 873.5370253765515, 1001.5643195210107, 1030.1912998296377, 1042.5100202904698, 1046.837740150278, 10782.299150555267, 11076.612318568812, 11089.72275693106, 11093.445327733662, 11136.10911127116, 4267.466566679165, 4321.67587430944, 4517.570830771071, 4527.932081018787, 4535.138323718483, 8802.03177650458, 8810.515421651742, 8823.20591146787, 8834.779130521789, 8865.601047952858, 5094.5160209780015, 5096.05722941257, 5165.845024649267, 5169.612279079084, 5183.663061900965, 4115.847562682454, 4289.914201715948, 4352.173087113501, 4851.539103785135, 4851.830185943475, 3587.515685382492, 3588.8923332300074, 3670.6696397324367, 3693.963060369416, 3707.1658876752513, 1144.2333636463422, 1148.5061423587938, 1148.529103067738, 1156.2311063143084, 1160.22265082395, 828.5996665635057, 855.8446097767963, 880.9691660791841, 897.7979954510183, 946.4605175050156, 5427.185922111636, 5672.801451949162, 5816.88366232673, 5847.122931560215, 5925.170476133345, 886.4636894678755, 918.4626993209048, 922.9861552076724, 924.4453328003278, 962.9037096290315, 3608.260306637133, 3696.926061478809, 3776.2825063237897, 3791.2038471921205, 3903.882883513499, 10201.693949181536, 10563.0, 10581.470926453647, 10585.894141058645, 10587.576496940035, 10475.685729428129, 10565.260431769702, 10573.62992295266, 10573.682789516326, 10579.94710026445, 7193.208747037717, 8005.199480051948, 8171.264586187152, 8177.386695997705, 8179.100298967109, 4790.329353890528, 5533.916991245215, 5584.497395234448, 5622.32532096151, 5687.459691329317, 9320.021397753138, 9651.855222171813, 9653.71038868835, 9675.612975480903, 9700.369475984038, 9456.810863782548, 9731.610735570497, 9799.804003920024, 9809.607615695293, 9949.950250248703, 7167.749128780676, 7197.352238298578, 7246.275372462712, 7361.595112268877, 7361.595112268877, 8004.83990320202, 8101.270885620087, 8101.392395570238, 8102.756917292255, 8118.918810811816, 6896.310368963063, 7178.964105179441, 7247.019435324421, 7353.742619008309, 7453.105627324756, 7049.823754406181, 7171.211166771546, 7188.101487314086, 7217.711291548279, 7229.7469588564145, 6708.530402871947, 6838.7264509419065, 6838.931610684029, 6842.076319696777, 6850.657467126524, 4693.55411235932, 4844.394450693663, 4856.781444835062, 4878.268259761065, 4910.459849416633, 4784.40194975628, 4793.7376606282405, 4894.657373983787, 5051.545360917535, 5074.137396642654], "value": 4421.572994237975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.5341946047404, 297.2316561688036, 306.82510968747806, 309.73053443504153, 311.66496016781963, 4938.283948827418, 5123.12906805843, 5155.3040339554145, 5232.843014709555, 5259.737013149342, 3849.653531182193, 4206.200821843855, 4522.439758283387, 5163.7159956202395, 5190.0, 3447.913802154954, 3949.032487040679, 3982.362821948489, 4029.851492324688, 4030.979845100785, 3827.4832897558754, 4019.959800402002, 4021.1756589899146, 4033.5764744701823, 4051.7163798534116, 3885.4754608127964, 3911.393733971239, 3928.3321835287925, 3984.521857377119, 3993.5207775067033, 3962.0387907985732, 3965.9291991162227, 3990.9999999999854, 3991.920161596781, 4004.5594984551676, 3165.9710594057024, 3445.7415693822813, 3452.430348992438, 3467.9826600867086, 3472.2013936794424, 4915.852524424273, 5011.724355160465, 5022.6233032522705, 5039.3196918415915, 5107.693538387709, 4502.324651302298, 4510.188166130095, 4521.422789010266, 4521.615662668677, 4537.342085397632, 4526.977365113154, 4557.498675145731, 4740.739259340735, 5403.78384864605, 5482.615062447848, 3675.6691897729065, 3760.953627561684, 3805.8582425272584, 3813.48517950076, 3829.463875057494, 4839.669090999968, 4859.032092002744, 5182.922256166161, 5354.839354819362, 5639.887202255973, 2973.44991176633, 3042.558828969787, 3703.6296370362747, 3791.6018818024263, 3943.585923478051, 3464.7921124732597, 3997.8001099944886, 4005.619466150711, 4020.3165461871554, 4037.7577345359373, 4556.817727290938, 4577.901303687091, 4610.54767748165, 4617.699849509771, 4651.395318608559, 5244.895102098013, 5367.463253674601, 5482.081271554924, 5495.368032676213, 5503.889922201533, 1246.3643541793751, 1309.240640428549, 1335.9866401336058, 1341.9060665753498, 1353.5668586052368, 757.7499425189674, 767.7044337929825, 791.7862177212236, 841.7769291137853, 846.8560344741286, 4187.811548480265, 4189.350650649154, 4245.8513952011535, 4305.761004277821, 4381.38660587518, 4514.525974772601, 4570.451545814523, 4580.190799660343, 4622.560856718606, 4713.481517033124, 3956.287868183697, 4173.64524015456, 4181.599164279939, 4228.61942425177, 4269.701120921506, 2152.666336717811, 2393.4136136646284, 2561.718210996789, 2929.384829185895, 2956.4826155422716, 5510.8071217507995, 5789.507891829157, 5812.3025236971835, 5837.824865254049, 5879.0, 5563.5798427684085, 5636.403561304721, 5752.539796816244, 5785.363610002896, 5789.739461724233, 4990.9750451248965, 5728.770849165987, 5758.337791153987, 5761.884762304814, 5801.796937107181, 4284.614384705392, 4810.350602668735, 5368.409474957673, 5543.196236545759, 5586.318567087739, 4448.599626033672, 4654.9767251164885, 4885.5847252983895, 4998.925231075336, 5477.260569823023, 4653.371794807657, 4759.381810184554, 5466.72666366688, 5776.884462310814, 5801.76792928295, 3121.5785868907406, 3631.0, 3760.191558814868, 3765.0, 3780.962190378061, 5288.9735551322, 5602.5517958562405, 5629.114246727228, 5646.463585959327, 5684.602077854511, 4617.607503362117, 5328.946710532845, 5388.811391601275, 5425.453745682462, 5468.207109968953, 4224.345226489899, 5123.436421993503, 5378.677279363251, 5393.8112166074, 5408.72956352173, 5004.299398084344, 5005.1241032819835, 5021.84934451967, 5276.709780962084, 5307.41618421981, 4978.775955081957, 5199.050356116456, 5383.300170977748, 5588.469095436089, 5598.0, 4662.230731929194, 5419.214213939035, 5435.2934118564335, 5474.972625136849, 5522.199281104297], "value": 3899.758896082753, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.9930500694993, 701.982450438739, 758.7989182866538, 799.4763429953379, 801.1227705662297, 15169.696606067886, 15353.61615959603, 15379.846201537981, 15393.538193854174, 15399.0, 13869.959753018513, 13871.098378605384, 13888.166709997407, 13896.583102506916, 13897.374618142183, 698.8707089188516, 720.2113685514354, 722.5664601239241, 815.8857759913617, 868.7133246028811, 880.7665968518347, 881.4534988307227, 895.6596493332524, 896.5114012862973, 897.291139999403, 943.8065196634708, 954.0745476887408, 954.5847556312991, 954.9474778887159, 956.4691596164126, 726.836461796097, 751.29003092078, 947.625687853301, 948.5257371314343, 949.3971328206569, 590.3181824992141, 678.9287124851869, 699.7061234281614, 699.7970588529321, 701.0850839654249, 14706.411743530245, 14744.778828317583, 14750.04124731889, 14771.852281477155, 14772.55682329532, 13251.271180085092, 13301.933490332487, 13391.263480508567, 13404.664883377896, 13550.390232439466, 11970.34163121028, 12092.637220883387, 12112.454939527654, 12361.938190309082, 12669.366531673379, 3715.386961151407, 3944.3886197639413, 4411.669124815619, 4599.034202817413, 4612.0, 77914.0, 79370.01574960614, 79854.600726996, 88371.99999999936, 92814.0, 10466.842997355046, 10602.20483463728, 10625.256232063835, 10868.945655271673, 10883.67348979532, 17920.28318867257, 20993.795651456683, 21188.682169767464, 21193.78806211918, 21206.893965530075, 7555.6977720890545, 7644.678611165203, 7839.33365663925, 7908.01149856268, 7935.722249721348, 4118.897027574335, 4185.414042034117, 4276.187524370422, 4307.913841723148, 4323.740575565476, 3410.2497450560836, 3571.7856928583847, 3652.7077833773233, 3665.633436656313, 3665.780053196817, 638.5944924972583, 641.415703213063, 653.2096163641951, 654.9279579246279, 656.0585559721815, 560.4451592923058, 563.7773079633522, 622.7508996401476, 626.5050610018035, 628.277480896963, 8405.663773448994, 8954.820903581893, 9032.0, 9036.683716069905, 9428.057194280516, 538.7979507684619, 588.2646691635456, 635.9904601430982, 638.8307098618869, 666.1040899989506, 4217.493900731871, 4400.207962566705, 4423.137488189772, 4481.305397663367, 4497.595216430537, 68665.65667171632, 71203.0, 99887.50056249817, 105973.94026059796, 113134.86865131243, 80257.19742802613, 83714.16285837063, 102557.48721256346, 102772.0, 104296.47851760844, 75088.62455687686, 76254.0, 85307.57346213353, 88608.67086993829, 90622.09377906268, 8480.872786908078, 8490.533020683923, 8647.740567782977, 8662.480251184948, 8687.431585232413, 52971.47028529819, 53182.46817531775, 54194.187087192935, 54929.176062358296, 57078.0, 59869.700651498206, 60820.0, 71536.92694609481, 79248.60375698315, 82840.0, 13163.868361316265, 13350.933245333712, 13375.598732038054, 13604.455821767018, 13744.931275343559, 14400.0, 14661.120332780067, 14687.779683304545, 14868.405263789327, 15053.473128440452, 12169.452374643339, 13243.404046817725, 13402.664933376354, 13493.527726529524, 13705.862941370458, 13065.738685226052, 13081.411336490071, 13339.933300333436, 13756.17462952186, 13798.862011379757, 12635.873641263837, 12705.17416367933, 12978.221306721627, 13272.734545309233, 13430.0, 11215.71960700989, 11224.438778060901, 11270.323780573191, 11282.717932051435, 11474.251830627241, 11247.66257012291, 11347.943260283646, 11386.0, 11439.542418303176, 11528.654140375802], "value": 7384.2720479685595, "unit": "score"}], "name": "dromaeo_css", "value": 7106.631847285757, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 113.0, 113.0, 112.0, 113.0, 115.0, 160.0, 110.0, 110.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 114.0, 164.0, 114.0, 113.0, 114.0, 114.0, 141.0, 111.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 185.0, 186.0, 186.0, 184.0, 181.0, 180.0, 184.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 60.0, 87.0, 62.0, 62.0, 61.0, 58.0, 82.0, 59.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 87.0, 56.0, 89.0, 54.0, 89.0, 88.0, 98.0, 122.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 87.0, 89.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 97.0, 98.0, 98.0, 97.0, 98.0, 99.0, 98.0, 99.0], "value": 106.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 65.0, 122.0, 127.0, 123.0, 85.0, 82.0, 83.0, 82.0, 80.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 53.0, 51.0, 54.0, 55.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 69.0, 72.0, 75.0, 71.0, 69.0, 69.0, 69.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 97.0, 107.0, 106.0, 85.0, 86.0, 87.0, 90.0, 90.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 133.0, 168.0, 157.0, 156.0, 150.0, 146.0, 145.0, 177.0, 146.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 171.0, 173.0, 171.0, 234.0, 220.0, 184.0, 173.0, 172.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 69.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1490.7, "alertThreshold": 2.0}]} 05:58:35 INFO - Return code: 0 05:58:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:35 INFO - # TBPL SUCCESS # 05:58:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:35 INFO - 2016-10-26 05:58:35.101 screenresolution[2385:30942] starting screenresolution argv=screenresolution get 05:58:35 INFO - 2016-10-26 05:58:35.104 screenresolution[2385:30942] Display 0: 1600x1200x32@60 05:58:35 INFO - 2016-10-26 05:58:35.110 screenresolution[2386:30945] starting screenresolution argv=screenresolution list 05:58:35 INFO - Available Modes on Display 0 05:58:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:35 INFO - Intel Iris: 05:58:35 INFO - Chipset Model: Intel Iris 05:58:35 INFO - Type: GPU 05:58:35 INFO - Bus: Built-In 05:58:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:35 INFO - Vendor: Intel (0x8086) 05:58:35 INFO - Device ID: 0x0a2e 05:58:35 INFO - Revision ID: 0x0009 05:58:35 INFO - Displays: 05:58:35 INFO - Display: 05:58:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:35 INFO - Display Serial Number: 1600x1200 60 05:58:35 INFO - Main Display: Yes 05:58:35 INFO - Mirror: Off 05:58:35 INFO - Online: Yes 05:58:35 INFO - Rotation: Supported 05:58:35 INFO - Return code: 0 05:58:35 INFO - Running post-action listener: _resource_record_post_action 05:58:35 INFO - [mozharness: 2016-10-26 12:58:35.345336Z] Finished run-tests step (success) 05:58:35 INFO - Running post-run listener: _resource_record_post_run 05:58:35 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 557056; Write bytes: 708395008; Read time: 5; Write time: 2005 05:58:35 INFO - TinderboxPrint: CPU usage
53.5% 05:58:35 INFO - TinderboxPrint: I/O read bytes / time
557,056 / 5 05:58:35 INFO - TinderboxPrint: I/O write bytes / time
708,395,008 / 2,005 05:58:35 INFO - TinderboxPrint: CPU idle
2,258.8 (46.5%) 05:58:35 INFO - TinderboxPrint: CPU system
972.5 (20.0%) 05:58:35 INFO - TinderboxPrint: CPU user
1,630.3 (33.5%) 05:58:35 INFO - TinderboxPrint: Swap in / out
404,189,184 / 0 05:58:35 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390801920; Write bytes: 386139648; Read time: 29191; Write time: 738 05:58:35 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 557056; Write bytes: 300579328; Read time: 5; Write time: 1206 05:58:35 INFO - Running post-run listener: _upload_blobber_files 05:58:35 INFO - Blob upload gear active. 05:58:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:35 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 05:58:35 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:58:35 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:58:36 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:37 INFO - (blobuploader) - INFO - Done attempting. 05:58:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:38 INFO - (blobuploader) - INFO - Done attempting. 05:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:41 INFO - (blobuploader) - INFO - Done attempting. 05:58:41 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:41 INFO - Return code: 0 05:58:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00e750ec526d0fc447e554f48dc22546a4e1022a6270c3b699dcc90880e4a63e47ef084938eb9dc6bf9511f7ef7aeb155f1af08c6d791084ef4d664dd4e8ab4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c72ece83a69e7d518a2140aaca9df0442d1cf480e48bf580d797a86b395bd96f4664445ac79bdeab743d9490dafe6ae85695ccdc73f2135590a10dcd9864aee8"} 05:58:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:41 INFO - Contents: 05:58:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00e750ec526d0fc447e554f48dc22546a4e1022a6270c3b699dcc90880e4a63e47ef084938eb9dc6bf9511f7ef7aeb155f1af08c6d791084ef4d664dd4e8ab4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c72ece83a69e7d518a2140aaca9df0442d1cf480e48bf580d797a86b395bd96f4664445ac79bdeab743d9490dafe6ae85695ccdc73f2135590a10dcd9864aee8"} 05:58:41 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:41 INFO - Copying logs to upload dir... 05:58:41 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.832934 ========= master_lag: 2.76 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-26 05:58:44.117878) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 05:58:44.121480) ========= 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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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/Fx-Team/sha512/00e750ec526d0fc447e554f48dc22546a4e1022a6270c3b699dcc90880e4a63e47ef084938eb9dc6bf9511f7ef7aeb155f1af08c6d791084ef4d664dd4e8ab4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c72ece83a69e7d518a2140aaca9df0442d1cf480e48bf580d797a86b395bd96f4664445ac79bdeab743d9490dafe6ae85695ccdc73f2135590a10dcd9864aee8"} build_url:https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/Ti88ZL1iQmeBR1QqwBzmMA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00e750ec526d0fc447e554f48dc22546a4e1022a6270c3b699dcc90880e4a63e47ef084938eb9dc6bf9511f7ef7aeb155f1af08c6d791084ef4d664dd4e8ab4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25e423910249444b2c0619211a1c1fdb73f76935158447518b579b183e2735790b48f3f7c815b2f54f229d87a46fcbbb2bcbce62d6974f5cff3b1fcb6f3dca70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c72ece83a69e7d518a2140aaca9df0442d1cf480e48bf580d797a86b395bd96f4664445ac79bdeab743d9490dafe6ae85695ccdc73f2135590a10dcd9864aee8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 05:58:44.164220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-26 05:58:44.164534) ========= 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.vGrGhpDZgH/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.sHMbq5D9h4/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.005043 ========= master_lag: 4.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-26 05:58:48.181092) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-10-26 05:58:48.181362) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-10-26 05:58:52.632689) ========= ========= Total master_lag: 6.88 =========