builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0383 starttime: 1477275109.19 results: success (0) buildid: 20161023170622 builduid: b585e8e56e64460e9c51d3ac4d3bdbb5 revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.187897) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.188736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.189071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.212054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.212351) ========= 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.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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-23 19:11:49-- 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% 7.82M=0.001s 2016-10-23 19:11:49 (7.82 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.624355 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.884870) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.885256) ========= 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.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.019868 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:49.949865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-23 19:11:49.950276) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 215f9686117673a2c914ed207bc7da9bb8d741ad --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 215f9686117673a2c914ed207bc7da9bb8d741ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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-23 19:11:50,033 truncating revision to first 12 chars 2016-10-23 19:11:50,033 Setting DEBUG logging. 2016-10-23 19:11:50,033 attempt 1/10 2016-10-23 19:11:50,033 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/215f96861176?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-23 19:11:51,974 unpacking tar archive at: fx-team-215f96861176/testing/mozharness/ program finished with exit code 0 elapsedTime=2.185557 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-23 19:11:52.163619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:52.163994) ========= script_repo_revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:52.164435) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:52.164736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 19:11:52.184223) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-23 19:11:52.184562) ========= /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.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:11:52 INFO - MultiFileLogger online at 20161023 19:11:52 in /builds/slave/test 19:11:52 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 19:11:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:11:52 INFO - {'append_to_log': False, 19:11:52 INFO - 'base_work_dir': '/builds/slave/test', 19:11:52 INFO - 'blob_upload_branch': 'Fx-Team', 19:11:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:11:52 INFO - 'branch': 'Fx-Team', 19:11:52 INFO - 'buildbot_json_path': 'buildprops.json', 19:11:52 INFO - 'config_files': ('talos/mac_config.py',), 19:11:52 INFO - 'default_actions': ('clobber', 19:11:52 INFO - 'read-buildbot-config', 19:11:52 INFO - 'download-and-extract', 19:11:52 INFO - 'populate-webroot', 19:11:52 INFO - 'create-virtualenv', 19:11:52 INFO - 'install', 19:11:52 INFO - 'run-tests'), 19:11:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:11:52 INFO - 'download_minidump_stackwalk': True, 19:11:52 INFO - 'download_symbols': 'ondemand', 19:11:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:11:52 INFO - 'tooltool.py': '/tools/tooltool.py', 19:11:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:11:52 INFO - '/tools/misc-python/virtualenv.py')}, 19:11:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:11:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:11:52 INFO - 'installer_path': 'installer.exe', 19:11:52 INFO - 'log_level': 'info', 19:11:52 INFO - 'log_name': 'talos', 19:11:52 INFO - 'log_to_console': True, 19:11:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:11:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:11:52 INFO - 'opt_config_files': (), 19:11:52 INFO - 'pip_index': False, 19:11:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:52 INFO - 'cmd': ('bash', 19:11:52 INFO - '-c', 19:11:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:52 INFO - 'enabled': True, 19:11:52 INFO - 'halt_on_failure': False, 19:11:52 INFO - 'name': 'check_screen_resolution'},), 19:11:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:52 INFO - 'cmd': ('bash', 19:11:52 INFO - '-c', 19:11:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:52 INFO - 'enabled': True, 19:11:52 INFO - 'halt_on_failure': False, 19:11:52 INFO - 'name': 'check_screen_resolution'},), 19:11:52 INFO - 'run_cmd_checks_enabled': True, 19:11:52 INFO - 'sps_profile': False, 19:11:52 INFO - 'sps_profile_interval': 0, 19:11:52 INFO - 'suite': 'dromaeojs', 19:11:52 INFO - 'system_bits': '32', 19:11:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:11:52 INFO - 'title': 't-yosemite-r7-0383', 19:11:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:11:52 INFO - 'use_talos_json': True, 19:11:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:11:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:11:52 INFO - 'work_dir': 'build'} 19:11:52 INFO - [mozharness: 2016-10-24 02:11:52.379341Z] Running clobber step. 19:11:52 INFO - Running pre-action listener: _resource_record_pre_action 19:11:52 INFO - Running main action method: clobber 19:11:52 INFO - rmtree: /builds/slave/test/build 19:11:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:11:53 INFO - Running post-action listener: _resource_record_post_action 19:11:53 INFO - [mozharness: 2016-10-24 02:11:53.299128Z] Finished clobber step (success) 19:11:53 INFO - [mozharness: 2016-10-24 02:11:53.299289Z] Running read-buildbot-config step. 19:11:53 INFO - Running pre-action listener: _resource_record_pre_action 19:11:53 INFO - Running main action method: read_buildbot_config 19:11:53 INFO - Using buildbot properties: 19:11:53 INFO - { 19:11:53 INFO - "project": "", 19:11:53 INFO - "product": "firefox", 19:11:53 INFO - "script_repo_revision": "production", 19:11:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:11:53 INFO - "repository": "", 19:11:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:11:53 INFO - "buildid": "20161023170622", 19:11:53 INFO - "pgo_build": "False", 19:11:53 INFO - "basedir": "/builds/slave/test", 19:11:53 INFO - "buildnumber": 172, 19:11:53 INFO - "slavename": "t-yosemite-r7-0383", 19:11:53 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:11:53 INFO - "platform": "yosemite_r7", 19:11:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 19:11:53 INFO - "branch": "fx-team", 19:11:53 INFO - "repo_path": "integration/fx-team", 19:11:53 INFO - "revision": "215f9686117673a2c914ed207bc7da9bb8d741ad", 19:11:53 INFO - "stage_platform": "macosx64", 19:11:53 INFO - "builduid": "b585e8e56e64460e9c51d3ac4d3bdbb5", 19:11:53 INFO - "slavebuilddir": "test" 19:11:53 INFO - } 19:11:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:11:53 INFO - Running post-action listener: _resource_record_post_action 19:11:53 INFO - [mozharness: 2016-10-24 02:11:53.302771Z] Finished read-buildbot-config step (success) 19:11:53 INFO - [mozharness: 2016-10-24 02:11:53.302862Z] Running download-and-extract step. 19:11:53 INFO - Running pre-action listener: _resource_record_pre_action 19:11:53 INFO - Running main action method: download_and_extract 19:11:53 INFO - mkdir: /builds/slave/test/build/tests 19:11:53 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')]} 19:11:53 INFO - trying https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/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 19:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/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 19:11:54 INFO - Downloaded 1199 bytes. 19:11:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:11:54 INFO - Using the following test package requirements: 19:11:54 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:11:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:11:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:11:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'jsshell-mac.zip'], 19:11:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:11:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:11:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:11:54 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:11:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:11:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:11:54 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:11:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:11:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:11:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:11:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:11:54 INFO - Http code: 200 19:11:54 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 19:11:54 INFO - x-amz-cf-id: zj6_mfWd_-ZoPkO9VkY-AKbi3W3BruuK_JUJr1S28Ov_s7lef6axQQ== 19:11:54 INFO - x-amz-version-id: BOJpiaP_s2AW6rojQaFtoF5wuOXcFT7C 19:11:55 INFO - Expected file size: 16409429 19:11:55 INFO - Obtained file size: 16409429 19:12:02 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 19:12:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:12:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:12:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:12:02 INFO - Http code: 200 19:12:02 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 19:12:02 INFO - x-amz-cf-id: S4TN5fq6ckREshwW1VQUnYapBGvtjXQYhhW74Cl-QPOOnKz8FVTZhg== 19:12:02 INFO - x-amz-version-id: BOJpiaP_s2AW6rojQaFtoF5wuOXcFT7C 19:12:02 INFO - Expected file size: 16409429 19:12:02 INFO - Obtained file size: 16409429 19:12:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:12:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:12:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:12:10 INFO - Http code: 200 19:12:10 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 19:12:10 INFO - x-amz-cf-id: gJawutikPXIG4RwzBZuhXcJEfL7jorI5ev4dVkGwv-4H33ZRqqdBrg== 19:12:10 INFO - x-amz-version-id: lnQfvW0F07uoK7nWBQ5doN6W2C4WiMxF 19:12:11 INFO - Expected file size: 14409028 19:12:11 INFO - Obtained file size: 14409028 19:12:11 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')]} 19:12:11 INFO - trying https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:12:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/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 19:12:14 INFO - Downloaded 111184849 bytes. 19:12:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:12:14 INFO - mkdir: /builds/slave/test/properties 19:12:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:14 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:14 INFO - Contents: 19:12:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:12:15 INFO - Running post-action listener: _resource_record_post_action 19:12:15 INFO - Running post-action listener: set_extra_try_arguments 19:12:15 INFO - [mozharness: 2016-10-24 02:12:15.079343Z] Finished download-and-extract step (success) 19:12:15 INFO - [mozharness: 2016-10-24 02:12:15.079470Z] Running populate-webroot step. 19:12:15 INFO - Running pre-action listener: _resource_record_pre_action 19:12:15 INFO - Running main action method: populate_webroot 19:12:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:15 INFO - '--tppagecycles', 19:12:15 INFO - '7'], 19:12:15 INFO - 'tests': ['tsvgm']}}, 19:12:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['damp', 'tps']}, 19:12:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'tests': ['damp', 'tps']}, 19:12:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['dromaeo_dom']}, 19:12:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['basic_compositor_video']}, 19:12:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:12:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['a11yr', 19:12:15 INFO - 'ts_paint', 19:12:15 INFO - 'tpaint', 19:12:15 INFO - 'sessionrestore', 19:12:15 INFO - 'sessionrestore_no_auto_restore', 19:12:15 INFO - 'tabpaint']}, 19:12:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['tsvgx', 19:12:15 INFO - 'tsvgr_opacity', 19:12:15 INFO - 'tart', 19:12:15 INFO - 'tscrollx', 19:12:15 INFO - 'cart']}, 19:12:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:15 INFO - 'tsvgr_opacity', 19:12:15 INFO - 'tart', 19:12:15 INFO - 'tscrollx', 19:12:15 INFO - 'cart']}, 19:12:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'talos_options': ['--disable-e10s'], 19:12:15 INFO - 'tests': ['tp5o']}, 19:12:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'tests': ['tp5o']}, 19:12:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'talos_options': ['--disable-e10s', 19:12:15 INFO - '--xperf_path', 19:12:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:15 INFO - 'tests': ['tp5n']}, 19:12:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:15 INFO - 'talos_options': ['--xperf_path', 19:12:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:15 INFO - 'tests': ['tp5n']}}, 19:12:15 INFO - 'talos.zip': {'path': '', 19:12:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:15 INFO - Running post-action listener: _resource_record_post_action 19:12:15 INFO - [mozharness: 2016-10-24 02:12:15.098091Z] Finished populate-webroot step (success) 19:12:15 INFO - [mozharness: 2016-10-24 02:12:15.098221Z] Running create-virtualenv step. 19:12:15 INFO - Running pre-action listener: _resource_record_pre_action 19:12:15 INFO - Running main action method: create_virtualenv 19:12:15 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:15 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 19:12:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:12:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:15 INFO - Using real prefix '/tools/python27' 19:12:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:15 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:18 INFO - Installing pip.................done. 19:12:18 INFO - Return code: 0 19:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:12:19 INFO - Reading from file tmpfile_stdout 19:12:19 INFO - Output received: 19:12:19 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) 19:12:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12: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', 'pip>=1.5'] in /builds/slave/test/build 19:12: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 pip>=1.5 19:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:19 INFO - 'HOME': '/Users/cltbld', 19:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:19 INFO - 'LOGNAME': 'cltbld', 19:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:19 INFO - 'MOZ_NO_REMOTE': '1', 19:12:19 INFO - 'NO_EM_RESTART': '1', 19:12:19 INFO - 'PAGER': '/bin/cat', 19:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:19 INFO - 'PWD': '/builds/slave/test', 19:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:19 INFO - 'SHELL': '/bin/bash', 19:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:19 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:19 INFO - 'USER': 'cltbld', 19:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:19 INFO - 'XPC_FLAGS': '0x0', 19:12:19 INFO - 'XPC_SERVICE_NAME': '0', 19:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:19 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 19:12:19 INFO - Cleaning up... 19:12:19 INFO - Return code: 0 19:12:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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', '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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:12: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 psutil>=3.1.1 19:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:19 INFO - 'HOME': '/Users/cltbld', 19:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:19 INFO - 'LOGNAME': 'cltbld', 19:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:19 INFO - 'MOZ_NO_REMOTE': '1', 19:12:19 INFO - 'NO_EM_RESTART': '1', 19:12:19 INFO - 'PAGER': '/bin/cat', 19:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:19 INFO - 'PWD': '/builds/slave/test', 19:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:19 INFO - 'SHELL': '/bin/bash', 19:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:19 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:19 INFO - 'USER': 'cltbld', 19:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:19 INFO - 'XPC_FLAGS': '0x0', 19:12:19 INFO - 'XPC_SERVICE_NAME': '0', 19:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:19 INFO - Downloading/unpacking psutil>=3.1.1 19:12:19 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 19:12:19 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 19:12:19 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 19:12:19 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 19:12:19 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 19:12:19 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 19:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:23 INFO - Installing collected packages: psutil 19:12:23 INFO - Running setup.py install for psutil 19:12:23 INFO - building 'psutil._psutil_osx' extension 19:12:23 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 19:12:23 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 19:12:23 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 19:12:24 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 19:12:24 INFO - building 'psutil._psutil_posix' extension 19:12:24 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 19:12:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:12:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:12:24 INFO - ^ 19:12:24 INFO - 1 warning generated. 19:12:24 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 19:12:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:24 INFO - Successfully installed psutil 19:12:24 INFO - Cleaning up... 19:12:24 INFO - Return code: 0 19:12:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12:24 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')]} 19:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:24 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')]} 19:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:24 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:24 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 19:12:24 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 19:12:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:24 INFO - 'HOME': '/Users/cltbld', 19:12:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:24 INFO - 'LOGNAME': 'cltbld', 19:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:24 INFO - 'MOZ_NO_REMOTE': '1', 19:12:24 INFO - 'NO_EM_RESTART': '1', 19:12:24 INFO - 'PAGER': '/bin/cat', 19:12:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:24 INFO - 'PWD': '/builds/slave/test', 19:12:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:24 INFO - 'SHELL': '/bin/bash', 19:12:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:24 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:24 INFO - 'USER': 'cltbld', 19:12:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:24 INFO - 'XPC_FLAGS': '0x0', 19:12:24 INFO - 'XPC_SERVICE_NAME': '0', 19:12:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12:24 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 19:12:24 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 19:12:24 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 19:12:24 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 19:12:24 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 19:12:24 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 19:12:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:27 INFO - Installing collected packages: mozsystemmonitor 19:12:27 INFO - Running setup.py install for mozsystemmonitor 19:12:27 INFO - Successfully installed mozsystemmonitor 19:12:27 INFO - Cleaning up... 19:12:27 INFO - Return code: 0 19:12:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:27 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:27 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 19:12:27 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 19:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:27 INFO - 'HOME': '/Users/cltbld', 19:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:27 INFO - 'LOGNAME': 'cltbld', 19:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:27 INFO - 'MOZ_NO_REMOTE': '1', 19:12:27 INFO - 'NO_EM_RESTART': '1', 19:12:27 INFO - 'PAGER': '/bin/cat', 19:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:27 INFO - 'PWD': '/builds/slave/test', 19:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:27 INFO - 'SHELL': '/bin/bash', 19:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:27 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:27 INFO - 'USER': 'cltbld', 19:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:27 INFO - 'XPC_FLAGS': '0x0', 19:12:27 INFO - 'XPC_SERVICE_NAME': '0', 19:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:27 INFO - Downloading/unpacking jsonschema==2.5.1 19:12:27 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 19:12:27 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 19:12:27 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 19:12:27 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 19:12:27 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 19:12:27 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 19:12:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:30 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 19:12:30 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 19:12:30 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 19:12:30 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 19:12:30 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 19:12:30 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 19:12:30 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:30 INFO - warning: no files found matching '*.txt' 19:12:30 INFO - no previously-included directories found matching 'build' 19:12:30 INFO - no previously-included directories found matching 'dist' 19:12:30 INFO - no previously-included directories found matching '.git*' 19:12:30 INFO - Installing collected packages: jsonschema, functools32 19:12:30 INFO - Running setup.py install for functools32 19:12:30 INFO - warning: no files found matching '*.txt' 19:12:30 INFO - no previously-included directories found matching 'build' 19:12:30 INFO - no previously-included directories found matching 'dist' 19:12:30 INFO - no previously-included directories found matching '.git*' 19:12:30 INFO - Successfully installed jsonschema functools32 19:12:30 INFO - Cleaning up... 19:12:30 INFO - Return code: 0 19:12:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:30 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')]} 19:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:30 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')]} 19:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:30 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:30 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 19:12:30 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 19:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:30 INFO - 'HOME': '/Users/cltbld', 19:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:30 INFO - 'LOGNAME': 'cltbld', 19:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:30 INFO - 'MOZ_NO_REMOTE': '1', 19:12:30 INFO - 'NO_EM_RESTART': '1', 19:12:30 INFO - 'PAGER': '/bin/cat', 19:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:30 INFO - 'PWD': '/builds/slave/test', 19:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:30 INFO - 'SHELL': '/bin/bash', 19:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:30 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:30 INFO - 'USER': 'cltbld', 19:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:30 INFO - 'XPC_FLAGS': '0x0', 19:12:30 INFO - 'XPC_SERVICE_NAME': '0', 19:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:31 INFO - Cleaning up... 19:12:31 INFO - Return code: 0 19:12:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:31 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:31 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 19:12:31 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 19:12:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:31 INFO - 'HOME': '/Users/cltbld', 19:12:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:31 INFO - 'LOGNAME': 'cltbld', 19:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:31 INFO - 'MOZ_NO_REMOTE': '1', 19:12:31 INFO - 'NO_EM_RESTART': '1', 19:12:31 INFO - 'PAGER': '/bin/cat', 19:12:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:31 INFO - 'PWD': '/builds/slave/test', 19:12:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:31 INFO - 'SHELL': '/bin/bash', 19:12:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:31 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:31 INFO - 'USER': 'cltbld', 19:12:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:31 INFO - 'XPC_FLAGS': '0x0', 19:12:31 INFO - 'XPC_SERVICE_NAME': '0', 19:12:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:12:31 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 19:12:31 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 19:12:31 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 19:12:31 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 19:12:31 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 19:12:31 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 19:12:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:35 INFO - Downloading docopt-0.6.1.tar.gz 19:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:35 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:35 INFO - Running setup.py install for blobuploader 19:12:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:35 INFO - Running setup.py install for requests 19:12:35 INFO - Running setup.py install for docopt 19:12:36 INFO - Successfully installed blobuploader requests docopt 19:12:36 INFO - Cleaning up... 19:12:36 INFO - Return code: 0 19:12:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:36 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')]} 19:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:36 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')]} 19:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:36 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:36 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 19:12:36 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 19:12:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:36 INFO - 'HOME': '/Users/cltbld', 19:12:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:36 INFO - 'LOGNAME': 'cltbld', 19:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:36 INFO - 'MOZ_NO_REMOTE': '1', 19:12:36 INFO - 'NO_EM_RESTART': '1', 19:12:36 INFO - 'PAGER': '/bin/cat', 19:12:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:36 INFO - 'PWD': '/builds/slave/test', 19:12:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:36 INFO - 'SHELL': '/bin/bash', 19:12:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:36 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:36 INFO - 'USER': 'cltbld', 19:12:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:36 INFO - 'XPC_FLAGS': '0x0', 19:12:36 INFO - 'XPC_SERVICE_NAME': '0', 19:12:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hG29_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-2CSb5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KKJpb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-TBb0Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-MOVS32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:36 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-moOm53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-3bYGpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-i4HQse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-D3FDRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-o2FmKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-23vnRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-oxZXDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-R5zmzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-DuQDuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-XChE5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-GodxDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-DaBBC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:38 INFO - Running setup.py install for manifestparser 19:12:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:38 INFO - Running setup.py install for mozcrash 19:12:38 INFO - Running setup.py install for mozdebug 19:12:38 INFO - Running setup.py install for mozdevice 19:12:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:12:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:38 INFO - Running setup.py install for mozfile 19:12:38 INFO - Running setup.py install for mozhttpd 19:12:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Running setup.py install for mozinfo 19:12:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Running setup.py install for mozInstall 19:12:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Running setup.py install for mozleak 19:12:39 INFO - Running setup.py install for mozlog 19:12:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Running setup.py install for moznetwork 19:12:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:39 INFO - Running setup.py install for mozprocess 19:12:40 INFO - Running setup.py install for mozprofile 19:12:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:40 INFO - Running setup.py install for mozrunner 19:12:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:40 INFO - Running setup.py install for mozscreenshot 19:12:40 INFO - Running setup.py install for moztest 19:12:40 INFO - Running setup.py install for mozversion 19:12:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:40 INFO - Cleaning up... 19:12:40 INFO - Return code: 0 19:12:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:40 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')]} 19:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:40 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')]} 19:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:40 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:40 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 19:12:40 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 19:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:40 INFO - 'HOME': '/Users/cltbld', 19:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:40 INFO - 'LOGNAME': 'cltbld', 19:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:40 INFO - 'MOZ_NO_REMOTE': '1', 19:12:40 INFO - 'NO_EM_RESTART': '1', 19:12:40 INFO - 'PAGER': '/bin/cat', 19:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:40 INFO - 'PWD': '/builds/slave/test', 19:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:40 INFO - 'SHELL': '/bin/bash', 19:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:40 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:40 INFO - 'USER': 'cltbld', 19:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:40 INFO - 'XPC_FLAGS': '0x0', 19:12:40 INFO - 'XPC_SERVICE_NAME': '0', 19:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hYO5D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-R8wuDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-CJnSv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-S2tykh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Z_eLl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-RSkJhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-x3Jldn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tcdSKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:41 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)) 19:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-__jG6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-NMh7jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tTs5SE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-nNpeI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-FYnSuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-kKwFhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-bt6lbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ogaBX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:42 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)) 19:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-yQCXfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:42 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)) 19:12:42 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)) 19:12:42 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)) 19:12:42 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)) 19:12:42 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)) 19:12:42 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)) 19:12:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:45 INFO - Downloading blessings-1.6.tar.gz 19:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:45 INFO - Installing collected packages: blessings 19:12:45 INFO - Running setup.py install for blessings 19:12:45 INFO - Successfully installed blessings 19:12:45 INFO - Cleaning up... 19:12:45 INFO - Return code: 0 19:12:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:46 INFO - Reading from file tmpfile_stdout 19:12:46 INFO - Current package versions: 19:12:46 INFO - blessings == 1.6 19:12:46 INFO - blobuploader == 1.2.4 19:12:46 INFO - docopt == 0.6.1 19:12:46 INFO - functools32 == 3.2.3-2 19:12:46 INFO - jsonschema == 2.5.1 19:12:46 INFO - manifestparser == 1.1 19:12:46 INFO - mozInstall == 1.12 19:12:46 INFO - mozcrash == 0.17 19:12:46 INFO - mozdebug == 0.1 19:12:46 INFO - mozdevice == 0.48 19:12:46 INFO - mozfile == 1.2 19:12:46 INFO - mozhttpd == 0.7 19:12:46 INFO - mozinfo == 0.9 19:12:46 INFO - mozleak == 0.1 19:12:46 INFO - mozlog == 3.4 19:12:46 INFO - moznetwork == 0.27 19:12:46 INFO - mozprocess == 0.23 19:12:46 INFO - mozprofile == 0.28 19:12:46 INFO - mozrunner == 6.12 19:12:46 INFO - mozscreenshot == 0.1 19:12:46 INFO - mozsystemmonitor == 0.3 19:12:46 INFO - moztest == 0.7 19:12:46 INFO - mozversion == 1.4 19:12:46 INFO - psutil == 3.1.1 19:12:46 INFO - requests == 1.2.3 19:12:46 INFO - wsgiref == 0.1.2 19:12:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:46 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')]} 19:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 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')]} 19:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:46 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 19:12:46 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 19:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:46 INFO - 'HOME': '/Users/cltbld', 19:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:46 INFO - 'LOGNAME': 'cltbld', 19:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:46 INFO - 'MOZ_NO_REMOTE': '1', 19:12:46 INFO - 'NO_EM_RESTART': '1', 19:12:46 INFO - 'PAGER': '/bin/cat', 19:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:46 INFO - 'PWD': '/builds/slave/test', 19:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:46 INFO - 'SHELL': '/bin/bash', 19:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:46 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:46 INFO - 'USER': 'cltbld', 19:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:46 INFO - 'XPC_FLAGS': '0x0', 19:12:46 INFO - 'XPC_SERVICE_NAME': '0', 19:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 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)) 19:12:46 INFO - Cleaning up... 19:12:46 INFO - Return code: 0 19:12:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12:46 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')]} 19:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 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')]} 19:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 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 0x10f5d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe58c87ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, '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.YyhIK93JIP/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.PLE6aIr3KP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 19:12:46 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 19:12:46 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 19:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:46 INFO - 'HOME': '/Users/cltbld', 19:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:46 INFO - 'LOGNAME': 'cltbld', 19:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:46 INFO - 'MOZ_NO_REMOTE': '1', 19:12:46 INFO - 'NO_EM_RESTART': '1', 19:12:46 INFO - 'PAGER': '/bin/cat', 19:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:46 INFO - 'PWD': '/builds/slave/test', 19:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:46 INFO - 'SHELL': '/bin/bash', 19:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:12:46 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:46 INFO - 'USER': 'cltbld', 19:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:46 INFO - 'XPC_FLAGS': '0x0', 19:12:46 INFO - 'XPC_SERVICE_NAME': '0', 19:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:46 INFO - Cleaning up... 19:12:46 INFO - Return code: 0 19:12:46 INFO - Running post-action listener: _resource_record_post_action 19:12:46 INFO - Running post-action listener: _start_resource_monitoring 19:12:46 INFO - Starting resource monitoring. 19:12:46 INFO - [mozharness: 2016-10-24 02:12:46.639874Z] Finished create-virtualenv step (success) 19:12:46 INFO - [mozharness: 2016-10-24 02:12:46.640922Z] Running install step. 19:12:46 INFO - Running pre-action listener: _resource_record_pre_action 19:12:46 INFO - Running main action method: install 19:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:46 INFO - Reading from file tmpfile_stdout 19:12:46 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:46 INFO - Reading from file tmpfile_stdout 19:12:46 INFO - Output received: 19:12:46 INFO - Usage: mozinstall [options] installer 19:12:46 INFO - Options: 19:12:46 INFO - -h, --help show this help message and exit 19:12:46 INFO - -d DEST, --destination=DEST 19:12:46 INFO - Directory to install application into. [default: 19:12:46 INFO - "/builds/slave/test"] 19:12:46 INFO - --app=APP Application being installed. [default: firefox] 19:12:46 INFO - mkdir: /builds/slave/test/build/application 19:12:46 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'] 19:12:46 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 19:13:21 INFO - Reading from file tmpfile_stdout 19:13:21 INFO - Output received: 19:13:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:13:21 INFO - Running post-action listener: _resource_record_post_action 19:13:21 INFO - [mozharness: 2016-10-24 02:13:21.245152Z] Finished install step (success) 19:13:21 INFO - [mozharness: 2016-10-24 02:13:21.245315Z] Running run-tests step. 19:13:21 INFO - Running pre-action listener: _resource_record_pre_action 19:13:21 INFO - Running main action method: run_tests 19:13:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:13:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:13:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:13:21 INFO - 2016-10-23 19:13:21.260 screenresolution[2248:19084] starting screenresolution argv=screenresolution get 19:13:21 INFO - 2016-10-23 19:13:21.262 screenresolution[2248:19084] Display 0: 1600x1200x32@60 19:13:21 INFO - 2016-10-23 19:13:21.268 screenresolution[2249:19086] starting screenresolution argv=screenresolution list 19:13:21 INFO - Available Modes on Display 0 19:13:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:13:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:13:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:13:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:13:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:13:21 INFO - Intel Iris: 19:13:21 INFO - Chipset Model: Intel Iris 19:13:21 INFO - Type: GPU 19:13:21 INFO - Bus: Built-In 19:13:21 INFO - VRAM (Dynamic, Max): 1536 MB 19:13:21 INFO - Vendor: Intel (0x8086) 19:13:21 INFO - Device ID: 0x0a2e 19:13:21 INFO - Revision ID: 0x0009 19:13:21 INFO - Displays: 19:13:21 INFO - Display: 19:13:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:13:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:13:21 INFO - Display Serial Number: 1600x1200 60 19:13:21 INFO - Main Display: Yes 19:13:21 INFO - Mirror: Off 19:13:21 INFO - Online: Yes 19:13:21 INFO - Rotation: Supported 19:13:21 INFO - Return code: 0 19:13:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:13:21 INFO - Python 2.7.3 19:13:21 INFO - Return code: 0 19:13:21 INFO - grabbing minidump binary from tooltool 19:13:21 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')]} 19:13:21 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 0x10f3e9d30>, '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 0x10f20fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:13:21 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 19:13:21 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 19:13:21 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 19:13:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:21 INFO - Return code: 0 19:13:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:13:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:13:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:13:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:13:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:13:21 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/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 19:13:21 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/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 19:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YyhIK93JIP/Render', 19:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:21 INFO - 'HOME': '/Users/cltbld', 19:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:21 INFO - 'LOGNAME': 'cltbld', 19:13:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:21 INFO - 'MOZ_NO_REMOTE': '1', 19:13:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:21 INFO - 'NO_EM_RESTART': '1', 19:13:21 INFO - 'PAGER': '/bin/cat', 19:13:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:21 INFO - 'PWD': '/builds/slave/test', 19:13:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:21 INFO - 'SHELL': '/bin/bash', 19:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PLE6aIr3KP/Listeners', 19:13:21 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 19:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:21 INFO - 'USER': 'cltbld', 19:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:21 INFO - 'XPC_FLAGS': '0x0', 19:13:21 INFO - 'XPC_SERVICE_NAME': '0', 19:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:21 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/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 19:13:21 INFO - mozversion application_buildid: 20161023170622 19:13:21 INFO - mozversion application_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 19:13:21 INFO - mozversion application_display_name: Nightly 19:13:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:21 INFO - mozversion application_name: Firefox 19:13:21 INFO - mozversion application_remotingname: firefox 19:13:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:13:21 INFO - mozversion application_vendor: Mozilla 19:13:21 INFO - mozversion application_version: 52.0a1 19:13:21 INFO - mozversion platform_buildid: 20161023170622 19:13:21 INFO - mozversion platform_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 19:13:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:13:21 INFO - mozversion platform_version: 52.0a1 19:13:21 INFO - using testdate: 1477275201 19:13:21 INFO - actual date: 1477275201 19:13:21 INFO - starting webserver on 'localhost:52410' 19:13:21 INFO - SUITE-START | Running 2 tests 19:13:21 INFO - TEST-START | dromaeo_css 19:13:21 INFO - operating with platform_type : mac_ 19:13:21 INFO - Initialising browser for dromaeo_css test... 19:13:21 INFO - TEST-INFO | started process 2259 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpFVbWGx/profile http://localhost:52410/getInfo.html) 19:13:24 INFO - PROCESS | 2259 | __metrics Screen width/height:1600/1200 19:13:24 INFO - PROCESS | 2259 | colorDepth:24 19:13:24 INFO - PROCESS | 2259 | Browser inner width/height: 1024/689 19:13:24 INFO - PROCESS | 2259 | __metrics 19:13:24 INFO - PROCESS | 2259 | 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" 19:13:24 INFO - PROCESS | 2259 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:24 INFO - TEST-INFO | 2259: exit 0 19:13:24 INFO - Browser initialized. 19:13:24 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:24 INFO - TEST-INFO | started process 2261 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpFVbWGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:36 INFO - PROCESS | 2261 | RSS: Main: 240431104 19:13:36 INFO - PROCESS | 2261 | 19:14:27 INFO - PROCESS | 2261 | 1477275267794 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] 19:16:40 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52410/tests/dromaeo/cssquery-ext.html) 19:16:42 INFO - PROCESS | 2261 | RSS: Main: 353128448 19:16:42 INFO - PROCESS | 2261 | 19:19:45 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-ext.html (next: http://localhost:52410/tests/dromaeo/cssquery-jquery.html) 19:19:45 INFO - PROCESS | 2261 | RSS: Main: 370880512 19:19:45 INFO - PROCESS | 2261 | 19:22:48 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52410/tests/dromaeo/cssquery-mootools.html) 19:22:48 INFO - PROCESS | 2261 | RSS: Main: 329932800 19:22:48 INFO - PROCESS | 2261 | 19:25:51 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52410/tests/dromaeo/cssquery-prototype.html) 19:25:51 INFO - PROCESS | 2261 | RSS: Main: 347525120 19:25:51 INFO - PROCESS | 2261 | 19:28:55 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52410/tests/dromaeo/cssquery-yui.html) 19:28:55 INFO - PROCESS | 2261 | RSS: Main: 326344704 19:28:55 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:52410/tests/dromaeo/cssquery-yui.html (next: http://localhost:52410/tests/dromaeo/cssquery-dojo.html) 19:31:59 INFO - PROCESS | 2261 | RSS: Main: 418713600 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | __start_tp_report 19:31:59 INFO - PROCESS | 2261 | _x_x_mozilla_page_load 19:31:59 INFO - PROCESS | 2261 | _x_x_mozilla_page_load_details 19:31:59 INFO - PROCESS | 2261 | |i|pagename|runs| 19:31:59 INFO - PROCESS | 2261 | |0;dojo.html;3311;3312.784668996515;3315.3866534691083;3335.4496508076168;3360.1599600099976;5540.252065971093;5607.831765047045;5667.4615911488345;5669.574781891359;5682.914756278654;5638.984982703122;5675.801346952858;5676.063449530822;5680.289963754531;5685.260916080903;5122.154844450662;5154.149565321717;5163.044836705203;5188.066148093341;5190.662606930557;5215.843524694265;5226.843194704165;5248.5276325130735;5256.316678831747;5263.947360526384;4775.710558149315;4852.126617208901;5262.631615786897;5327.014502317064;5377.569794416457;558.9649225907767;5284.682919024852;5296.390915044781;5300.231466437345;5350.5987050971335;3121.9865440532617;5154.639175257696;5170.069387510247;5200.323957885488;5266.789328426858;5565.332160140769;5670.7731690732735;5679.062954612484;5696.259486266758;5717.371089180187;6235.657038862861;6251.718672659741;6256.0303153011355;6267.4986001119805;6270.498360131179;6104.96947515264;6112.1442997979875;6133.983044923954;6145.723442445101;6166.969165154191;5764.855878602984;5770.596058275923;5771.740271687784;5781.595288329819;5786.2477877876445;6224.719887605069;6242.1261023455845;6248.4688801451475;6248.562600618004;6251.374862513712;5994.580379373402;6051.001584738557;6067.24159480065;6088.939110608837;6191.164192834;6127.89207890682;6178.351273116348;6255.937440625536;6260.874782504325;6265.4361107500545;6165.0444181152;6203.534734894811;6210.130581718563;6210.968945155336;6220.782272620437;6075.878482430327;6143.354947730424;6196.225471816023;6235.625862448177;6247.375262473716;4862.197737373364;4875.488073752276;4893.608511319086;4894.119058569491;4944.802207911715;4372.125574885035;4376.868693939187;4387.27609944362;4399.934000989988;4412.558744125562;5672.8865422691315;5731.512821410227;5736.397678243724;5741.7990370337675;5751.051076572402;5873.353931067579;5974.014287642491;6002.279726432856;6202.317745048041;6242.344553821874;5781.82654520365;5793.478586927113;5798.855028624318;5819.127130930309;5840.065589505659;5396.892062158813;5399.811006614749;5403.108487099663;5407.567394608422;5413.512783849394;6218;6231.096491008688;6276.089966954857;6281.497480201573;6292.937070629235;5123.718195499283;6140.688304616564;6195.1636529068;6206.348333424925;6281.4660753834605;5298.602604804578;6187.659678717714;6213.2233470816145;6245.750169993149;6286.6856657166045;6040.09398590215;6125.356837532173;6151.32335443092;6175.382461753788;6194.0708893664505;6135.5695317960535;6140.228185505611;6160.507159427325;6201.720922558586;6270.623762574261;6064.3026052002815;6084.39156084388;6086.756529738761;6097.878042439038;6106.535527620506;5979.4917432018765;6036.849078773066;6054.9101161791305;6096.359882212304;6099.481544068626;4747.264174053027;5821.592488525763;5880.411958804257;5891.528677705687;5911.3201981772645;6145.416185462553;6151.96924015377;6153.446189842935;6155.953487906977;6162.044883043135;5768.625039372595;5837.591368604157;5838.153467747238;5962.731677074629;6001.729922153426 19:31:59 INFO - PROCESS | 2261 | |1;ext.html;469.77920377422623;499.21872269897585;514.1901505129421;515.1988657637377;550.3285991090868;28562;40541;42014.57985420149;42324.94187645303;43928.90177745558;43147.784261078654;43485.78257108718;43503;43538.56461435385;43783.3432498512;8559.572021398937;9440.244780417584;9665.951670241675;9667.806643867118;9678.806423871518;8571.571421428935;11567.132465065111;11858.703532411673;11871.406429678525;11982.580609678664;11680.591179308725;11752.118591105702;11763.588274410396;11780.057595392347;11860.406979651027;11639.35983520906;11721.824172637418;11722;11734.413279335999;11739.882601174008;11534.538618455252;11575.710607234887;11576.305421674726;11578.131640127023;11606.535738570446;40296.99257518556;40500;40510;40568;40639.59360406388;3601.7478776485655;3629.1471504196493;4047.0691740899756;4058.736182148151;4183.518895327046;29843.701562984308;31272.530912036338;31319.686803131906;31319.843400783077;31361.372772544648;3101.3332133591384;3107.37852429515;3243.2216268095485;3264.755143364257;3267.9836600816843;486341;490542;491175.54412228067;492572;497195.51402243116;59612.80774384488;60158.69920650369;60224.69887650621;60282;60286.39713602808;58632.70683646554;58745.11882321769;58780.70609646924;58814.11778823321;58896;15675.608109797346;15907;15910.920445397698;15942.442014529668;15969.20153992319;11127.499262533205;11375.146863985068;12169.2698438094;12369.257844529357;12471.812922806164;15848;15996.68006639884;16291;16352.345906163859;16363.509094727176;6269.780557680459;6305.716242769086;6307.211598550181;6378.77674281398;6379.936200637934;799.4643588795404;855.4952577978897;923.630547780893;1012.6050840172338;1017.6692574913183;28994.985175518756;29142.277296516015;29474.557881631794;29713;29749;1082.9133669306436;1085.9945700271448;1088.374184843718;1097.13874608432;1099.4612639806596;14330;14657.486987955584;15002.774958375632;15295.464658736892;15402.6149346265;616771;619582;620216;620372;620982;58077;58182.418175818544;58314;58402.70798646064;58459.70770146207;1246.8254444377794;1297.1374036265966;1379.3034517568765;1400.2998500749625;1426.0588011912089;30262.940797072;31128;31259.218519537193;31462.79477334349;32192;44770.328445074;45651.771741141085;45675.858103547675;45689;45762.31356529588;486255;486906;488209;488636;488882.5555872198;25550.361240969123;26079.087231946793;26162;26591.468170636093;26896.32759180958;45920.54079459162;46903.76548117238;47025.52974470348;47303.58089257328;47469.762651186524;35205.471917921765;38323;39435.802820985715;39517.209655807295;40071.79605121746;39121.021974450865;39211.41182882202;40263.59736402598;40373.394399084624;40509.189816202925;37372;37802.810985944896;38124.809375952944;38410.847674569806;38525;33187.50218746769;34226.31547368989;35052.773152939524;35095.824520877235;35241.647583524864;21856;25224.62163067519;25808;26038.34904127412;26092.478150437266 19:31:59 INFO - PROCESS | 2261 | |2;jquery.html;353.6710858901221;372.59573362901233;377.48096367494696;383.80809595202396;409.4860949508367;14875.553733388002;14976.850231497681;15547.844521554782;16718.749218761714;20937.37187884362;19247.518812029706;19748.605027899433;20250.39248822534;20390;20762.584748305024;5793.5365170786345;5899.387432649996;6099.847503812396;6102.389761023885;6107.480864126533;5867.76528938842;5996.640201587897;6079.240094988127;6080.270367555878;6080.30076541199;5827.679477628729;5898.139402389837;6026.548008899328;6039;6039.184710064129;5911.261092363455;5916.26046744157;5957;5959.642421454683;5959.6722180280285;4980.9252861207115;5143.948560514385;5161.27742116104;5178.249153872705;5213.582913366921;25335.87332063328;25434.36414089644;25436.872815635805;25475;25640.230793076236;2058.876467411945;2273.7044184256106;2509.912153074634;2566.3455818766283;2763.9170824875287;10387.220958428072;10463.581456741722;12712.682182945407;14683.486077987325;15453;1844.4190080124852;1857.7914360588652;1863.7204419337072;1907.0655378864396;1938.1472152253104;87522;128483.71516284811;128896;129517;130920;9266.721998340061;9631.470269135265;9966.103050725469;9966.651167209113;11277;12293.93853030729;12359.38203089842;12368.75262494745;12370.195937264049;12406.193597416139;7845.882311765328;8002.319802816709;8021.398395120389;8110.067342255598;8112;7437;7799.181085986003;8317.542535160503;8351.874721879176;8467.915320846712;3813.733038687321;3870.845166193377;3876.7092468065007;3945;3945.3292940200236;1623.8782091343196;1692.5430133863788;1892.9053547322578;1928.8553358498168;1935.1195206181435;883.1565854608833;886.8980067292345;890.5617427854098;905.6739573753443;969.7139343893568;18297.81702182961;21489.032993515328;21568.41179058523;21581.67627485589;21593.67609485859;896.9641214351483;934.780326623243;940.6331530703031;946.2760987844305;958.2812890332251;10771;11129.165312601588;11217.270877392943;11239.494222759995;11242.49408776607;506266.4686676543;524673;563878;579126;584074;86695;102376.48811756042;108430;129856;135617.3219133918;914.2548822709604;932.7481579973505;960.6109525642127;961.1829944547079;965.3628605120587;15253.694926101638;18895;19451;19454.513637159183;19557.022148892782;52505.7374713124;54617.726911365186;55757.260632846665;56166;56677.716611416676;77793.83309250252;77877.61061194658;79776.60111699405;80430;82241.58879205566;7564.432667549846;7931.255123872739;7972;8211;8286.674132138829;12271.386430678253;13156.934215328862;13239.007074469646;13630.522931697344;13843.169409835442;10945.507452164513;11105;11645.88354116448;11687;11747.765044698886;11362.602308919148;11372.20394572372;11540.076793856304;11651.359175245443;11811.291322520676;11481.483333249857;11645.88354116448;11701.180917335705;11758.529658813894;11856.762864742484;6976.790696279119;7335.7432489862595;7344.339009489172;7549.527842070744;8228.300594449538;9417.717468475958;11574.363410012193;11599.536018559163;11658.708532286422;11732.589359372381 19:31:59 INFO - PROCESS | 2261 | |3;mootools.html;1526.97709534357;1559.992200039;1791.3461586520916;1909.7899231084587;1944.6791279438894;8384.16158384162;8904.777380565482;8905.465672059681;8910.688125915594;8913.910860891388;7715.575643339614;8040.718574849881;8044.718434854782;8194.508329500219;8409.20112589303;2279.521300526887;2320.535892821434;2321.7330007049236;2322.465832858435;2325.139698311627;2281.870474115307;2288.141946769961;2319.9884000579973;2325.5813953488364;2325.697659304288;2290.667853161299;2312.7340355859037;2316.9884150579223;2318.8260880434036;2318.9420264493438;2278.7265528136563;2293.8508996915148;2297.5404919016264;2301.1255722825304;2301.42464383904;2278.1798552521077;2285.325828880484;2286.8284878634004;2291.175176936302;2297.4371279036577;8403.83192336156;8575.185357391038;8582.399232053758;8595.914040859574;8602.655893764242;5154.536091751723;5313.601479889024;5317.680939143664;5328.280682107905;5359.222912677679;3373.477111047767;3434.142459212082;3573.803440810754;3577.8926632201074;3587.0494319005484;1019.7807471393686;1037.9169666426667;1050.8528805967094;1074.6131392698621;1076.036946932499;11442.313461192354;11593.13051521131;11593.884061159364;11601.129915256304;11612;4085.325921222965;4121.361189015707;4184.974681203124;4542.068875880436;4746.738929358849;9938;9946.850797238047;9955.054269844353;9962.302638815358;9962.352447090916;5318.388385335736;5325.201219817059;5331.680099194061;5336.1195402758885;5338.786448542015;4346.826126954949;4389.934150987674;4514.300283456069;4840.15297322967;4884.7313397762755;3796.5823759387;3853.8843834685003;3879.592642772525;3937.5668676445566;3977.5823538528616;1232.8520577530753;1234.2409418207737;1234.912997035008;1245.1968480330136;1272.4910035985495;832.3341326938425;859.862422012475;883.3066043156092;890.1988210610502;901.8872640919885;5871.94128058714;5984.551158663117;6020.307664618537;6165.075238714139;6309.053641953652;938.4088024544493;938.7655233368235;942.5711301357866;963.1764841060932;978.1441238915949;3445.793252404864;3807.238552289498;3950.466686997276;3994.800259986989;4092.7544347338658;10323.277370583986;10813.08088812444;10820.89179108199;10849.457527123612;10882.727931801768;10746;10746.623868164577;10749.946250268855;10754.623588174376;10759.408232547285;6860.416864566568;7272.200057993722;8147.185281471805;8188.099309075997;8222;5188.221766734869;5904.261967254093;5970.31341395745;6116.26604807426;6251.531135164972;9888.802223955625;10051.899481005097;10053.396796192252;10067.949660251652;10079.99200079986;9875.456849873326;10010.299279050396;10133.797324053625;10171.440570768678;10309.793804124025;6935.618540980093;7141.464390170654;7155.212926577969;7430.219826918095;7658.119316278699;8117.269445749911;8425.662973480992;8426.620802064044;8529.914700852913;8535.8719619207;7436.73971410998;7524.548527088392;7573.848523029398;7589.051368578927;7728.381729461742;7294.744683936036;7296.744613938699;7452.813679658052;7458.552486850589;7494.17564067963;6876.243613202644;7035.964820175866;7037.085178926707;7039.9296007039275;7043.330883566053;4770.320645151731;4880.316755654282;4893.266010098514;4907.656464047483;4952.083864485082;4032.9798351008058;4800.7599620019555;4832.8791780205775;4890.364252647133;4980.477049909708 19:31:59 INFO - PROCESS | 2261 | |4;prototype.html;289.7160782433214;300.8555893171278;307.56479581392324;314.33518109199025;315.0689711901332;4838.975805120978;4891.48639392864;5254.397408790317;5472.835814925554;5516.586256030803;5152.639315247925;5257.342832145981;5259.844093543872;5465.754041068151;5471.671699698011;4097.426360309559;4113.950942509656;4147.357159640245;4149.626533611974;4169.206631556849;4141.825541590659;4161.459010328653;4164.68764842638;4183.288840897039;4213.050864394921;3596.37063513884;3979.2686722760386;4066.79666016698;4117.3206420940505;4121.814518346681;3854.0516504873794;4079.9184016319805;4080.7347522410946;4082.693538067834;4087.8364865405347;2943.882244710209;3494.336076145524;3498.6851183393364;3513.244652399721;3540.6636369544854;4834.6971195635915;5048.697078175322;5245;5309.469053094698;5419.918701219485;4288.485381754178;4668.789904454307;4674.387336368981;4701.929471057937;4709.764511774431;4937.432195297551;4953.628477864138;4961.826336078256;5597.216389705444;5647.463490968352;2868.5553739170464;3738.8504459821575;3883.165119499321;3891.3579259422163;3931.7444366116397;4934.358533390637;5389.541888939445;5581.553475721932;5866.296044474648;5917.319508256563;3775.8300876460075;3824.4454554089502;3941.4087886816633;3943.4873466449176;3952.4268980997595;3993.5008123984503;3993.6206060424215;3999.5200575931076;4004.479417675742;4013.1973605278476;3840.6543411093135;4465.26323156682;4548.635409377173;4695.107929493385;4714.57568818808;4942.703437793743;4981.825636102719;5149.87165273331;5589.133684278943;5715.628484148517;1063.6011495689118;1246.8566114896903;1261.1739310751473;1351.8036537664238;1360.5170164591625;769.442154438028;815.9306458951056;816.6284340625001;831.7380025292014;835.2274146414542;4192.874213773592;4456.799444025027;4482.731036137777;4525.864224073283;4541.591256786839;4837.427835953259;4868.95690193629;4897.086233690949;4911.68074075184;4916.680415772963;4102.815373308208;4273.18809426208;4485.241576430537;4499.910001800011;4573.382593349856;2156.1914282144194;2191.5069109450565;2280.9771902280763;2352.4354155002675;2579.877753177374;5036.521530454601;5037.798488060436;5070.526689217824;5089.541941225233;5115.925655612363;4187.78554219273;4353.804078816461;4562.585598464487;4935.333729946483;5046.369203849519;4105.507339119265;4216.42797893291;4366.912661746747;4627.986825730818;5028.1590901140735;4515.751633660049;4634.907301853877;4675.942585262474;4910.680805747615;4922.252600326832;4048.1701251244012;4182.979085104676;4850.98684046131;4907.484714104968;4951.480094590016;4801.759912004456;5144.659180073133;5341.813036543703;5390.113460288868;5573.749181286933;3499.755017148877;3605.7920596600434;3839.2513459876172;3903.2974064667496;3934.606539346157;4764.761761911821;5542.3903370628395;5880.323762767336;5886.882262354643;5928.555358348055;5533.391326953952;5712.914546236202;5829;5839.8832023358445;5853.882922341444;4486.730796152241;5361.605982444515;5748.367679555329;5758.309002919509;5817.621854579439;4493.404841281298;4929.151568161939;5338.492843179892;5663.725661726078;5716.399778023248;4923.187674033744;5059.443461219336;5463.262459567907;5776.934219069875;5885.764569417347;5563.860903477445;5610.719464026701;5647.29408823897;5682.289713785777;5690.487856092972 19:31:59 INFO - PROCESS | 2261 | |5;yui.html;614.9354317796631;751.9661615227311;799.7920540659427;818.6438899078905;826.8635675113607;15519.922400387997;15827.683446331066;15827.841721582796;15879.682406351865;16233.350665973376;14281.642958926031;14392.992490525667;14393.920455965817;14395.712085758278;14436.783448248285;702.7997020849059;734.0347443112317;734.0787311923528;734.170387462167;894.9731508054768;728.624758249502;869.9782505437352;906.112010229975;910.1899309614415;912.1106920752246;957.8946315905245;962.7111866440075;971.6307803034872;972.4894430424014;973.610555777691;952.2429668413558;955.3169483819033;955.7180631713626;957.1290125985389;957.7749228931195;548.7420912171273;567.4098937105447;718.2637796258824;719.5934297122161;722.1009842745815;12225.960793332577;12284.201526900722;12289.93855030728;14042.508512202025;14632.195229262386;14035.649108772363;14653;14686.706265874729;14726.48457304;14768;10814.891851081467;10887.510062047228;10918.836217456665;11210.27133236337;11240.943795281053;3755.680767134797;3769.6230376962358;3805.5813860475328;3897.123147291865;3911.413288006793;108359.45820270848;112344.43827780809;112869;113168;113930.43034784855;10458.63394781179;10867.347959122479;11085.556577736961;11184.832227516594;11243.943780281046;21055.578888422147;21791.782082179292;23104.65343019856;23127.653085203736;23129.768702313097;7996.510757671111;8047.235512626291;8153.633086511121;8213.219744124299;8248.958755206186;4215.199112168678;4253.893652658647;4282.914341713211;4289.7640629765665;4301.935470967938;3655.8484077515495;3784.6783023442763;3793.24135172961;3796.867109651149;3799.430085487199;668.0647094068246;669.2771806449024;678.5860625018735;679.9932000680029;687.9484038697018;590.9084091965752;675.6924605639051;680.5728734581515;680.9182898052167;701.9964900175569;8767.561621918878;8768.298536117096;9280.67517636893;9410.551163920833;9515.714528564155;675.598019178588;678.2844099475003;688.0882830249939;688.4905170174035;688.8840079071836;4313.439252897166;4567.703099298535;4614.1694494991225;4673.7429441381055;4995.300657907896;99020.53428129981;120629;150911;156005.2199738994;159824;132784;137045;137085.3145734265;138960;141750;89466.55266723625;94420;99484.50773238407;103404;103538;8070.119711738795;9559.20715921904;9604.759881002752;9645.855312170183;9671.323007389698;65620;65772.01341979779;66597;67124.32875671376;69315.65342173257;101765.98234017564;103485.93028139244;103732;104607.07858428193;106354;10881.836772448578;13083.702852137765;13231.933840330737;13287.42895751358;13410.664733381744;11974.880251197377;14738.189399582698;15058;15143.318550665466;15667.451639192574;13857.72284554283;13904.652383690489;14082.718345633235;14100.224487653277;14259.420519316056;13542.526011589547;13819.516316928859;13955.720885582028;14121.858781412055;14275;13737.862621373659;13741.175529468264;13871.23708196059;13946.930265348608;14157.504487342894;10941.726456838644;11347.432628368384;11349.659510214708;11590.536378544764;11791.823122653339;10403.422256550257;10542.789144217226;10583.20625953072;10644.414557199429;11512.19414640967 19:31:59 INFO - PROCESS | 2261 | __end_tp_report 19:31:59 INFO - PROCESS | 2261 | __start_cc_report 19:31:59 INFO - PROCESS | 2261 | _x_x_mozilla_cycle_collect,1341 19:31:59 INFO - PROCESS | 2261 | __end_cc_report 19:31:59 INFO - PROCESS | 2261 | __startTimestamp1477276319406__endTimestamp 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | ------- Summary: start ------- 19:31:59 INFO - PROCESS | 2261 | Number of tests: 6 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#0] dojo.html Cycles:165 Average:5659.73 Median:5873.35 stddev:776.18 (13.2%) stddev-sans-first:756.37 19:31:59 INFO - PROCESS | 2261 | Values: 3311.0 3312.8 3315.4 3335.4 3360.2 5540.3 5607.8 5667.5 5669.6 5682.9 5639.0 5675.8 5676.1 5680.3 5685.3 5122.2 5154.1 5163.0 5188.1 5190.7 5215.8 5226.8 5248.5 5256.3 5263.9 4775.7 4852.1 5262.6 5327.0 5377.6 559.0 5284.7 5296.4 5300.2 5350.6 3122.0 5154.6 5170.1 5200.3 5266.8 5565.3 5670.8 5679.1 5696.3 5717.4 6235.7 6251.7 6256.0 6267.5 6270.5 6105.0 6112.1 6134.0 6145.7 6167.0 5764.9 5770.6 5771.7 5781.6 5786.2 6224.7 6242.1 6248.5 6248.6 6251.4 5994.6 6051.0 6067.2 6088.9 6191.2 6127.9 6178.4 6255.9 6260.9 6265.4 6165.0 6203.5 6210.1 6211.0 6220.8 6075.9 6143.4 6196.2 6235.6 6247.4 4862.2 4875.5 4893.6 4894.1 4944.8 4372.1 4376.9 4387.3 4399.9 4412.6 5672.9 5731.5 5736.4 5741.8 5751.1 5873.4 5974.0 6002.3 6202.3 6242.3 5781.8 5793.5 5798.9 5819.1 5840.1 5396.9 5399.8 5403.1 5407.6 5413.5 6218.0 6231.1 6276.1 6281.5 6292.9 5123.7 6140.7 6195.2 6206.3 6281.5 5298.6 6187.7 6213.2 6245.8 6286.7 6040.1 6125.4 6151.3 6175.4 6194.1 6135.6 6140.2 6160.5 6201.7 6270.6 6064.3 6084.4 6086.8 6097.9 6106.5 5979.5 6036.8 6054.9 6096.4 6099.5 4747.3 5821.6 5880.4 5891.5 5911.3 6145.4 6152.0 6153.4 6156.0 6162.0 5768.6 5837.6 5838.2 5962.7 6001.7 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#1] ext.html Cycles:180 Average:67161.02 Median:37587.41 stddev:143027.90 (380.5%) stddev-sans-first:143341.48 19:31:59 INFO - PROCESS | 2261 | Values: 469.8 499.2 514.2 515.2 550.3 28562.0 40541.0 42014.6 42324.9 43928.9 43147.8 43485.8 43503.0 43538.6 43783.3 8559.6 9440.2 9666.0 9667.8 9678.8 8571.6 11567.1 11858.7 11871.4 11982.6 11680.6 11752.1 11763.6 11780.1 11860.4 11639.4 11721.8 11722.0 11734.4 11739.9 11534.5 11575.7 11576.3 11578.1 11606.5 40297.0 40500.0 40510.0 40568.0 40639.6 3601.7 3629.1 4047.1 4058.7 4183.5 29843.7 31272.5 31319.7 31319.8 31361.4 3101.3 3107.4 3243.2 3264.8 3268.0 486341.0 490542.0 491175.5 492572.0 497195.5 59612.8 60158.7 60224.7 60282.0 60286.4 58632.7 58745.1 58780.7 58814.1 58896.0 15675.6 15907.0 15910.9 15942.4 15969.2 11127.5 11375.1 12169.3 12369.3 12471.8 15848.0 15996.7 16291.0 16352.3 16363.5 6269.8 6305.7 6307.2 6378.8 6379.9 799.5 855.5 923.6 1012.6 1017.7 28995.0 29142.3 29474.6 29713.0 29749.0 1082.9 1086.0 1088.4 1097.1 1099.5 14330.0 14657.5 15002.8 15295.5 15402.6 616771.0 619582.0 620216.0 620372.0 620982.0 58077.0 58182.4 58314.0 58402.7 58459.7 1246.8 1297.1 1379.3 1400.3 1426.1 30262.9 31128.0 31259.2 31462.8 32192.0 44770.3 45651.8 45675.9 45689.0 45762.3 486255.0 486906.0 488209.0 488636.0 488882.6 25550.4 26079.1 26162.0 26591.5 26896.3 45920.5 46903.8 47025.5 47303.6 47469.8 35205.5 38323.0 39435.8 39517.2 40071.8 39121.0 39211.4 40263.6 40373.4 40509.2 37372.0 37802.8 38124.8 38410.8 38525.0 33187.5 34226.3 35052.8 35095.8 35241.6 21856.0 25224.6 25808.0 26038.3 26092.5 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#2] jquery.html Cycles:180 Average:33354.86 Median:2095.61 stddev:92498.56 (4413.9%) stddev-sans-first:92724.85 19:31:59 INFO - PROCESS | 2261 | Values: 353.7 372.6 377.5 383.8 409.5 14875.6 14976.9 15547.8 16718.7 20937.4 19247.5 19748.6 20250.4 20390.0 20762.6 5793.5 5899.4 6099.8 6102.4 6107.5 5867.8 5996.6 6079.2 6080.3 6080.3 5827.7 5898.1 6026.5 6039.0 6039.2 5911.3 5916.3 5957.0 5959.6 5959.7 4980.9 5143.9 5161.3 5178.2 5213.6 25335.9 25434.4 25436.9 25475.0 25640.2 2058.9 2273.7 2509.9 2566.3 2763.9 10387.2 10463.6 12712.7 14683.5 15453.0 1844.4 1857.8 1863.7 1907.1 1938.1 87522.0 128483.7 128896.0 129517.0 130920.0 9266.7 9631.5 9966.1 9966.7 11277.0 12293.9 12359.4 12368.8 12370.2 12406.2 7845.9 8002.3 8021.4 8110.1 8112.0 7437.0 7799.2 8317.5 8351.9 8467.9 3813.7 3870.8 3876.7 3945.0 3945.3 1623.9 1692.5 1892.9 1928.9 1935.1 883.2 886.9 890.6 905.7 969.7 18297.8 21489.0 21568.4 21581.7 21593.7 897.0 934.8 940.6 946.3 958.3 10771.0 11129.2 11217.3 11239.5 11242.5 506266.5 524673.0 563878.0 579126.0 584074.0 86695.0 102376.5 108430.0 129856.0 135617.3 914.3 932.7 960.6 961.2 965.4 15253.7 18895.0 19451.0 19454.5 19557.0 52505.7 54617.7 55757.3 56166.0 56677.7 77793.8 77877.6 79776.6 80430.0 82241.6 7564.4 7931.3 7972.0 8211.0 8286.7 12271.4 13156.9 13239.0 13630.5 13843.2 10945.5 11105.0 11645.9 11687.0 11747.8 11362.6 11372.2 11540.1 11651.4 11811.3 11481.5 11645.9 11701.2 11758.5 11856.8 6976.8 7335.7 7344.3 7549.5 8228.3 9417.7 11574.4 11599.5 11658.7 11732.6 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#3] mootools.html Cycles:180 Average:5658.41 Median:4860.23 stddev:3194.43 (65.7%) stddev-sans-first:3188.30 19:31:59 INFO - PROCESS | 2261 | Values: 1527.0 1560.0 1791.3 1909.8 1944.7 8384.2 8904.8 8905.5 8910.7 8913.9 7715.6 8040.7 8044.7 8194.5 8409.2 2279.5 2320.5 2321.7 2322.5 2325.1 2281.9 2288.1 2320.0 2325.6 2325.7 2290.7 2312.7 2317.0 2318.8 2318.9 2278.7 2293.9 2297.5 2301.1 2301.4 2278.2 2285.3 2286.8 2291.2 2297.4 8403.8 8575.2 8582.4 8595.9 8602.7 5154.5 5313.6 5317.7 5328.3 5359.2 3373.5 3434.1 3573.8 3577.9 3587.0 1019.8 1037.9 1050.9 1074.6 1076.0 11442.3 11593.1 11593.9 11601.1 11612.0 4085.3 4121.4 4185.0 4542.1 4746.7 9938.0 9946.9 9955.1 9962.3 9962.4 5318.4 5325.2 5331.7 5336.1 5338.8 4346.8 4389.9 4514.3 4840.2 4884.7 3796.6 3853.9 3879.6 3937.6 3977.6 1232.9 1234.2 1234.9 1245.2 1272.5 832.3 859.9 883.3 890.2 901.9 5871.9 5984.6 6020.3 6165.1 6309.1 938.4 938.8 942.6 963.2 978.1 3445.8 3807.2 3950.5 3994.8 4092.8 10323.3 10813.1 10820.9 10849.5 10882.7 10746.0 10746.6 10749.9 10754.6 10759.4 6860.4 7272.2 8147.2 8188.1 8222.0 5188.2 5904.3 5970.3 6116.3 6251.5 9888.8 10051.9 10053.4 10067.9 10080.0 9875.5 10010.3 10133.8 10171.4 10309.8 6935.6 7141.5 7155.2 7430.2 7658.1 8117.3 8425.7 8426.6 8529.9 8535.9 7436.7 7524.5 7573.8 7589.1 7728.4 7294.7 7296.7 7452.8 7458.6 7494.2 6876.2 7036.0 7037.1 7039.9 7043.3 4770.3 4880.3 4893.3 4907.7 4952.1 4033.0 4800.8 4832.9 4890.4 4980.5 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#4] prototype.html Cycles:180 Average:4333.78 Median:4698.52 stddev:1325.14 (28.2%) stddev-sans-first:1293.63 19:31:59 INFO - PROCESS | 2261 | Values: 289.7 300.9 307.6 314.3 315.1 4839.0 4891.5 5254.4 5472.8 5516.6 5152.6 5257.3 5259.8 5465.8 5471.7 4097.4 4114.0 4147.4 4149.6 4169.2 4141.8 4161.5 4164.7 4183.3 4213.1 3596.4 3979.3 4066.8 4117.3 4121.8 3854.1 4079.9 4080.7 4082.7 4087.8 2943.9 3494.3 3498.7 3513.2 3540.7 4834.7 5048.7 5245.0 5309.5 5419.9 4288.5 4668.8 4674.4 4701.9 4709.8 4937.4 4953.6 4961.8 5597.2 5647.5 2868.6 3738.9 3883.2 3891.4 3931.7 4934.4 5389.5 5581.6 5866.3 5917.3 3775.8 3824.4 3941.4 3943.5 3952.4 3993.5 3993.6 3999.5 4004.5 4013.2 3840.7 4465.3 4548.6 4695.1 4714.6 4942.7 4981.8 5149.9 5589.1 5715.6 1063.6 1246.9 1261.2 1351.8 1360.5 769.4 815.9 816.6 831.7 835.2 4192.9 4456.8 4482.7 4525.9 4541.6 4837.4 4869.0 4897.1 4911.7 4916.7 4102.8 4273.2 4485.2 4499.9 4573.4 2156.2 2191.5 2281.0 2352.4 2579.9 5036.5 5037.8 5070.5 5089.5 5115.9 4187.8 4353.8 4562.6 4935.3 5046.4 4105.5 4216.4 4366.9 4628.0 5028.2 4515.8 4634.9 4675.9 4910.7 4922.3 4048.2 4183.0 4851.0 4907.5 4951.5 4801.8 5144.7 5341.8 5390.1 5573.7 3499.8 3605.8 3839.3 3903.3 3934.6 4764.8 5542.4 5880.3 5886.9 5928.6 5533.4 5712.9 5829.0 5839.9 5853.9 4486.7 5361.6 5748.4 5758.3 5817.6 4493.4 4929.2 5338.5 5663.7 5716.4 4923.2 5059.4 5463.3 5776.9 5885.8 5563.9 5610.7 5647.3 5682.3 5690.5 19:31:59 INFO - PROCESS | 2261 | 19:31:59 INFO - PROCESS | 2261 | [#5] yui.html Cycles:180 Average:24988.80 Median:13392.81 stddev:39715.05 (296.5%) stddev-sans-first:39784.30 19:31:59 INFO - PROCESS | 2261 | Values: 614.9 752.0 799.8 818.6 826.9 15519.9 15827.7 15827.8 15879.7 16233.4 14281.6 14393.0 14393.9 14395.7 14436.8 702.8 734.0 734.1 734.2 895.0 728.6 870.0 906.1 910.2 912.1 957.9 962.7 971.6 972.5 973.6 952.2 955.3 955.7 957.1 957.8 548.7 567.4 718.3 719.6 722.1 12226.0 12284.2 12289.9 14042.5 14632.2 14035.6 14653.0 14686.7 14726.5 14768.0 10814.9 10887.5 10918.8 11210.3 11240.9 3755.7 3769.6 3805.6 3897.1 3911.4 108359.5 112344.4 112869.0 113168.0 113930.4 10458.6 10867.3 11085.6 11184.8 11243.9 21055.6 21791.8 23104.7 23127.7 23129.8 7996.5 8047.2 8153.6 8213.2 8249.0 4215.2 4253.9 4282.9 4289.8 4301.9 3655.8 3784.7 3793.2 3796.9 3799.4 668.1 669.3 678.6 680.0 687.9 590.9 675.7 680.6 680.9 702.0 8767.6 8768.3 9280.7 9410.6 9515.7 675.6 678.3 688.1 688.5 688.9 4313.4 4567.7 4614.2 4673.7 4995.3 99020.5 120629.0 150911.0 156005.2 159824.0 132784.0 137045.0 137085.3 138960.0 141750.0 89466.6 94420.0 99484.5 103404.0 103538.0 8070.1 9559.2 9604.8 9645.9 9671.3 65620.0 65772.0 66597.0 67124.3 69315.7 101766.0 103485.9 103732.0 104607.1 106354.0 10881.8 13083.7 13231.9 13287.4 13410.7 11974.9 14738.2 15058.0 15143.3 15667.5 13857.7 13904.7 14082.7 14100.2 14259.4 13542.5 13819.5 13955.7 14121.9 14275.0 13737.9 13741.2 13871.2 13946.9 14157.5 10941.7 11347.4 11349.7 11590.5 11791.8 10403.4 10542.8 10583.2 10644.4 11512.2 19:31:59 INFO - PROCESS | 2261 | -------- Summary: end -------- 19:31:59 INFO - PROCESS | 2261 | 19:32:00 INFO - TEST-INFO | 2261: exit 0 19:32:00 INFO - TEST-OK | dromaeo_css | took 1118231ms 19:32:00 INFO - TEST-START | kraken 19:32:00 INFO - operating with platform_type : mac_ 19:32:00 INFO - Initialising browser for kraken test... 19:32:00 INFO - TEST-INFO | started process 2264 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpICd20z/profile http://localhost:52410/getInfo.html) 19:32:02 INFO - PROCESS | 2264 | __metrics Screen width/height:1600/1200 19:32:02 INFO - PROCESS | 2264 | colorDepth:24 19:32:02 INFO - PROCESS | 2264 | Browser inner width/height: 1024/689 19:32:02 INFO - PROCESS | 2264 | __metrics 19:32:02 INFO - PROCESS | 2264 | 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" 19:32:02 INFO - PROCESS | 2264 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:32:03 INFO - TEST-INFO | 2264: exit 0 19:32:03 INFO - Browser initialized. 19:32:03 INFO - Running cycle 1/1 for kraken test... 19:32:03 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpICd20z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:32:15 INFO - PROCESS | 2265 | RSS: Main: 231874560 19:32:15 INFO - PROCESS | 2265 | 19:32:18 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52410/tests/kraken/driver.html?testName=audio-beat-detection) 19:32:19 INFO - PROCESS | 2265 | RSS: Main: 290353152 19:32:19 INFO - PROCESS | 2265 | 19:32:21 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52410/tests/kraken/driver.html?testName=audio-dft) 19:32:22 INFO - PROCESS | 2265 | RSS: Main: 292016128 19:32:22 INFO - PROCESS | 2265 | 19:32:25 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52410/tests/kraken/driver.html?testName=audio-fft) 19:32:26 INFO - PROCESS | 2265 | RSS: Main: 382812160 19:32:26 INFO - PROCESS | 2265 | 19:32:28 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52410/tests/kraken/driver.html?testName=audio-oscillator) 19:32:28 INFO - PROCESS | 2265 | RSS: Main: 287739904 19:32:28 INFO - PROCESS | 2265 | 19:32:31 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52410/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:31 INFO - PROCESS | 2265 | RSS: Main: 289513472 19:32:31 INFO - PROCESS | 2265 | 19:32:34 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52410/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:35 INFO - PROCESS | 2265 | RSS: Main: 325861376 19:32:35 INFO - PROCESS | 2265 | 19:32:37 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52410/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:38 INFO - PROCESS | 2265 | RSS: Main: 327200768 19:32:38 INFO - PROCESS | 2265 | 19:32:41 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52410/tests/kraken/driver.html?testName=json-parse-financial) 19:32:41 INFO - PROCESS | 2265 | RSS: Main: 325595136 19:32:41 INFO - PROCESS | 2265 | 19:32:43 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52410/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:44 INFO - PROCESS | 2265 | RSS: Main: 302960640 19:32:44 INFO - PROCESS | 2265 | 19:32:45 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:46 INFO - PROCESS | 2265 | RSS: Main: 308006912 19:32:46 INFO - PROCESS | 2265 | 19:32:48 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:49 INFO - PROCESS | 2265 | RSS: Main: 309968896 19:32:49 INFO - PROCESS | 2265 | 19:32:52 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:53 INFO - PROCESS | 2265 | RSS: Main: 316772352 19:32:53 INFO - PROCESS | 2265 | 19:32:56 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:56 INFO - PROCESS | 2265 | RSS: Main: 316858368 19:32:56 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:52410/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52410/tests/kraken/driver.html?testName=ai-astar) 19:32:58 INFO - PROCESS | 2265 | RSS: Main: 361377792 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | __start_tp_report 19:32:58 INFO - PROCESS | 2265 | _x_x_mozilla_page_load 19:32:58 INFO - PROCESS | 2265 | _x_x_mozilla_page_load_details 19:32:58 INFO - PROCESS | 2265 | |i|pagename|runs| 19:32:58 INFO - PROCESS | 2265 | |0;ai-astar;103;123;148;114;113;113;108;114;113;114 19:32:58 INFO - PROCESS | 2265 | |1;audio-beat-detection;152;113;114;149;114;113;117;111;141;111 19:32:58 INFO - PROCESS | 2265 | |2;audio-dft;182;184;181;185;185;183;180;182;184;180 19:32:58 INFO - PROCESS | 2265 | |3;audio-fft;80;58;59;87;62;74;80;81;81;56 19:32:58 INFO - PROCESS | 2265 | |4;audio-oscillator;84;71;95;72;69;68;94;90;98;123 19:32:58 INFO - PROCESS | 2265 | |5;imaging-gaussian-blur;87;85;89;86;88;83;89;86;88;84 19:32:58 INFO - PROCESS | 2265 | |6;imaging-darkroom;93;93;93;108;148;93;94;94;93;96 19:32:58 INFO - PROCESS | 2265 | |7;imaging-desaturate;83;82;81;81;83;81;83;84;89;124 19:32:58 INFO - PROCESS | 2265 | |8;json-parse-financial;56;53;53;53;54;54;54;52;55;53 19:32:58 INFO - PROCESS | 2265 | |9;json-stringify-tinderbox;70;66;77;75;74;70;71;71;71;72 19:32:58 INFO - PROCESS | 2265 | |10;stanford-crypto-aes;91;86;86;89;86;86;85;86;90;89 19:32:58 INFO - PROCESS | 2265 | |11;stanford-crypto-ccm;148;145;146;148;147;159;161;162;207;162 19:32:58 INFO - PROCESS | 2265 | |12;stanford-crypto-pbkdf2;171;171;172;169;173;171;170;170;171;169 19:32:58 INFO - PROCESS | 2265 | |13;stanford-crypto-sha256-iterative;68;69;71;76;88;73;68;67;68;67 19:32:58 INFO - PROCESS | 2265 | __end_tp_report 19:32:58 INFO - PROCESS | 2265 | __start_cc_report 19:32:58 INFO - PROCESS | 2265 | _x_x_mozilla_cycle_collect,2107 19:32:58 INFO - PROCESS | 2265 | __end_cc_report 19:32:58 INFO - PROCESS | 2265 | __startTimestamp1477276378927__endTimestamp 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | ------- Summary: start ------- 19:32:58 INFO - PROCESS | 2265 | Number of tests: 14 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:12.22 (10.7%) stddev-sans-first:11.98 19:32:58 INFO - PROCESS | 2265 | Values: 103.0 123.0 148.0 114.0 113.0 113.0 108.0 114.0 113.0 114.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:115.50 stddev:16.75 (14.5%) stddev-sans-first:14.24 19:32:58 INFO - PROCESS | 2265 | Values: 152.0 113.0 114.0 149.0 114.0 113.0 117.0 111.0 141.0 111.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.90 (1.0%) stddev-sans-first:2.00 19:32:58 INFO - PROCESS | 2265 | Values: 182.0 184.0 181.0 185.0 185.0 183.0 180.0 182.0 184.0 180.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#3] audio-fft Cycles:10 Average:71.80 Median:80.00 stddev:11.74 (14.7%) stddev-sans-first:12.07 19:32:58 INFO - PROCESS | 2265 | Values: 80.0 58.0 59.0 87.0 62.0 74.0 80.0 81.0 81.0 56.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:87.00 stddev:17.35 (19.9%) stddev-sans-first:18.38 19:32:58 INFO - PROCESS | 2265 | Values: 84.0 71.0 95.0 72.0 69.0 68.0 94.0 90.0 98.0 123.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:2.07 (2.4%) stddev-sans-first:2.19 19:32:58 INFO - PROCESS | 2265 | Values: 87.0 85.0 89.0 86.0 88.0 83.0 89.0 86.0 88.0 84.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:93.00 stddev:17.32 (18.6%) stddev-sans-first:18.15 19:32:58 INFO - PROCESS | 2265 | Values: 93.0 93.0 93.0 108.0 148.0 93.0 94.0 94.0 93.0 96.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#7] imaging-desaturate Cycles:10 Average:87.10 Median:83.00 stddev:13.18 (15.9%) stddev-sans-first:13.89 19:32:58 INFO - PROCESS | 2265 | Values: 83.0 82.0 81.0 81.0 83.0 81.0 83.0 84.0 89.0 124.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:0.88 19:32:58 INFO - PROCESS | 2265 | Values: 56.0 53.0 53.0 53.0 54.0 54.0 54.0 52.0 55.0 53.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:3.06 (4.3%) stddev-sans-first:3.18 19:32:58 INFO - PROCESS | 2265 | Values: 70.0 66.0 77.0 75.0 74.0 70.0 71.0 71.0 71.0 72.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.50 stddev:2.12 (2.4%) stddev-sans-first:1.80 19:32:58 INFO - PROCESS | 2265 | Values: 91.0 86.0 86.0 89.0 86.0 86.0 85.0 86.0 90.0 89.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:160.00 stddev:18.48 (11.6%) stddev-sans-first:19.21 19:32:58 INFO - PROCESS | 2265 | Values: 148.0 145.0 146.0 148.0 147.0 159.0 161.0 162.0 207.0 162.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:171.00 stddev:1.25 (0.7%) stddev-sans-first:1.32 19:32:58 INFO - PROCESS | 2265 | Values: 171.0 171.0 172.0 169.0 173.0 171.0 170.0 170.0 171.0 169.0 19:32:58 INFO - PROCESS | 2265 | 19:32:58 INFO - PROCESS | 2265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.00 stddev:6.49 (9.3%) stddev-sans-first:6.75 19:32:58 INFO - PROCESS | 2265 | Values: 68.0 69.0 71.0 76.0 88.0 73.0 68.0 67.0 68.0 67.0 19:32:58 INFO - PROCESS | 2265 | -------- Summary: end -------- 19:32:58 INFO - PROCESS | 2265 | 19:32:59 INFO - TEST-INFO | 2265: exit 0 19:32:59 INFO - TEST-OK | kraken | took 59913ms 19:32:59 INFO - SUITE-END | took 1178s 19:33:00 INFO - Completed test suite (00:19:38) 19:33:00 INFO - javascript benchmark 19:33:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.0, 3312.784668996515, 3315.3866534691083, 3335.4496508076168, 3360.1599600099976, 5540.252065971093, 5607.831765047045, 5667.4615911488345, 5669.574781891359, 5682.914756278654, 5638.984982703122, 5675.801346952858, 5676.063449530822, 5680.289963754531, 5685.260916080903, 5122.154844450662, 5154.149565321717, 5163.044836705203, 5188.066148093341, 5190.662606930557, 5215.843524694265, 5226.843194704165, 5248.5276325130735, 5256.316678831747, 5263.947360526384, 4775.710558149315, 4852.126617208901, 5262.631615786897, 5327.014502317064, 5377.569794416457, 558.9649225907767, 5284.682919024852, 5296.390915044781, 5300.231466437345, 5350.5987050971335, 3121.9865440532617, 5154.639175257696, 5170.069387510247, 5200.323957885488, 5266.789328426858, 5565.332160140769, 5670.7731690732735, 5679.062954612484, 5696.259486266758, 5717.371089180187, 6235.657038862861, 6251.718672659741, 6256.0303153011355, 6267.4986001119805, 6270.498360131179, 6104.96947515264, 6112.1442997979875, 6133.983044923954, 6145.723442445101, 6166.969165154191, 5764.855878602984, 5770.596058275923, 5771.740271687784, 5781.595288329819, 5786.2477877876445, 6224.719887605069, 6242.1261023455845, 6248.4688801451475, 6248.562600618004, 6251.374862513712, 5994.580379373402, 6051.001584738557, 6067.24159480065, 6088.939110608837, 6191.164192834, 6127.89207890682, 6178.351273116348, 6255.937440625536, 6260.874782504325, 6265.4361107500545, 6165.0444181152, 6203.534734894811, 6210.130581718563, 6210.968945155336, 6220.782272620437, 6075.878482430327, 6143.354947730424, 6196.225471816023, 6235.625862448177, 6247.375262473716, 4862.197737373364, 4875.488073752276, 4893.608511319086, 4894.119058569491, 4944.802207911715, 4372.125574885035, 4376.868693939187, 4387.27609944362, 4399.934000989988, 4412.558744125562, 5672.8865422691315, 5731.512821410227, 5736.397678243724, 5741.7990370337675, 5751.051076572402, 5873.353931067579, 5974.014287642491, 6002.279726432856, 6202.317745048041, 6242.344553821874, 5781.82654520365, 5793.478586927113, 5798.855028624318, 5819.127130930309, 5840.065589505659, 5396.892062158813, 5399.811006614749, 5403.108487099663, 5407.567394608422, 5413.512783849394, 6218.0, 6231.096491008688, 6276.089966954857, 6281.497480201573, 6292.937070629235, 5123.718195499283, 6140.688304616564, 6195.1636529068, 6206.348333424925, 6281.4660753834605, 5298.602604804578, 6187.659678717714, 6213.2233470816145, 6245.750169993149, 6286.6856657166045, 6040.09398590215, 6125.356837532173, 6151.32335443092, 6175.382461753788, 6194.0708893664505, 6135.5695317960535, 6140.228185505611, 6160.507159427325, 6201.720922558586, 6270.623762574261, 6064.3026052002815, 6084.39156084388, 6086.756529738761, 6097.878042439038, 6106.535527620506, 5979.4917432018765, 6036.849078773066, 6054.9101161791305, 6096.359882212304, 6099.481544068626, 4747.264174053027, 5821.592488525763, 5880.411958804257, 5891.528677705687, 5911.3201981772645, 6145.416185462553, 6151.96924015377, 6153.446189842935, 6155.953487906977, 6162.044883043135, 5768.625039372595, 5837.591368604157, 5838.153467747238, 5962.731677074629, 6001.729922153426], "value": 5612.870133431735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.77920377422623, 499.21872269897585, 514.1901505129421, 515.1988657637377, 550.3285991090868, 28562.0, 40541.0, 42014.57985420149, 42324.94187645303, 43928.90177745558, 43147.784261078654, 43485.78257108718, 43503.0, 43538.56461435385, 43783.3432498512, 8559.572021398937, 9440.244780417584, 9665.951670241675, 9667.806643867118, 9678.806423871518, 8571.571421428935, 11567.132465065111, 11858.703532411673, 11871.406429678525, 11982.580609678664, 11680.591179308725, 11752.118591105702, 11763.588274410396, 11780.057595392347, 11860.406979651027, 11639.35983520906, 11721.824172637418, 11722.0, 11734.413279335999, 11739.882601174008, 11534.538618455252, 11575.710607234887, 11576.305421674726, 11578.131640127023, 11606.535738570446, 40296.99257518556, 40500.0, 40510.0, 40568.0, 40639.59360406388, 3601.7478776485655, 3629.1471504196493, 4047.0691740899756, 4058.736182148151, 4183.518895327046, 29843.701562984308, 31272.530912036338, 31319.686803131906, 31319.843400783077, 31361.372772544648, 3101.3332133591384, 3107.37852429515, 3243.2216268095485, 3264.755143364257, 3267.9836600816843, 486341.0, 490542.0, 491175.54412228067, 492572.0, 497195.51402243116, 59612.80774384488, 60158.69920650369, 60224.69887650621, 60282.0, 60286.39713602808, 58632.70683646554, 58745.11882321769, 58780.70609646924, 58814.11778823321, 58896.0, 15675.608109797346, 15907.0, 15910.920445397698, 15942.442014529668, 15969.20153992319, 11127.499262533205, 11375.146863985068, 12169.2698438094, 12369.257844529357, 12471.812922806164, 15848.0, 15996.68006639884, 16291.0, 16352.345906163859, 16363.509094727176, 6269.780557680459, 6305.716242769086, 6307.211598550181, 6378.77674281398, 6379.936200637934, 799.4643588795404, 855.4952577978897, 923.630547780893, 1012.6050840172338, 1017.6692574913183, 28994.985175518756, 29142.277296516015, 29474.557881631794, 29713.0, 29749.0, 1082.9133669306436, 1085.9945700271448, 1088.374184843718, 1097.13874608432, 1099.4612639806596, 14330.0, 14657.486987955584, 15002.774958375632, 15295.464658736892, 15402.6149346265, 616771.0, 619582.0, 620216.0, 620372.0, 620982.0, 58077.0, 58182.418175818544, 58314.0, 58402.70798646064, 58459.70770146207, 1246.8254444377794, 1297.1374036265966, 1379.3034517568765, 1400.2998500749625, 1426.0588011912089, 30262.940797072, 31128.0, 31259.218519537193, 31462.79477334349, 32192.0, 44770.328445074, 45651.771741141085, 45675.858103547675, 45689.0, 45762.31356529588, 486255.0, 486906.0, 488209.0, 488636.0, 488882.5555872198, 25550.361240969123, 26079.087231946793, 26162.0, 26591.468170636093, 26896.32759180958, 45920.54079459162, 46903.76548117238, 47025.52974470348, 47303.58089257328, 47469.762651186524, 35205.471917921765, 38323.0, 39435.802820985715, 39517.209655807295, 40071.79605121746, 39121.021974450865, 39211.41182882202, 40263.59736402598, 40373.394399084624, 40509.189816202925, 37372.0, 37802.810985944896, 38124.809375952944, 38410.847674569806, 38525.0, 33187.50218746769, 34226.31547368989, 35052.773152939524, 35095.824520877235, 35241.647583524864, 21856.0, 25224.62163067519, 25808.0, 26038.34904127412, 26092.478150437266], "value": 20027.423174039715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.6710858901221, 372.59573362901233, 377.48096367494696, 383.80809595202396, 409.4860949508367, 14875.553733388002, 14976.850231497681, 15547.844521554782, 16718.749218761714, 20937.37187884362, 19247.518812029706, 19748.605027899433, 20250.39248822534, 20390.0, 20762.584748305024, 5793.5365170786345, 5899.387432649996, 6099.847503812396, 6102.389761023885, 6107.480864126533, 5867.76528938842, 5996.640201587897, 6079.240094988127, 6080.270367555878, 6080.30076541199, 5827.679477628729, 5898.139402389837, 6026.548008899328, 6039.0, 6039.184710064129, 5911.261092363455, 5916.26046744157, 5957.0, 5959.642421454683, 5959.6722180280285, 4980.9252861207115, 5143.948560514385, 5161.27742116104, 5178.249153872705, 5213.582913366921, 25335.87332063328, 25434.36414089644, 25436.872815635805, 25475.0, 25640.230793076236, 2058.876467411945, 2273.7044184256106, 2509.912153074634, 2566.3455818766283, 2763.9170824875287, 10387.220958428072, 10463.581456741722, 12712.682182945407, 14683.486077987325, 15453.0, 1844.4190080124852, 1857.7914360588652, 1863.7204419337072, 1907.0655378864396, 1938.1472152253104, 87522.0, 128483.71516284811, 128896.0, 129517.0, 130920.0, 9266.721998340061, 9631.470269135265, 9966.103050725469, 9966.651167209113, 11277.0, 12293.93853030729, 12359.38203089842, 12368.75262494745, 12370.195937264049, 12406.193597416139, 7845.882311765328, 8002.319802816709, 8021.398395120389, 8110.067342255598, 8112.0, 7437.0, 7799.181085986003, 8317.542535160503, 8351.874721879176, 8467.915320846712, 3813.733038687321, 3870.845166193377, 3876.7092468065007, 3945.0, 3945.3292940200236, 1623.8782091343196, 1692.5430133863788, 1892.9053547322578, 1928.8553358498168, 1935.1195206181435, 883.1565854608833, 886.8980067292345, 890.5617427854098, 905.6739573753443, 969.7139343893568, 18297.81702182961, 21489.032993515328, 21568.41179058523, 21581.67627485589, 21593.67609485859, 896.9641214351483, 934.780326623243, 940.6331530703031, 946.2760987844305, 958.2812890332251, 10771.0, 11129.165312601588, 11217.270877392943, 11239.494222759995, 11242.49408776607, 506266.4686676543, 524673.0, 563878.0, 579126.0, 584074.0, 86695.0, 102376.48811756042, 108430.0, 129856.0, 135617.3219133918, 914.2548822709604, 932.7481579973505, 960.6109525642127, 961.1829944547079, 965.3628605120587, 15253.694926101638, 18895.0, 19451.0, 19454.513637159183, 19557.022148892782, 52505.7374713124, 54617.726911365186, 55757.260632846665, 56166.0, 56677.716611416676, 77793.83309250252, 77877.61061194658, 79776.60111699405, 80430.0, 82241.58879205566, 7564.432667549846, 7931.255123872739, 7972.0, 8211.0, 8286.674132138829, 12271.386430678253, 13156.934215328862, 13239.007074469646, 13630.522931697344, 13843.169409835442, 10945.507452164513, 11105.0, 11645.88354116448, 11687.0, 11747.765044698886, 11362.602308919148, 11372.20394572372, 11540.076793856304, 11651.359175245443, 11811.291322520676, 11481.483333249857, 11645.88354116448, 11701.180917335705, 11758.529658813894, 11856.762864742484, 6976.790696279119, 7335.7432489862595, 7344.339009489172, 7549.527842070744, 8228.300594449538, 9417.717468475958, 11574.363410012193, 11599.536018559163, 11658.708532286422, 11732.589359372381], "value": 9405.4102833411, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1526.97709534357, 1559.992200039, 1791.3461586520916, 1909.7899231084587, 1944.6791279438894, 8384.16158384162, 8904.777380565482, 8905.465672059681, 8910.688125915594, 8913.910860891388, 7715.575643339614, 8040.718574849881, 8044.718434854782, 8194.508329500219, 8409.20112589303, 2279.521300526887, 2320.535892821434, 2321.7330007049236, 2322.465832858435, 2325.139698311627, 2281.870474115307, 2288.141946769961, 2319.9884000579973, 2325.5813953488364, 2325.697659304288, 2290.667853161299, 2312.7340355859037, 2316.9884150579223, 2318.8260880434036, 2318.9420264493438, 2278.7265528136563, 2293.8508996915148, 2297.5404919016264, 2301.1255722825304, 2301.42464383904, 2278.1798552521077, 2285.325828880484, 2286.8284878634004, 2291.175176936302, 2297.4371279036577, 8403.83192336156, 8575.185357391038, 8582.399232053758, 8595.914040859574, 8602.655893764242, 5154.536091751723, 5313.601479889024, 5317.680939143664, 5328.280682107905, 5359.222912677679, 3373.477111047767, 3434.142459212082, 3573.803440810754, 3577.8926632201074, 3587.0494319005484, 1019.7807471393686, 1037.9169666426667, 1050.8528805967094, 1074.6131392698621, 1076.036946932499, 11442.313461192354, 11593.13051521131, 11593.884061159364, 11601.129915256304, 11612.0, 4085.325921222965, 4121.361189015707, 4184.974681203124, 4542.068875880436, 4746.738929358849, 9938.0, 9946.850797238047, 9955.054269844353, 9962.302638815358, 9962.352447090916, 5318.388385335736, 5325.201219817059, 5331.680099194061, 5336.1195402758885, 5338.786448542015, 4346.826126954949, 4389.934150987674, 4514.300283456069, 4840.15297322967, 4884.7313397762755, 3796.5823759387, 3853.8843834685003, 3879.592642772525, 3937.5668676445566, 3977.5823538528616, 1232.8520577530753, 1234.2409418207737, 1234.912997035008, 1245.1968480330136, 1272.4910035985495, 832.3341326938425, 859.862422012475, 883.3066043156092, 890.1988210610502, 901.8872640919885, 5871.94128058714, 5984.551158663117, 6020.307664618537, 6165.075238714139, 6309.053641953652, 938.4088024544493, 938.7655233368235, 942.5711301357866, 963.1764841060932, 978.1441238915949, 3445.793252404864, 3807.238552289498, 3950.466686997276, 3994.800259986989, 4092.7544347338658, 10323.277370583986, 10813.08088812444, 10820.89179108199, 10849.457527123612, 10882.727931801768, 10746.0, 10746.623868164577, 10749.946250268855, 10754.623588174376, 10759.408232547285, 6860.416864566568, 7272.200057993722, 8147.185281471805, 8188.099309075997, 8222.0, 5188.221766734869, 5904.261967254093, 5970.31341395745, 6116.26604807426, 6251.531135164972, 9888.802223955625, 10051.899481005097, 10053.396796192252, 10067.949660251652, 10079.99200079986, 9875.456849873326, 10010.299279050396, 10133.797324053625, 10171.440570768678, 10309.793804124025, 6935.618540980093, 7141.464390170654, 7155.212926577969, 7430.219826918095, 7658.119316278699, 8117.269445749911, 8425.662973480992, 8426.620802064044, 8529.914700852913, 8535.8719619207, 7436.73971410998, 7524.548527088392, 7573.848523029398, 7589.051368578927, 7728.381729461742, 7294.744683936036, 7296.744613938699, 7452.813679658052, 7458.552486850589, 7494.17564067963, 6876.243613202644, 7035.964820175866, 7037.085178926707, 7039.9296007039275, 7043.330883566053, 4770.320645151731, 4880.316755654282, 4893.266010098514, 4907.656464047483, 4952.083864485082, 4032.9798351008058, 4800.7599620019555, 4832.8791780205775, 4890.364252647133, 4980.477049909708], "value": 4541.508203886601, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.7160782433214, 300.8555893171278, 307.56479581392324, 314.33518109199025, 315.0689711901332, 4838.975805120978, 4891.48639392864, 5254.397408790317, 5472.835814925554, 5516.586256030803, 5152.639315247925, 5257.342832145981, 5259.844093543872, 5465.754041068151, 5471.671699698011, 4097.426360309559, 4113.950942509656, 4147.357159640245, 4149.626533611974, 4169.206631556849, 4141.825541590659, 4161.459010328653, 4164.68764842638, 4183.288840897039, 4213.050864394921, 3596.37063513884, 3979.2686722760386, 4066.79666016698, 4117.3206420940505, 4121.814518346681, 3854.0516504873794, 4079.9184016319805, 4080.7347522410946, 4082.693538067834, 4087.8364865405347, 2943.882244710209, 3494.336076145524, 3498.6851183393364, 3513.244652399721, 3540.6636369544854, 4834.6971195635915, 5048.697078175322, 5245.0, 5309.469053094698, 5419.918701219485, 4288.485381754178, 4668.789904454307, 4674.387336368981, 4701.929471057937, 4709.764511774431, 4937.432195297551, 4953.628477864138, 4961.826336078256, 5597.216389705444, 5647.463490968352, 2868.5553739170464, 3738.8504459821575, 3883.165119499321, 3891.3579259422163, 3931.7444366116397, 4934.358533390637, 5389.541888939445, 5581.553475721932, 5866.296044474648, 5917.319508256563, 3775.8300876460075, 3824.4454554089502, 3941.4087886816633, 3943.4873466449176, 3952.4268980997595, 3993.5008123984503, 3993.6206060424215, 3999.5200575931076, 4004.479417675742, 4013.1973605278476, 3840.6543411093135, 4465.26323156682, 4548.635409377173, 4695.107929493385, 4714.57568818808, 4942.703437793743, 4981.825636102719, 5149.87165273331, 5589.133684278943, 5715.628484148517, 1063.6011495689118, 1246.8566114896903, 1261.1739310751473, 1351.8036537664238, 1360.5170164591625, 769.442154438028, 815.9306458951056, 816.6284340625001, 831.7380025292014, 835.2274146414542, 4192.874213773592, 4456.799444025027, 4482.731036137777, 4525.864224073283, 4541.591256786839, 4837.427835953259, 4868.95690193629, 4897.086233690949, 4911.68074075184, 4916.680415772963, 4102.815373308208, 4273.18809426208, 4485.241576430537, 4499.910001800011, 4573.382593349856, 2156.1914282144194, 2191.5069109450565, 2280.9771902280763, 2352.4354155002675, 2579.877753177374, 5036.521530454601, 5037.798488060436, 5070.526689217824, 5089.541941225233, 5115.925655612363, 4187.78554219273, 4353.804078816461, 4562.585598464487, 4935.333729946483, 5046.369203849519, 4105.507339119265, 4216.42797893291, 4366.912661746747, 4627.986825730818, 5028.1590901140735, 4515.751633660049, 4634.907301853877, 4675.942585262474, 4910.680805747615, 4922.252600326832, 4048.1701251244012, 4182.979085104676, 4850.98684046131, 4907.484714104968, 4951.480094590016, 4801.759912004456, 5144.659180073133, 5341.813036543703, 5390.113460288868, 5573.749181286933, 3499.755017148877, 3605.7920596600434, 3839.2513459876172, 3903.2974064667496, 3934.606539346157, 4764.761761911821, 5542.3903370628395, 5880.323762767336, 5886.882262354643, 5928.555358348055, 5533.391326953952, 5712.914546236202, 5829.0, 5839.8832023358445, 5853.882922341444, 4486.730796152241, 5361.605982444515, 5748.367679555329, 5758.309002919509, 5817.621854579439, 4493.404841281298, 4929.151568161939, 5338.492843179892, 5663.725661726078, 5716.399778023248, 4923.187674033744, 5059.443461219336, 5463.262459567907, 5776.934219069875, 5885.764569417347, 5563.860903477445, 5610.719464026701, 5647.29408823897, 5682.289713785777, 5690.487856092972], "value": 3909.713642249367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.9354317796631, 751.9661615227311, 799.7920540659427, 818.6438899078905, 826.8635675113607, 15519.922400387997, 15827.683446331066, 15827.841721582796, 15879.682406351865, 16233.350665973376, 14281.642958926031, 14392.992490525667, 14393.920455965817, 14395.712085758278, 14436.783448248285, 702.7997020849059, 734.0347443112317, 734.0787311923528, 734.170387462167, 894.9731508054768, 728.624758249502, 869.9782505437352, 906.112010229975, 910.1899309614415, 912.1106920752246, 957.8946315905245, 962.7111866440075, 971.6307803034872, 972.4894430424014, 973.610555777691, 952.2429668413558, 955.3169483819033, 955.7180631713626, 957.1290125985389, 957.7749228931195, 548.7420912171273, 567.4098937105447, 718.2637796258824, 719.5934297122161, 722.1009842745815, 12225.960793332577, 12284.201526900722, 12289.93855030728, 14042.508512202025, 14632.195229262386, 14035.649108772363, 14653.0, 14686.706265874729, 14726.48457304, 14768.0, 10814.891851081467, 10887.510062047228, 10918.836217456665, 11210.27133236337, 11240.943795281053, 3755.680767134797, 3769.6230376962358, 3805.5813860475328, 3897.123147291865, 3911.413288006793, 108359.45820270848, 112344.43827780809, 112869.0, 113168.0, 113930.43034784855, 10458.63394781179, 10867.347959122479, 11085.556577736961, 11184.832227516594, 11243.943780281046, 21055.578888422147, 21791.782082179292, 23104.65343019856, 23127.653085203736, 23129.768702313097, 7996.510757671111, 8047.235512626291, 8153.633086511121, 8213.219744124299, 8248.958755206186, 4215.199112168678, 4253.893652658647, 4282.914341713211, 4289.7640629765665, 4301.935470967938, 3655.8484077515495, 3784.6783023442763, 3793.24135172961, 3796.867109651149, 3799.430085487199, 668.0647094068246, 669.2771806449024, 678.5860625018735, 679.9932000680029, 687.9484038697018, 590.9084091965752, 675.6924605639051, 680.5728734581515, 680.9182898052167, 701.9964900175569, 8767.561621918878, 8768.298536117096, 9280.67517636893, 9410.551163920833, 9515.714528564155, 675.598019178588, 678.2844099475003, 688.0882830249939, 688.4905170174035, 688.8840079071836, 4313.439252897166, 4567.703099298535, 4614.1694494991225, 4673.7429441381055, 4995.300657907896, 99020.53428129981, 120629.0, 150911.0, 156005.2199738994, 159824.0, 132784.0, 137045.0, 137085.3145734265, 138960.0, 141750.0, 89466.55266723625, 94420.0, 99484.50773238407, 103404.0, 103538.0, 8070.119711738795, 9559.20715921904, 9604.759881002752, 9645.855312170183, 9671.323007389698, 65620.0, 65772.01341979779, 66597.0, 67124.32875671376, 69315.65342173257, 101765.98234017564, 103485.93028139244, 103732.0, 104607.07858428193, 106354.0, 10881.836772448578, 13083.702852137765, 13231.933840330737, 13287.42895751358, 13410.664733381744, 11974.880251197377, 14738.189399582698, 15058.0, 15143.318550665466, 15667.451639192574, 13857.72284554283, 13904.652383690489, 14082.718345633235, 14100.224487653277, 14259.420519316056, 13542.526011589547, 13819.516316928859, 13955.720885582028, 14121.858781412055, 14275.0, 13737.862621373659, 13741.175529468264, 13871.23708196059, 13946.930265348608, 14157.504487342894, 10941.726456838644, 11347.432628368384, 11349.659510214708, 11590.536378544764, 11791.823122653339, 10403.422256550257, 10542.789144217226, 10583.20625953072, 10644.414557199429, 11512.19414640967], "value": 7889.327899482524, "unit": "score"}], "name": "dromaeo_css", "value": 7273.9493015270245, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 123.0, 148.0, 114.0, 113.0, 113.0, 108.0, 114.0, 113.0, 114.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 113.0, 114.0, 149.0, 114.0, 113.0, 117.0, 111.0, 141.0, 111.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 181.0, 185.0, 185.0, 183.0, 180.0, 182.0, 184.0, 180.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 87.0, 62.0, 74.0, 80.0, 81.0, 81.0, 56.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 95.0, 72.0, 69.0, 68.0, 94.0, 90.0, 98.0, 123.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 86.0, 88.0, 83.0, 89.0, 86.0, 88.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 108.0, 148.0, 93.0, 94.0, 94.0, 93.0, 96.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 81.0, 83.0, 81.0, 83.0, 84.0, 89.0, 124.0], "value": 87.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 53.0, 54.0, 54.0, 54.0, 52.0, 55.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 77.0, 75.0, 74.0, 70.0, 71.0, 71.0, 71.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 86.0, 89.0, 86.0, 86.0, 85.0, 86.0, 90.0, 89.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 145.0, 146.0, 148.0, 147.0, 159.0, 161.0, 162.0, 207.0, 162.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 172.0, 169.0, 173.0, 171.0, 170.0, 170.0, 171.0, 169.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 76.0, 88.0, 73.0, 68.0, 67.0, 68.0, 67.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1468.2000000000003, "alertThreshold": 2.0}]} 19:33:00 INFO - Return code: 0 19:33:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:33:00 INFO - # TBPL SUCCESS # 19:33:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:33:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:33:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:33:00 INFO - 2016-10-23 19:33:00.246 screenresolution[2269:21270] starting screenresolution argv=screenresolution get 19:33:00 INFO - 2016-10-23 19:33:00.249 screenresolution[2269:21270] Display 0: 1600x1200x32@60 19:33:00 INFO - 2016-10-23 19:33:00.255 screenresolution[2270:21273] starting screenresolution argv=screenresolution list 19:33:00 INFO - Available Modes on Display 0 19:33:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:33:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:33:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:33:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:33:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:33:00 INFO - Intel Iris: 19:33:00 INFO - Chipset Model: Intel Iris 19:33:00 INFO - Type: GPU 19:33:00 INFO - Bus: Built-In 19:33:00 INFO - VRAM (Dynamic, Max): 1536 MB 19:33:00 INFO - Vendor: Intel (0x8086) 19:33:00 INFO - Device ID: 0x0a2e 19:33:00 INFO - Revision ID: 0x0009 19:33:00 INFO - Displays: 19:33:00 INFO - Display: 19:33:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:33:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:33:00 INFO - Display Serial Number: 1600x1200 60 19:33:00 INFO - Main Display: Yes 19:33:00 INFO - Mirror: Off 19:33:00 INFO - Online: Yes 19:33:00 INFO - Rotation: Supported 19:33:00 INFO - Return code: 0 19:33:00 INFO - Running post-action listener: _resource_record_post_action 19:33:00 INFO - [mozharness: 2016-10-24 02:33:00.500508Z] Finished run-tests step (success) 19:33:00 INFO - Running post-run listener: _resource_record_post_run 19:33:00 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 565248; Write bytes: 706713600; Read time: 3; Write time: 2023 19:33:00 INFO - TinderboxPrint: CPU usage
53.7% 19:33:00 INFO - TinderboxPrint: I/O read bytes / time
565,248 / 3 19:33:00 INFO - TinderboxPrint: I/O write bytes / time
706,713,600 / 2,023 19:33:00 INFO - TinderboxPrint: CPU idle
2,249.3 (46.3%) 19:33:00 INFO - TinderboxPrint: CPU system
972.4 (20.0%) 19:33:00 INFO - TinderboxPrint: CPU user
1,631.9 (33.6%) 19:33:00 INFO - TinderboxPrint: Swap in / out
404,508,672 / 0 19:33:00 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393298432; Write bytes: 388470784; Read time: 29556; Write time: 850 19:33:00 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 565248; Write bytes: 297820160; Read time: 3; Write time: 1114 19:33:00 INFO - Running post-run listener: _upload_blobber_files 19:33:00 INFO - Blob upload gear active. 19:33:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:33:00 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 19:33:00 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'] 19:33:00 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 19:33:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:02 INFO - (blobuploader) - INFO - Done attempting. 19:33:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:33:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:03 INFO - (blobuploader) - INFO - Done attempting. 19:33:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:33:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:33:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:05 INFO - (blobuploader) - INFO - Done attempting. 19:33:05 INFO - (blobuploader) - INFO - Iteration through files over. 19:33:05 INFO - Return code: 0 19:33:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:33:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:33:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c5ff73d2aa4c60f3ed7ed4797c3c5bed6d1a6e1c99f6e5c2a6cd6f8e102a568aaed5dd01bc4d3bd6167c43ff0866da74dd00b7b4de6f4c9c154c0e9da14e139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0442d8bcc22b4b1085a7d6ce45a9c5a64efa63a17e9214fc6800573725f0e48e9199659793e1bd7166fe3cf899648c401e8cbee492c79cc7da6a92132c632cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0a2c268bd35fdbf9851ac0712c5de9d116d7b3724c83070d3805c0ea4fe4e8de115f452c157b886737018b82905c9c07c2281986c3a6a1ad996c1bc12e4f65"} 19:33:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:33:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:33:05 INFO - Contents: 19:33:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c5ff73d2aa4c60f3ed7ed4797c3c5bed6d1a6e1c99f6e5c2a6cd6f8e102a568aaed5dd01bc4d3bd6167c43ff0866da74dd00b7b4de6f4c9c154c0e9da14e139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0442d8bcc22b4b1085a7d6ce45a9c5a64efa63a17e9214fc6800573725f0e48e9199659793e1bd7166fe3cf899648c401e8cbee492c79cc7da6a92132c632cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0a2c268bd35fdbf9851ac0712c5de9d116d7b3724c83070d3805c0ea4fe4e8de115f452c157b886737018b82905c9c07c2281986c3a6a1ad996c1bc12e4f65"} 19:33:05 INFO - Running post-run listener: copy_logs_to_upload_dir 19:33:05 INFO - Copying logs to upload dir... 19:33:05 INFO - mkdir: /builds/slave/test/build/upload/logs 19:33:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.174167 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-23 19:33:05.449407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 19:33:05.455799) ========= 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.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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/0c5ff73d2aa4c60f3ed7ed4797c3c5bed6d1a6e1c99f6e5c2a6cd6f8e102a568aaed5dd01bc4d3bd6167c43ff0866da74dd00b7b4de6f4c9c154c0e9da14e139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0442d8bcc22b4b1085a7d6ce45a9c5a64efa63a17e9214fc6800573725f0e48e9199659793e1bd7166fe3cf899648c401e8cbee492c79cc7da6a92132c632cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0a2c268bd35fdbf9851ac0712c5de9d116d7b3724c83070d3805c0ea4fe4e8de115f452c157b886737018b82905c9c07c2281986c3a6a1ad996c1bc12e4f65"} build_url:https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011172 build_url: 'https://queue.taskcluster.net/v1/task/Q_4zx7E7TOuhWR38I1NY9g/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/0c5ff73d2aa4c60f3ed7ed4797c3c5bed6d1a6e1c99f6e5c2a6cd6f8e102a568aaed5dd01bc4d3bd6167c43ff0866da74dd00b7b4de6f4c9c154c0e9da14e139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0442d8bcc22b4b1085a7d6ce45a9c5a64efa63a17e9214fc6800573725f0e48e9199659793e1bd7166fe3cf899648c401e8cbee492c79cc7da6a92132c632cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0a2c268bd35fdbf9851ac0712c5de9d116d7b3724c83070d3805c0ea4fe4e8de115f452c157b886737018b82905c9c07c2281986c3a6a1ad996c1bc12e4f65"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 19:33:05.511016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:33:05.511433) ========= 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.YyhIK93JIP/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.PLE6aIr3KP/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005283 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 19:33:05.591586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 19:33:05.592153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 19:33:05.595183) ========= ========= Total master_lag: 0.33 =========