builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0100 starttime: 1477470387.61 results: success (0) buildid: 20161025221325 builduid: 09ad6336a6c94ab980a15525118f2c43 revision: f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:27.608498) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:27.609015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:27.609331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:27.699767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:27.700024) ========= 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.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-26 01:26:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.34M=0.001s 2016-10-26 01:26:28 (8.34 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.542346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:28.266455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:28.266783) ========= 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.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.019662 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:28.315368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 01:26:28.315744) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 --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 f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-26 01:26:28,392 truncating revision to first 12 chars 2016-10-26 01:26:28,392 Setting DEBUG logging. 2016-10-26 01:26:28,392 attempt 1/10 2016-10-26 01:26:28,392 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f9f3cc95d728?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 01:26:29,183 unpacking tar archive at: fx-team-f9f3cc95d728/testing/mozharness/ program finished with exit code 0 elapsedTime=1.031682 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 01:26:29.375392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:29.375706) ========= script_repo_revision: f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:29.376091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:29.376386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 01:26:29.401937) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-26 01:26:29.405266) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:26:29 INFO - MultiFileLogger online at 20161026 01:26:29 in /builds/slave/test 01:26:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:26:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:29 INFO - {'append_to_log': False, 01:26:29 INFO - 'base_work_dir': '/builds/slave/test', 01:26:29 INFO - 'blob_upload_branch': 'Fx-Team', 01:26:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:29 INFO - 'branch': 'Fx-Team', 01:26:29 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:29 INFO - 'config_files': ('talos/mac_config.py',), 01:26:29 INFO - 'default_actions': ('clobber', 01:26:29 INFO - 'read-buildbot-config', 01:26:29 INFO - 'download-and-extract', 01:26:29 INFO - 'populate-webroot', 01:26:29 INFO - 'create-virtualenv', 01:26:29 INFO - 'install', 01:26:29 INFO - 'run-tests'), 01:26:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:29 INFO - 'download_minidump_stackwalk': True, 01:26:29 INFO - 'download_symbols': 'ondemand', 01:26:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:29 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:29 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:29 INFO - 'installer_path': 'installer.exe', 01:26:29 INFO - 'log_level': 'info', 01:26:29 INFO - 'log_name': 'talos', 01:26:29 INFO - 'log_to_console': True, 01:26:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:26:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:26:29 INFO - 'opt_config_files': (), 01:26:29 INFO - 'pip_index': False, 01:26:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:29 INFO - 'cmd': ('bash', 01:26:29 INFO - '-c', 01:26:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:29 INFO - 'enabled': True, 01:26:29 INFO - 'halt_on_failure': False, 01:26:29 INFO - 'name': 'check_screen_resolution'},), 01:26:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:29 INFO - 'cmd': ('bash', 01:26:29 INFO - '-c', 01:26:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:29 INFO - 'enabled': True, 01:26:29 INFO - 'halt_on_failure': False, 01:26:29 INFO - 'name': 'check_screen_resolution'},), 01:26:29 INFO - 'run_cmd_checks_enabled': True, 01:26:29 INFO - 'sps_profile': False, 01:26:29 INFO - 'sps_profile_interval': 0, 01:26:29 INFO - 'suite': 'dromaeojs-e10s', 01:26:29 INFO - 'system_bits': '32', 01:26:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:29 INFO - 'title': 't-yosemite-r7-0100', 01:26:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:29 INFO - 'use_talos_json': True, 01:26:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:29 INFO - 'work_dir': 'build'} 01:26:29 INFO - [mozharness: 2016-10-26 08:26:29.610028Z] Running clobber step. 01:26:29 INFO - Running pre-action listener: _resource_record_pre_action 01:26:29 INFO - Running main action method: clobber 01:26:29 INFO - rmtree: /builds/slave/test/build 01:26:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:31 INFO - Running post-action listener: _resource_record_post_action 01:26:31 INFO - [mozharness: 2016-10-26 08:26:31.242946Z] Finished clobber step (success) 01:26:31 INFO - [mozharness: 2016-10-26 08:26:31.243091Z] Running read-buildbot-config step. 01:26:31 INFO - Running pre-action listener: _resource_record_pre_action 01:26:31 INFO - Running main action method: read_buildbot_config 01:26:31 INFO - Using buildbot properties: 01:26:31 INFO - { 01:26:31 INFO - "project": "", 01:26:31 INFO - "product": "firefox", 01:26:31 INFO - "script_repo_revision": "production", 01:26:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 01:26:31 INFO - "repository": "", 01:26:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 01:26:31 INFO - "buildid": "20161025221325", 01:26:31 INFO - "pgo_build": "False", 01:26:31 INFO - "basedir": "/builds/slave/test", 01:26:31 INFO - "buildnumber": 17, 01:26:31 INFO - "slavename": "t-yosemite-r7-0100", 01:26:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:26:31 INFO - "platform": "yosemite_r7", 01:26:31 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 01:26:31 INFO - "branch": "fx-team", 01:26:31 INFO - "repo_path": "integration/fx-team", 01:26:31 INFO - "revision": "f9f3cc95d7282f1fd83f66dd74acbcdbfe821915", 01:26:31 INFO - "stage_platform": "macosx64", 01:26:31 INFO - "builduid": "09ad6336a6c94ab980a15525118f2c43", 01:26:31 INFO - "slavebuilddir": "test" 01:26:31 INFO - } 01:26:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:26:31 INFO - Running post-action listener: _resource_record_post_action 01:26:31 INFO - [mozharness: 2016-10-26 08:26:31.246191Z] Finished read-buildbot-config step (success) 01:26:31 INFO - [mozharness: 2016-10-26 08:26:31.246295Z] Running download-and-extract step. 01:26:31 INFO - Running pre-action listener: _resource_record_pre_action 01:26:31 INFO - Running main action method: download_and_extract 01:26:31 INFO - mkdir: /builds/slave/test/build/tests 01:26: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')]} 01:26:31 INFO - trying https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:26:31 INFO - Downloading https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/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 01:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/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 01:26:32 INFO - Downloaded 1199 bytes. 01:26:32 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:26:32 INFO - Using the following test package requirements: 01:26:32 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:26:32 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:26:32 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:26:32 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'jsshell-mac.zip'], 01:26:32 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:26:32 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:26:32 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:26:32 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:26:32 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:26:32 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:32 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:26:32 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:26:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:26:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:26:32 INFO - Fetch https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:26:32 INFO - Http code: 200 01:26:32 INFO - via: 1.1 dc7c4fb5024ff022cad1642ec506a6e8.cloudfront.net (CloudFront) 01:26:32 INFO - x-amz-cf-id: dl4nN0p9Dhxn6iAp-wHB5LKreQb9fX263YAHpDI8JvYb10-OJxfdrA== 01:26:32 INFO - x-amz-version-id: IZ8TyIQWYIh8x5pGsR935ZfWU9hVa0KP 01:26:33 INFO - Expected file size: 16438394 01:26:33 INFO - Obtained file size: 16438394 01:26:40 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 01:26:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:26:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:26:40 INFO - Fetch https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:26:40 INFO - Http code: 200 01:26:40 INFO - via: 1.1 5574699f9d8b8aad21acf2edbb25dc62.cloudfront.net (CloudFront) 01:26:40 INFO - x-amz-cf-id: frLJUx6dQStTfr0B-OW5c0_rTSqqKnDVgEgqUkUSiLr2MQxKntAuQw== 01:26:40 INFO - x-amz-version-id: IZ8TyIQWYIh8x5pGsR935ZfWU9hVa0KP 01:26:41 INFO - Expected file size: 16438394 01:26:41 INFO - Obtained file size: 16438394 01:26:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:26:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:26:47 INFO - Fetch https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:26:47 INFO - Http code: 200 01:26:47 INFO - via: 1.1 96255a5b0caf50bcafcd101c1ff13691.cloudfront.net (CloudFront) 01:26:47 INFO - x-amz-cf-id: ChyvlXMKzFrEhRiQJS2ySiZ73GUTq90l0gGdkRyLM3M5QQG-sVyahQ== 01:26:47 INFO - x-amz-version-id: c_z94rUFkydbkb4jPdns9ptt9kA_Q4.T 01:26:48 INFO - Expected file size: 14409028 01:26:48 INFO - Obtained file size: 14409028 01:26:48 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')]} 01:26:48 INFO - trying https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:48 INFO - Downloading https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:26:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/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 01:26:53 INFO - Downloaded 111653951 bytes. 01:26:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:53 INFO - mkdir: /builds/slave/test/properties 01:26:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:53 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:53 INFO - Contents: 01:26:53 INFO - build_url:https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:53 INFO - Running post-action listener: _resource_record_post_action 01:26:53 INFO - Running post-action listener: set_extra_try_arguments 01:26:53 INFO - [mozharness: 2016-10-26 08:26:53.821967Z] Finished download-and-extract step (success) 01:26:53 INFO - [mozharness: 2016-10-26 08:26:53.822123Z] Running populate-webroot step. 01:26:53 INFO - Running pre-action listener: _resource_record_pre_action 01:26:53 INFO - Running main action method: populate_webroot 01:26:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:53 INFO - '--tppagecycles', 01:26:53 INFO - '7'], 01:26:53 INFO - 'tests': ['tsvgm']}}, 01:26:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['damp', 'tps']}, 01:26:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'tests': ['damp', 'tps']}, 01:26:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['dromaeo_dom']}, 01:26:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['basic_compositor_video']}, 01:26:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:26:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['a11yr', 01:26:53 INFO - 'ts_paint', 01:26:53 INFO - 'tpaint', 01:26:53 INFO - 'sessionrestore', 01:26:53 INFO - 'sessionrestore_no_auto_restore', 01:26:53 INFO - 'tabpaint']}, 01:26:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['tsvgx', 01:26:53 INFO - 'tsvgr_opacity', 01:26:53 INFO - 'tart', 01:26:53 INFO - 'tscrollx', 01:26:53 INFO - 'cart']}, 01:26:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:53 INFO - 'tsvgr_opacity', 01:26:53 INFO - 'tart', 01:26:53 INFO - 'tscrollx', 01:26:53 INFO - 'cart']}, 01:26:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'talos_options': ['--disable-e10s'], 01:26:53 INFO - 'tests': ['tp5o']}, 01:26:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'tests': ['tp5o']}, 01:26:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'talos_options': ['--disable-e10s', 01:26:53 INFO - '--xperf_path', 01:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:53 INFO - 'tests': ['tp5n']}, 01:26:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:53 INFO - 'talos_options': ['--xperf_path', 01:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:53 INFO - 'tests': ['tp5n']}}, 01:26:53 INFO - 'talos.zip': {'path': '', 01:26:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:53 INFO - Running post-action listener: _resource_record_post_action 01:26:53 INFO - [mozharness: 2016-10-26 08:26:53.840771Z] Finished populate-webroot step (success) 01:26:53 INFO - [mozharness: 2016-10-26 08:26:53.840905Z] Running create-virtualenv step. 01:26:53 INFO - Running pre-action listener: _resource_record_pre_action 01:26:53 INFO - Running main action method: create_virtualenv 01:26:53 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26:53 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 01:26:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:53 INFO - Using real prefix '/tools/python27' 01:26:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:54 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:57 INFO - Installing pip.................done. 01:26:57 INFO - Return code: 0 01:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:26:57 INFO - Reading from file tmpfile_stdout 01:26:57 INFO - Output received: 01:26:57 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) 01:26:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:26:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:57 INFO - 'HOME': '/Users/cltbld', 01:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:57 INFO - 'LOGNAME': 'cltbld', 01:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:57 INFO - 'MOZ_NO_REMOTE': '1', 01:26:57 INFO - 'NO_EM_RESTART': '1', 01:26:57 INFO - 'PAGER': '/bin/cat', 01:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:57 INFO - 'PWD': '/builds/slave/test', 01:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:57 INFO - 'SHELL': '/bin/bash', 01:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:26:57 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:57 INFO - 'USER': 'cltbld', 01:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:57 INFO - 'XPC_FLAGS': '0x0', 01:26:57 INFO - 'XPC_SERVICE_NAME': '0', 01:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:26:58 INFO - Cleaning up... 01:26:58 INFO - Return code: 0 01:26:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:58 INFO - 'HOME': '/Users/cltbld', 01:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:58 INFO - 'LOGNAME': 'cltbld', 01:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:58 INFO - 'MOZ_NO_REMOTE': '1', 01:26:58 INFO - 'NO_EM_RESTART': '1', 01:26:58 INFO - 'PAGER': '/bin/cat', 01:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:58 INFO - 'PWD': '/builds/slave/test', 01:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:58 INFO - 'SHELL': '/bin/bash', 01:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:26:58 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:58 INFO - 'USER': 'cltbld', 01:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:58 INFO - 'XPC_FLAGS': '0x0', 01:26:58 INFO - 'XPC_SERVICE_NAME': '0', 01:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:58 INFO - Downloading/unpacking psutil>=3.1.1 01:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:02 INFO - Installing collected packages: psutil 01:27:02 INFO - Running setup.py install for psutil 01:27:02 INFO - building 'psutil._psutil_osx' extension 01:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:27:02 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 01:27:02 INFO - building 'psutil._psutil_posix' extension 01:27:02 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 01:27:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:27:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:27:02 INFO - ^ 01:27:02 INFO - 1 warning generated. 01:27:02 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 01:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:03 INFO - Successfully installed psutil 01:27:03 INFO - Cleaning up... 01:27:03 INFO - Return code: 0 01:27:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:03 INFO - 'HOME': '/Users/cltbld', 01:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:03 INFO - 'LOGNAME': 'cltbld', 01:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:03 INFO - 'MOZ_NO_REMOTE': '1', 01:27:03 INFO - 'NO_EM_RESTART': '1', 01:27:03 INFO - 'PAGER': '/bin/cat', 01:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:03 INFO - 'PWD': '/builds/slave/test', 01:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:03 INFO - 'SHELL': '/bin/bash', 01:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:03 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:03 INFO - 'USER': 'cltbld', 01:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:03 INFO - 'XPC_FLAGS': '0x0', 01:27:03 INFO - 'XPC_SERVICE_NAME': '0', 01:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:27:05 INFO - Installing collected packages: mozsystemmonitor 01:27:05 INFO - Running setup.py install for mozsystemmonitor 01:27:06 INFO - Successfully installed mozsystemmonitor 01:27:06 INFO - Cleaning up... 01:27:06 INFO - Return code: 0 01:27:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:06 INFO - 'HOME': '/Users/cltbld', 01:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:06 INFO - 'LOGNAME': 'cltbld', 01:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:06 INFO - 'MOZ_NO_REMOTE': '1', 01:27:06 INFO - 'NO_EM_RESTART': '1', 01:27:06 INFO - 'PAGER': '/bin/cat', 01:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:06 INFO - 'PWD': '/builds/slave/test', 01:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:06 INFO - 'SHELL': '/bin/bash', 01:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:06 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:06 INFO - 'USER': 'cltbld', 01:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:06 INFO - 'XPC_FLAGS': '0x0', 01:27:06 INFO - 'XPC_SERVICE_NAME': '0', 01:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:06 INFO - Downloading/unpacking jsonschema==2.5.1 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:09 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:09 INFO - warning: no files found matching '*.txt' 01:27:09 INFO - no previously-included directories found matching 'build' 01:27:09 INFO - no previously-included directories found matching 'dist' 01:27:09 INFO - no previously-included directories found matching '.git*' 01:27:09 INFO - Installing collected packages: jsonschema, functools32 01:27:09 INFO - Running setup.py install for functools32 01:27:09 INFO - warning: no files found matching '*.txt' 01:27:09 INFO - no previously-included directories found matching 'build' 01:27:09 INFO - no previously-included directories found matching 'dist' 01:27:09 INFO - no previously-included directories found matching '.git*' 01:27:09 INFO - Successfully installed jsonschema functools32 01:27:09 INFO - Cleaning up... 01:27:09 INFO - Return code: 0 01:27:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:09 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 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:09 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 01:27:09 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 01:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:09 INFO - 'HOME': '/Users/cltbld', 01:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:09 INFO - 'LOGNAME': 'cltbld', 01:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:09 INFO - 'MOZ_NO_REMOTE': '1', 01:27:09 INFO - 'NO_EM_RESTART': '1', 01:27:09 INFO - 'PAGER': '/bin/cat', 01:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:09 INFO - 'PWD': '/builds/slave/test', 01:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:09 INFO - 'SHELL': '/bin/bash', 01:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:09 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:09 INFO - 'USER': 'cltbld', 01:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:09 INFO - 'XPC_FLAGS': '0x0', 01:27:09 INFO - 'XPC_SERVICE_NAME': '0', 01:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:27:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:10 INFO - 'HOME': '/Users/cltbld', 01:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:10 INFO - 'LOGNAME': 'cltbld', 01:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:10 INFO - 'MOZ_NO_REMOTE': '1', 01:27:10 INFO - 'NO_EM_RESTART': '1', 01:27:10 INFO - 'PAGER': '/bin/cat', 01:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:10 INFO - 'PWD': '/builds/slave/test', 01:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:10 INFO - 'SHELL': '/bin/bash', 01:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:10 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:10 INFO - 'USER': 'cltbld', 01:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:10 INFO - 'XPC_FLAGS': '0x0', 01:27:10 INFO - 'XPC_SERVICE_NAME': '0', 01:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:10 INFO - Downloading/unpacking blobuploader==1.2.4 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:12 INFO - Downloading blobuploader-1.2.4.tar.gz 01:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:27:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:27:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:14 INFO - Downloading docopt-0.6.1.tar.gz 01:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:27:14 INFO - Installing collected packages: blobuploader, requests, docopt 01:27:14 INFO - Running setup.py install for blobuploader 01:27:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:27:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:27:14 INFO - Running setup.py install for requests 01:27:14 INFO - Running setup.py install for docopt 01:27:14 INFO - Successfully installed blobuploader requests docopt 01:27:14 INFO - Cleaning up... 01:27:15 INFO - Return code: 0 01:27:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:15 INFO - 'HOME': '/Users/cltbld', 01:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:15 INFO - 'LOGNAME': 'cltbld', 01:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:15 INFO - 'MOZ_NO_REMOTE': '1', 01:27:15 INFO - 'NO_EM_RESTART': '1', 01:27:15 INFO - 'PAGER': '/bin/cat', 01:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:15 INFO - 'PWD': '/builds/slave/test', 01:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:15 INFO - 'SHELL': '/bin/bash', 01:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:15 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:15 INFO - 'USER': 'cltbld', 01:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:15 INFO - 'XPC_FLAGS': '0x0', 01:27:15 INFO - 'XPC_SERVICE_NAME': '0', 01:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-nDYPo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-312pDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-53iKmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-TG0HKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-rjofOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-0MD3Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:15 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ssEZRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-4cjnmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-LxaiwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-QsLybl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-gN8B90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-NUyWUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-X1mJqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-juoMV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-LsMKNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-_CRUps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:16 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-5S28y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:27:17 INFO - Running setup.py install for manifestparser 01:27:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:27:17 INFO - Running setup.py install for mozcrash 01:27:17 INFO - Running setup.py install for mozdebug 01:27:17 INFO - Running setup.py install for mozdevice 01:27:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:27:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:27:17 INFO - Running setup.py install for mozfile 01:27:17 INFO - Running setup.py install for mozhttpd 01:27:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:27:17 INFO - Running setup.py install for mozinfo 01:27:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozInstall 01:27:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozleak 01:27:18 INFO - Running setup.py install for mozlog 01:27:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for moznetwork 01:27:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozprocess 01:27:18 INFO - Running setup.py install for mozprofile 01:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Running setup.py install for mozrunner 01:27:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Running setup.py install for mozscreenshot 01:27:19 INFO - Running setup.py install for moztest 01:27:19 INFO - Running setup.py install for mozversion 01:27:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:19 INFO - Cleaning up... 01:27:19 INFO - Return code: 0 01:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27: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')]} 01:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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')]} 01:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:19 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 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:19 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 01:27:19 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 01:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:19 INFO - 'HOME': '/Users/cltbld', 01:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:19 INFO - 'LOGNAME': 'cltbld', 01:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:19 INFO - 'MOZ_NO_REMOTE': '1', 01:27:19 INFO - 'NO_EM_RESTART': '1', 01:27:19 INFO - 'PAGER': '/bin/cat', 01:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:19 INFO - 'PWD': '/builds/slave/test', 01:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:19 INFO - 'SHELL': '/bin/bash', 01:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:19 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:19 INFO - 'USER': 'cltbld', 01:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:19 INFO - 'XPC_FLAGS': '0x0', 01:27:19 INFO - 'XPC_SERVICE_NAME': '0', 01:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:19 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Msttg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-2koDQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-kB_DBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-UhVi26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-KWq0Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-a7L41t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-hXFQbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Hic6r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-mnl9AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:20 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-aqN6ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-8ifHvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-5tqIzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-xyb3u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-VVGFrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:21 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)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Mmh9Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:21 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)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-dNrLt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:21 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)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:21 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-wFdE_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:24 INFO - Downloading blessings-1.6.tar.gz 01:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:24 INFO - Installing collected packages: blessings 01:27:24 INFO - Running setup.py install for blessings 01:27:24 INFO - Successfully installed blessings 01:27:24 INFO - Cleaning up... 01:27:24 INFO - Return code: 0 01:27:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:24 INFO - Reading from file tmpfile_stdout 01:27:24 INFO - Current package versions: 01:27:24 INFO - blessings == 1.6 01:27:24 INFO - blobuploader == 1.2.4 01:27:24 INFO - docopt == 0.6.1 01:27:24 INFO - functools32 == 3.2.3-2 01:27:24 INFO - jsonschema == 2.5.1 01:27:24 INFO - manifestparser == 1.1 01:27:24 INFO - mozInstall == 1.12 01:27:24 INFO - mozcrash == 0.17 01:27:24 INFO - mozdebug == 0.1 01:27:24 INFO - mozdevice == 0.48 01:27:24 INFO - mozfile == 1.2 01:27:24 INFO - mozhttpd == 0.7 01:27:24 INFO - mozinfo == 0.9 01:27:24 INFO - mozleak == 0.1 01:27:24 INFO - mozlog == 3.4 01:27:24 INFO - moznetwork == 0.27 01:27:24 INFO - mozprocess == 0.23 01:27:24 INFO - mozprofile == 0.28 01:27:24 INFO - mozrunner == 6.12 01:27:24 INFO - mozscreenshot == 0.1 01:27:24 INFO - mozsystemmonitor == 0.3 01:27:24 INFO - moztest == 0.7 01:27:24 INFO - mozversion == 1.4 01:27:24 INFO - psutil == 3.1.1 01:27:24 INFO - requests == 1.2.3 01:27:24 INFO - wsgiref == 0.1.2 01:27:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27: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')]} 01:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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')]} 01:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:24 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 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:24 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 01:27:24 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 01:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:24 INFO - 'HOME': '/Users/cltbld', 01:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:24 INFO - 'LOGNAME': 'cltbld', 01:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:24 INFO - 'MOZ_NO_REMOTE': '1', 01:27:24 INFO - 'NO_EM_RESTART': '1', 01:27:24 INFO - 'PAGER': '/bin/cat', 01:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:24 INFO - 'PWD': '/builds/slave/test', 01:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:24 INFO - 'SHELL': '/bin/bash', 01:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:24 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:24 INFO - 'USER': 'cltbld', 01:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:24 INFO - 'XPC_FLAGS': '0x0', 01:27:24 INFO - 'XPC_SERVICE_NAME': '0', 01:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 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)) 01:27:25 INFO - Cleaning up... 01:27:25 INFO - Return code: 0 01:27:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:25 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 0x104efe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e74570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d04b29f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, '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.bld0mrI1PH/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.REJ3sqklhw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 01:27:25 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 01:27:25 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 01:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:25 INFO - 'HOME': '/Users/cltbld', 01:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:25 INFO - 'LOGNAME': 'cltbld', 01:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:25 INFO - 'MOZ_NO_REMOTE': '1', 01:27:25 INFO - 'NO_EM_RESTART': '1', 01:27:25 INFO - 'PAGER': '/bin/cat', 01:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:25 INFO - 'PWD': '/builds/slave/test', 01:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:25 INFO - 'SHELL': '/bin/bash', 01:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:27:25 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:25 INFO - 'USER': 'cltbld', 01:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:25 INFO - 'XPC_FLAGS': '0x0', 01:27:25 INFO - 'XPC_SERVICE_NAME': '0', 01:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:27:25 INFO - Cleaning up... 01:27:25 INFO - Return code: 0 01:27:25 INFO - Running post-action listener: _resource_record_post_action 01:27:25 INFO - Running post-action listener: _start_resource_monitoring 01:27:25 INFO - Starting resource monitoring. 01:27:25 INFO - [mozharness: 2016-10-26 08:27:25.555730Z] Finished create-virtualenv step (success) 01:27:25 INFO - [mozharness: 2016-10-26 08:27:25.556754Z] Running install step. 01:27:25 INFO - Running pre-action listener: _resource_record_pre_action 01:27:25 INFO - Running main action method: install 01:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:25 INFO - Reading from file tmpfile_stdout 01:27:25 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:25 INFO - Reading from file tmpfile_stdout 01:27:25 INFO - Output received: 01:27:25 INFO - Usage: mozinstall [options] installer 01:27:25 INFO - Options: 01:27:25 INFO - -h, --help show this help message and exit 01:27:25 INFO - -d DEST, --destination=DEST 01:27:25 INFO - Directory to install application into. [default: 01:27:25 INFO - "/builds/slave/test"] 01:27:25 INFO - --app=APP Application being installed. [default: firefox] 01:27:25 INFO - mkdir: /builds/slave/test/build/application 01:27:25 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'] 01:27:25 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 01:28:00 INFO - Reading from file tmpfile_stdout 01:28:00 INFO - Output received: 01:28:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:28:00 INFO - Running post-action listener: _resource_record_post_action 01:28:00 INFO - [mozharness: 2016-10-26 08:28:00.297592Z] Finished install step (success) 01:28:00 INFO - [mozharness: 2016-10-26 08:28:00.297728Z] Running run-tests step. 01:28:00 INFO - Running pre-action listener: _resource_record_pre_action 01:28:00 INFO - Running main action method: run_tests 01:28:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:28:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:28:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:28:00 INFO - 2016-10-26 01:28:00.312 screenresolution[1935:15700] starting screenresolution argv=screenresolution get 01:28:00 INFO - 2016-10-26 01:28:00.315 screenresolution[1935:15700] Display 0: 1600x1200x32@60 01:28:00 INFO - 2016-10-26 01:28:00.321 screenresolution[1936:15702] starting screenresolution argv=screenresolution list 01:28:00 INFO - Available Modes on Display 0 01:28:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:28:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:28:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:28:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:28:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:28:00 INFO - Intel Iris: 01:28:00 INFO - Chipset Model: Intel Iris 01:28:00 INFO - Type: GPU 01:28:00 INFO - Bus: Built-In 01:28:00 INFO - VRAM (Dynamic, Max): 1536 MB 01:28:00 INFO - Vendor: Intel (0x8086) 01:28:00 INFO - Device ID: 0x0a2e 01:28:00 INFO - Revision ID: 0x0009 01:28:00 INFO - Displays: 01:28:00 INFO - Display: 01:28:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:28:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:28:00 INFO - Display Serial Number: 1600x1200 60 01:28:00 INFO - Main Display: Yes 01:28:00 INFO - Mirror: Off 01:28:00 INFO - Online: Yes 01:28:00 INFO - Rotation: Supported 01:28:00 INFO - Return code: 0 01:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:28:00 INFO - Python 2.7.3 01:28:00 INFO - Return code: 0 01:28:00 INFO - grabbing minidump binary from tooltool 01:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:28:00 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 0x104d12d30>, '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 0x104b38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:28:00 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 01:28:00 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 01:28:00 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 01:28:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:28:00 INFO - Return code: 0 01:28:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:28:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:28:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:28:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:28:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:28:00 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:28:00 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bld0mrI1PH/Render', 01:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:28:00 INFO - 'HOME': '/Users/cltbld', 01:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:28:00 INFO - 'LOGNAME': 'cltbld', 01:28:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:28:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:28:00 INFO - 'MOZ_NO_REMOTE': '1', 01:28:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:28:00 INFO - 'NO_EM_RESTART': '1', 01:28:00 INFO - 'PAGER': '/bin/cat', 01:28:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:28:00 INFO - 'PWD': '/builds/slave/test', 01:28:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:28:00 INFO - 'SHELL': '/bin/bash', 01:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.REJ3sqklhw/Listeners', 01:28:00 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 01:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:28:00 INFO - 'USER': 'cltbld', 01:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:28:00 INFO - 'XPC_FLAGS': '0x0', 01:28:00 INFO - 'XPC_SERVICE_NAME': '0', 01:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:28:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:28:00 INFO - mozversion application_buildid: 20161025221325 01:28:00 INFO - mozversion application_changeset: f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 01:28:00 INFO - mozversion application_display_name: Nightly 01:28:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:28:00 INFO - mozversion application_name: Firefox 01:28:00 INFO - mozversion application_remotingname: firefox 01:28:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:28:00 INFO - mozversion application_vendor: Mozilla 01:28:00 INFO - mozversion application_version: 52.0a1 01:28:00 INFO - mozversion platform_buildid: 20161025221325 01:28:00 INFO - mozversion platform_changeset: f9f3cc95d7282f1fd83f66dd74acbcdbfe821915 01:28:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:28:00 INFO - mozversion platform_version: 52.0a1 01:28:00 INFO - using testdate: 1477470480 01:28:00 INFO - actual date: 1477470480 01:28:00 INFO - starting webserver on 'localhost:51597' 01:28:00 INFO - SUITE-START | Running 2 tests 01:28:00 INFO - TEST-START | dromaeo_css 01:28:00 INFO - operating with platform_type : mac_ 01:28:00 INFO - Initialising browser for dromaeo_css test... 01:28:00 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpzGdQBS/profile http://localhost:51597/getInfo.html) 01:28:03 INFO - PROCESS | 1946 | 2016-10-26 01:28:03.905 plugin-container[1949:15869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 01:28:03 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:28:03 INFO - PROCESS | 1946 | 2016-10-26 01:28:03.909 plugin-container[1949:15869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 01:28:03 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:28:03 INFO - PROCESS | 1946 | 2016-10-26 01:28:03.909 plugin-container[1949:15869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:28:03 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 01:28:03 INFO - PROCESS | 1946 | colorDepth:24 01:28:03 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 01:28:03 INFO - PROCESS | 1946 | __metrics 01:28:04 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 01:28:04 INFO - TEST-INFO | 1946: exit 0 01:28:04 INFO - Browser initialized. 01:28:04 INFO - Running cycle 1/1 for dromaeo_css test... 01:28:04 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpzGdQBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:28:07 INFO - PROCESS | 1950 | 2016-10-26 01:28:07.290 plugin-container[1951:15986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 01:28:07 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:28:07 INFO - PROCESS | 1950 | 2016-10-26 01:28:07.292 plugin-container[1951:15986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 01:28:07 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:28:07 INFO - PROCESS | 1950 | 2016-10-26 01:28:07.292 plugin-container[1951:15986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:28:16 INFO - PROCESS | 1950 | RSS: Main: 236777472 01:28:16 INFO - PROCESS | 1950 | 01:29:06 INFO - PROCESS | 1950 | 1477470546623 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] 01:31:20 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51597/tests/dromaeo/cssquery-ext.html) 01:31:21 INFO - PROCESS | 1950 | RSS: Main: 249753600 01:31:21 INFO - PROCESS | 1950 | 01:34:23 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-ext.html (next: http://localhost:51597/tests/dromaeo/cssquery-jquery.html) 01:34:24 INFO - PROCESS | 1950 | RSS: Main: 261398528 01:34:24 INFO - PROCESS | 1950 | 01:37:26 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51597/tests/dromaeo/cssquery-mootools.html) 01:37:26 INFO - PROCESS | 1950 | RSS: Main: 265961472 01:37:26 INFO - PROCESS | 1950 | 01:40:28 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51597/tests/dromaeo/cssquery-prototype.html) 01:40:29 INFO - PROCESS | 1950 | RSS: Main: 265474048 01:40:29 INFO - PROCESS | 1950 | 01:43:32 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51597/tests/dromaeo/cssquery-yui.html) 01:43:32 INFO - PROCESS | 1950 | RSS: Main: 269881344 01:43:32 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51597/tests/dromaeo/cssquery-yui.html (next: http://localhost:51597/tests/dromaeo/cssquery-dojo.html) 01:46:35 INFO - PROCESS | 1950 | RSS: Main: 271888384 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | __start_tp_report 01:46:35 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 01:46:35 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 01:46:35 INFO - PROCESS | 1950 | |i|pagename|runs| 01:46:35 INFO - PROCESS | 1950 | |0;dojo.html;3210.5023721323187;3232.6120865496137;3263.2657652028283;3295.57157569516;3307.3219989902072;5405.810796622119;5408.864778380533;5415.160650099236;5443.809466668662;5465.945340546593;5406.189071639256;5409.161579955104;5481.616286859911;5522.944770552293;5533.501984821365;4378.10248898975;4811.893264549139;5027.547520723134;5137.845864624067;5186.962607478519;4905;5136.563392111656;5138.306328645623;5152.433232344439;5166.767495462695;4975.154223781966;5074.238864170386;5114.846554603368;5139.743012849362;5158.329417175743;4977.303177555145;4978.103941290567;5005.624578156653;5048.116579598555;5145.84562463123;4996.4254110777365;5022.723750193738;5031.622628302855;5037.5214354636255;5073.746312684388;4274.252005898955;5252.870632813925;5500.614956953015;5513.173024046385;5521.061419558685;5775.1337299405;5886.7939622113445;5936.139259807347;5961.940380596182;5978.760849566012;6109.663968481732;6119.051547010221;6125.663088530129;6131.417515336036;6132.601380910227;5579.6652200867675;5602.775888964477;5628.859278518029;5633.577481688849;5638.830835074954;5984.132300816357;6005.519558435315;6022.066579680157;6116;6192.969035154795;5693.28833895763;5782.479576838105;5800.796972105956;5819.301683797971;5834;5872.0898260769645;5931.584789064724;5973.79091731796;5974.193483879708;5979.4618484336615;4771.904561908743;4827.951720482821;5844.47399734026;5868.295804503487;5907.379725128885;6010.699465026732;6035.124906888476;6037.305709843336;6039.456448919618;6077.908831367533;4772.570468657837;4786.545278198566;4791.353167322437;4803.735794531334;4843.370361852936;4205.243056249905;4232.8518501852905;4243.724157929724;4261.531231564525;4310;5584.553235741131;5622.57830662702;5624.578156638268;5628.943710562842;5661.971690141605;6086.421789929949;6110.388961103943;6125.540584456184;6129.570930034944;6146.508279337731;5382.703951282639;5484.067708489487;5494.917576236359;5585.162225666182;5587.692676902728;5149.073166829931;5205.479452054764;5231.267622532849;5251.789928402821;5265.736713164327;6214.25428948529;6221.5333849961435;6239.719212635533;6251.312355640966;6269.717862696099;5837.883242335214;5909.645421274566;6079;6108.908366374597;6155.199824022849;5130.743462826769;6097.282711138064;6102.883130681719;6106.6946652668075;6169.784057557964;5768.913466298093;5921.3190483094995;5929.080992446177;5953.196318496947;6018.067199584071;5889.49939255151;5976.342602313655;5993.490553302844;6020.036794112921;6052.334243233153;4811.350467686818;5630.8029218977135;5703;5858.765649373977;5878.941210587839;5751.1373294006235;5773.422657734361;5847.532197424281;5865.794697185578;5944.048952167633;4945.876353091202;5444.26502422168;5673.149027645721;5688.800891968926;5694.373618902;5806.622569532967;5838.978178818605;5843.883122337615;5867.149263356875;5871.706414679164;5521.868017056413;5605.775768969195;5618.269624948894;5677.063284558001;5712.485876271156 01:46:35 INFO - PROCESS | 1950 | |1;ext.html;440.2911312786411;455.1693159983032;470.4824692837878;485.1534073042539;526.0557299647134;39500;41483.79258103709;41693.58306416935;43426.34860477095;43483.78258108713;42255;42498.57501424985;42834.92912677184;42962.78518607411;43178.136437271234;9511.6195352186;9582.281328900326;9662.5168741563;9678.032196780301;9709.805803883917;9541.666041688542;9614.80770384592;9620.182284505825;9628.374155679894;9647.662331818388;9485.81028379432;9530.237580993538;9625.807483850318;9645.903540964571;9661.758456038586;9570.569324380385;9621.230301575857;9655.986121457216;9660.37207581505;9665.565049572786;9537.666181683608;9540.856887146698;9621.134097931219;9640.42157470547;9640.710778676581;40071;40123.39814902779;40232.195356092714;40455.393169102485;40553.98615034648;3052.450558899397;3430.1767575781882;3454.9827250863837;3464.861405543775;3519.6304388039143;30576.08271751851;30638.9276375328;30663;30721.078367649006;31243.84378078095;3024.5916801231992;3032.4086803073187;3058.9541156882665;3068.6317641883115;3118.9688103118906;491957;496099;496858;497235;497252;58938.41061589415;59263.111053334236;59269;59285;59333;60322;61779.38220617826;61915.6904215476;62023;62428;13580.932095339658;15281.847181528043;16044.75932861008;16076.839231607768;16257;12207.450664720109;12307.75384492305;12319;12331.6917077072;12342.81485777714;15546.455874044354;19179.712304315446;19317.323893663655;19385.806141938683;19575.314863979693;7857.842843143105;7873.25204105609;8006.959965200137;8017.305549865927;8053.436259461781;748.5994992679;895.5969813583913;913.5021413329752;986.6546708652058;993.7081793668202;25817.612735808976;29516.262093447836;30250;30265.697343026728;33480.83259583735;904.1591320072264;929.8028639120188;1013.7414959185508;1027.0653705128298;1104.9613363438343;15791.131487768283;15805.13071781063;15868.761968570481;16001.199940002953;16163;653250;657426;663097;664259;664571;56706;57497.425025750046;57643.42356576464;57827;58118.41881581215;1349.9325033748273;1377.2012232905092;1383.3567391162956;1389.6248013036625;1398.489551313763;25735.742642574085;28973.275668108465;29886.103416897527;30381.392372152874;30608.693913060586;46244;49756.75121624369;49791.253131202335;49807.750961244965;49817.00365992732;573266;580322;581705;582410;584005.0799745974;25554.105606304434;25666.230013099637;26039.95840166372;26422.471550569266;26518.734812651626;45560.316595250435;46487.53512464832;47040.35358762427;47230;48336.7583162096;38168.80915595404;38819.417708733825;39087.02282442848;39469.40795888007;40253;32652;38038.80980595079;38376.970418105695;38389;39372.409413858244;32177.517337240428;33000;37560.24879502449;38141;38429.039274018374;31608.209794754395;32152.37333466681;34658.82670586631;35468;36291.637083628826;21007.264745733824;21624.89187554052;25569.616455753552;25668.871655641604;26131.34671633148 01:46:35 INFO - PROCESS | 1950 | |2;jquery.html;353.4062774538775;377.4828484975582;382.1821302412837;388.31074842155186;401.97990100494985;19012.433321501594;20936.581268374623;21292.89353553234;21467.785322146774;21650.56698866022;15158.696826063471;18995.145218465168;19098;19231.71152432711;19606.411807645793;4712.835050773224;5508.779648814043;5645.802396916109;5667.4615911488345;6054.152418661391;5788.18965344852;5983;5988.281406231258;5991.670458124801;5995.790147344844;5637.098064309711;5818.883622327551;5848.181254624356;5927.673977931212;5935.910961335583;5850.707464626773;5879.323877754049;5900.586958912878;5907.084401917709;5921.023031199847;4405.339199120125;4918.311436398907;5086.287919691246;5155.7679904404395;5162.251473536317;24497.87751061251;24844.254672359857;24882.880270387875;25547;25830.483390332087;2001.2795393658416;2127.2341956895507;2302.8272879533934;2393.1504315967754;2830.3631682871396;5648.265725455705;5697.6866272355;5700.515456186187;9865.50672466381;10141.225285574996;1855.7309190167484;1866.3281218761235;1875.831175194239;1912.2159914435153;1928.7203355513373;125534;126298.36850815779;127091.36454317669;127242.72757272494;133381.3330933349;10977.945110274397;11279.605213817476;11448.828267575993;11555.364454955059;11819.763604727857;14539.709205816036;14593.562193134223;14618.85381146175;14620.342084606218;14633.780493292608;7758.185390534026;8121.390895682847;8469.957650211709;8650.95674521636;8654.78363040929;8423.620937057847;8435.536045517432;8458.49249045047;8547.974243090868;8616.870746938801;3767.3783825669007;4502.954970450253;4525.162844873709;4595.1728688835665;4603.560113140041;1892.668782962976;1943.7278780970676;1948.8928108954144;1949.5516031312877;1951.3267922566693;699.0458024796117;821.2444551013082;897.7531178925717;899.9370044096981;975.9606019589256;20254.79745202529;21798;21964.560708785735;22104.557908842056;22231.221907233168;881.7575166829045;922.5387306346827;957.899420560845;957.9036950541217;986.4919566423298;8402.78993025167;10567.04896559298;11500.079993600491;11511.76976460483;11547.76904461906;548193;556625;561296.580551292;601889;602112;86929.39211823656;118416;118419;140731.29634351764;142811;846.6782622603371;925.500229875873;950.254050570299;953.6328513522344;986.556049777603;18554.350597729015;18595.34916277924;18843;18938.52653683669;18954.905225474336;50343;50373.74065648388;51058;51639.74180129226;53037;56705;74356;74950.25049749433;76477.85283220645;76692;6365.809025729235;6540.901886471612;6602.801915942529;7597.6581053853815;7842.490238134503;12055.336956467128;12370.670265407036;12460.314682692539;12472.002239820975;12630.684232894251;8697.304215662605;9171.724848254564;10610.045095941403;10667.89332106669;10880.401577913291;8551;8715.564221789013;10337.896621033693;10598.31110977784;10790.622328218475;9292.6282948684;10304.072633463024;10423.94788026055;10727.624533141303;10832.350058996486;7632.656530456254;7885.29032387088;8038.718644847402;8095.23095305945;8168.469049511763;8014.639341229776;8604.881365422454;10259.820120686216;10912.563497460013;11154.330740155616 01:46:35 INFO - PROCESS | 1950 | |3;mootools.html;1531.3797911845702;1552.6817002514483;1601.383467365064;1829.3322937127944;1831.459719382782;7871.370290376771;8158.143394943534;8439.32485401168;8945.239654629357;8956.014838367782;8395.496270223775;8686.261667758232;8709;8809.38334316598;8816.911830881689;2328.4644531757663;2343.871087090209;2351.094828491026;2356.905723771056;2357.0807385119815;2341.718993720756;2364.527094581082;2374.560706269337;2394.7964423023977;2397.4845408237225;2328.639060945556;2338.2166974063707;2360.598698221298;2363.621820508719;2378.9286321410386;2280.6122959096992;2294.7475777664445;2299.3906614747107;2308.042162502559;2330.067972810872;2132.5948069866677;2323.0011095229047;2341.718993720747;2356.693629828111;2356.8232382571205;7419.8145046374275;8744.169303916118;8842.115788421172;8886.777830554223;8903.421277616999;5076.137056700333;5184.274201611777;5333.253344531769;5342.171963345649;5360;3039.8936037238814;3525.471179323122;3528.964710352889;3658.122050707811;3680.9079773005888;1002.8245057114975;1010.5974358117973;1044.3942513342242;1045.29442626227;1063.3672964586058;10619.25665203436;11073.50169242386;11514.884851151464;11617.361045142514;11723.76552468946;4547.454305483363;4700.694454860423;4744.312074749211;4759.571638552547;4800.879978000578;9808.21534277256;9912.653057142965;9914.40513569188;9930.304878658424;9940.254480913816;5309.761060752276;5312.203169524602;5315.069862773999;5322.973385133127;5335.786568537293;4372.628326592212;4399.076193999232;4796;4845.442774081026;4863.051704917578;3798.6581207691443;3819.3698039823116;3904.2581909437117;3933.2330195612153;3948.6051394860283;1259.8740125987329;1272.166730791333;1279.5137847617846;1279.8400199975003;1282.948682052726;911.2755359489222;912.4023764434306;938.3759799733101;942.2885721280389;947.6541062512133;5555.138953462219;5751.051076572402;5853.351515787285;5890.175375447441;5893.882122357529;421.2648927621259;427.36962979605454;429.2638125614586;432.60848931716845;440.7597859166762;3790.2040571479747;3792.1657235408716;3898.8830335089992;3900.980495097563;3902.0439992201523;10236.59053637861;10482.790344193074;10613.787724245472;10632.149428045737;10640.201984851166;10557.996990286065;10589;10596.576136954589;10615.946920265349;10640.095591874622;8009.559474228973;8090.474119182234;8149;8231.09457959624;8253.669853205805;5246.658967167122;5555.472230138131;5589.441055894378;5701.4298570142655;5735;9718.611255549698;9779.070988256104;9817.410955342702;9829.066238707312;9833.459159746282;9705.514724263618;9816;9822.705318840446;9857.704268871945;9983.550740216562;6790.185177778698;6974.511784175059;7292.307230813064;7372.115346158495;7543.585102819397;8234.65312385967;8323.001239851257;8373.49759014461;8422.620982055945;8438.493690378597;7280.9271907280245;7414;7424.406047516294;7517.398608111447;7521.586312752851;7151.32062454066;7239.239879812544;7275.454340924346;7287.890681639886;7377.668004939898;6825.385715285648;6934.445244380337;6950.54821436605;6961.582305061713;6962.164540255202;4894.583960363409;5015.824446144367;5018.096742586369;5067.594592432522;5128.538431541179;4922.163232250455;4967.354243948408;5091.287219789307;5099.515546023122;5171.224316352577 01:46:35 INFO - PROCESS | 1950 | |4;prototype.html;298.9506731389321;302.98788048478065;311.35704769650664;314.5721818327076;316.6042446941323;4424.469063712351;5247.816326428576;5441.646292990958;5449.618526703128;5476.507114359707;4677.789499472532;4813.350197723306;4997.950020499794;5421.864453388667;5480.616356855022;3389.5594372391756;4091.2022155679656;4119.320312148492;4133.834646614132;4134.152498737752;3436.639152888948;4012.678985681153;4149.983254102749;4158.4594002779595;4197.412362269285;3372.207531230159;3818.217265460574;3959.841606335743;4076.347784354504;4095;4069.3285607874664;4078.6125318094732;4089.7750623715833;4099.528554216274;4116.608922152421;3283.5731354923705;3556.1820781220204;3577.4633804929467;3581.2658405026905;3588.4796704477703;4869.561739443467;5051.898962020776;5199.66202196856;5252.579793616502;5259.474052594748;4590.586847183736;4669.813207471697;4684.367610372624;4686.461056978457;4694.43666759991;4678.415198100238;5167.844964651066;5618.634788738719;5699.658020518741;5744.482996530291;3688.3729765939856;3752.6490463433142;3896.902577435587;3928.744631598937;3949.032487040665;5218.877941242651;5842.218123472339;5930.436608522184;5951.613145145595;5961.284645842483;3978.5225772906856;3987.282289187974;3988.700847436454;3994.980025099914;4013.979930100331;3899.512560929884;3955.4660120883887;4048.4939382577177;4051.885731423823;4067.186562687415;3801.4297855321925;4132.442120313779;4159.793659838613;4501;4674.182018146811;4692.7653617319;5092.592592592584;5241.816536421207;5492.928878868583;5519.613627046148;1136.2954967919943;1334.4528743215235;1357.9796303055462;1360.8911287097008;1373.4411443012252;776.6155752902349;856.8971723393232;916.5692124701379;945.921649319776;948.2271948362063;4241.321388577812;4241.4698162729655;4704.764761761899;4979.253112033224;5092;4969.90060198794;5365.302510673588;5772.595918285764;5800.477956983892;5838.649681019152;4504.617107545895;5063.291139240509;5197.7401129943355;5249.947500524946;5296.549793267615;3051.343961048385;3126.85929133185;3139.952900706491;3140.199249191487;3148;5079.644424890296;5112.488751124932;6048.455638992512;6210.4100110489435;6225.953130465144;4970.900581988412;5701.258836351248;5936.821895343147;6043.456088952015;6050.515958723292;5410.594205434608;6021.42796434338;6114.563077676742;6134.938650613526;6179.969100154651;5420.590646431877;5432.972835135799;5476.397596264487;5568.443155684399;5936.951751645675;5837.766489340379;5888.087346461305;5913.113033045078;5955.553333499918;5958.986972214647;5420.457954204548;5729.479029227126;6241.656708881055;6244.500439964882;6253.904317362934;3493.7554371194788;3705.9258814824093;3795.3737633291303;3825;3902.9219415612097;5425.538829199404;5709.857253568694;5962.25471816023;5962.344142144448;6003.489703375262;4794.400699912511;5008.847964968005;5544.528715059104;5732.455416735404;5749.367569567428;5053.0399224147295;5765.308163020297;5784.1535378974195;5828.679422631796;5843.707814609337;5336.9466305338;5347.919781203207;5393.757280922289;5571.7492712828835;5682.43175682445;4638.652101092364;5017.8243761468175;5428.669975855941;5581.832545023656;5787.94212057874;4712.670113092051;5342.382232706023;5682.744276507649;5695.515881149983;5706.2011318414625 01:46:35 INFO - PROCESS | 1950 | |5;yui.html;642.871425714857;645.670707938951;652.9673516324183;724.2359310926973;747.6224506624155;13298;13801.93099034506;14109.43562257511;14134.010619256656;14136.575902722909;13104.14823036502;13148.671283217924;13153.60539183826;13209.801852972214;13268.933655331733;706.6043015911081;727.1383410658386;875.916787905148;879.5162660536711;890.1765866573426;856.4689892266804;883.0286684646901;896.050186801992;897.9281657467419;902.9954850225739;967.1634036558362;967.2213867836389;968.3850754770701;970.4419958523877;971.4608392342247;909.6225066597354;946.1012038563358;959.5106495687215;960.783823639679;964.363520076746;675.8107729835654;677.5426587053719;719.6365835253164;719.9532030418006;721.7473884140509;13745.24401157936;13831.654208644764;14002.719945601133;14163;14202.50291239812;11695.239809412446;11832.763344733143;11948.940255298754;12773.169743966613;13425.932870335586;11891.918666567652;11930.10524210679;12048.457819398149;12228.938855305667;12528.498860045587;4189.874303770892;4193.832246710128;4205.243056249874;4212.368144778277;4369.650427965787;97461.5126924368;98008.50995745047;98034;98562;99390.00609993952;9567.569459374345;9601.47191904452;9681.08029737188;9844.212463002943;9954.25343099256;18978;19296.71054934177;19327.516812079528;19378.224871005;19511.317103901296;7366.815829604196;7455.925440745523;7510.4742668013805;7572.4699271050495;7572.886406703904;3989.3218152914073;4139.9793001035405;4232.78836058202;4285.7642829644665;4296;3351.6983471487683;3429.228423604669;3461.238527523941;3463.930721385558;3466.324066806999;565.3187908570163;640.5356116815271;643.4177069751883;652.3052948609716;662.6554191820227;556.0130767886952;599.0475144520234;609.2354095609982;615.827568280882;636.9713362898681;7331.9266807332315;8030.277275045157;8265.842782010523;8609.956950215208;9040.412373195853;571.9628224165499;590.2268028882178;623.2956758862456;626.6678660310043;634.5050860328859;4391.143726973273;4392.956070439319;4413.911721765547;4414.404055452537;4454;48035.03929921382;77884.83172752413;106696;121462;122864.38567807103;55337.50981205855;60072.399276007556;79551.8067228992;102018.48990754999;108656.45671771749;65035;88745;88919.55540222386;90341.64487532692;91412;8365.247127758288;8648.394612377073;8702.1297870215;8721.607527661397;8765.211130998241;56351.43648563462;56889.71555142364;57798.71100644638;58885.705571471866;59717;73257.26742732717;80206.59896700479;82042.58978705069;82118;83555;10674.57301707923;10734.624288149877;10839.837402438812;10872.836907446237;11065.502052407823;11540.054387146536;12008.699782505159;12144;12534.561290354794;12602.054845886412;11627.709307267385;11715.707107322385;11824.70438239051;11858.940705296418;11875;11236.943815281198;11457.885421145682;11463.598774042868;11498.597549086076;11566.30602163873;10605.204609654153;10648.414337211529;10849.349039057683;11511.712207194887;11821.113416493625;8284;9297.349185557217;9476.431414114899;9688.951555242178;9719.514024298898;9724.70825875225;9949.204063675033;9961.651342203279;9981.950090249502;9997.650082247086 01:46:35 INFO - PROCESS | 1950 | __end_tp_report 01:46:35 INFO - PROCESS | 1950 | __start_cc_report 01:46:35 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,367 01:46:35 INFO - PROCESS | 1950 | __end_cc_report 01:46:35 INFO - PROCESS | 1950 | __startTimestamp1477471595725__endTimestamp 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | ------- Summary: start ------- 01:46:35 INFO - PROCESS | 1950 | Number of tests: 6 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5498.36 Median:5633.58 stddev:628.04 (11.1%) stddev-sans-first:603.78 01:46:35 INFO - PROCESS | 1950 | Values: 3210.5 3232.6 3263.3 3295.6 3307.3 5405.8 5408.9 5415.2 5443.8 5465.9 5406.2 5409.2 5481.6 5522.9 5533.5 4378.1 4811.9 5027.5 5137.8 5187.0 4905.0 5136.6 5138.3 5152.4 5166.8 4975.2 5074.2 5114.8 5139.7 5158.3 4977.3 4978.1 5005.6 5048.1 5145.8 4996.4 5022.7 5031.6 5037.5 5073.7 4274.3 5252.9 5500.6 5513.2 5521.1 5775.1 5886.8 5936.1 5961.9 5978.8 6109.7 6119.1 6125.7 6131.4 6132.6 5579.7 5602.8 5628.9 5633.6 5638.8 5984.1 6005.5 6022.1 6116.0 6193.0 5693.3 5782.5 5800.8 5819.3 5834.0 5872.1 5931.6 5973.8 5974.2 5979.5 4771.9 4828.0 5844.5 5868.3 5907.4 6010.7 6035.1 6037.3 6039.5 6077.9 4772.6 4786.5 4791.4 4803.7 4843.4 4205.2 4232.9 4243.7 4261.5 4310.0 5584.6 5622.6 5624.6 5628.9 5662.0 6086.4 6110.4 6125.5 6129.6 6146.5 5382.7 5484.1 5494.9 5585.2 5587.7 5149.1 5205.5 5231.3 5251.8 5265.7 6214.3 6221.5 6239.7 6251.3 6269.7 5837.9 5909.6 6079.0 6108.9 6155.2 5130.7 6097.3 6102.9 6106.7 6169.8 5768.9 5921.3 5929.1 5953.2 6018.1 5889.5 5976.3 5993.5 6020.0 6052.3 4811.4 5630.8 5703.0 5858.8 5878.9 5751.1 5773.4 5847.5 5865.8 5944.0 4945.9 5444.3 5673.1 5688.8 5694.4 5806.6 5839.0 5843.9 5867.1 5871.7 5521.9 5605.8 5618.3 5677.1 5712.5 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:70858.19 Median:41974.29 stddev:155854.73 (371.3%) stddev-sans-first:156202.26 01:46:35 INFO - PROCESS | 1950 | Values: 440.3 455.2 470.5 485.2 526.1 39500.0 41483.8 41693.6 43426.3 43483.8 42255.0 42498.6 42834.9 42962.8 43178.1 9511.6 9582.3 9662.5 9678.0 9709.8 9541.7 9614.8 9620.2 9628.4 9647.7 9485.8 9530.2 9625.8 9645.9 9661.8 9570.6 9621.2 9656.0 9660.4 9665.6 9537.7 9540.9 9621.1 9640.4 9640.7 40071.0 40123.4 40232.2 40455.4 40554.0 3052.5 3430.2 3455.0 3464.9 3519.6 30576.1 30638.9 30663.0 30721.1 31243.8 3024.6 3032.4 3059.0 3068.6 3119.0 491957.0 496099.0 496858.0 497235.0 497252.0 58938.4 59263.1 59269.0 59285.0 59333.0 60322.0 61779.4 61915.7 62023.0 62428.0 13580.9 15281.8 16044.8 16076.8 16257.0 12207.5 12307.8 12319.0 12331.7 12342.8 15546.5 19179.7 19317.3 19385.8 19575.3 7857.8 7873.3 8007.0 8017.3 8053.4 748.6 895.6 913.5 986.7 993.7 25817.6 29516.3 30250.0 30265.7 33480.8 904.2 929.8 1013.7 1027.1 1105.0 15791.1 15805.1 15868.8 16001.2 16163.0 653250.0 657426.0 663097.0 664259.0 664571.0 56706.0 57497.4 57643.4 57827.0 58118.4 1349.9 1377.2 1383.4 1389.6 1398.5 25735.7 28973.3 29886.1 30381.4 30608.7 46244.0 49756.8 49791.3 49807.8 49817.0 573266.0 580322.0 581705.0 582410.0 584005.1 25554.1 25666.2 26040.0 26422.5 26518.7 45560.3 46487.5 47040.4 47230.0 48336.8 38168.8 38819.4 39087.0 39469.4 40253.0 32652.0 38038.8 38377.0 38389.0 39372.4 32177.5 33000.0 37560.2 38141.0 38429.0 31608.2 32152.4 34658.8 35468.0 36291.6 21007.3 21624.9 25569.6 25668.9 26131.3 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:33928.00 Median:4560.17 stddev:96262.19 (2110.9%) stddev-sans-first:96499.22 01:46:35 INFO - PROCESS | 1950 | Values: 353.4 377.5 382.2 388.3 402.0 19012.4 20936.6 21292.9 21467.8 21650.6 15158.7 18995.1 19098.0 19231.7 19606.4 4712.8 5508.8 5645.8 5667.5 6054.2 5788.2 5983.0 5988.3 5991.7 5995.8 5637.1 5818.9 5848.2 5927.7 5935.9 5850.7 5879.3 5900.6 5907.1 5921.0 4405.3 4918.3 5086.3 5155.8 5162.3 24497.9 24844.3 24882.9 25547.0 25830.5 2001.3 2127.2 2302.8 2393.2 2830.4 5648.3 5697.7 5700.5 9865.5 10141.2 1855.7 1866.3 1875.8 1912.2 1928.7 125534.0 126298.4 127091.4 127242.7 133381.3 10977.9 11279.6 11448.8 11555.4 11819.8 14539.7 14593.6 14618.9 14620.3 14633.8 7758.2 8121.4 8470.0 8651.0 8654.8 8423.6 8435.5 8458.5 8548.0 8616.9 3767.4 4503.0 4525.2 4595.2 4603.6 1892.7 1943.7 1948.9 1949.6 1951.3 699.0 821.2 897.8 899.9 976.0 20254.8 21798.0 21964.6 22104.6 22231.2 881.8 922.5 957.9 957.9 986.5 8402.8 10567.0 11500.1 11511.8 11547.8 548193.0 556625.0 561296.6 601889.0 602112.0 86929.4 118416.0 118419.0 140731.3 142811.0 846.7 925.5 950.3 953.6 986.6 18554.4 18595.3 18843.0 18938.5 18954.9 50343.0 50373.7 51058.0 51639.7 53037.0 56705.0 74356.0 74950.3 76477.9 76692.0 6365.8 6540.9 6602.8 7597.7 7842.5 12055.3 12370.7 12460.3 12472.0 12630.7 8697.3 9171.7 10610.0 10667.9 10880.4 8551.0 8715.6 10337.9 10598.3 10790.6 9292.6 10304.1 10423.9 10727.6 10832.4 7632.7 7885.3 8038.7 8095.2 8168.5 8014.6 8604.9 10259.8 10912.6 11154.3 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5628.41 Median:5071.87 stddev:3161.30 (62.3%) stddev-sans-first:3155.18 01:46:35 INFO - PROCESS | 1950 | Values: 1531.4 1552.7 1601.4 1829.3 1831.5 7871.4 8158.1 8439.3 8945.2 8956.0 8395.5 8686.3 8709.0 8809.4 8816.9 2328.5 2343.9 2351.1 2356.9 2357.1 2341.7 2364.5 2374.6 2394.8 2397.5 2328.6 2338.2 2360.6 2363.6 2378.9 2280.6 2294.7 2299.4 2308.0 2330.1 2132.6 2323.0 2341.7 2356.7 2356.8 7419.8 8744.2 8842.1 8886.8 8903.4 5076.1 5184.3 5333.3 5342.2 5360.0 3039.9 3525.5 3529.0 3658.1 3680.9 1002.8 1010.6 1044.4 1045.3 1063.4 10619.3 11073.5 11514.9 11617.4 11723.8 4547.5 4700.7 4744.3 4759.6 4800.9 9808.2 9912.7 9914.4 9930.3 9940.3 5309.8 5312.2 5315.1 5323.0 5335.8 4372.6 4399.1 4796.0 4845.4 4863.1 3798.7 3819.4 3904.3 3933.2 3948.6 1259.9 1272.2 1279.5 1279.8 1282.9 911.3 912.4 938.4 942.3 947.7 5555.1 5751.1 5853.4 5890.2 5893.9 421.3 427.4 429.3 432.6 440.8 3790.2 3792.2 3898.9 3901.0 3902.0 10236.6 10482.8 10613.8 10632.1 10640.2 10558.0 10589.0 10596.6 10615.9 10640.1 8009.6 8090.5 8149.0 8231.1 8253.7 5246.7 5555.5 5589.4 5701.4 5735.0 9718.6 9779.1 9817.4 9829.1 9833.5 9705.5 9816.0 9822.7 9857.7 9983.6 6790.2 6974.5 7292.3 7372.1 7543.6 8234.7 8323.0 8373.5 8422.6 8438.5 7280.9 7414.0 7424.4 7517.4 7521.6 7151.3 7239.2 7275.5 7287.9 7377.7 6825.4 6934.4 6950.5 6961.6 6962.2 4894.6 5015.8 5018.1 5067.6 5128.5 4922.2 4967.4 5091.3 5099.5 5171.2 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4570.64 Median:5058.17 stddev:1409.91 (27.9%) stddev-sans-first:1376.93 01:46:35 INFO - PROCESS | 1950 | Values: 299.0 303.0 311.4 314.6 316.6 4424.5 5247.8 5441.6 5449.6 5476.5 4677.8 4813.4 4998.0 5421.9 5480.6 3389.6 4091.2 4119.3 4133.8 4134.2 3436.6 4012.7 4150.0 4158.5 4197.4 3372.2 3818.2 3959.8 4076.3 4095.0 4069.3 4078.6 4089.8 4099.5 4116.6 3283.6 3556.2 3577.5 3581.3 3588.5 4869.6 5051.9 5199.7 5252.6 5259.5 4590.6 4669.8 4684.4 4686.5 4694.4 4678.4 5167.8 5618.6 5699.7 5744.5 3688.4 3752.6 3896.9 3928.7 3949.0 5218.9 5842.2 5930.4 5951.6 5961.3 3978.5 3987.3 3988.7 3995.0 4014.0 3899.5 3955.5 4048.5 4051.9 4067.2 3801.4 4132.4 4159.8 4501.0 4674.2 4692.8 5092.6 5241.8 5492.9 5519.6 1136.3 1334.5 1358.0 1360.9 1373.4 776.6 856.9 916.6 945.9 948.2 4241.3 4241.5 4704.8 4979.3 5092.0 4969.9 5365.3 5772.6 5800.5 5838.6 4504.6 5063.3 5197.7 5249.9 5296.5 3051.3 3126.9 3140.0 3140.2 3148.0 5079.6 5112.5 6048.5 6210.4 6226.0 4970.9 5701.3 5936.8 6043.5 6050.5 5410.6 6021.4 6114.6 6134.9 6180.0 5420.6 5433.0 5476.4 5568.4 5937.0 5837.8 5888.1 5913.1 5955.6 5959.0 5420.5 5729.5 6241.7 6244.5 6253.9 3493.8 3705.9 3795.4 3825.0 3902.9 5425.5 5709.9 5962.3 5962.3 6003.5 4794.4 5008.8 5544.5 5732.5 5749.4 5053.0 5765.3 5784.2 5828.7 5843.7 5336.9 5347.9 5393.8 5571.7 5682.4 4638.7 5017.8 5428.7 5581.8 5787.9 4712.7 5342.4 5682.7 5695.5 5706.2 01:46:35 INFO - PROCESS | 1950 | 01:46:35 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:19901.63 Median:30158.02 stddev:29862.67 (99.0%) stddev-sans-first:29911.43 01:46:35 INFO - PROCESS | 1950 | Values: 642.9 645.7 653.0 724.2 747.6 13298.0 13801.9 14109.4 14134.0 14136.6 13104.1 13148.7 13153.6 13209.8 13268.9 706.6 727.1 875.9 879.5 890.2 856.5 883.0 896.1 897.9 903.0 967.2 967.2 968.4 970.4 971.5 909.6 946.1 959.5 960.8 964.4 675.8 677.5 719.6 720.0 721.7 13745.2 13831.7 14002.7 14163.0 14202.5 11695.2 11832.8 11948.9 12773.2 13425.9 11891.9 11930.1 12048.5 12228.9 12528.5 4189.9 4193.8 4205.2 4212.4 4369.7 97461.5 98008.5 98034.0 98562.0 99390.0 9567.6 9601.5 9681.1 9844.2 9954.3 18978.0 19296.7 19327.5 19378.2 19511.3 7366.8 7455.9 7510.5 7572.5 7572.9 3989.3 4140.0 4232.8 4285.8 4296.0 3351.7 3429.2 3461.2 3463.9 3466.3 565.3 640.5 643.4 652.3 662.7 556.0 599.0 609.2 615.8 637.0 7331.9 8030.3 8265.8 8610.0 9040.4 572.0 590.2 623.3 626.7 634.5 4391.1 4393.0 4413.9 4414.4 4454.0 48035.0 77884.8 106696.0 121462.0 122864.4 55337.5 60072.4 79551.8 102018.5 108656.5 65035.0 88745.0 88919.6 90341.6 91412.0 8365.2 8648.4 8702.1 8721.6 8765.2 56351.4 56889.7 57798.7 58885.7 59717.0 73257.3 80206.6 82042.6 82118.0 83555.0 10674.6 10734.6 10839.8 10872.8 11065.5 11540.1 12008.7 12144.0 12534.6 12602.1 11627.7 11715.7 11824.7 11858.9 11875.0 11236.9 11457.9 11463.6 11498.6 11566.3 10605.2 10648.4 10849.3 11511.7 11821.1 8284.0 9297.3 9476.4 9689.0 9719.5 9724.7 9949.2 9961.7 9982.0 9997.7 01:46:35 INFO - PROCESS | 1950 | -------- Summary: end -------- 01:46:35 INFO - PROCESS | 1950 | 01:46:36 INFO - TEST-INFO | 1950: exit 0 01:46:36 INFO - TEST-OK | dromaeo_css | took 1115470ms 01:46:36 INFO - TEST-START | kraken 01:46:36 INFO - operating with platform_type : mac_ 01:46:36 INFO - Initialising browser for kraken test... 01:46:36 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpmMfOAh/profile http://localhost:51597/getInfo.html) 01:46:38 INFO - PROCESS | 1957 | 2016-10-26 01:46:38.941 plugin-container[1958:18135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 01:46:38 INFO - PROCESS | 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:46:38 INFO - PROCESS | 1957 | 2016-10-26 01:46:38.944 plugin-container[1958:18135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 01:46:38 INFO - PROCESS | 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:46:38 INFO - PROCESS | 1957 | 2016-10-26 01:46:38.944 plugin-container[1958:18135] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:46:39 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 01:46:39 INFO - PROCESS | 1957 | colorDepth:24 01:46:39 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 01:46:39 INFO - PROCESS | 1957 | __metrics 01:46:39 ERROR - PROCESS | 1957 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 01:46:39 INFO - TEST-INFO | 1957: exit 0 01:46:39 INFO - Browser initialized. 01:46:39 INFO - Running cycle 1/1 for kraken test... 01:46:39 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpmMfOAh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:42 INFO - PROCESS | 1959 | 2016-10-26 01:46:42.084 plugin-container[1960:18249] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 01:46:42 INFO - PROCESS | 1959 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:46:42 INFO - PROCESS | 1959 | 2016-10-26 01:46:42.084 plugin-container[1960:18249] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 01:46:42 INFO - PROCESS | 1959 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:46:42 INFO - PROCESS | 1959 | 2016-10-26 01:46:42.084 plugin-container[1960:18249] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:46:51 INFO - PROCESS | 1959 | RSS: Main: 236519424 01:46:51 INFO - PROCESS | 1959 | 01:46:54 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51597/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:55 INFO - PROCESS | 1959 | RSS: Main: 249135104 01:46:55 INFO - PROCESS | 1959 | 01:46:58 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51597/tests/kraken/driver.html?testName=audio-dft) 01:46:58 INFO - PROCESS | 1959 | RSS: Main: 256716800 01:46:58 INFO - PROCESS | 1959 | 01:47:02 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51597/tests/kraken/driver.html?testName=audio-fft) 01:47:02 INFO - PROCESS | 1959 | RSS: Main: 259493888 01:47:02 INFO - PROCESS | 1959 | 01:47:04 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51597/tests/kraken/driver.html?testName=audio-oscillator) 01:47:05 INFO - PROCESS | 1959 | RSS: Main: 261660672 01:47:05 INFO - PROCESS | 1959 | 01:47:07 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51597/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:47:07 INFO - PROCESS | 1959 | RSS: Main: 262537216 01:47:07 INFO - PROCESS | 1959 | 01:47:10 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51597/tests/kraken/driver.html?testName=imaging-darkroom) 01:47:11 INFO - PROCESS | 1959 | RSS: Main: 262819840 01:47:11 INFO - PROCESS | 1959 | 01:47:14 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51597/tests/kraken/driver.html?testName=imaging-desaturate) 01:47:14 INFO - PROCESS | 1959 | RSS: Main: 263053312 01:47:14 INFO - PROCESS | 1959 | 01:47:17 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51597/tests/kraken/driver.html?testName=json-parse-financial) 01:47:18 INFO - PROCESS | 1959 | RSS: Main: 263639040 01:47:18 INFO - PROCESS | 1959 | 01:47:19 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51597/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:47:20 INFO - PROCESS | 1959 | RSS: Main: 263172096 01:47:20 INFO - PROCESS | 1959 | 01:47:22 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:47:23 INFO - PROCESS | 1959 | RSS: Main: 263290880 01:47:23 INFO - PROCESS | 1959 | 01:47:25 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:47:25 INFO - PROCESS | 1959 | RSS: Main: 263335936 01:47:25 INFO - PROCESS | 1959 | 01:47:28 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:29 INFO - PROCESS | 1959 | RSS: Main: 263335936 01:47:29 INFO - PROCESS | 1959 | 01:47:32 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:33 INFO - PROCESS | 1959 | RSS: Main: 263684096 01:47:33 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51597/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51597/tests/kraken/driver.html?testName=ai-astar) 01:47:35 INFO - PROCESS | 1959 | RSS: Main: 265519104 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | __start_tp_report 01:47:35 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 01:47:35 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 01:47:35 INFO - PROCESS | 1959 | |i|pagename|runs| 01:47:35 INFO - PROCESS | 1959 | |0;ai-astar;106;118;117;118;115;118;119;120;117;120 01:47:35 INFO - PROCESS | 1959 | |1;audio-beat-detection;145;117;118;150;115;116;120;116;144;111 01:47:35 INFO - PROCESS | 1959 | |2;audio-dft;200;199;200;230;197;195;194;197;195;195 01:47:35 INFO - PROCESS | 1959 | |3;audio-fft;79;62;82;74;76;78;83;85;70;51 01:47:35 INFO - PROCESS | 1959 | |4;audio-oscillator;84;70;86;72;68;70;86;69;72;87 01:47:35 INFO - PROCESS | 1959 | |5;imaging-gaussian-blur;88;87;88;85;93;85;89;85;79;131 01:47:35 INFO - PROCESS | 1959 | |6;imaging-darkroom;93;95;94;94;93;95;95;95;94;93 01:47:35 INFO - PROCESS | 1959 | |7;imaging-desaturate;90;89;86;88;86;90;89;122;135;141 01:47:35 INFO - PROCESS | 1959 | |8;json-parse-financial;56;56;55;57;57;57;56;58;58;57 01:47:35 INFO - PROCESS | 1959 | |9;json-stringify-tinderbox;68;75;71;76;74;72;76;106;108;82 01:47:35 INFO - PROCESS | 1959 | |10;stanford-crypto-aes;85;89;85;90;92;86;90;95;92;84 01:47:35 INFO - PROCESS | 1959 | |11;stanford-crypto-ccm;152;152;148;147;152;144;151;149;184;161 01:47:35 INFO - PROCESS | 1959 | |12;stanford-crypto-pbkdf2;181;218;185;177;174;192;207;176;175;184 01:47:35 INFO - PROCESS | 1959 | |13;stanford-crypto-sha256-iterative;74;72;69;73;73;74;101;91;91;89 01:47:35 INFO - PROCESS | 1959 | __end_tp_report 01:47:35 INFO - PROCESS | 1959 | __start_cc_report 01:47:35 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,938 01:47:35 INFO - PROCESS | 1959 | __end_cc_report 01:47:35 INFO - PROCESS | 1959 | __startTimestamp1477471655389__endTimestamp 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | ------- Summary: start ------- 01:47:35 INFO - PROCESS | 1959 | Number of tests: 14 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#0] ai-astar Cycles:10 Average:116.80 Median:118.00 stddev:4.08 (3.5%) stddev-sans-first:1.58 01:47:35 INFO - PROCESS | 1959 | Values: 106.0 118.0 117.0 118.0 115.0 118.0 119.0 120.0 117.0 120.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:119.00 stddev:14.84 (12.5%) stddev-sans-first:13.90 01:47:35 INFO - PROCESS | 1959 | Values: 145.0 117.0 118.0 150.0 115.0 116.0 120.0 116.0 144.0 111.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#2] audio-dft Cycles:10 Average:200.20 Median:198.00 stddev:10.70 (5.4%) stddev-sans-first:11.34 01:47:35 INFO - PROCESS | 1959 | Values: 200.0 199.0 200.0 230.0 197.0 195.0 194.0 197.0 195.0 195.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#3] audio-fft Cycles:10 Average:74.00 Median:78.50 stddev:10.54 (13.4%) stddev-sans-first:11.02 01:47:35 INFO - PROCESS | 1959 | Values: 79.0 62.0 82.0 74.0 76.0 78.0 83.0 85.0 70.0 51.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:78.00 stddev:8.17 (10.5%) stddev-sans-first:8.19 01:47:35 INFO - PROCESS | 1959 | Values: 84.0 70.0 86.0 72.0 68.0 70.0 86.0 69.0 72.0 87.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:87.50 stddev:14.51 (16.6%) stddev-sans-first:15.35 01:47:35 INFO - PROCESS | 1959 | Values: 88.0 87.0 88.0 85.0 93.0 85.0 89.0 85.0 79.0 131.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.50 stddev:0.88 (0.9%) stddev-sans-first:0.83 01:47:35 INFO - PROCESS | 1959 | Values: 93.0 95.0 94.0 94.0 93.0 95.0 95.0 95.0 94.0 93.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:88.50 stddev:21.97 (24.8%) stddev-sans-first:22.89 01:47:35 INFO - PROCESS | 1959 | Values: 90.0 89.0 86.0 88.0 86.0 90.0 89.0 122.0 135.0 141.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:0.95 (1.7%) stddev-sans-first:0.97 01:47:35 INFO - PROCESS | 1959 | Values: 56.0 56.0 55.0 57.0 57.0 57.0 56.0 58.0 58.0 57.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:74.50 stddev:14.30 (19.2%) stddev-sans-first:14.39 01:47:35 INFO - PROCESS | 1959 | Values: 68.0 75.0 71.0 76.0 74.0 72.0 76.0 106.0 108.0 82.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:90.00 stddev:3.68 (4.1%) stddev-sans-first:3.63 01:47:35 INFO - PROCESS | 1959 | Values: 85.0 89.0 85.0 90.0 92.0 86.0 90.0 95.0 92.0 84.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:152.00 stddev:11.45 (7.5%) stddev-sans-first:12.12 01:47:35 INFO - PROCESS | 1959 | Values: 152.0 152.0 148.0 147.0 152.0 144.0 151.0 149.0 184.0 161.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.90 Median:184.50 stddev:14.79 (8.0%) stddev-sans-first:15.53 01:47:35 INFO - PROCESS | 1959 | Values: 181.0 218.0 185.0 177.0 174.0 192.0 207.0 176.0 175.0 184.0 01:47:35 INFO - PROCESS | 1959 | 01:47:35 INFO - PROCESS | 1959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.70 Median:74.00 stddev:11.13 (15.0%) stddev-sans-first:11.53 01:47:35 INFO - PROCESS | 1959 | Values: 74.0 72.0 69.0 73.0 73.0 74.0 101.0 91.0 91.0 89.0 01:47:35 INFO - PROCESS | 1959 | -------- Summary: end -------- 01:47:35 INFO - PROCESS | 1959 | 01:47:36 INFO - TEST-INFO | 1959: exit 0 01:47:36 INFO - TEST-OK | kraken | took 59853ms 01:47:36 INFO - SUITE-END | took 1175s 01:47:36 INFO - Completed test suite (00:19:35) 01:47:36 INFO - javascript benchmark 01:47:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7110.691889815913, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.5023721323187, 3232.6120865496137, 3263.2657652028283, 3295.57157569516, 3307.3219989902072, 5405.810796622119, 5408.864778380533, 5415.160650099236, 5443.809466668662, 5465.945340546593, 5406.189071639256, 5409.161579955104, 5481.616286859911, 5522.944770552293, 5533.501984821365, 4378.10248898975, 4811.893264549139, 5027.547520723134, 5137.845864624067, 5186.962607478519, 4905.0, 5136.563392111656, 5138.306328645623, 5152.433232344439, 5166.767495462695, 4975.154223781966, 5074.238864170386, 5114.846554603368, 5139.743012849362, 5158.329417175743, 4977.303177555145, 4978.103941290567, 5005.624578156653, 5048.116579598555, 5145.84562463123, 4996.4254110777365, 5022.723750193738, 5031.622628302855, 5037.5214354636255, 5073.746312684388, 4274.252005898955, 5252.870632813925, 5500.614956953015, 5513.173024046385, 5521.061419558685, 5775.1337299405, 5886.7939622113445, 5936.139259807347, 5961.940380596182, 5978.760849566012, 6109.663968481732, 6119.051547010221, 6125.663088530129, 6131.417515336036, 6132.601380910227, 5579.6652200867675, 5602.775888964477, 5628.859278518029, 5633.577481688849, 5638.830835074954, 5984.132300816357, 6005.519558435315, 6022.066579680157, 6116.0, 6192.969035154795, 5693.28833895763, 5782.479576838105, 5800.796972105956, 5819.301683797971, 5834.0, 5872.0898260769645, 5931.584789064724, 5973.79091731796, 5974.193483879708, 5979.4618484336615, 4771.904561908743, 4827.951720482821, 5844.47399734026, 5868.295804503487, 5907.379725128885, 6010.699465026732, 6035.124906888476, 6037.305709843336, 6039.456448919618, 6077.908831367533, 4772.570468657837, 4786.545278198566, 4791.353167322437, 4803.735794531334, 4843.370361852936, 4205.243056249905, 4232.8518501852905, 4243.724157929724, 4261.531231564525, 4310.0, 5584.553235741131, 5622.57830662702, 5624.578156638268, 5628.943710562842, 5661.971690141605, 6086.421789929949, 6110.388961103943, 6125.540584456184, 6129.570930034944, 6146.508279337731, 5382.703951282639, 5484.067708489487, 5494.917576236359, 5585.162225666182, 5587.692676902728, 5149.073166829931, 5205.479452054764, 5231.267622532849, 5251.789928402821, 5265.736713164327, 6214.25428948529, 6221.5333849961435, 6239.719212635533, 6251.312355640966, 6269.717862696099, 5837.883242335214, 5909.645421274566, 6079.0, 6108.908366374597, 6155.199824022849, 5130.743462826769, 6097.282711138064, 6102.883130681719, 6106.6946652668075, 6169.784057557964, 5768.913466298093, 5921.3190483094995, 5929.080992446177, 5953.196318496947, 6018.067199584071, 5889.49939255151, 5976.342602313655, 5993.490553302844, 6020.036794112921, 6052.334243233153, 4811.350467686818, 5630.8029218977135, 5703.0, 5858.765649373977, 5878.941210587839, 5751.1373294006235, 5773.422657734361, 5847.532197424281, 5865.794697185578, 5944.048952167633, 4945.876353091202, 5444.26502422168, 5673.149027645721, 5688.800891968926, 5694.373618902, 5806.622569532967, 5838.978178818605, 5843.883122337615, 5867.149263356875, 5871.706414679164, 5521.868017056413, 5605.775768969195, 5618.269624948894, 5677.063284558001, 5712.485876271156], "value": 5459.844252132619, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.2911312786411, 455.1693159983032, 470.4824692837878, 485.1534073042539, 526.0557299647134, 39500.0, 41483.79258103709, 41693.58306416935, 43426.34860477095, 43483.78258108713, 42255.0, 42498.57501424985, 42834.92912677184, 42962.78518607411, 43178.136437271234, 9511.6195352186, 9582.281328900326, 9662.5168741563, 9678.032196780301, 9709.805803883917, 9541.666041688542, 9614.80770384592, 9620.182284505825, 9628.374155679894, 9647.662331818388, 9485.81028379432, 9530.237580993538, 9625.807483850318, 9645.903540964571, 9661.758456038586, 9570.569324380385, 9621.230301575857, 9655.986121457216, 9660.37207581505, 9665.565049572786, 9537.666181683608, 9540.856887146698, 9621.134097931219, 9640.42157470547, 9640.710778676581, 40071.0, 40123.39814902779, 40232.195356092714, 40455.393169102485, 40553.98615034648, 3052.450558899397, 3430.1767575781882, 3454.9827250863837, 3464.861405543775, 3519.6304388039143, 30576.08271751851, 30638.9276375328, 30663.0, 30721.078367649006, 31243.84378078095, 3024.5916801231992, 3032.4086803073187, 3058.9541156882665, 3068.6317641883115, 3118.9688103118906, 491957.0, 496099.0, 496858.0, 497235.0, 497252.0, 58938.41061589415, 59263.111053334236, 59269.0, 59285.0, 59333.0, 60322.0, 61779.38220617826, 61915.6904215476, 62023.0, 62428.0, 13580.932095339658, 15281.847181528043, 16044.75932861008, 16076.839231607768, 16257.0, 12207.450664720109, 12307.75384492305, 12319.0, 12331.6917077072, 12342.81485777714, 15546.455874044354, 19179.712304315446, 19317.323893663655, 19385.806141938683, 19575.314863979693, 7857.842843143105, 7873.25204105609, 8006.959965200137, 8017.305549865927, 8053.436259461781, 748.5994992679, 895.5969813583913, 913.5021413329752, 986.6546708652058, 993.7081793668202, 25817.612735808976, 29516.262093447836, 30250.0, 30265.697343026728, 33480.83259583735, 904.1591320072264, 929.8028639120188, 1013.7414959185508, 1027.0653705128298, 1104.9613363438343, 15791.131487768283, 15805.13071781063, 15868.761968570481, 16001.199940002953, 16163.0, 653250.0, 657426.0, 663097.0, 664259.0, 664571.0, 56706.0, 57497.425025750046, 57643.42356576464, 57827.0, 58118.41881581215, 1349.9325033748273, 1377.2012232905092, 1383.3567391162956, 1389.6248013036625, 1398.489551313763, 25735.742642574085, 28973.275668108465, 29886.103416897527, 30381.392372152874, 30608.693913060586, 46244.0, 49756.75121624369, 49791.253131202335, 49807.750961244965, 49817.00365992732, 573266.0, 580322.0, 581705.0, 582410.0, 584005.0799745974, 25554.105606304434, 25666.230013099637, 26039.95840166372, 26422.471550569266, 26518.734812651626, 45560.316595250435, 46487.53512464832, 47040.35358762427, 47230.0, 48336.7583162096, 38168.80915595404, 38819.417708733825, 39087.02282442848, 39469.40795888007, 40253.0, 32652.0, 38038.80980595079, 38376.970418105695, 38389.0, 39372.409413858244, 32177.517337240428, 33000.0, 37560.24879502449, 38141.0, 38429.039274018374, 31608.209794754395, 32152.37333466681, 34658.82670586631, 35468.0, 36291.637083628826, 21007.264745733824, 21624.89187554052, 25569.616455753552, 25668.871655641604, 26131.34671633148], "value": 19767.010287398865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4062774538775, 377.4828484975582, 382.1821302412837, 388.31074842155186, 401.97990100494985, 19012.433321501594, 20936.581268374623, 21292.89353553234, 21467.785322146774, 21650.56698866022, 15158.696826063471, 18995.145218465168, 19098.0, 19231.71152432711, 19606.411807645793, 4712.835050773224, 5508.779648814043, 5645.802396916109, 5667.4615911488345, 6054.152418661391, 5788.18965344852, 5983.0, 5988.281406231258, 5991.670458124801, 5995.790147344844, 5637.098064309711, 5818.883622327551, 5848.181254624356, 5927.673977931212, 5935.910961335583, 5850.707464626773, 5879.323877754049, 5900.586958912878, 5907.084401917709, 5921.023031199847, 4405.339199120125, 4918.311436398907, 5086.287919691246, 5155.7679904404395, 5162.251473536317, 24497.87751061251, 24844.254672359857, 24882.880270387875, 25547.0, 25830.483390332087, 2001.2795393658416, 2127.2341956895507, 2302.8272879533934, 2393.1504315967754, 2830.3631682871396, 5648.265725455705, 5697.6866272355, 5700.515456186187, 9865.50672466381, 10141.225285574996, 1855.7309190167484, 1866.3281218761235, 1875.831175194239, 1912.2159914435153, 1928.7203355513373, 125534.0, 126298.36850815779, 127091.36454317669, 127242.72757272494, 133381.3330933349, 10977.945110274397, 11279.605213817476, 11448.828267575993, 11555.364454955059, 11819.763604727857, 14539.709205816036, 14593.562193134223, 14618.85381146175, 14620.342084606218, 14633.780493292608, 7758.185390534026, 8121.390895682847, 8469.957650211709, 8650.95674521636, 8654.78363040929, 8423.620937057847, 8435.536045517432, 8458.49249045047, 8547.974243090868, 8616.870746938801, 3767.3783825669007, 4502.954970450253, 4525.162844873709, 4595.1728688835665, 4603.560113140041, 1892.668782962976, 1943.7278780970676, 1948.8928108954144, 1949.5516031312877, 1951.3267922566693, 699.0458024796117, 821.2444551013082, 897.7531178925717, 899.9370044096981, 975.9606019589256, 20254.79745202529, 21798.0, 21964.560708785735, 22104.557908842056, 22231.221907233168, 881.7575166829045, 922.5387306346827, 957.899420560845, 957.9036950541217, 986.4919566423298, 8402.78993025167, 10567.04896559298, 11500.079993600491, 11511.76976460483, 11547.76904461906, 548193.0, 556625.0, 561296.580551292, 601889.0, 602112.0, 86929.39211823656, 118416.0, 118419.0, 140731.29634351764, 142811.0, 846.6782622603371, 925.500229875873, 950.254050570299, 953.6328513522344, 986.556049777603, 18554.350597729015, 18595.34916277924, 18843.0, 18938.52653683669, 18954.905225474336, 50343.0, 50373.74065648388, 51058.0, 51639.74180129226, 53037.0, 56705.0, 74356.0, 74950.25049749433, 76477.85283220645, 76692.0, 6365.809025729235, 6540.901886471612, 6602.801915942529, 7597.6581053853815, 7842.490238134503, 12055.336956467128, 12370.670265407036, 12460.314682692539, 12472.002239820975, 12630.684232894251, 8697.304215662605, 9171.724848254564, 10610.045095941403, 10667.89332106669, 10880.401577913291, 8551.0, 8715.564221789013, 10337.896621033693, 10598.31110977784, 10790.622328218475, 9292.6282948684, 10304.072633463024, 10423.94788026055, 10727.624533141303, 10832.350058996486, 7632.656530456254, 7885.29032387088, 8038.718644847402, 8095.23095305945, 8168.469049511763, 8014.639341229776, 8604.881365422454, 10259.820120686216, 10912.563497460013, 11154.330740155616], "value": 9201.047151900808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1531.3797911845702, 1552.6817002514483, 1601.383467365064, 1829.3322937127944, 1831.459719382782, 7871.370290376771, 8158.143394943534, 8439.32485401168, 8945.239654629357, 8956.014838367782, 8395.496270223775, 8686.261667758232, 8709.0, 8809.38334316598, 8816.911830881689, 2328.4644531757663, 2343.871087090209, 2351.094828491026, 2356.905723771056, 2357.0807385119815, 2341.718993720756, 2364.527094581082, 2374.560706269337, 2394.7964423023977, 2397.4845408237225, 2328.639060945556, 2338.2166974063707, 2360.598698221298, 2363.621820508719, 2378.9286321410386, 2280.6122959096992, 2294.7475777664445, 2299.3906614747107, 2308.042162502559, 2330.067972810872, 2132.5948069866677, 2323.0011095229047, 2341.718993720747, 2356.693629828111, 2356.8232382571205, 7419.8145046374275, 8744.169303916118, 8842.115788421172, 8886.777830554223, 8903.421277616999, 5076.137056700333, 5184.274201611777, 5333.253344531769, 5342.171963345649, 5360.0, 3039.8936037238814, 3525.471179323122, 3528.964710352889, 3658.122050707811, 3680.9079773005888, 1002.8245057114975, 1010.5974358117973, 1044.3942513342242, 1045.29442626227, 1063.3672964586058, 10619.25665203436, 11073.50169242386, 11514.884851151464, 11617.361045142514, 11723.76552468946, 4547.454305483363, 4700.694454860423, 4744.312074749211, 4759.571638552547, 4800.879978000578, 9808.21534277256, 9912.653057142965, 9914.40513569188, 9930.304878658424, 9940.254480913816, 5309.761060752276, 5312.203169524602, 5315.069862773999, 5322.973385133127, 5335.786568537293, 4372.628326592212, 4399.076193999232, 4796.0, 4845.442774081026, 4863.051704917578, 3798.6581207691443, 3819.3698039823116, 3904.2581909437117, 3933.2330195612153, 3948.6051394860283, 1259.8740125987329, 1272.166730791333, 1279.5137847617846, 1279.8400199975003, 1282.948682052726, 911.2755359489222, 912.4023764434306, 938.3759799733101, 942.2885721280389, 947.6541062512133, 5555.138953462219, 5751.051076572402, 5853.351515787285, 5890.175375447441, 5893.882122357529, 421.2648927621259, 427.36962979605454, 429.2638125614586, 432.60848931716845, 440.7597859166762, 3790.2040571479747, 3792.1657235408716, 3898.8830335089992, 3900.980495097563, 3902.0439992201523, 10236.59053637861, 10482.790344193074, 10613.787724245472, 10632.149428045737, 10640.201984851166, 10557.996990286065, 10589.0, 10596.576136954589, 10615.946920265349, 10640.095591874622, 8009.559474228973, 8090.474119182234, 8149.0, 8231.09457959624, 8253.669853205805, 5246.658967167122, 5555.472230138131, 5589.441055894378, 5701.4298570142655, 5735.0, 9718.611255549698, 9779.070988256104, 9817.410955342702, 9829.066238707312, 9833.459159746282, 9705.514724263618, 9816.0, 9822.705318840446, 9857.704268871945, 9983.550740216562, 6790.185177778698, 6974.511784175059, 7292.307230813064, 7372.115346158495, 7543.585102819397, 8234.65312385967, 8323.001239851257, 8373.49759014461, 8422.620982055945, 8438.493690378597, 7280.9271907280245, 7414.0, 7424.406047516294, 7517.398608111447, 7521.586312752851, 7151.32062454066, 7239.239879812544, 7275.454340924346, 7287.890681639886, 7377.668004939898, 6825.385715285648, 6934.445244380337, 6950.54821436605, 6961.582305061713, 6962.164540255202, 4894.583960363409, 5015.824446144367, 5018.096742586369, 5067.594592432522, 5128.538431541179, 4922.163232250455, 4967.354243948408, 5091.287219789307, 5099.515546023122, 5171.224316352577], "value": 4452.344780995594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.9506731389321, 302.98788048478065, 311.35704769650664, 314.5721818327076, 316.6042446941323, 4424.469063712351, 5247.816326428576, 5441.646292990958, 5449.618526703128, 5476.507114359707, 4677.789499472532, 4813.350197723306, 4997.950020499794, 5421.864453388667, 5480.616356855022, 3389.5594372391756, 4091.2022155679656, 4119.320312148492, 4133.834646614132, 4134.152498737752, 3436.639152888948, 4012.678985681153, 4149.983254102749, 4158.4594002779595, 4197.412362269285, 3372.207531230159, 3818.217265460574, 3959.841606335743, 4076.347784354504, 4095.0, 4069.3285607874664, 4078.6125318094732, 4089.7750623715833, 4099.528554216274, 4116.608922152421, 3283.5731354923705, 3556.1820781220204, 3577.4633804929467, 3581.2658405026905, 3588.4796704477703, 4869.561739443467, 5051.898962020776, 5199.66202196856, 5252.579793616502, 5259.474052594748, 4590.586847183736, 4669.813207471697, 4684.367610372624, 4686.461056978457, 4694.43666759991, 4678.415198100238, 5167.844964651066, 5618.634788738719, 5699.658020518741, 5744.482996530291, 3688.3729765939856, 3752.6490463433142, 3896.902577435587, 3928.744631598937, 3949.032487040665, 5218.877941242651, 5842.218123472339, 5930.436608522184, 5951.613145145595, 5961.284645842483, 3978.5225772906856, 3987.282289187974, 3988.700847436454, 3994.980025099914, 4013.979930100331, 3899.512560929884, 3955.4660120883887, 4048.4939382577177, 4051.885731423823, 4067.186562687415, 3801.4297855321925, 4132.442120313779, 4159.793659838613, 4501.0, 4674.182018146811, 4692.7653617319, 5092.592592592584, 5241.816536421207, 5492.928878868583, 5519.613627046148, 1136.2954967919943, 1334.4528743215235, 1357.9796303055462, 1360.8911287097008, 1373.4411443012252, 776.6155752902349, 856.8971723393232, 916.5692124701379, 945.921649319776, 948.2271948362063, 4241.321388577812, 4241.4698162729655, 4704.764761761899, 4979.253112033224, 5092.0, 4969.90060198794, 5365.302510673588, 5772.595918285764, 5800.477956983892, 5838.649681019152, 4504.617107545895, 5063.291139240509, 5197.7401129943355, 5249.947500524946, 5296.549793267615, 3051.343961048385, 3126.85929133185, 3139.952900706491, 3140.199249191487, 3148.0, 5079.644424890296, 5112.488751124932, 6048.455638992512, 6210.4100110489435, 6225.953130465144, 4970.900581988412, 5701.258836351248, 5936.821895343147, 6043.456088952015, 6050.515958723292, 5410.594205434608, 6021.42796434338, 6114.563077676742, 6134.938650613526, 6179.969100154651, 5420.590646431877, 5432.972835135799, 5476.397596264487, 5568.443155684399, 5936.951751645675, 5837.766489340379, 5888.087346461305, 5913.113033045078, 5955.553333499918, 5958.986972214647, 5420.457954204548, 5729.479029227126, 6241.656708881055, 6244.500439964882, 6253.904317362934, 3493.7554371194788, 3705.9258814824093, 3795.3737633291303, 3825.0, 3902.9219415612097, 5425.538829199404, 5709.857253568694, 5962.25471816023, 5962.344142144448, 6003.489703375262, 4794.400699912511, 5008.847964968005, 5544.528715059104, 5732.455416735404, 5749.367569567428, 5053.0399224147295, 5765.308163020297, 5784.1535378974195, 5828.679422631796, 5843.707814609337, 5336.9466305338, 5347.919781203207, 5393.757280922289, 5571.7492712828835, 5682.43175682445, 4638.652101092364, 5017.8243761468175, 5428.669975855941, 5581.832545023656, 5787.94212057874, 4712.670113092051, 5342.382232706023, 5682.744276507649, 5695.515881149983, 5706.2011318414625], "value": 4122.3763918559325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.871425714857, 645.670707938951, 652.9673516324183, 724.2359310926973, 747.6224506624155, 13298.0, 13801.93099034506, 14109.43562257511, 14134.010619256656, 14136.575902722909, 13104.14823036502, 13148.671283217924, 13153.60539183826, 13209.801852972214, 13268.933655331733, 706.6043015911081, 727.1383410658386, 875.916787905148, 879.5162660536711, 890.1765866573426, 856.4689892266804, 883.0286684646901, 896.050186801992, 897.9281657467419, 902.9954850225739, 967.1634036558362, 967.2213867836389, 968.3850754770701, 970.4419958523877, 971.4608392342247, 909.6225066597354, 946.1012038563358, 959.5106495687215, 960.783823639679, 964.363520076746, 675.8107729835654, 677.5426587053719, 719.6365835253164, 719.9532030418006, 721.7473884140509, 13745.24401157936, 13831.654208644764, 14002.719945601133, 14163.0, 14202.50291239812, 11695.239809412446, 11832.763344733143, 11948.940255298754, 12773.169743966613, 13425.932870335586, 11891.918666567652, 11930.10524210679, 12048.457819398149, 12228.938855305667, 12528.498860045587, 4189.874303770892, 4193.832246710128, 4205.243056249874, 4212.368144778277, 4369.650427965787, 97461.5126924368, 98008.50995745047, 98034.0, 98562.0, 99390.00609993952, 9567.569459374345, 9601.47191904452, 9681.08029737188, 9844.212463002943, 9954.25343099256, 18978.0, 19296.71054934177, 19327.516812079528, 19378.224871005, 19511.317103901296, 7366.815829604196, 7455.925440745523, 7510.4742668013805, 7572.4699271050495, 7572.886406703904, 3989.3218152914073, 4139.9793001035405, 4232.78836058202, 4285.7642829644665, 4296.0, 3351.6983471487683, 3429.228423604669, 3461.238527523941, 3463.930721385558, 3466.324066806999, 565.3187908570163, 640.5356116815271, 643.4177069751883, 652.3052948609716, 662.6554191820227, 556.0130767886952, 599.0475144520234, 609.2354095609982, 615.827568280882, 636.9713362898681, 7331.9266807332315, 8030.277275045157, 8265.842782010523, 8609.956950215208, 9040.412373195853, 571.9628224165499, 590.2268028882178, 623.2956758862456, 626.6678660310043, 634.5050860328859, 4391.143726973273, 4392.956070439319, 4413.911721765547, 4414.404055452537, 4454.0, 48035.03929921382, 77884.83172752413, 106696.0, 121462.0, 122864.38567807103, 55337.50981205855, 60072.399276007556, 79551.8067228992, 102018.48990754999, 108656.45671771749, 65035.0, 88745.0, 88919.55540222386, 90341.64487532692, 91412.0, 8365.247127758288, 8648.394612377073, 8702.1297870215, 8721.607527661397, 8765.211130998241, 56351.43648563462, 56889.71555142364, 57798.71100644638, 58885.705571471866, 59717.0, 73257.26742732717, 80206.59896700479, 82042.58978705069, 82118.0, 83555.0, 10674.57301707923, 10734.624288149877, 10839.837402438812, 10872.836907446237, 11065.502052407823, 11540.054387146536, 12008.699782505159, 12144.0, 12534.561290354794, 12602.054845886412, 11627.709307267385, 11715.707107322385, 11824.70438239051, 11858.940705296418, 11875.0, 11236.943815281198, 11457.885421145682, 11463.598774042868, 11498.597549086076, 11566.30602163873, 10605.204609654153, 10648.414337211529, 10849.349039057683, 11511.712207194887, 11821.113416493625, 8284.0, 9297.349185557217, 9476.431414114899, 9688.951555242178, 9719.514024298898, 9724.70825875225, 9949.204063675033, 9961.651342203279, 9981.950090249502, 9997.650082247086], "value": 7091.365261294861, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 118.0, 115.0, 118.0, 119.0, 120.0, 117.0, 120.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 118.0, 150.0, 115.0, 116.0, 120.0, 116.0, 144.0, 111.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 200.0, 230.0, 197.0, 195.0, 194.0, 197.0, 195.0, 195.0], "value": 200.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 82.0, 74.0, 76.0, 78.0, 83.0, 85.0, 70.0, 51.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 86.0, 72.0, 68.0, 70.0, 86.0, 69.0, 72.0, 87.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 85.0, 93.0, 85.0, 89.0, 85.0, 79.0, 131.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 93.0, 95.0, 95.0, 95.0, 94.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 88.0, 86.0, 90.0, 89.0, 122.0, 135.0, 141.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 57.0, 57.0, 57.0, 56.0, 58.0, 58.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 71.0, 76.0, 74.0, 72.0, 76.0, 106.0, 108.0, 82.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 85.0, 90.0, 92.0, 86.0, 90.0, 95.0, 92.0, 84.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 148.0, 147.0, 152.0, 144.0, 151.0, 149.0, 184.0, 161.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 218.0, 185.0, 177.0, 174.0, 192.0, 207.0, 176.0, 175.0, 184.0], "value": 186.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 69.0, 73.0, 73.0, 74.0, 101.0, 91.0, 91.0, 89.0], "value": 80.7, "unit": "score"}]}]} 01:47:36 INFO - Return code: 0 01:47:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:47:36 ERROR - # TBPL SUCCESS # 01:47:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:36 INFO - 2016-10-26 01:47:36.670 screenresolution[1964:18638] starting screenresolution argv=screenresolution get 01:47:36 INFO - 2016-10-26 01:47:36.674 screenresolution[1964:18638] Display 0: 1600x1200x32@60 01:47:36 INFO - 2016-10-26 01:47:36.681 screenresolution[1965:18641] starting screenresolution argv=screenresolution list 01:47:36 INFO - Available Modes on Display 0 01:47:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:36 INFO - Intel Iris: 01:47:36 INFO - Chipset Model: Intel Iris 01:47:36 INFO - Type: GPU 01:47:36 INFO - Bus: Built-In 01:47:36 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:36 INFO - Vendor: Intel (0x8086) 01:47:36 INFO - Device ID: 0x0a2e 01:47:36 INFO - Revision ID: 0x0009 01:47:36 INFO - Displays: 01:47:36 INFO - Display: 01:47:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:36 INFO - Display Serial Number: 1600x1200 60 01:47:36 INFO - Main Display: Yes 01:47:36 INFO - Mirror: Off 01:47:36 INFO - Online: Yes 01:47:36 INFO - Rotation: Supported 01:47:36 INFO - Return code: 0 01:47:36 INFO - Running post-action listener: _resource_record_post_action 01:47:36 INFO - [mozharness: 2016-10-26 08:47:36.918072Z] Finished run-tests step (success) 01:47:36 INFO - Running post-run listener: _resource_record_post_run 01:47:36 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 917504; Write bytes: 711104512; Read time: 10; Write time: 1962 01:47:36 INFO - TinderboxPrint: CPU usage
54.5% 01:47:36 INFO - TinderboxPrint: I/O read bytes / time
917,504 / 10 01:47:36 INFO - TinderboxPrint: I/O write bytes / time
711,104,512 / 1,962 01:47:36 INFO - TinderboxPrint: CPU idle
2,202.8 (45.5%) 01:47:36 INFO - TinderboxPrint: CPU system
979.3 (20.2%) 01:47:36 INFO - TinderboxPrint: CPU user
1,659.7 (34.3%) 01:47:36 INFO - TinderboxPrint: Swap in / out
406,876,160 / 0 01:47:36 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392829440; Write bytes: 388300288; Read time: 29647; Write time: 688 01:47:36 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 917504; Write bytes: 301128192; Read time: 10; Write time: 1225 01:47:37 INFO - Running post-run listener: _upload_blobber_files 01:47:37 INFO - Blob upload gear active. 01:47:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:37 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 01:47:37 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'] 01:47:37 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 01:47:37 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:39 INFO - (blobuploader) - INFO - Done attempting. 01:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:40 INFO - (blobuploader) - INFO - Done attempting. 01:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:42 INFO - (blobuploader) - INFO - Done attempting. 01:47:42 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:42 INFO - Return code: 0 01:47:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041fc3b2da048106311e969f5a9ba68d567fbcaddad35ffcc0555324edb6cdef897968af7c1bc2327adb2b56aec11b3f03f0bdb8c2d1f12095de046e5d0cf4e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee304a71358bf7398d6a2a1f2d9c9d1275b42c70e84d8e6848f6b0f2bd032d6087fd09a70e1fdad87f039d76c0e168df45d624d083a0c25c682f9026076b604", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb9802a9bb027de590d6b941ae8c65aaaf9b18a44ff0e23fe85b3505e5bb708acb146e4f5962f185471e55df2a82f204315d603c4f75bea28e1c870ca3227b7c"} 01:47:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:42 INFO - Contents: 01:47:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041fc3b2da048106311e969f5a9ba68d567fbcaddad35ffcc0555324edb6cdef897968af7c1bc2327adb2b56aec11b3f03f0bdb8c2d1f12095de046e5d0cf4e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee304a71358bf7398d6a2a1f2d9c9d1275b42c70e84d8e6848f6b0f2bd032d6087fd09a70e1fdad87f039d76c0e168df45d624d083a0c25c682f9026076b604", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb9802a9bb027de590d6b941ae8c65aaaf9b18a44ff0e23fe85b3505e5bb708acb146e4f5962f185471e55df2a82f204315d603c4f75bea28e1c870ca3227b7c"} 01:47:42 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:42 INFO - Copying logs to upload dir... 01:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.148961 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-26 01:47:42.607983) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 01:47:42.612028) ========= 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.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041fc3b2da048106311e969f5a9ba68d567fbcaddad35ffcc0555324edb6cdef897968af7c1bc2327adb2b56aec11b3f03f0bdb8c2d1f12095de046e5d0cf4e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee304a71358bf7398d6a2a1f2d9c9d1275b42c70e84d8e6848f6b0f2bd032d6087fd09a70e1fdad87f039d76c0e168df45d624d083a0c25c682f9026076b604", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb9802a9bb027de590d6b941ae8c65aaaf9b18a44ff0e23fe85b3505e5bb708acb146e4f5962f185471e55df2a82f204315d603c4f75bea28e1c870ca3227b7c"} build_url:https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010618 build_url: 'https://queue.taskcluster.net/v1/task/HtltD5jAS0qPIKFv1Rn9JQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041fc3b2da048106311e969f5a9ba68d567fbcaddad35ffcc0555324edb6cdef897968af7c1bc2327adb2b56aec11b3f03f0bdb8c2d1f12095de046e5d0cf4e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee304a71358bf7398d6a2a1f2d9c9d1275b42c70e84d8e6848f6b0f2bd032d6087fd09a70e1fdad87f039d76c0e168df45d624d083a0c25c682f9026076b604", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb9802a9bb027de590d6b941ae8c65aaaf9b18a44ff0e23fe85b3505e5bb708acb146e4f5962f185471e55df2a82f204315d603c4f75bea28e1c870ca3227b7c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 01:47:42.651510) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:47:42.652158) ========= 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.bld0mrI1PH/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.REJ3sqklhw/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.005366 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 01:47:42.701413) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 01:47:42.701743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 01:47:42.702111) ========= ========= Total master_lag: 0.21 =========