builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0087 starttime: 1480780162.35 results: success (0) buildid: 20161203062844 builduid: 91922d5fece94bc2aba7341ec5c85ab4 revision: c21f4124a5b79b9989579efa33edc20795692af2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.353118) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.353555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.353827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.381742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.382001) ========= 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.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-12-03 07:49:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2016-12-03 07:49:22 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.487283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.892503) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.892781) ========= 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.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.019442 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.943747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:22.944053) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c21f4124a5b79b9989579efa33edc20795692af2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c21f4124a5b79b9989579efa33edc20795692af2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-12-03 07:49:23,021 truncating revision to first 12 chars 2016-12-03 07:49:23,021 Setting DEBUG logging. 2016-12-03 07:49:23,021 attempt 1/10 2016-12-03 07:49:23,021 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c21f4124a5b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-03 07:49:23,731 unpacking tar archive at: mozilla-aurora-c21f4124a5b7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.956103 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:23.934285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:23.934662) ========= script_repo_revision: c21f4124a5b79b9989579efa33edc20795692af2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:23.935040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:23.935306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 07:49:24.033659) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-03 07:49:24.033933) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:49:24 INFO - MultiFileLogger online at 20161203 07:49:24 in /builds/slave/test 07:49:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:49:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:24 INFO - {'append_to_log': False, 07:49:24 INFO - 'base_work_dir': '/builds/slave/test', 07:49:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:49:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:24 INFO - 'branch': 'Mozilla-Aurora', 07:49:24 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:24 INFO - 'config_files': ('talos/mac_config.py',), 07:49:24 INFO - 'default_actions': ('clobber', 07:49:24 INFO - 'read-buildbot-config', 07:49:24 INFO - 'download-and-extract', 07:49:24 INFO - 'populate-webroot', 07:49:24 INFO - 'create-virtualenv', 07:49:24 INFO - 'install', 07:49:24 INFO - 'run-tests'), 07:49:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:24 INFO - 'download_minidump_stackwalk': True, 07:49:24 INFO - 'download_symbols': 'ondemand', 07:49:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:24 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:24 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:24 INFO - 'installer_path': 'installer.exe', 07:49:24 INFO - 'log_level': 'info', 07:49:24 INFO - 'log_name': 'talos', 07:49:24 INFO - 'log_to_console': True, 07:49:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:49:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:49:24 INFO - 'opt_config_files': (), 07:49:24 INFO - 'pip_index': False, 07:49:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:49:24 INFO - 'cmd': ('bash', 07:49:24 INFO - '-c', 07:49:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:49:24 INFO - 'enabled': True, 07:49:24 INFO - 'halt_on_failure': False, 07:49:24 INFO - 'name': 'check_screen_resolution'},), 07:49:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:49:24 INFO - 'cmd': ('bash', 07:49:24 INFO - '-c', 07:49:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:49:24 INFO - 'enabled': True, 07:49:24 INFO - 'halt_on_failure': False, 07:49:24 INFO - 'name': 'check_screen_resolution'},), 07:49:24 INFO - 'run_cmd_checks_enabled': True, 07:49:24 INFO - 'sps_profile': False, 07:49:24 INFO - 'sps_profile_interval': 0, 07:49:24 INFO - 'suite': 'dromaeojs-e10s', 07:49:24 INFO - 'system_bits': '32', 07:49:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:24 INFO - 'title': 't-yosemite-r7-0087', 07:49:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:24 INFO - 'use_talos_json': True, 07:49:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:24 INFO - 'work_dir': 'build'} 07:49:24 INFO - [mozharness: 2016-12-03 15:49:24.232791Z] Running clobber step. 07:49:24 INFO - Running pre-action listener: _resource_record_pre_action 07:49:24 INFO - Running main action method: clobber 07:49:24 INFO - rmtree: /builds/slave/test/build 07:49:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:25 INFO - Running post-action listener: _resource_record_post_action 07:49:25 INFO - [mozharness: 2016-12-03 15:49:25.900489Z] Finished clobber step (success) 07:49:25 INFO - [mozharness: 2016-12-03 15:49:25.900630Z] Running read-buildbot-config step. 07:49:25 INFO - Running pre-action listener: _resource_record_pre_action 07:49:25 INFO - Running main action method: read_buildbot_config 07:49:25 INFO - Using buildbot properties: 07:49:25 INFO - { 07:49:25 INFO - "project": "", 07:49:25 INFO - "product": "firefox", 07:49:25 INFO - "script_repo_revision": "production", 07:49:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:49:25 INFO - "repository": "", 07:49:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:49:25 INFO - "buildid": "20161203062844", 07:49:25 INFO - "pgo_build": "False", 07:49:25 INFO - "basedir": "/builds/slave/test", 07:49:25 INFO - "buildnumber": 6, 07:49:25 INFO - "slavename": "t-yosemite-r7-0087", 07:49:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:49:25 INFO - "platform": "yosemite_r7", 07:49:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:49:25 INFO - "branch": "mozilla-aurora", 07:49:25 INFO - "repo_path": "releases/mozilla-aurora", 07:49:25 INFO - "revision": "c21f4124a5b79b9989579efa33edc20795692af2", 07:49:25 INFO - "stage_platform": "macosx64", 07:49:25 INFO - "builduid": "91922d5fece94bc2aba7341ec5c85ab4", 07:49:25 INFO - "slavebuilddir": "test" 07:49:25 INFO - } 07:49:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:49:25 INFO - Running post-action listener: _resource_record_post_action 07:49:25 INFO - [mozharness: 2016-12-03 15:49:25.904095Z] Finished read-buildbot-config step (success) 07:49:25 INFO - [mozharness: 2016-12-03 15:49:25.904270Z] Running download-and-extract step. 07:49:25 INFO - Running pre-action listener: _resource_record_pre_action 07:49:25 INFO - Running main action method: download_and_extract 07:49:25 INFO - mkdir: /builds/slave/test/build/tests 07:49: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')]} 07:49:25 INFO - trying https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:49:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:49:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:49:27 INFO - Downloaded 1199 bytes. 07:49:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:49:27 INFO - Using the following test package requirements: 07:49:27 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:49:27 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:49:27 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:49:27 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'jsshell-mac.zip'], 07:49:27 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:49:27 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:49:27 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:49:27 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:49:27 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:49:27 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:49:27 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:49:27 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:49:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:49:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:49:27 INFO - Fetch https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:49:27 INFO - Http code: 200 07:49:27 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 07:49:27 INFO - x-amz-cf-id: ZPS922l_mZbZpTR-mzt6xDYGRL-r7lqZOP4KmFcywQ_WrT0gN1Vupw== 07:49:27 INFO - x-amz-version-id: FU3wuAgzDwjQurF.5nyjPYmQtd95DJn8 07:49:28 INFO - Expected file size: 16281977 07:49:28 INFO - Obtained file size: 16281977 07:49:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:49:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:49:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:49:34 INFO - Fetch https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:49:35 INFO - Http code: 200 07:49:35 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 07:49:35 INFO - x-amz-cf-id: _tFdxFynD9JmaQ3Dxr7gr1bx61VsumTtEbx3Pev9GadzriYVPNRokg== 07:49:35 INFO - x-amz-version-id: FU3wuAgzDwjQurF.5nyjPYmQtd95DJn8 07:49:35 INFO - Expected file size: 16281977 07:49:35 INFO - Obtained file size: 16281977 07:49:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:49:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:49:41 INFO - Fetch https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:49:42 INFO - Http code: 200 07:49:42 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 07:49:42 INFO - x-amz-cf-id: 19Xzpk2tZieWxsq4BWNk4KuZ8QSkJlVKUAZ6H_UU9vc_NGGCJxTe0g== 07:49:42 INFO - x-amz-version-id: WNSlipIdPxybm.yJssPAuh0Ms9y1rg.W 07:49:43 INFO - Expected file size: 14409038 07:49:43 INFO - Obtained file size: 14409038 07:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:44 INFO - trying https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:49:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:49:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:49:46 INFO - Downloaded 109760147 bytes. 07:49:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:49:46 INFO - mkdir: /builds/slave/test/properties 07:49:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:46 INFO - Contents: 07:49:46 INFO - build_url:https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:49:47 INFO - Running post-action listener: _resource_record_post_action 07:49:47 INFO - Running post-action listener: set_extra_try_arguments 07:49:47 INFO - [mozharness: 2016-12-03 15:49:47.487324Z] Finished download-and-extract step (success) 07:49:47 INFO - [mozharness: 2016-12-03 15:49:47.487463Z] Running populate-webroot step. 07:49:47 INFO - Running pre-action listener: _resource_record_pre_action 07:49:47 INFO - Running main action method: populate_webroot 07:49:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:47 INFO - '--tppagecycles', 07:49:47 INFO - '7'], 07:49:47 INFO - 'tests': ['tsvgm']}}, 07:49:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['damp', 'tps']}, 07:49:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'tests': ['damp', 'tps']}, 07:49:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['dromaeo_dom']}, 07:49:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['basic_compositor_video']}, 07:49:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:49:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['a11yr', 07:49:47 INFO - 'ts_paint', 07:49:47 INFO - 'tpaint', 07:49:47 INFO - 'sessionrestore', 07:49:47 INFO - 'sessionrestore_no_auto_restore', 07:49:47 INFO - 'tabpaint']}, 07:49:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['tsvgx', 07:49:47 INFO - 'tsvgr_opacity', 07:49:47 INFO - 'tart', 07:49:47 INFO - 'tscrollx', 07:49:47 INFO - 'cart']}, 07:49:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:47 INFO - 'tsvgr_opacity', 07:49:47 INFO - 'tart', 07:49:47 INFO - 'tscrollx', 07:49:47 INFO - 'cart']}, 07:49:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'talos_options': ['--disable-e10s'], 07:49:47 INFO - 'tests': ['tp5o']}, 07:49:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'tests': ['tp5o']}, 07:49:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'talos_options': ['--disable-e10s', 07:49:47 INFO - '--xperf_path', 07:49:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:47 INFO - 'tests': ['tp5n']}, 07:49:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:47 INFO - 'talos_options': ['--xperf_path', 07:49:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:47 INFO - 'tests': ['tp5n']}}, 07:49:47 INFO - 'talos.zip': {'path': '', 07:49:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:47 INFO - Running post-action listener: _resource_record_post_action 07:49:47 INFO - [mozharness: 2016-12-03 15:49:47.506410Z] Finished populate-webroot step (success) 07:49:47 INFO - [mozharness: 2016-12-03 15:49:47.506542Z] Running create-virtualenv step. 07:49:47 INFO - Running pre-action listener: _resource_record_pre_action 07:49:47 INFO - Running main action method: create_virtualenv 07:49:47 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:47 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 07:49:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:47 INFO - Using real prefix '/tools/python27' 07:49:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:48 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:51 INFO - Installing pip.................done. 07:49:51 INFO - Return code: 0 07:49:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:49:51 INFO - Reading from file tmpfile_stdout 07:49:51 INFO - Output received: 07:49:51 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) 07:49:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49:51 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')]} 07:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:51 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')]} 07:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:51 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:49:51 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 07:49:51 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 07:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:51 INFO - 'HOME': '/Users/cltbld', 07:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:51 INFO - 'LOGNAME': 'cltbld', 07:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:51 INFO - 'MOZ_NO_REMOTE': '1', 07:49:51 INFO - 'NO_EM_RESTART': '1', 07:49:51 INFO - 'PAGER': '/bin/cat', 07:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:51 INFO - 'PWD': '/builds/slave/test', 07:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:51 INFO - 'SHELL': '/bin/bash', 07:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:49:51 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:51 INFO - 'USER': 'cltbld', 07:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:51 INFO - 'XPC_FLAGS': '0x0', 07:49:51 INFO - 'XPC_SERVICE_NAME': '0', 07:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:51 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 07:49:51 INFO - Cleaning up... 07:49:51 INFO - Return code: 0 07:49:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:51 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')]} 07:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:51 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')]} 07:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:51 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:49:51 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 07:49:51 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 07:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:51 INFO - 'HOME': '/Users/cltbld', 07:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:51 INFO - 'LOGNAME': 'cltbld', 07:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:51 INFO - 'MOZ_NO_REMOTE': '1', 07:49:51 INFO - 'NO_EM_RESTART': '1', 07:49:51 INFO - 'PAGER': '/bin/cat', 07:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:51 INFO - 'PWD': '/builds/slave/test', 07:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:51 INFO - 'SHELL': '/bin/bash', 07:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:49:51 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:51 INFO - 'USER': 'cltbld', 07:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:51 INFO - 'XPC_FLAGS': '0x0', 07:49:51 INFO - 'XPC_SERVICE_NAME': '0', 07:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:51 INFO - Downloading/unpacking psutil>=3.1.1 07:49:51 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 07:49:51 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 07:49:51 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 07:49:51 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 07:49:51 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 07:49:51 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 07:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:55 INFO - Installing collected packages: psutil 07:49:55 INFO - Running setup.py install for psutil 07:49:56 INFO - building 'psutil._psutil_osx' extension 07:49:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:49:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:49:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:49:56 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 07:49:56 INFO - building 'psutil._psutil_posix' extension 07:49:56 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 07:49:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:49:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:49:56 INFO - ^ 07:49:56 INFO - 1 warning generated. 07:49:56 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 07:49:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:56 INFO - Successfully installed psutil 07:49:56 INFO - Cleaning up... 07:49:56 INFO - Return code: 0 07:49:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49:56 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')]} 07:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:56 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')]} 07:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:56 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:49:56 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 07:49:56 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 07:49:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:49:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:56 INFO - 'HOME': '/Users/cltbld', 07:49:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:56 INFO - 'LOGNAME': 'cltbld', 07:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:56 INFO - 'MOZ_NO_REMOTE': '1', 07:49:56 INFO - 'NO_EM_RESTART': '1', 07:49:56 INFO - 'PAGER': '/bin/cat', 07:49:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:56 INFO - 'PWD': '/builds/slave/test', 07:49:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:56 INFO - 'SHELL': '/bin/bash', 07:49:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:49:56 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:49:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:56 INFO - 'USER': 'cltbld', 07:49:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:56 INFO - 'XPC_FLAGS': '0x0', 07:49:56 INFO - 'XPC_SERVICE_NAME': '0', 07:49:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:50:00 INFO - Installing collected packages: mozsystemmonitor 07:50:00 INFO - Running setup.py install for mozsystemmonitor 07:50:00 INFO - Successfully installed mozsystemmonitor 07:50:00 INFO - Cleaning up... 07:50:00 INFO - Return code: 0 07:50:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:50:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:00 INFO - 'HOME': '/Users/cltbld', 07:50:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:00 INFO - 'LOGNAME': 'cltbld', 07:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:00 INFO - 'MOZ_NO_REMOTE': '1', 07:50:00 INFO - 'NO_EM_RESTART': '1', 07:50:00 INFO - 'PAGER': '/bin/cat', 07:50:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:00 INFO - 'PWD': '/builds/slave/test', 07:50:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:00 INFO - 'SHELL': '/bin/bash', 07:50:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:00 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:00 INFO - 'USER': 'cltbld', 07:50:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:00 INFO - 'XPC_FLAGS': '0x0', 07:50:00 INFO - 'XPC_SERVICE_NAME': '0', 07:50:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:00 INFO - Downloading/unpacking jsonschema==2.5.1 07:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:50:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:04 INFO - Downloading functools32-3.2.3-2.tar.gz 07:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:50:04 INFO - warning: no files found matching '*.txt' 07:50:04 INFO - no previously-included directories found matching 'build' 07:50:04 INFO - no previously-included directories found matching 'dist' 07:50:04 INFO - no previously-included directories found matching '.git*' 07:50:04 INFO - Installing collected packages: jsonschema, functools32 07:50:04 INFO - Running setup.py install for functools32 07:50:04 INFO - warning: no files found matching '*.txt' 07:50:04 INFO - no previously-included directories found matching 'build' 07:50:04 INFO - no previously-included directories found matching 'dist' 07:50:04 INFO - no previously-included directories found matching '.git*' 07:50:04 INFO - Successfully installed jsonschema functools32 07:50:04 INFO - Cleaning up... 07:50:04 INFO - Return code: 0 07:50:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:04 INFO - 'HOME': '/Users/cltbld', 07:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:04 INFO - 'LOGNAME': 'cltbld', 07:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:04 INFO - 'MOZ_NO_REMOTE': '1', 07:50:04 INFO - 'NO_EM_RESTART': '1', 07:50:04 INFO - 'PAGER': '/bin/cat', 07:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:04 INFO - 'PWD': '/builds/slave/test', 07:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:04 INFO - 'SHELL': '/bin/bash', 07:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:04 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:04 INFO - 'USER': 'cltbld', 07:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:04 INFO - 'XPC_FLAGS': '0x0', 07:50:04 INFO - 'XPC_SERVICE_NAME': '0', 07:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:50:05 INFO - Cleaning up... 07:50:05 INFO - Return code: 0 07:50:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:50:05 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')]} 07:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:05 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')]} 07:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:05 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:05 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 07:50:05 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 07:50:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:05 INFO - 'HOME': '/Users/cltbld', 07:50:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:05 INFO - 'LOGNAME': 'cltbld', 07:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:05 INFO - 'MOZ_NO_REMOTE': '1', 07:50:05 INFO - 'NO_EM_RESTART': '1', 07:50:05 INFO - 'PAGER': '/bin/cat', 07:50:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:05 INFO - 'PWD': '/builds/slave/test', 07:50:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:05 INFO - 'SHELL': '/bin/bash', 07:50:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:05 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:05 INFO - 'USER': 'cltbld', 07:50:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:05 INFO - 'XPC_FLAGS': '0x0', 07:50:05 INFO - 'XPC_SERVICE_NAME': '0', 07:50:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:05 INFO - Downloading/unpacking blobuploader==1.2.4 07:50:05 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 07:50:05 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 07:50:05 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 07:50:05 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 07:50:05 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 07:50:05 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 07:50:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:50:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:50:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50: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 07:50:09 INFO - Downloading docopt-0.6.1.tar.gz 07:50:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:50:09 INFO - Installing collected packages: blobuploader, requests, docopt 07:50:09 INFO - Running setup.py install for blobuploader 07:50:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:50:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:50:09 INFO - Running setup.py install for requests 07:50:10 INFO - Running setup.py install for docopt 07:50:10 INFO - Successfully installed blobuploader requests docopt 07:50:10 INFO - Cleaning up... 07:50:10 INFO - Return code: 0 07:50:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:10 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:10 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 07:50:10 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 07:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:10 INFO - 'HOME': '/Users/cltbld', 07:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:10 INFO - 'LOGNAME': 'cltbld', 07:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:10 INFO - 'MOZ_NO_REMOTE': '1', 07:50:10 INFO - 'NO_EM_RESTART': '1', 07:50:10 INFO - 'PAGER': '/bin/cat', 07:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:10 INFO - 'PWD': '/builds/slave/test', 07:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:10 INFO - 'SHELL': '/bin/bash', 07:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:10 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:10 INFO - 'USER': 'cltbld', 07:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:10 INFO - 'XPC_FLAGS': '0x0', 07:50:10 INFO - 'XPC_SERVICE_NAME': '0', 07:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:10 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-3RLJOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:10 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-6dN6lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:10 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-E29aIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-dzunob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-VmNFfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-9lx4ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-eSooEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-6OLKll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-T_xyJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-nQyiNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-F0wz0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-a5KJ8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-pisgLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-_6_puO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-0H__K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-kJbx6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-zQBDpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:50:12 INFO - Running setup.py install for manifestparser 07:50:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:50:12 INFO - Running setup.py install for mozcrash 07:50:12 INFO - Running setup.py install for mozdebug 07:50:12 INFO - Running setup.py install for mozdevice 07:50:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Running setup.py install for mozfile 07:50:13 INFO - Running setup.py install for mozhttpd 07:50:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Running setup.py install for mozinfo 07:50:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Running setup.py install for mozInstall 07:50:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Running setup.py install for mozleak 07:50:13 INFO - Running setup.py install for mozlog 07:50:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:50:13 INFO - Running setup.py install for moznetwork 07:50:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:50:14 INFO - Running setup.py install for mozprocess 07:50:14 INFO - Running setup.py install for mozprofile 07:50:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:50:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:50:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:50:14 INFO - Running setup.py install for mozrunner 07:50:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:50:14 INFO - Running setup.py install for mozscreenshot 07:50:14 INFO - Running setup.py install for moztest 07:50:14 INFO - Running setup.py install for mozversion 07:50:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:50:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:50:15 INFO - Cleaning up... 07:50:15 INFO - Return code: 0 07:50:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50: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')]} 07:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:15 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:15 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 07:50:15 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 07:50:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:15 INFO - 'HOME': '/Users/cltbld', 07:50:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:15 INFO - 'LOGNAME': 'cltbld', 07:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:15 INFO - 'MOZ_NO_REMOTE': '1', 07:50:15 INFO - 'NO_EM_RESTART': '1', 07:50:15 INFO - 'PAGER': '/bin/cat', 07:50:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:15 INFO - 'PWD': '/builds/slave/test', 07:50:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:15 INFO - 'SHELL': '/bin/bash', 07:50:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:15 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:15 INFO - 'USER': 'cltbld', 07:50:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:15 INFO - 'XPC_FLAGS': '0x0', 07:50:15 INFO - 'XPC_SERVICE_NAME': '0', 07:50:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-gPYnDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:15 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)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-lvrMtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-pZFrFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:15 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)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-gOcNsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:15 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)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Z2u5y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:15 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)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-lsQ11S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:15 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)) 07:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:15 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Opn2s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-p_TlDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-QzSziV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-buPDjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-6hWRjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Wzv5D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-SzRkFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-jM_o1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-iyHv5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:16 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)) 07:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:16 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-nOkxw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:17 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)) 07:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:17 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Ubjw3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:17 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)) 07:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:50:17 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)) 07:50:17 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)) 07:50:17 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)) 07:50:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:17 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 07:50:19 INFO - Downloading blessings-1.6.tar.gz 07:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:50:19 INFO - Installing collected packages: blessings 07:50:19 INFO - Running setup.py install for blessings 07:50:19 INFO - Successfully installed blessings 07:50:19 INFO - Cleaning up... 07:50:20 INFO - Return code: 0 07:50:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:50:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:20 INFO - Reading from file tmpfile_stdout 07:50:20 INFO - Current package versions: 07:50:20 INFO - blessings == 1.6 07:50:20 INFO - blobuploader == 1.2.4 07:50:20 INFO - docopt == 0.6.1 07:50:20 INFO - functools32 == 3.2.3-2 07:50:20 INFO - jsonschema == 2.5.1 07:50:20 INFO - manifestparser == 1.1 07:50:20 INFO - mozInstall == 1.12 07:50:20 INFO - mozcrash == 1.0 07:50:20 INFO - mozdebug == 0.1 07:50:20 INFO - mozdevice == 0.48 07:50:20 INFO - mozfile == 1.2 07:50:20 INFO - mozhttpd == 0.7 07:50:20 INFO - mozinfo == 0.9 07:50:20 INFO - mozleak == 0.1 07:50:20 INFO - mozlog == 3.4 07:50:20 INFO - moznetwork == 0.27 07:50:20 INFO - mozprocess == 0.23 07:50:20 INFO - mozprofile == 0.28 07:50:20 INFO - mozrunner == 6.13 07:50:20 INFO - mozscreenshot == 0.1 07:50:20 INFO - mozsystemmonitor == 0.3 07:50:20 INFO - moztest == 0.7 07:50:20 INFO - mozversion == 1.4 07:50:20 INFO - psutil == 3.1.1 07:50:20 INFO - requests == 1.2.3 07:50:20 INFO - wsgiref == 0.1.2 07:50:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:20 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:20 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 07:50:20 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 07:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:20 INFO - 'HOME': '/Users/cltbld', 07:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:20 INFO - 'LOGNAME': 'cltbld', 07:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:20 INFO - 'MOZ_NO_REMOTE': '1', 07:50:20 INFO - 'NO_EM_RESTART': '1', 07:50:20 INFO - 'PAGER': '/bin/cat', 07:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:20 INFO - 'PWD': '/builds/slave/test', 07:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:20 INFO - 'SHELL': '/bin/bash', 07:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:20 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:20 INFO - 'USER': 'cltbld', 07:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:20 INFO - 'XPC_FLAGS': '0x0', 07:50:20 INFO - 'XPC_SERVICE_NAME': '0', 07:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 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)) 07:50:20 INFO - Cleaning up... 07:50:20 INFO - Return code: 0 07:50:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:20 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 0x10b76e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f935bc96700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, '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.9PMmS37nqy/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.ye1yxAodxt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 07:50:20 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 07:50:20 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 07:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:20 INFO - 'HOME': '/Users/cltbld', 07:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:20 INFO - 'LOGNAME': 'cltbld', 07:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:20 INFO - 'MOZ_NO_REMOTE': '1', 07:50:20 INFO - 'NO_EM_RESTART': '1', 07:50:20 INFO - 'PAGER': '/bin/cat', 07:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:20 INFO - 'PWD': '/builds/slave/test', 07:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:20 INFO - 'SHELL': '/bin/bash', 07:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:20 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:20 INFO - 'USER': 'cltbld', 07:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:20 INFO - 'XPC_FLAGS': '0x0', 07:50:20 INFO - 'XPC_SERVICE_NAME': '0', 07:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:50:20 INFO - Cleaning up... 07:50:20 INFO - Return code: 0 07:50:20 INFO - Running post-action listener: _resource_record_post_action 07:50:20 INFO - Running post-action listener: _start_resource_monitoring 07:50:20 INFO - Starting resource monitoring. 07:50:20 INFO - [mozharness: 2016-12-03 15:50:20.876073Z] Finished create-virtualenv step (success) 07:50:20 INFO - [mozharness: 2016-12-03 15:50:20.877075Z] Running install step. 07:50:20 INFO - Running pre-action listener: _resource_record_pre_action 07:50:20 INFO - Running main action method: install 07:50:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:21 INFO - Reading from file tmpfile_stdout 07:50:21 INFO - Detecting whether we're running mozinstall >=1.0... 07:50:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:50:21 INFO - Reading from file tmpfile_stdout 07:50:21 INFO - Output received: 07:50:21 INFO - Usage: mozinstall [options] installer 07:50:21 INFO - Options: 07:50:21 INFO - -h, --help show this help message and exit 07:50:21 INFO - -d DEST, --destination=DEST 07:50:21 INFO - Directory to install application into. [default: 07:50:21 INFO - "/builds/slave/test"] 07:50:21 INFO - --app=APP Application being installed. [default: firefox] 07:50:21 INFO - mkdir: /builds/slave/test/build/application 07:50:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:50:52 INFO - Reading from file tmpfile_stdout 07:50:52 INFO - Output received: 07:50:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:50:52 INFO - Running post-action listener: _resource_record_post_action 07:50:52 INFO - [mozharness: 2016-12-03 15:50:52.263582Z] Finished install step (success) 07:50:52 INFO - [mozharness: 2016-12-03 15:50:52.263715Z] Running run-tests step. 07:50:52 INFO - Running pre-action listener: _resource_record_pre_action 07:50:52 INFO - Running main action method: run_tests 07:50:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:50:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:50:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:50:52 INFO - 2016-12-03 07:50:52.277 screenresolution[1950:22268] starting screenresolution argv=screenresolution get 07:50:52 INFO - 2016-12-03 07:50:52.280 screenresolution[1950:22268] Display 0: 1600x1200x32@60 07:50:52 INFO - 2016-12-03 07:50:52.286 screenresolution[1951:22270] starting screenresolution argv=screenresolution list 07:50:52 INFO - Available Modes on Display 0 07:50:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:50:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:50:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:50:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:50:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:50:52 INFO - Intel Iris: 07:50:52 INFO - Chipset Model: Intel Iris 07:50:52 INFO - Type: GPU 07:50:52 INFO - Bus: Built-In 07:50:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:50:52 INFO - Vendor: Intel (0x8086) 07:50:52 INFO - Device ID: 0x0a2e 07:50:52 INFO - Revision ID: 0x0009 07:50:52 INFO - Displays: 07:50:52 INFO - Display: 07:50:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:50:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:50:52 INFO - Display Serial Number: 1600x1200 60 07:50:52 INFO - Main Display: Yes 07:50:52 INFO - Mirror: Off 07:50:52 INFO - Online: Yes 07:50:52 INFO - Rotation: Supported 07:50:52 INFO - Return code: 0 07:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:50:52 INFO - Python 2.7.3 07:50:52 INFO - Return code: 0 07:50:52 INFO - grabbing minidump binary from tooltool 07:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:52 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 0x10b582d30>, '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 0x10b3e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b57ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:50:52 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 07:50:52 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 07:50:52 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 07:50:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:50:52 INFO - Return code: 0 07:50:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:50:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:50:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:50:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:50:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 07:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0087 --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 07:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PMmS37nqy/Render', 07:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:52 INFO - 'HOME': '/Users/cltbld', 07:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:52 INFO - 'LOGNAME': 'cltbld', 07:50:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:50:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:52 INFO - 'MOZ_NO_REMOTE': '1', 07:50:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:50:52 INFO - 'NO_EM_RESTART': '1', 07:50:52 INFO - 'PAGER': '/bin/cat', 07:50:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:52 INFO - 'PWD': '/builds/slave/test', 07:50:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:52 INFO - 'SHELL': '/bin/bash', 07:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ye1yxAodxt/Listeners', 07:50:52 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 07:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:52 INFO - 'USER': 'cltbld', 07:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:52 INFO - 'XPC_FLAGS': '0x0', 07:50:52 INFO - 'XPC_SERVICE_NAME': '0', 07:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 07:50:52 INFO - mozversion application_buildid: 20161203062844 07:50:52 INFO - mozversion application_changeset: c21f4124a5b79b9989579efa33edc20795692af2 07:50:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:50:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:50:52 INFO - mozversion application_name: Firefox 07:50:52 INFO - mozversion application_remotingname: firefox-dev 07:50:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:50:52 INFO - mozversion application_vendor: Mozilla 07:50:52 INFO - mozversion application_version: 52.0a2 07:50:52 INFO - mozversion platform_buildid: 20161203062844 07:50:52 INFO - mozversion platform_changeset: c21f4124a5b79b9989579efa33edc20795692af2 07:50:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:50:52 INFO - mozversion platform_version: 52.0a2 07:50:52 INFO - using testdate: 1480780252 07:50:52 INFO - actual date: 1480780252 07:50:52 INFO - starting webserver on 'localhost:52411' 07:50:52 INFO - SUITE-START | Running 2 tests 07:50:52 INFO - TEST-START | dromaeo_css 07:50:52 INFO - operating with platform_type : mac_ 07:50:52 INFO - Initialising browser for dromaeo_css test... 07:50:52 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmptqLeuQ/profile http://localhost:52411/getInfo.html) 07:50:55 INFO - PROCESS | 1961 | 2016-12-03 07:50:55.690 plugin-container[1963:22442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 07:50:55 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:55 INFO - PROCESS | 1961 | 2016-12-03 07:50:55.693 plugin-container[1963:22442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 07:50:55 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:55 INFO - PROCESS | 1961 | 2016-12-03 07:50:55.693 plugin-container[1963:22442] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:50:55 INFO - PROCESS | 1961 | __metrics Screen width/height:1600/1200 07:50:55 INFO - PROCESS | 1961 | colorDepth:24 07:50:55 INFO - PROCESS | 1961 | Browser inner width/height: 1024/689 07:50:55 INFO - PROCESS | 1961 | __metrics 07:50:55 ERROR - PROCESS | 1961 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 07:50:56 INFO - TEST-INFO | 1961: exit 0 07:50:56 INFO - Browser initialized. 07:50:56 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:56 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmptqLeuQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:59 INFO - PROCESS | 1964 | 2016-12-03 07:50:59.395 plugin-container[1965:22567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 07:50:59 INFO - PROCESS | 1964 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:59 INFO - PROCESS | 1964 | 2016-12-03 07:50:59.396 plugin-container[1965:22567] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 07:50:59 INFO - PROCESS | 1964 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:59 INFO - PROCESS | 1964 | 2016-12-03 07:50:59.396 plugin-container[1965:22567] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:09 INFO - PROCESS | 1964 | RSS: Main: 229593088 07:51:09 INFO - PROCESS | 1964 | 07:51:58 INFO - PROCESS | 1964 | 1480780318703 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] 07:54:13 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52411/tests/dromaeo/cssquery-ext.html) 07:54:14 INFO - PROCESS | 1964 | RSS: Main: 234123264 07:54:14 INFO - PROCESS | 1964 | 07:57:16 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-ext.html (next: http://localhost:52411/tests/dromaeo/cssquery-jquery.html) 07:57:17 INFO - PROCESS | 1964 | RSS: Main: 243552256 07:57:17 INFO - PROCESS | 1964 | 08:00:19 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52411/tests/dromaeo/cssquery-mootools.html) 08:00:20 INFO - PROCESS | 1964 | RSS: Main: 246280192 08:00:20 INFO - PROCESS | 1964 | 08:03:22 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52411/tests/dromaeo/cssquery-prototype.html) 08:03:22 INFO - PROCESS | 1964 | RSS: Main: 245542912 08:03:22 INFO - PROCESS | 1964 | 08:06:25 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52411/tests/dromaeo/cssquery-yui.html) 08:06:26 INFO - PROCESS | 1964 | RSS: Main: 249163776 08:06:26 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:52411/tests/dromaeo/cssquery-yui.html (next: http://localhost:52411/tests/dromaeo/cssquery-dojo.html) 08:09:28 INFO - PROCESS | 1964 | RSS: Main: 251387904 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | __start_tp_report 08:09:28 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 08:09:28 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 08:09:28 INFO - PROCESS | 1964 | |i|pagename|runs| 08:09:28 INFO - PROCESS | 1964 | |0;dojo.html;3278.5901762279714;3299.8845040423585;3339.8811398181624;3359.328134373126;3369.494575813628;5657.46032695971;5717.485426311626;5719.084946408576;5781.595288329819;5793.884122317561;5679.971600141994;5701.572382071351;5735.1970724098655;5744.310682718069;5745.856353591163;4294.506131794852;5171.689698618095;5212.973935130329;5216.869578260555;5250.65870718402;5226.973865130669;5256.342957130359;5263.789448422078;5269.235960785683;5357.196420536931;5226.425093239741;5236.162214045753;5236.450172731866;5239.869003274911;5250.186221135711;5079.212722028073;5090.541851233388;5157.071727089123;5222;5222.477752224785;5047.697138171721;5083.822066227664;5122.385313762373;5122.692638441706;5178.404483484373;5690.800821971253;5702.942970570282;5705.172749951275;5744.626599271034;5795.2176456178295;4783.3064205690325;6168.414000669929;6209.441150296449;6258.34287399821;6274.843128921768;6037.275526936795;6073.908891366633;6079.270487541479;6101.664408457533;6104.175936248638;5563;5605.579581531401;5608.10270356741;5617.129344951539;5625;6129.358790882775;6177.876442471215;6184.288806787187;6209.658468784261;6258.280297765815;6081.27024757032;6116.113163591254;6116.153172518083;6203.7828675996125;6214.689265536705;5721.256236689203;6071.876702809995;6236.126942228092;6247.656378899204;6286.277078135981;6081.939180608226;6172.259328880473;6220.844478888064;6237.875242495215;6275.780347687809;5232.581393488588;6115.635867915819;6144.187464697836;6213.689315534205;6236.968815155895;4186.434831297796;4526.773661316987;4819.180739274261;4936.90126197474;4965.4289756678445;4381.211381951216;4383.824647014084;4412.4705035395955;4414.073044660593;4437.534058923831;5056.786371270869;5068.569171620454;5655.547556195576;5694.800681976194;5838.12428135783;6176.7838125665385;6199.101130336076;6208.658523781235;6234.189555357774;6238.65687387189;5795.913061304083;5816.854578635568;5894.440028197314;5931.110333450017;5953.077273022688;4496.482904465963;5364.490373414597;5388.3533975922355;5405.297311349578;5423.918641220384;6199.56603037783;6298.842528936813;6316.052592111128;6320.178376810985;6329.367063293634;6114.358196334042;6174.6912654368;6219.191505104308;6228.5951413158;6228.719707613259;6151.815445536641;6258.468030217483;6295.716692748929;6301.684915754286;6302.842428939313;6176.104464852481;6177.4749146323065;6202.7208775606105;6218.9378106218355;6229.875402491834;6119.045604113885;6142.007694998287;6237.251529816451;6256.155419018374;6260.092286618506;5033.295338652519;5836.941630583639;6123.724432400463;6128.442013630826;6137.8979746733885;6065.181200537871;6134.78528251509;6148.016317389196;6157.138000679819;6166.599171053867;5817.040188368872;5955.702214889325;6029.8191054268445;6040.882436749215;6057.030875059969;5846.94153058464;5901.7639294427745;5932.911006334822;6023.885581167323;6065.6663883486835;5757;5778.739956702043;5783.392743761844;5793.131030345482;5796.594238403271 08:09:28 INFO - PROCESS | 1964 | |1;ext.html;468.37705851217885;517.9356422551655;520.0145723853298;521.5462547583603;542.4710906865809;37570.436443453335;39158.21683566327;39197.80401097998;39273.80363098188;39341.01647458815;41166.794166029125;41763.37354939678;41820.58179418205;41848.58151418485;41936;8481.745547633582;9298.256139508823;9442.291828112884;9451.621935122621;9477.052294770538;8024;9436.716898493056;9437.339386242966;9440.291978101635;9459.810803783954;9425.76435589109;9476.00501947296;9488.051194880525;9496.762580935498;9501.239900807954;9357.251419886392;9366.250699943988;9461.763455913588;9464.19554337882;9501.382410143387;9261.16649501548;9338.719838404792;9342.90657093434;9345.485998270093;9350.29872759539;32749.836250818833;39265;39469.40795888035;39528.20943581141;39609.40585891214;3162.588863447737;3242.902712918616;3333.7666363354333;3458.1181798641437;3632.7638703484454;28049.85975070132;28349.433011339865;30017.69982300171;30268.54597181044;31196.688033119834;3182.8249446280447;3195.328980914011;3209.759268054882;3219.0664707234873;3228.3059142284287;485420.5728971368;485826;489236;491268;491488;51492.227616585784;53801.46198537965;54925.725371372886;55098.17352739712;55299.44700552943;59561.40438595558;59726.40273597295;59772.10341844876;59911.400885991454;60275.79448411007;13978.720425591635;13991.020628555903;13996.790048149285;14005.719885602231;14014.369353379123;12372.690682732824;12416.875831241752;12421.813672794915;12434.253944763343;12523.311217883103;15308.770368444482;15474;15588.84411155874;15644.374225030871;15652.765208521882;3154.6845315468727;6060.848478788065;6158.907616385758;6220.377962203744;6239.282482514478;841.2302742990217;999.0658734083655;1001.2840818814583;1024.4621573673733;1076.8707755069443;29701.85149074284;30041.098767037023;30253;30350.696493034786;30611.693883060885;956.9234461243223;1002.6537901310967;1045.5608644369383;1050.564015933379;1102.2835157147917;14972.550823475312;15268.770968435483;15596.376144954302;15612.985155965052;15718.842811571967;676150;678667;680347;682065;684024;56087;56203.156952644924;56619;56659.43340566624;56836.863262734514;1319.769040417923;1331.9866801331862;1355.2613825465146;1356.6540532164236;1361.6527785414853;28733.856330718212;29276.707232927398;29733.553996690498;29765.404691906475;30320.696793031788;48584;48995;49116.263256051905;49184.50815491942;49340.75329623329;606935;612002;614624;615259;618755;26221.34446638849;26261.16171867951;26528;26634;26679.7332026685;40414.1917161661;41121.38317925174;45203.095938081715;45285.77357113193;45774.02468839648;33311.33377332391;37276.50893964211;37614.435783463814;37669.81165094157;38389;37752.622473776006;37889;37917.05207369838;39163.41254881236;39245.803770980965;33987.49018764671;34316.31367372588;37127.44308835315;37153.442698360086;37836.62163378331;27695.44609107847;30678;32559.837200813843;34119.147021324665;34330.48504272488;24924;24965.375865603506;25176.87411563004;25486.490270194863;25666.61500077463 08:09:28 INFO - PROCESS | 1964 | |2;jquery.html;325.38177462820653;325.58487927891935;364.74103386595516;368.182634551296;389.79535743734533;18630.34793782218;19217.84692918426;20226.08982595783;20250.29123980661;20505;19011.429657110275;19264.940428276474;19976.900115499404;20102.89434081125;20425.795742042574;5567.777288908449;5830.737616807254;5845.620034697752;5858.121281807742;5874.588778785486;5532.225488431623;5768.682722450264;5777.133429985493;5801.419858014186;5862.736176872051;5470.6202801781355;5721.113227449752;5766.481016708516;5792.652440853562;5840.941590584103;4427.77861106945;5707.4577915098;5770;5777.653340799545;5808.941910580882;5009.524095210949;5039.21892106724;5080.364954380702;5080.568151707109;5101.20931255656;23066.76933230663;23912.880435597886;24364;24416.38959026021;24484.755152448426;2183.3340831046526;2191.0687957617947;2327.2669109230706;2560.2063360358347;2726.2230264374557;7715.807104822368;8944.955275223581;9751.853722194102;9850.50747462624;9964.451955142464;1776.7246076858107;1828.6799810033192;1844.5112045308003;1851.1577232359243;1853.8794978326366;101460.99999999926;121970;122832;124668;126955;9149.176574108362;11531.365774882293;11576.768464630828;11649.41752912351;11746.17776755619;12605.684857878443;14346.282685865664;14722.116672999655;14739.705205895822;14765.261736913326;7880.448368614142;8287.337013038943;8326.584480638307;8404.11756765543;8404.915950840536;8006.279434850891;8010.519368837889;8031.598420078973;8150.75547733569;8155.184481551797;3738.3084129436143;4487.304467807495;4530.161920044802;4546.681732278775;4561.064981678748;1852.703567429205;1913.100842603974;1913.339897735279;1913.4546654203764;1918.5299601597699;716.5808002318598;793.8610743119931;914.9359544831931;995.6216637677636;1007.4156988946394;20332.69500957487;20926.790732092788;21067.68398474024;21171.47071323229;21386.78613213848;914.4558987402485;930.5393830054167;934.8831396075491;971.7667759737753;1004.1364426593124;10893.564257429614;11221.775564488664;11239.66281011571;11280.492377842853;11281.548738050386;555513.6672949828;572346;572397;583168;584006;111600;111717.88282117074;116190.99999999831;122951.38524307321;132856;944.1596978688972;955.6225291010151;1001.6344034427382;1009.9899001009895;1010.1968934696934;18776.436706898814;19222.327218547285;19869.900650496656;19924.203031878562;19940;53418.73290633522;55798.88402232014;55956.72021640029;56965.71517142388;57029.71485142548;69912;73774.89337659832;75995.62002189954;78392.08039597883;81706;5790.507806836466;6528;8031.718889838828;8069.31410830086;8229.917700822914;10236.881172142423;10494.527746251284;12002.39988000614;12227.572034978733;12512.310838036748;10610.363378197024;10631.308964917256;10671.199660025684;10732.034116929513;10772.946135269274;9746.805063898824;9938.552765125442;10540.789184216426;10663.733406664895;10671.413072281099;10049.447280399356;10802.135829133807;10878;10898.07366373867;10928.868870570162;6816.216135144324;7304.306090921354;7342.743003994835;7763.378929685725;8014.278714915686;9949.403035817873;10282.331648443127;10732.302400343953;10832.512536936014;10903.400312982862 08:09:28 INFO - PROCESS | 1964 | |3;mootools.html;1360.9455621775126;1453.4767483705864;1611.9435819746304;1735.557432854622;1794.1657129434818;6059.484943779773;7600.467967242295;7849.960750196248;8305.335573154141;8331.083580806106;6460.838479038015;6914.44684425246;7956.403269754763;8107;8111;2261.42333704905;2279.794818466346;2280.6693029510793;2291.0835665733757;2303.9308820735405;2275.987185702363;2304.1820153845397;2318.8956496957676;2323.7211534615785;2329.4176455886027;2257.1084421653436;2277.123307526606;2281.2471884278148;2294.552562250362;2294.9655755163685;2245.236619549353;2246.2025980776752;2257.142285931352;2271.5797577448143;2279.680844681746;2284.1662793080573;2297.5404919016264;2304.0783686525356;2307.9884600577057;2309.3418375762826;8112.6349314281;8157.673693052271;8207.548584827831;8231.95884020582;8251.669933202666;4956.776945037482;4973.477784832613;5089.567386772129;5130.050940576006;5131.230315452674;2339.181286549711;3225.919352016195;3238.951415728766;3458.775179613317;3464.3764122457947;1008.112860682602;1045.7281106912183;1056.910162636177;1058.3808472043863;1084.4523515624637;9287.442753434816;9762.90237097634;10407.271490995634;10409.271351005433;10514.106300964428;4413.823447062082;4486.573775491323;4606.124836281163;4619.376384188159;4621.768911554409;9093.909060909438;9230.815383692288;9241.306901982243;9241.861372079424;9271.814563708687;5045.31888195089;5073.239014147907;5113.233015047773;5119;5133.050385678662;3815;4033.8184781684895;4177.874663760092;4675.485696573374;4677.4854765975715;2794.8043636945627;3244.8702051917658;3585.30086633104;3607.296577167427;3868.2263547290654;1046.1839764983324;1238.993805030969;1241.1064033895582;1249.1693024139026;1250.8311377963857;795.2564352330589;872.1060912564545;902.869083982832;903.3450748207629;904.7466709321401;5695.40198279183;5710.486056254957;5770.6249093808765;5786.218860453869;5809.7966571170655;806.8386322735477;893.3433926064338;894.7449976756593;926.6478738079487;988.2637435110745;3795.2788970095594;3872;3881.7282790204413;3897.415387691812;3923.803809809498;9603.471809050568;9905.900940990497;9915.157211636948;9955.30312878106;10009.499525023719;9743.902560974442;9841.704748857546;9889;9914.752131196778;10027.39835609851;7213.711451541984;7509.887351689838;7610.695572177051;7745.7676269712;7770;5642.125470552071;5685.573581981285;5790.131480277992;5857.092150716646;5860.033094539353;9062.456252624863;9069.319801015083;9113;9132;9158.626206069142;9272.258219342302;9275;9372.859407109036;9395.85906211421;9416.858747118662;6582.3746744059235;6804.455643548603;7114.644267786486;7156.606386648576;7163.462740294394;7579.469437139347;7804.84390312202;7809.726659566888;7925.762227133196;7944.801379965547;7010.824729381602;7154.427645788429;7185.173705023989;7191.280871912976;7191.532550384418;6018.3680713524445;6941.93058069433;7024.1571011478945;7052.083229180379;7071.116110486189;6529.836754081186;6607.834804129935;6699.732010719516;6709.429698475489;6715.093462382516;4406.889827754332;4797.736124513185;4840.29815676732;4854.1747902857305;4862.00328932575;4391.385206071216;4448.733076015449;4559.954400456086;4578.839740609063;4653.348531205702 08:09:28 INFO - PROCESS | 1964 | |4;prototype.html;264.9668791401075;285.26970954356864;299.3190491631538;301.5582172117848;305.0862666314389;5258;5518.806841760539;5519.365272993607;5543.503254121395;5585;4684.601808846243;5370.812021579236;5461.924468701897;5462.399136095027;5472.72636368182;3877.1696661437027;3911.5892831252663;4025.6779457643465;4058.533268674111;4064.654504367132;4047.8380864765372;4049.3521036634143;4055.695822813286;4087.652549533293;4105.085389373548;3024.9848750756214;3946.723729338933;4062.1672557125717;4071.30787766079;4078.796060196993;2990.745786608141;3791.7345785795005;3886.417037444392;4015.217032678629;4060.5939406059156;3323.8005719656658;3503.351879902201;3525.919707372637;3534.9748572913822;3535.4014799122624;5201.739913004372;5202.661826981272;5213.061648903196;5216.096124914417;5256.921146182772;4109.856155034589;4424.778761061934;4662.440507139165;4677.251639737659;4689.249720044776;5622.465865742748;5629.71851407432;5657.830265092054;5674.3190817101795;5680.5455563554815;3668.743187976843;3682.3371793077235;3912;3913.5695073541888;3922.3135951208424;4333.371661109121;4846.563809257149;5548.917960997647;5958.40415958405;5963.761449542013;3915.5497117831815;3931;3963.980180099081;3969.186316805048;3983.940240896389;4013.4381186633896;4018.33697439925;4024.899377515527;4036.233115708006;4049.392591111298;4617.376654151713;4655.255159174516;4655.767211639472;4670.159371313128;4678.2982552616695;5092.465291144451;5361;5408.432114627986;5649.209110724501;5686.663634045996;1129.6949823547595;1234.3334599316447;1244.4693027576131;1330.6274243211917;1330.846952600444;848.7368915636173;856.8928883889514;897.0491279244022;897.5018864530249;901.8511945528915;5281.39263984639;5365.309927372865;5422.213779002022;5558.859838962028;5587.38538760736;5284.947150528523;5628.831135065954;5746.8793585251315;5821.981153298156;6040.6677632730625;5064.4935506449065;5089.211172268304;5104.004719079744;5480.08197131001;5658.547316214693;2593.649857269282;2764.875580598878;2971.747401470856;2986.507226307678;3016.3213277012496;5200.479952004845;5585.804496842591;5619.662820230799;5681.34664513578;5970.850728731816;5452.555072905604;5568.9443105569235;5834.329052159056;5883.0879964011865;5944.405559444108;4828.251620998751;5489.533389661844;5542.889142217214;5951.345352011362;5961.493273071837;4376.693631445768;5375.731213439391;5414.756335964812;5603.1984007996;5744.310682718101;4640.280756482751;4658.277966915133;5472.398036215934;5625.5965141089055;5662.943370566406;5222.451642577475;5886.852063847508;5900.350961394157;5911.438413350725;5929.250870992981;3335.4496508075895;3363.0751543324805;3649.543807024122;3822.464854920258;3837.731358804857;5918.585699001029;6000.189974353407;6010.188624535632;6023.48800351975;6024.427679370453;5873.948404372151;5874.853128671817;5888.882222355614;5894.524299794011;5901.645901245939;5155.7164355959285;5616.9608622404985;5827.76688932438;5844.883102338014;5853.356130825691;4934.55588996992;5340.786368545215;5523.447655234444;5704.714764261853;5717.371089180104;4703.976480117578;4801.583532857746;5290.5191140853685;5618.3258009037545;5786.235198264401;4646.883827904329;5717.028105222039;5746.683932383592;5748.223035848945;5781.537477001746 08:09:28 INFO - PROCESS | 1964 | |5;yui.html;688.208560155821;688.4526801193051;735.0664655887024;754.3211110000998;757.5530437042144;13894.166350018993;14716.6320841979;14736.778948315783;14748.483803066894;14751.778723319145;13110.803337949937;13159.078864479467;13186.670333241675;13195.736085278313;13196.868031319684;642.8392901774556;661.4344735251362;776.6660336055494;788.4441468764521;798.9600519974007;791.695197349019;800.5356893002045;802.5626033811586;802.6307898366729;805.2832978649008;810.0643756461296;824.2787560884226;834.3158609939852;835.5279267214034;835.8161204534993;804.927556519916;820.2248874813304;822.5270469480013;822.8272062866776;823.9176082391713;616.5406771954878;620.1349117980437;621.832105331558;622.3496446213672;623.0436280309748;13273.535426260034;13465.798013029813;13502.324883755871;13639.795403068962;13734.65663358414;12946.542310055574;13427.798583021262;13448.462061517528;13562.660933476742;13758.65603359914;10937.83593246102;10942.4528773561;11165.38590377529;11379.260348077349;11610.303381797035;3590.9281814363567;3661.2860492203763;3685.318216129998;3697.2051009032916;3800.7339486235974;85746.5712671439;86029;86726.69909951356;87147.69278460828;88698.55650721706;8548.615312310962;10152.949235253776;10374;10446.947765261126;10524.789504209873;20007.799922000595;20130.39608811738;20160.697589535874;20191.889446080608;20201.696974545393;7546.056742907137;7567.432442566829;7680.654370553227;7704.807379815549;7842.019747531559;3822.445745366962;4145.316022856256;4216.135692183157;4227;4259.87220383389;3305.2067503799394;3398.796072235674;3404.5233667286598;3410.0281419795733;3418.3334249821046;557.804768331089;571.9428057194331;596.883607696495;615.5045188623201;640.2285246278224;507.7106049551794;633.996830015847;636.3191385217865;640.3404493371835;704.35551470404;7808.960955195188;8658.740237792876;8701.521416322037;8790.296776257885;9922.702318930444;533.3333333333334;541.8103663717746;573.5898832334817;650.3983814971133;670.6043434373743;4001.9594905324243;4359.256297481033;4370.147821174841;4398.912021759547;4404.889877753082;73236;104388;105564.47217763861;105592.47203763932;108425;59073.70463147657;61317.386826131165;68868.58959936378;92855;96785.51607241918;74795.62602186954;80648;81417.59291203624;82681.58659206786;83924;8150.695888657786;8667.089955554577;8678.175573320525;8709.172628600272;8755.387122901337;51980;52604.210936836746;53236.338183089916;54142;55223.44776552344;42441.575584243765;54781.45218547763;66511.33488665245;67194.66402667956;81859;12961.611151665466;13099.345032748133;13373.706537684076;13390.799138012742;13720.313984300545;14111.885740502414;14152.575422737335;14255.572332830032;14459.566213013626;14541.927290363481;13017.414216360476;13284.933575332061;13320;13369.39837707325;14154.433822646979;13257.138286011379;13300.866991329964;13383.598492045254;13472.393742281816;13496.865031349953;10560.419176945341;12558.183718058297;12630.368481576068;12766.936165319485;12914.289714065817;10627.309224900357;11002;11102.389368584805;11412.600558980395;11511.19421640477;9249.583768730288;11172.273802202832;11500.712482188012;11536.769264614828;11597.594084207012 08:09:28 INFO - PROCESS | 1964 | __end_tp_report 08:09:28 INFO - PROCESS | 1964 | __start_cc_report 08:09:28 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,375 08:09:28 INFO - PROCESS | 1964 | __end_cc_report 08:09:28 INFO - PROCESS | 1964 | __startTimestamp1480781368947__endTimestamp 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | ------- Summary: start ------- 08:09:28 INFO - PROCESS | 1964 | Number of tests: 6 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#0] dojo.html Cycles:165 Average:5675.58 Median:5838.12 stddev:665.53 (11.4%) stddev-sans-first:640.45 08:09:28 INFO - PROCESS | 1964 | Values: 3278.6 3299.9 3339.9 3359.3 3369.5 5657.5 5717.5 5719.1 5781.6 5793.9 5680.0 5701.6 5735.2 5744.3 5745.9 4294.5 5171.7 5213.0 5216.9 5250.7 5227.0 5256.3 5263.8 5269.2 5357.2 5226.4 5236.2 5236.5 5239.9 5250.2 5079.2 5090.5 5157.1 5222.0 5222.5 5047.7 5083.8 5122.4 5122.7 5178.4 5690.8 5702.9 5705.2 5744.6 5795.2 4783.3 6168.4 6209.4 6258.3 6274.8 6037.3 6073.9 6079.3 6101.7 6104.2 5563.0 5605.6 5608.1 5617.1 5625.0 6129.4 6177.9 6184.3 6209.7 6258.3 6081.3 6116.1 6116.2 6203.8 6214.7 5721.3 6071.9 6236.1 6247.7 6286.3 6081.9 6172.3 6220.8 6237.9 6275.8 5232.6 6115.6 6144.2 6213.7 6237.0 4186.4 4526.8 4819.2 4936.9 4965.4 4381.2 4383.8 4412.5 4414.1 4437.5 5056.8 5068.6 5655.5 5694.8 5838.1 6176.8 6199.1 6208.7 6234.2 6238.7 5795.9 5816.9 5894.4 5931.1 5953.1 4496.5 5364.5 5388.4 5405.3 5423.9 6199.6 6298.8 6316.1 6320.2 6329.4 6114.4 6174.7 6219.2 6228.6 6228.7 6151.8 6258.5 6295.7 6301.7 6302.8 6176.1 6177.5 6202.7 6218.9 6229.9 6119.0 6142.0 6237.3 6256.2 6260.1 5033.3 5836.9 6123.7 6128.4 6137.9 6065.2 6134.8 6148.0 6157.1 6166.6 5817.0 5955.7 6029.8 6040.9 6057.0 5846.9 5901.8 5932.9 6023.9 6065.7 5757.0 5778.7 5783.4 5793.1 5796.6 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#1] ext.html Cycles:180 Average:71347.32 Median:39221.80 stddev:160646.78 (409.6%) stddev-sans-first:161009.29 08:09:28 INFO - PROCESS | 1964 | Values: 468.4 517.9 520.0 521.5 542.5 37570.4 39158.2 39197.8 39273.8 39341.0 41166.8 41763.4 41820.6 41848.6 41936.0 8481.7 9298.3 9442.3 9451.6 9477.1 8024.0 9436.7 9437.3 9440.3 9459.8 9425.8 9476.0 9488.1 9496.8 9501.2 9357.3 9366.3 9461.8 9464.2 9501.4 9261.2 9338.7 9342.9 9345.5 9350.3 32749.8 39265.0 39469.4 39528.2 39609.4 3162.6 3242.9 3333.8 3458.1 3632.8 28049.9 28349.4 30017.7 30268.5 31196.7 3182.8 3195.3 3209.8 3219.1 3228.3 485420.6 485826.0 489236.0 491268.0 491488.0 51492.2 53801.5 54925.7 55098.2 55299.4 59561.4 59726.4 59772.1 59911.4 60275.8 13978.7 13991.0 13996.8 14005.7 14014.4 12372.7 12416.9 12421.8 12434.3 12523.3 15308.8 15474.0 15588.8 15644.4 15652.8 3154.7 6060.8 6158.9 6220.4 6239.3 841.2 999.1 1001.3 1024.5 1076.9 29701.9 30041.1 30253.0 30350.7 30611.7 956.9 1002.7 1045.6 1050.6 1102.3 14972.6 15268.8 15596.4 15613.0 15718.8 676150.0 678667.0 680347.0 682065.0 684024.0 56087.0 56203.2 56619.0 56659.4 56836.9 1319.8 1332.0 1355.3 1356.7 1361.7 28733.9 29276.7 29733.6 29765.4 30320.7 48584.0 48995.0 49116.3 49184.5 49340.8 606935.0 612002.0 614624.0 615259.0 618755.0 26221.3 26261.2 26528.0 26634.0 26679.7 40414.2 41121.4 45203.1 45285.8 45774.0 33311.3 37276.5 37614.4 37669.8 38389.0 37752.6 37889.0 37917.1 39163.4 39245.8 33987.5 34316.3 37127.4 37153.4 37836.6 27695.4 30678.0 32559.8 34119.1 34330.5 24924.0 24965.4 25176.9 25486.5 25666.6 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#2] jquery.html Cycles:180 Average:33893.35 Median:1525.80 stddev:95849.93 (6281.9%) stddev-sans-first:96085.68 08:09:28 INFO - PROCESS | 1964 | Values: 325.4 325.6 364.7 368.2 389.8 18630.3 19217.8 20226.1 20250.3 20505.0 19011.4 19264.9 19976.9 20102.9 20425.8 5567.8 5830.7 5845.6 5858.1 5874.6 5532.2 5768.7 5777.1 5801.4 5862.7 5470.6 5721.1 5766.5 5792.7 5840.9 4427.8 5707.5 5770.0 5777.7 5808.9 5009.5 5039.2 5080.4 5080.6 5101.2 23066.8 23912.9 24364.0 24416.4 24484.8 2183.3 2191.1 2327.3 2560.2 2726.2 7715.8 8945.0 9751.9 9850.5 9964.5 1776.7 1828.7 1844.5 1851.2 1853.9 101461.0 121970.0 122832.0 124668.0 126955.0 9149.2 11531.4 11576.8 11649.4 11746.2 12605.7 14346.3 14722.1 14739.7 14765.3 7880.4 8287.3 8326.6 8404.1 8404.9 8006.3 8010.5 8031.6 8150.8 8155.2 3738.3 4487.3 4530.2 4546.7 4561.1 1852.7 1913.1 1913.3 1913.5 1918.5 716.6 793.9 914.9 995.6 1007.4 20332.7 20926.8 21067.7 21171.5 21386.8 914.5 930.5 934.9 971.8 1004.1 10893.6 11221.8 11239.7 11280.5 11281.5 555513.7 572346.0 572397.0 583168.0 584006.0 111600.0 111717.9 116191.0 122951.4 132856.0 944.2 955.6 1001.6 1010.0 1010.2 18776.4 19222.3 19869.9 19924.2 19940.0 53418.7 55798.9 55956.7 56965.7 57029.7 69912.0 73774.9 75995.6 78392.1 81706.0 5790.5 6528.0 8031.7 8069.3 8229.9 10236.9 10494.5 12002.4 12227.6 12512.3 10610.4 10631.3 10671.2 10732.0 10772.9 9746.8 9938.6 10540.8 10663.7 10671.4 10049.4 10802.1 10878.0 10898.1 10928.9 6816.2 7304.3 7342.7 7763.4 8014.3 9949.4 10282.3 10732.3 10832.5 10903.4 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#3] mootools.html Cycles:180 Average:5307.91 Median:5663.85 stddev:2893.98 (51.1%) stddev-sans-first:2886.89 08:09:28 INFO - PROCESS | 1964 | Values: 1360.9 1453.5 1611.9 1735.6 1794.2 6059.5 7600.5 7850.0 8305.3 8331.1 6460.8 6914.4 7956.4 8107.0 8111.0 2261.4 2279.8 2280.7 2291.1 2303.9 2276.0 2304.2 2318.9 2323.7 2329.4 2257.1 2277.1 2281.2 2294.6 2295.0 2245.2 2246.2 2257.1 2271.6 2279.7 2284.2 2297.5 2304.1 2308.0 2309.3 8112.6 8157.7 8207.5 8232.0 8251.7 4956.8 4973.5 5089.6 5130.1 5131.2 2339.2 3225.9 3239.0 3458.8 3464.4 1008.1 1045.7 1056.9 1058.4 1084.5 9287.4 9762.9 10407.3 10409.3 10514.1 4413.8 4486.6 4606.1 4619.4 4621.8 9093.9 9230.8 9241.3 9241.9 9271.8 5045.3 5073.2 5113.2 5119.0 5133.1 3815.0 4033.8 4177.9 4675.5 4677.5 2794.8 3244.9 3585.3 3607.3 3868.2 1046.2 1239.0 1241.1 1249.2 1250.8 795.3 872.1 902.9 903.3 904.7 5695.4 5710.5 5770.6 5786.2 5809.8 806.8 893.3 894.7 926.6 988.3 3795.3 3872.0 3881.7 3897.4 3923.8 9603.5 9905.9 9915.2 9955.3 10009.5 9743.9 9841.7 9889.0 9914.8 10027.4 7213.7 7509.9 7610.7 7745.8 7770.0 5642.1 5685.6 5790.1 5857.1 5860.0 9062.5 9069.3 9113.0 9132.0 9158.6 9272.3 9275.0 9372.9 9395.9 9416.9 6582.4 6804.5 7114.6 7156.6 7163.5 7579.5 7804.8 7809.7 7925.8 7944.8 7010.8 7154.4 7185.2 7191.3 7191.5 6018.4 6941.9 7024.2 7052.1 7071.1 6529.8 6607.8 6699.7 6709.4 6715.1 4406.9 4797.7 4840.3 4854.2 4862.0 4391.4 4448.7 4560.0 4578.8 4653.3 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#4] prototype.html Cycles:180 Average:4577.10 Median:5219.27 stddev:1430.07 (27.4%) stddev-sans-first:1396.98 08:09:28 INFO - PROCESS | 1964 | Values: 265.0 285.3 299.3 301.6 305.1 5258.0 5518.8 5519.4 5543.5 5585.0 4684.6 5370.8 5461.9 5462.4 5472.7 3877.2 3911.6 4025.7 4058.5 4064.7 4047.8 4049.4 4055.7 4087.7 4105.1 3025.0 3946.7 4062.2 4071.3 4078.8 2990.7 3791.7 3886.4 4015.2 4060.6 3323.8 3503.4 3525.9 3535.0 3535.4 5201.7 5202.7 5213.1 5216.1 5256.9 4109.9 4424.8 4662.4 4677.3 4689.2 5622.5 5629.7 5657.8 5674.3 5680.5 3668.7 3682.3 3912.0 3913.6 3922.3 4333.4 4846.6 5548.9 5958.4 5963.8 3915.5 3931.0 3964.0 3969.2 3983.9 4013.4 4018.3 4024.9 4036.2 4049.4 4617.4 4655.3 4655.8 4670.2 4678.3 5092.5 5361.0 5408.4 5649.2 5686.7 1129.7 1234.3 1244.5 1330.6 1330.8 848.7 856.9 897.0 897.5 901.9 5281.4 5365.3 5422.2 5558.9 5587.4 5284.9 5628.8 5746.9 5822.0 6040.7 5064.5 5089.2 5104.0 5480.1 5658.5 2593.6 2764.9 2971.7 2986.5 3016.3 5200.5 5585.8 5619.7 5681.3 5970.9 5452.6 5568.9 5834.3 5883.1 5944.4 4828.3 5489.5 5542.9 5951.3 5961.5 4376.7 5375.7 5414.8 5603.2 5744.3 4640.3 4658.3 5472.4 5625.6 5662.9 5222.5 5886.9 5900.4 5911.4 5929.3 3335.4 3363.1 3649.5 3822.5 3837.7 5918.6 6000.2 6010.2 6023.5 6024.4 5873.9 5874.9 5888.9 5894.5 5901.6 5155.7 5617.0 5827.8 5844.9 5853.4 4934.6 5340.8 5523.4 5704.7 5717.4 4704.0 4801.6 5290.5 5618.3 5786.2 4646.9 5717.0 5746.7 5748.2 5781.5 08:09:28 INFO - PROCESS | 1964 | 08:09:28 INFO - PROCESS | 1964 | [#5] yui.html Cycles:180 Average:19182.58 Median:4384.53 stddev:27269.21 (621.9%) stddev-sans-first:27310.34 08:09:28 INFO - PROCESS | 1964 | Values: 688.2 688.5 735.1 754.3 757.6 13894.2 14716.6 14736.8 14748.5 14751.8 13110.8 13159.1 13186.7 13195.7 13196.9 642.8 661.4 776.7 788.4 799.0 791.7 800.5 802.6 802.6 805.3 810.1 824.3 834.3 835.5 835.8 804.9 820.2 822.5 822.8 823.9 616.5 620.1 621.8 622.3 623.0 13273.5 13465.8 13502.3 13639.8 13734.7 12946.5 13427.8 13448.5 13562.7 13758.7 10937.8 10942.5 11165.4 11379.3 11610.3 3590.9 3661.3 3685.3 3697.2 3800.7 85746.6 86029.0 86726.7 87147.7 88698.6 8548.6 10152.9 10374.0 10446.9 10524.8 20007.8 20130.4 20160.7 20191.9 20201.7 7546.1 7567.4 7680.7 7704.8 7842.0 3822.4 4145.3 4216.1 4227.0 4259.9 3305.2 3398.8 3404.5 3410.0 3418.3 557.8 571.9 596.9 615.5 640.2 507.7 634.0 636.3 640.3 704.4 7809.0 8658.7 8701.5 8790.3 9922.7 533.3 541.8 573.6 650.4 670.6 4002.0 4359.3 4370.1 4398.9 4404.9 73236.0 104388.0 105564.5 105592.5 108425.0 59073.7 61317.4 68868.6 92855.0 96785.5 74795.6 80648.0 81417.6 82681.6 83924.0 8150.7 8667.1 8678.2 8709.2 8755.4 51980.0 52604.2 53236.3 54142.0 55223.4 42441.6 54781.5 66511.3 67194.7 81859.0 12961.6 13099.3 13373.7 13390.8 13720.3 14111.9 14152.6 14255.6 14459.6 14541.9 13017.4 13284.9 13320.0 13369.4 14154.4 13257.1 13300.9 13383.6 13472.4 13496.9 10560.4 12558.2 12630.4 12766.9 12914.3 10627.3 11002.0 11102.4 11412.6 11511.2 9249.6 11172.3 11500.7 11536.8 11597.6 08:09:28 INFO - PROCESS | 1964 | -------- Summary: end -------- 08:09:28 INFO - PROCESS | 1964 | 08:09:29 INFO - TEST-INFO | 1964: exit 0 08:09:29 INFO - TEST-OK | dromaeo_css | took 1116663ms 08:09:29 INFO - TEST-START | kraken 08:09:29 INFO - operating with platform_type : mac_ 08:09:29 INFO - Initialising browser for kraken test... 08:09:29 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpcedPpR/profile http://localhost:52411/getInfo.html) 08:09:32 INFO - PROCESS | 1969 | 2016-12-03 08:09:32.423 plugin-container[1970:24631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 08:09:32 INFO - PROCESS | 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:32 INFO - PROCESS | 1969 | 2016-12-03 08:09:32.428 plugin-container[1970:24631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 08:09:32 INFO - PROCESS | 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:32 INFO - PROCESS | 1969 | 2016-12-03 08:09:32.428 plugin-container[1970:24631] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:32 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 08:09:32 INFO - PROCESS | 1969 | colorDepth:24 08:09:32 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 08:09:32 INFO - PROCESS | 1969 | __metrics 08:09:32 ERROR - PROCESS | 1969 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:09:33 INFO - TEST-INFO | 1969: exit 0 08:09:33 INFO - Browser initialized. 08:09:33 INFO - Running cycle 1/1 for kraken test... 08:09:33 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpcedPpR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:35 INFO - PROCESS | 1971 | 2016-12-03 08:09:35.932 plugin-container[1972:24750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 08:09:35 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:35 INFO - PROCESS | 1971 | 2016-12-03 08:09:35.933 plugin-container[1972:24750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 08:09:35 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:35 INFO - PROCESS | 1971 | 2016-12-03 08:09:35.933 plugin-container[1972:24750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:45 INFO - PROCESS | 1971 | RSS: Main: 229158912 08:09:45 INFO - PROCESS | 1971 | 08:09:48 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52411/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:49 INFO - PROCESS | 1971 | RSS: Main: 241291264 08:09:49 INFO - PROCESS | 1971 | 08:09:52 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52411/tests/kraken/driver.html?testName=audio-dft) 08:09:52 INFO - PROCESS | 1971 | RSS: Main: 248934400 08:09:52 INFO - PROCESS | 1971 | 08:09:56 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52411/tests/kraken/driver.html?testName=audio-fft) 08:09:57 INFO - PROCESS | 1971 | RSS: Main: 250830848 08:09:57 INFO - PROCESS | 1971 | 08:09:59 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52411/tests/kraken/driver.html?testName=audio-oscillator) 08:09:59 INFO - PROCESS | 1971 | RSS: Main: 252338176 08:09:59 INFO - PROCESS | 1971 | 08:10:01 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52411/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:10:02 INFO - PROCESS | 1971 | RSS: Main: 254038016 08:10:02 INFO - PROCESS | 1971 | 08:10:05 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52411/tests/kraken/driver.html?testName=imaging-darkroom) 08:10:05 INFO - PROCESS | 1971 | RSS: Main: 258478080 08:10:05 INFO - PROCESS | 1971 | 08:10:08 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52411/tests/kraken/driver.html?testName=imaging-desaturate) 08:10:09 INFO - PROCESS | 1971 | RSS: Main: 257462272 08:10:09 INFO - PROCESS | 1971 | 08:10:12 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52411/tests/kraken/driver.html?testName=json-parse-financial) 08:10:13 INFO - PROCESS | 1971 | RSS: Main: 257519616 08:10:13 INFO - PROCESS | 1971 | 08:10:14 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52411/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:10:15 INFO - PROCESS | 1971 | RSS: Main: 256663552 08:10:15 INFO - PROCESS | 1971 | 08:10:17 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:10:18 INFO - PROCESS | 1971 | RSS: Main: 256700416 08:10:18 INFO - PROCESS | 1971 | 08:10:20 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:10:21 INFO - PROCESS | 1971 | RSS: Main: 256778240 08:10:21 INFO - PROCESS | 1971 | 08:10:24 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:10:24 INFO - PROCESS | 1971 | RSS: Main: 256987136 08:10:24 INFO - PROCESS | 1971 | 08:10:27 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:10:28 INFO - PROCESS | 1971 | RSS: Main: 256880640 08:10:28 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52411/tests/kraken/driver.html?testName=ai-astar) 08:10:30 INFO - PROCESS | 1971 | RSS: Main: 258715648 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | __start_tp_report 08:10:30 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 08:10:30 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 08:10:30 INFO - PROCESS | 1971 | |i|pagename|runs| 08:10:30 INFO - PROCESS | 1971 | |0;ai-astar;108;115;115;120;120;119;119;121;118;118 08:10:30 INFO - PROCESS | 1971 | |1;audio-beat-detection;146;129;149;185;116;118;116;124;179;121 08:10:30 INFO - PROCESS | 1971 | |2;audio-dft;219;232;283;248;198;240;234;197;196;196 08:10:30 INFO - PROCESS | 1971 | |3;audio-fft;90;66;71;74;61;53;80;73;104;83 08:10:30 INFO - PROCESS | 1971 | |4;audio-oscillator;86;71;102;102;98;100;92;71;69;94 08:10:30 INFO - PROCESS | 1971 | |5;imaging-gaussian-blur;120;128;90;99;134;85;93;86;90;90 08:10:30 INFO - PROCESS | 1971 | |6;imaging-darkroom;96;96;96;95;95;104;97;124;96;152 08:10:30 INFO - PROCESS | 1971 | |7;imaging-desaturate;97;75;139;136;90;121;148;82;145;141 08:10:30 INFO - PROCESS | 1971 | |8;json-parse-financial;56;56;56;57;55;59;58;59;60;60 08:10:30 INFO - PROCESS | 1971 | |9;json-stringify-tinderbox;70;71;77;83;110;109;87;73;70;70 08:10:30 INFO - PROCESS | 1971 | |10;stanford-crypto-aes;92;103;89;86;82;86;84;86;86;86 08:10:30 INFO - PROCESS | 1971 | |11;stanford-crypto-ccm;169;161;148;149;153;173;168;176;208;167 08:10:30 INFO - PROCESS | 1971 | |12;stanford-crypto-pbkdf2;167;175;167;170;170;176;172;170;173;171 08:10:30 INFO - PROCESS | 1971 | |13;stanford-crypto-sha256-iterative;69;72;75;83;87;70;69;68;68;73 08:10:30 INFO - PROCESS | 1971 | __end_tp_report 08:10:30 INFO - PROCESS | 1971 | __start_cc_report 08:10:30 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,973 08:10:30 INFO - PROCESS | 1971 | __end_cc_report 08:10:30 INFO - PROCESS | 1971 | __startTimestamp1480781430454__endTimestamp 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | ------- Summary: start ------- 08:10:30 INFO - PROCESS | 1971 | Number of tests: 14 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#0] ai-astar Cycles:10 Average:117.30 Median:119.00 stddev:3.83 (3.2%) stddev-sans-first:2.12 08:10:30 INFO - PROCESS | 1971 | Values: 108.0 115.0 115.0 120.0 120.0 119.0 119.0 121.0 118.0 118.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:137.50 stddev:25.84 (18.8%) stddev-sans-first:27.25 08:10:30 INFO - PROCESS | 1971 | Values: 146.0 129.0 149.0 185.0 116.0 118.0 116.0 124.0 179.0 121.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#2] audio-dft Cycles:10 Average:224.30 Median:233.00 stddev:28.82 (12.4%) stddev-sans-first:30.50 08:10:30 INFO - PROCESS | 1971 | Values: 219.0 232.0 283.0 248.0 198.0 240.0 234.0 197.0 196.0 196.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#3] audio-fft Cycles:10 Average:75.50 Median:73.50 stddev:14.66 (19.9%) stddev-sans-first:14.58 08:10:30 INFO - PROCESS | 1971 | Values: 90.0 66.0 71.0 74.0 61.0 53.0 80.0 73.0 104.0 83.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:78.50 stddev:13.45 (17.1%) stddev-sans-first:14.24 08:10:30 INFO - PROCESS | 1971 | Values: 86.0 71.0 102.0 102.0 98.0 100.0 92.0 71.0 69.0 94.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#5] imaging-gaussian-blur Cycles:10 Average:101.50 Median:90.00 stddev:18.52 (20.6%) stddev-sans-first:18.40 08:10:30 INFO - PROCESS | 1971 | Values: 120.0 128.0 90.0 99.0 134.0 85.0 93.0 86.0 90.0 90.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:96.00 stddev:18.73 (19.5%) stddev-sans-first:19.58 08:10:30 INFO - PROCESS | 1971 | Values: 96.0 96.0 96.0 95.0 95.0 104.0 97.0 124.0 96.0 152.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#7] imaging-desaturate Cycles:10 Average:117.40 Median:111.50 stddev:28.48 (25.5%) stddev-sans-first:29.23 08:10:30 INFO - PROCESS | 1971 | Values: 97.0 75.0 139.0 136.0 90.0 121.0 148.0 82.0 145.0 141.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.50 stddev:1.84 (3.1%) stddev-sans-first:1.86 08:10:30 INFO - PROCESS | 1971 | Values: 56.0 56.0 56.0 57.0 55.0 59.0 58.0 59.0 60.0 60.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:72.00 stddev:15.63 (21.7%) stddev-sans-first:15.96 08:10:30 INFO - PROCESS | 1971 | Values: 70.0 71.0 77.0 83.0 110.0 109.0 87.0 73.0 70.0 70.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:86.00 stddev:5.91 (6.9%) stddev-sans-first:6.09 08:10:30 INFO - PROCESS | 1971 | Values: 92.0 103.0 89.0 86.0 82.0 86.0 84.0 86.0 86.0 86.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#11] stanford-crypto-ccm Cycles:10 Average:167.20 Median:168.50 stddev:17.38 (10.3%) stddev-sans-first:18.43 08:10:30 INFO - PROCESS | 1971 | Values: 169.0 161.0 148.0 149.0 153.0 173.0 168.0 176.0 208.0 167.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.50 stddev:3.00 (1.7%) stddev-sans-first:2.79 08:10:30 INFO - PROCESS | 1971 | Values: 167.0 175.0 167.0 170.0 170.0 176.0 172.0 170.0 173.0 171.0 08:10:30 INFO - PROCESS | 1971 | 08:10:30 INFO - PROCESS | 1971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:72.50 stddev:6.59 (9.1%) stddev-sans-first:6.79 08:10:30 INFO - PROCESS | 1971 | Values: 69.0 72.0 75.0 83.0 87.0 70.0 69.0 68.0 68.0 73.0 08:10:30 INFO - PROCESS | 1971 | -------- Summary: end -------- 08:10:30 INFO - PROCESS | 1971 | 08:10:31 INFO - TEST-INFO | 1971: exit 0 08:10:31 INFO - TEST-OK | kraken | took 61913ms 08:10:31 INFO - SUITE-END | took 1178s 08:10:31 INFO - Completed test suite (00:19:38) 08:10:31 INFO - javascript benchmark 08:10:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7080.883452441545, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.5901762279714, 3299.8845040423585, 3339.8811398181624, 3359.328134373126, 3369.494575813628, 5657.46032695971, 5717.485426311626, 5719.084946408576, 5781.595288329819, 5793.884122317561, 5679.971600141994, 5701.572382071351, 5735.1970724098655, 5744.310682718069, 5745.856353591163, 4294.506131794852, 5171.689698618095, 5212.973935130329, 5216.869578260555, 5250.65870718402, 5226.973865130669, 5256.342957130359, 5263.789448422078, 5269.235960785683, 5357.196420536931, 5226.425093239741, 5236.162214045753, 5236.450172731866, 5239.869003274911, 5250.186221135711, 5079.212722028073, 5090.541851233388, 5157.071727089123, 5222.0, 5222.477752224785, 5047.697138171721, 5083.822066227664, 5122.385313762373, 5122.692638441706, 5178.404483484373, 5690.800821971253, 5702.942970570282, 5705.172749951275, 5744.626599271034, 5795.2176456178295, 4783.3064205690325, 6168.414000669929, 6209.441150296449, 6258.34287399821, 6274.843128921768, 6037.275526936795, 6073.908891366633, 6079.270487541479, 6101.664408457533, 6104.175936248638, 5563.0, 5605.579581531401, 5608.10270356741, 5617.129344951539, 5625.0, 6129.358790882775, 6177.876442471215, 6184.288806787187, 6209.658468784261, 6258.280297765815, 6081.27024757032, 6116.113163591254, 6116.153172518083, 6203.7828675996125, 6214.689265536705, 5721.256236689203, 6071.876702809995, 6236.126942228092, 6247.656378899204, 6286.277078135981, 6081.939180608226, 6172.259328880473, 6220.844478888064, 6237.875242495215, 6275.780347687809, 5232.581393488588, 6115.635867915819, 6144.187464697836, 6213.689315534205, 6236.968815155895, 4186.434831297796, 4526.773661316987, 4819.180739274261, 4936.90126197474, 4965.4289756678445, 4381.211381951216, 4383.824647014084, 4412.4705035395955, 4414.073044660593, 4437.534058923831, 5056.786371270869, 5068.569171620454, 5655.547556195576, 5694.800681976194, 5838.12428135783, 6176.7838125665385, 6199.101130336076, 6208.658523781235, 6234.189555357774, 6238.65687387189, 5795.913061304083, 5816.854578635568, 5894.440028197314, 5931.110333450017, 5953.077273022688, 4496.482904465963, 5364.490373414597, 5388.3533975922355, 5405.297311349578, 5423.918641220384, 6199.56603037783, 6298.842528936813, 6316.052592111128, 6320.178376810985, 6329.367063293634, 6114.358196334042, 6174.6912654368, 6219.191505104308, 6228.5951413158, 6228.719707613259, 6151.815445536641, 6258.468030217483, 6295.716692748929, 6301.684915754286, 6302.842428939313, 6176.104464852481, 6177.4749146323065, 6202.7208775606105, 6218.9378106218355, 6229.875402491834, 6119.045604113885, 6142.007694998287, 6237.251529816451, 6256.155419018374, 6260.092286618506, 5033.295338652519, 5836.941630583639, 6123.724432400463, 6128.442013630826, 6137.8979746733885, 6065.181200537871, 6134.78528251509, 6148.016317389196, 6157.138000679819, 6166.599171053867, 5817.040188368872, 5955.702214889325, 6029.8191054268445, 6040.882436749215, 6057.030875059969, 5846.94153058464, 5901.7639294427745, 5932.911006334822, 6023.885581167323, 6065.6663883486835, 5757.0, 5778.739956702043, 5783.392743761844, 5793.131030345482, 5796.594238403271], "value": 5633.3962239955445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.37705851217885, 517.9356422551655, 520.0145723853298, 521.5462547583603, 542.4710906865809, 37570.436443453335, 39158.21683566327, 39197.80401097998, 39273.80363098188, 39341.01647458815, 41166.794166029125, 41763.37354939678, 41820.58179418205, 41848.58151418485, 41936.0, 8481.745547633582, 9298.256139508823, 9442.291828112884, 9451.621935122621, 9477.052294770538, 8024.0, 9436.716898493056, 9437.339386242966, 9440.291978101635, 9459.810803783954, 9425.76435589109, 9476.00501947296, 9488.051194880525, 9496.762580935498, 9501.239900807954, 9357.251419886392, 9366.250699943988, 9461.763455913588, 9464.19554337882, 9501.382410143387, 9261.16649501548, 9338.719838404792, 9342.90657093434, 9345.485998270093, 9350.29872759539, 32749.836250818833, 39265.0, 39469.40795888035, 39528.20943581141, 39609.40585891214, 3162.588863447737, 3242.902712918616, 3333.7666363354333, 3458.1181798641437, 3632.7638703484454, 28049.85975070132, 28349.433011339865, 30017.69982300171, 30268.54597181044, 31196.688033119834, 3182.8249446280447, 3195.328980914011, 3209.759268054882, 3219.0664707234873, 3228.3059142284287, 485420.5728971368, 485826.0, 489236.0, 491268.0, 491488.0, 51492.227616585784, 53801.46198537965, 54925.725371372886, 55098.17352739712, 55299.44700552943, 59561.40438595558, 59726.40273597295, 59772.10341844876, 59911.400885991454, 60275.79448411007, 13978.720425591635, 13991.020628555903, 13996.790048149285, 14005.719885602231, 14014.369353379123, 12372.690682732824, 12416.875831241752, 12421.813672794915, 12434.253944763343, 12523.311217883103, 15308.770368444482, 15474.0, 15588.84411155874, 15644.374225030871, 15652.765208521882, 3154.6845315468727, 6060.848478788065, 6158.907616385758, 6220.377962203744, 6239.282482514478, 841.2302742990217, 999.0658734083655, 1001.2840818814583, 1024.4621573673733, 1076.8707755069443, 29701.85149074284, 30041.098767037023, 30253.0, 30350.696493034786, 30611.693883060885, 956.9234461243223, 1002.6537901310967, 1045.5608644369383, 1050.564015933379, 1102.2835157147917, 14972.550823475312, 15268.770968435483, 15596.376144954302, 15612.985155965052, 15718.842811571967, 676150.0, 678667.0, 680347.0, 682065.0, 684024.0, 56087.0, 56203.156952644924, 56619.0, 56659.43340566624, 56836.863262734514, 1319.769040417923, 1331.9866801331862, 1355.2613825465146, 1356.6540532164236, 1361.6527785414853, 28733.856330718212, 29276.707232927398, 29733.553996690498, 29765.404691906475, 30320.696793031788, 48584.0, 48995.0, 49116.263256051905, 49184.50815491942, 49340.75329623329, 606935.0, 612002.0, 614624.0, 615259.0, 618755.0, 26221.34446638849, 26261.16171867951, 26528.0, 26634.0, 26679.7332026685, 40414.1917161661, 41121.38317925174, 45203.095938081715, 45285.77357113193, 45774.02468839648, 33311.33377332391, 37276.50893964211, 37614.435783463814, 37669.81165094157, 38389.0, 37752.622473776006, 37889.0, 37917.05207369838, 39163.41254881236, 39245.803770980965, 33987.49018764671, 34316.31367372588, 37127.44308835315, 37153.442698360086, 37836.62163378331, 27695.44609107847, 30678.0, 32559.837200813843, 34119.147021324665, 34330.48504272488, 24924.0, 24965.375865603506, 25176.87411563004, 25486.490270194863, 25666.61500077463], "value": 19261.252694405783, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.38177462820653, 325.58487927891935, 364.74103386595516, 368.182634551296, 389.79535743734533, 18630.34793782218, 19217.84692918426, 20226.08982595783, 20250.29123980661, 20505.0, 19011.429657110275, 19264.940428276474, 19976.900115499404, 20102.89434081125, 20425.795742042574, 5567.777288908449, 5830.737616807254, 5845.620034697752, 5858.121281807742, 5874.588778785486, 5532.225488431623, 5768.682722450264, 5777.133429985493, 5801.419858014186, 5862.736176872051, 5470.6202801781355, 5721.113227449752, 5766.481016708516, 5792.652440853562, 5840.941590584103, 4427.77861106945, 5707.4577915098, 5770.0, 5777.653340799545, 5808.941910580882, 5009.524095210949, 5039.21892106724, 5080.364954380702, 5080.568151707109, 5101.20931255656, 23066.76933230663, 23912.880435597886, 24364.0, 24416.38959026021, 24484.755152448426, 2183.3340831046526, 2191.0687957617947, 2327.2669109230706, 2560.2063360358347, 2726.2230264374557, 7715.807104822368, 8944.955275223581, 9751.853722194102, 9850.50747462624, 9964.451955142464, 1776.7246076858107, 1828.6799810033192, 1844.5112045308003, 1851.1577232359243, 1853.8794978326366, 101460.99999999926, 121970.0, 122832.0, 124668.0, 126955.0, 9149.176574108362, 11531.365774882293, 11576.768464630828, 11649.41752912351, 11746.17776755619, 12605.684857878443, 14346.282685865664, 14722.116672999655, 14739.705205895822, 14765.261736913326, 7880.448368614142, 8287.337013038943, 8326.584480638307, 8404.11756765543, 8404.915950840536, 8006.279434850891, 8010.519368837889, 8031.598420078973, 8150.75547733569, 8155.184481551797, 3738.3084129436143, 4487.304467807495, 4530.161920044802, 4546.681732278775, 4561.064981678748, 1852.703567429205, 1913.100842603974, 1913.339897735279, 1913.4546654203764, 1918.5299601597699, 716.5808002318598, 793.8610743119931, 914.9359544831931, 995.6216637677636, 1007.4156988946394, 20332.69500957487, 20926.790732092788, 21067.68398474024, 21171.47071323229, 21386.78613213848, 914.4558987402485, 930.5393830054167, 934.8831396075491, 971.7667759737753, 1004.1364426593124, 10893.564257429614, 11221.775564488664, 11239.66281011571, 11280.492377842853, 11281.548738050386, 555513.6672949828, 572346.0, 572397.0, 583168.0, 584006.0, 111600.0, 111717.88282117074, 116190.99999999831, 122951.38524307321, 132856.0, 944.1596978688972, 955.6225291010151, 1001.6344034427382, 1009.9899001009895, 1010.1968934696934, 18776.436706898814, 19222.327218547285, 19869.900650496656, 19924.203031878562, 19940.0, 53418.73290633522, 55798.88402232014, 55956.72021640029, 56965.71517142388, 57029.71485142548, 69912.0, 73774.89337659832, 75995.62002189954, 78392.08039597883, 81706.0, 5790.507806836466, 6528.0, 8031.718889838828, 8069.31410830086, 8229.917700822914, 10236.881172142423, 10494.527746251284, 12002.39988000614, 12227.572034978733, 12512.310838036748, 10610.363378197024, 10631.308964917256, 10671.199660025684, 10732.034116929513, 10772.946135269274, 9746.805063898824, 9938.552765125442, 10540.789184216426, 10663.733406664895, 10671.413072281099, 10049.447280399356, 10802.135829133807, 10878.0, 10898.07366373867, 10928.868870570162, 6816.216135144324, 7304.306090921354, 7342.743003994835, 7763.378929685725, 8014.278714915686, 9949.403035817873, 10282.331648443127, 10732.302400343953, 10832.512536936014, 10903.400312982862], "value": 9269.473393393331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.9455621775126, 1453.4767483705864, 1611.9435819746304, 1735.557432854622, 1794.1657129434818, 6059.484943779773, 7600.467967242295, 7849.960750196248, 8305.335573154141, 8331.083580806106, 6460.838479038015, 6914.44684425246, 7956.403269754763, 8107.0, 8111.0, 2261.42333704905, 2279.794818466346, 2280.6693029510793, 2291.0835665733757, 2303.9308820735405, 2275.987185702363, 2304.1820153845397, 2318.8956496957676, 2323.7211534615785, 2329.4176455886027, 2257.1084421653436, 2277.123307526606, 2281.2471884278148, 2294.552562250362, 2294.9655755163685, 2245.236619549353, 2246.2025980776752, 2257.142285931352, 2271.5797577448143, 2279.680844681746, 2284.1662793080573, 2297.5404919016264, 2304.0783686525356, 2307.9884600577057, 2309.3418375762826, 8112.6349314281, 8157.673693052271, 8207.548584827831, 8231.95884020582, 8251.669933202666, 4956.776945037482, 4973.477784832613, 5089.567386772129, 5130.050940576006, 5131.230315452674, 2339.181286549711, 3225.919352016195, 3238.951415728766, 3458.775179613317, 3464.3764122457947, 1008.112860682602, 1045.7281106912183, 1056.910162636177, 1058.3808472043863, 1084.4523515624637, 9287.442753434816, 9762.90237097634, 10407.271490995634, 10409.271351005433, 10514.106300964428, 4413.823447062082, 4486.573775491323, 4606.124836281163, 4619.376384188159, 4621.768911554409, 9093.909060909438, 9230.815383692288, 9241.306901982243, 9241.861372079424, 9271.814563708687, 5045.31888195089, 5073.239014147907, 5113.233015047773, 5119.0, 5133.050385678662, 3815.0, 4033.8184781684895, 4177.874663760092, 4675.485696573374, 4677.4854765975715, 2794.8043636945627, 3244.8702051917658, 3585.30086633104, 3607.296577167427, 3868.2263547290654, 1046.1839764983324, 1238.993805030969, 1241.1064033895582, 1249.1693024139026, 1250.8311377963857, 795.2564352330589, 872.1060912564545, 902.869083982832, 903.3450748207629, 904.7466709321401, 5695.40198279183, 5710.486056254957, 5770.6249093808765, 5786.218860453869, 5809.7966571170655, 806.8386322735477, 893.3433926064338, 894.7449976756593, 926.6478738079487, 988.2637435110745, 3795.2788970095594, 3872.0, 3881.7282790204413, 3897.415387691812, 3923.803809809498, 9603.471809050568, 9905.900940990497, 9915.157211636948, 9955.30312878106, 10009.499525023719, 9743.902560974442, 9841.704748857546, 9889.0, 9914.752131196778, 10027.39835609851, 7213.711451541984, 7509.887351689838, 7610.695572177051, 7745.7676269712, 7770.0, 5642.125470552071, 5685.573581981285, 5790.131480277992, 5857.092150716646, 5860.033094539353, 9062.456252624863, 9069.319801015083, 9113.0, 9132.0, 9158.626206069142, 9272.258219342302, 9275.0, 9372.859407109036, 9395.85906211421, 9416.858747118662, 6582.3746744059235, 6804.455643548603, 7114.644267786486, 7156.606386648576, 7163.462740294394, 7579.469437139347, 7804.84390312202, 7809.726659566888, 7925.762227133196, 7944.801379965547, 7010.824729381602, 7154.427645788429, 7185.173705023989, 7191.280871912976, 7191.532550384418, 6018.3680713524445, 6941.93058069433, 7024.1571011478945, 7052.083229180379, 7071.116110486189, 6529.836754081186, 6607.834804129935, 6699.732010719516, 6709.429698475489, 6715.093462382516, 4406.889827754332, 4797.736124513185, 4840.29815676732, 4854.1747902857305, 4862.00328932575, 4391.385206071216, 4448.733076015449, 4559.954400456086, 4578.839740609063, 4653.348531205702], "value": 4314.564948284146, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.9668791401075, 285.26970954356864, 299.3190491631538, 301.5582172117848, 305.0862666314389, 5258.0, 5518.806841760539, 5519.365272993607, 5543.503254121395, 5585.0, 4684.601808846243, 5370.812021579236, 5461.924468701897, 5462.399136095027, 5472.72636368182, 3877.1696661437027, 3911.5892831252663, 4025.6779457643465, 4058.533268674111, 4064.654504367132, 4047.8380864765372, 4049.3521036634143, 4055.695822813286, 4087.652549533293, 4105.085389373548, 3024.9848750756214, 3946.723729338933, 4062.1672557125717, 4071.30787766079, 4078.796060196993, 2990.745786608141, 3791.7345785795005, 3886.417037444392, 4015.217032678629, 4060.5939406059156, 3323.8005719656658, 3503.351879902201, 3525.919707372637, 3534.9748572913822, 3535.4014799122624, 5201.739913004372, 5202.661826981272, 5213.061648903196, 5216.096124914417, 5256.921146182772, 4109.856155034589, 4424.778761061934, 4662.440507139165, 4677.251639737659, 4689.249720044776, 5622.465865742748, 5629.71851407432, 5657.830265092054, 5674.3190817101795, 5680.5455563554815, 3668.743187976843, 3682.3371793077235, 3912.0, 3913.5695073541888, 3922.3135951208424, 4333.371661109121, 4846.563809257149, 5548.917960997647, 5958.40415958405, 5963.761449542013, 3915.5497117831815, 3931.0, 3963.980180099081, 3969.186316805048, 3983.940240896389, 4013.4381186633896, 4018.33697439925, 4024.899377515527, 4036.233115708006, 4049.392591111298, 4617.376654151713, 4655.255159174516, 4655.767211639472, 4670.159371313128, 4678.2982552616695, 5092.465291144451, 5361.0, 5408.432114627986, 5649.209110724501, 5686.663634045996, 1129.6949823547595, 1234.3334599316447, 1244.4693027576131, 1330.6274243211917, 1330.846952600444, 848.7368915636173, 856.8928883889514, 897.0491279244022, 897.5018864530249, 901.8511945528915, 5281.39263984639, 5365.309927372865, 5422.213779002022, 5558.859838962028, 5587.38538760736, 5284.947150528523, 5628.831135065954, 5746.8793585251315, 5821.981153298156, 6040.6677632730625, 5064.4935506449065, 5089.211172268304, 5104.004719079744, 5480.08197131001, 5658.547316214693, 2593.649857269282, 2764.875580598878, 2971.747401470856, 2986.507226307678, 3016.3213277012496, 5200.479952004845, 5585.804496842591, 5619.662820230799, 5681.34664513578, 5970.850728731816, 5452.555072905604, 5568.9443105569235, 5834.329052159056, 5883.0879964011865, 5944.405559444108, 4828.251620998751, 5489.533389661844, 5542.889142217214, 5951.345352011362, 5961.493273071837, 4376.693631445768, 5375.731213439391, 5414.756335964812, 5603.1984007996, 5744.310682718101, 4640.280756482751, 4658.277966915133, 5472.398036215934, 5625.5965141089055, 5662.943370566406, 5222.451642577475, 5886.852063847508, 5900.350961394157, 5911.438413350725, 5929.250870992981, 3335.4496508075895, 3363.0751543324805, 3649.543807024122, 3822.464854920258, 3837.731358804857, 5918.585699001029, 6000.189974353407, 6010.188624535632, 6023.48800351975, 6024.427679370453, 5873.948404372151, 5874.853128671817, 5888.882222355614, 5894.524299794011, 5901.645901245939, 5155.7164355959285, 5616.9608622404985, 5827.76688932438, 5844.883102338014, 5853.356130825691, 4934.55588996992, 5340.786368545215, 5523.447655234444, 5704.714764261853, 5717.371089180104, 4703.976480117578, 4801.583532857746, 5290.5191140853685, 5618.3258009037545, 5786.235198264401, 4646.883827904329, 5717.028105222039, 5746.683932383592, 5748.223035848945, 5781.537477001746], "value": 4111.026173564145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.208560155821, 688.4526801193051, 735.0664655887024, 754.3211110000998, 757.5530437042144, 13894.166350018993, 14716.6320841979, 14736.778948315783, 14748.483803066894, 14751.778723319145, 13110.803337949937, 13159.078864479467, 13186.670333241675, 13195.736085278313, 13196.868031319684, 642.8392901774556, 661.4344735251362, 776.6660336055494, 788.4441468764521, 798.9600519974007, 791.695197349019, 800.5356893002045, 802.5626033811586, 802.6307898366729, 805.2832978649008, 810.0643756461296, 824.2787560884226, 834.3158609939852, 835.5279267214034, 835.8161204534993, 804.927556519916, 820.2248874813304, 822.5270469480013, 822.8272062866776, 823.9176082391713, 616.5406771954878, 620.1349117980437, 621.832105331558, 622.3496446213672, 623.0436280309748, 13273.535426260034, 13465.798013029813, 13502.324883755871, 13639.795403068962, 13734.65663358414, 12946.542310055574, 13427.798583021262, 13448.462061517528, 13562.660933476742, 13758.65603359914, 10937.83593246102, 10942.4528773561, 11165.38590377529, 11379.260348077349, 11610.303381797035, 3590.9281814363567, 3661.2860492203763, 3685.318216129998, 3697.2051009032916, 3800.7339486235974, 85746.5712671439, 86029.0, 86726.69909951356, 87147.69278460828, 88698.55650721706, 8548.615312310962, 10152.949235253776, 10374.0, 10446.947765261126, 10524.789504209873, 20007.799922000595, 20130.39608811738, 20160.697589535874, 20191.889446080608, 20201.696974545393, 7546.056742907137, 7567.432442566829, 7680.654370553227, 7704.807379815549, 7842.019747531559, 3822.445745366962, 4145.316022856256, 4216.135692183157, 4227.0, 4259.87220383389, 3305.2067503799394, 3398.796072235674, 3404.5233667286598, 3410.0281419795733, 3418.3334249821046, 557.804768331089, 571.9428057194331, 596.883607696495, 615.5045188623201, 640.2285246278224, 507.7106049551794, 633.996830015847, 636.3191385217865, 640.3404493371835, 704.35551470404, 7808.960955195188, 8658.740237792876, 8701.521416322037, 8790.296776257885, 9922.702318930444, 533.3333333333334, 541.8103663717746, 573.5898832334817, 650.3983814971133, 670.6043434373743, 4001.9594905324243, 4359.256297481033, 4370.147821174841, 4398.912021759547, 4404.889877753082, 73236.0, 104388.0, 105564.47217763861, 105592.47203763932, 108425.0, 59073.70463147657, 61317.386826131165, 68868.58959936378, 92855.0, 96785.51607241918, 74795.62602186954, 80648.0, 81417.59291203624, 82681.58659206786, 83924.0, 8150.695888657786, 8667.089955554577, 8678.175573320525, 8709.172628600272, 8755.387122901337, 51980.0, 52604.210936836746, 53236.338183089916, 54142.0, 55223.44776552344, 42441.575584243765, 54781.45218547763, 66511.33488665245, 67194.66402667956, 81859.0, 12961.611151665466, 13099.345032748133, 13373.706537684076, 13390.799138012742, 13720.313984300545, 14111.885740502414, 14152.575422737335, 14255.572332830032, 14459.566213013626, 14541.927290363481, 13017.414216360476, 13284.933575332061, 13320.0, 13369.39837707325, 14154.433822646979, 13257.138286011379, 13300.866991329964, 13383.598492045254, 13472.393742281816, 13496.865031349953, 10560.419176945341, 12558.183718058297, 12630.368481576068, 12766.936165319485, 12914.289714065817, 10627.309224900357, 11002.0, 11102.389368584805, 11412.600558980395, 11511.19421640477, 9249.583768730288, 11172.273802202832, 11500.712482188012, 11536.769264614828, 11597.594084207012], "value": 7064.497580329025, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 115.0, 120.0, 120.0, 119.0, 119.0, 121.0, 118.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 129.0, 149.0, 185.0, 116.0, 118.0, 116.0, 124.0, 179.0, 121.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 232.0, 283.0, 248.0, 198.0, 240.0, 234.0, 197.0, 196.0, 196.0], "value": 224.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 66.0, 71.0, 74.0, 61.0, 53.0, 80.0, 73.0, 104.0, 83.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 102.0, 102.0, 98.0, 100.0, 92.0, 71.0, 69.0, 94.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 128.0, 90.0, 99.0, 134.0, 85.0, 93.0, 86.0, 90.0, 90.0], "value": 101.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 104.0, 97.0, 124.0, 96.0, 152.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 75.0, 139.0, 136.0, 90.0, 121.0, 148.0, 82.0, 145.0, 141.0], "value": 117.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 55.0, 59.0, 58.0, 59.0, 60.0, 60.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 77.0, 83.0, 110.0, 109.0, 87.0, 73.0, 70.0, 70.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 103.0, 89.0, 86.0, 82.0, 86.0, 84.0, 86.0, 86.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 161.0, 148.0, 149.0, 153.0, 173.0, 168.0, 176.0, 208.0, 167.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 175.0, 167.0, 170.0, 170.0, 176.0, 172.0, 170.0, 173.0, 171.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 75.0, 83.0, 87.0, 70.0, 69.0, 68.0, 68.0, 73.0], "value": 73.4, "unit": "score"}]}]} 08:10:31 INFO - Return code: 0 08:10:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:10:31 ERROR - # TBPL SUCCESS # 08:10:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:31 INFO - 2016-12-03 08:10:31.792 screenresolution[1976:25139] starting screenresolution argv=screenresolution get 08:10:31 INFO - 2016-12-03 08:10:31.797 screenresolution[1976:25139] Display 0: 1600x1200x32@60 08:10:31 INFO - 2016-12-03 08:10:31.803 screenresolution[1977:25142] starting screenresolution argv=screenresolution list 08:10:31 INFO - Available Modes on Display 0 08:10:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:32 INFO - Intel Iris: 08:10:32 INFO - Chipset Model: Intel Iris 08:10:32 INFO - Type: GPU 08:10:32 INFO - Bus: Built-In 08:10:32 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:32 INFO - Vendor: Intel (0x8086) 08:10:32 INFO - Device ID: 0x0a2e 08:10:32 INFO - Revision ID: 0x0009 08:10:32 INFO - Displays: 08:10:32 INFO - Display: 08:10:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:32 INFO - Display Serial Number: 1600x1200 60 08:10:32 INFO - Main Display: Yes 08:10:32 INFO - Mirror: Off 08:10:32 INFO - Online: Yes 08:10:32 INFO - Rotation: Supported 08:10:32 INFO - Return code: 0 08:10:32 INFO - Running post-action listener: _resource_record_post_action 08:10:32 INFO - [mozharness: 2016-12-03 16:10:32.055065Z] Finished run-tests step (success) 08:10:32 INFO - Running post-run listener: _resource_record_post_run 08:10:32 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1623552; Write bytes: 656478208; Read time: 16; Write time: 2289 08:10:32 INFO - TinderboxPrint: CPU usage
54.5% 08:10:32 INFO - TinderboxPrint: I/O read bytes / time
1,623,552 / 16 08:10:32 INFO - TinderboxPrint: I/O write bytes / time
656,478,208 / 2,289 08:10:32 INFO - TinderboxPrint: CPU idle
2,200.6 (45.5%) 08:10:32 INFO - TinderboxPrint: CPU system
980.1 (20.2%) 08:10:32 INFO - TinderboxPrint: CPU user
1,661.0 (34.3%) 08:10:32 INFO - TinderboxPrint: Swap in / out
349,835,264 / 0 08:10:32 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345602560; Write bytes: 345561088; Read time: 26897; Write time: 644 08:10:32 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1623552; Write bytes: 303888384; Read time: 16; Write time: 1552 08:10:32 INFO - Running post-run listener: _upload_blobber_files 08:10:32 INFO - Blob upload gear active. 08:10:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:10:32 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 08:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:10:32 INFO - (blobuploader) - INFO - Open directory for files ... 08:10:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:10:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:10:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:33 INFO - (blobuploader) - INFO - Done attempting. 08:10:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:10:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:10:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:35 INFO - (blobuploader) - INFO - Done attempting. 08:10:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:10:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:10:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:37 INFO - (blobuploader) - INFO - Done attempting. 08:10:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:10:37 INFO - Return code: 0 08:10:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:10:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:10:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/771f8d980f717080c2caac793a8706279c5089b49f58c20cf07b60dd4fa4b8a900b7a48c317c219773e819c45ebda04140b4d349bb92dc5c9d544486b319cb56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/790b1deac908d95285df61985dc275d0e1811051fc734661fb1b47f98e028a77e29910fcd90b80a65159db4c55b5263a2ce9c68489dc8c614e202cee26138575", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36b6ee293c8e2197f5e1da35d52aa821eed7c044b76e1af99fd1d40ba286251e47b73cda4bc8bae130229da83cda7493d64c7c0c890ec5994675ba0e366d2715"} 08:10:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:10:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:10:37 INFO - Contents: 08:10:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/771f8d980f717080c2caac793a8706279c5089b49f58c20cf07b60dd4fa4b8a900b7a48c317c219773e819c45ebda04140b4d349bb92dc5c9d544486b319cb56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/790b1deac908d95285df61985dc275d0e1811051fc734661fb1b47f98e028a77e29910fcd90b80a65159db4c55b5263a2ce9c68489dc8c614e202cee26138575", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36b6ee293c8e2197f5e1da35d52aa821eed7c044b76e1af99fd1d40ba286251e47b73cda4bc8bae130229da83cda7493d64c7c0c890ec5994675ba0e366d2715"} 08:10:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:10:37 INFO - Copying logs to upload dir... 08:10:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:10:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.236537 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-03 08:10:37.335909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 08:10:37.344744) ========= 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.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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/Mozilla-Aurora/sha512/771f8d980f717080c2caac793a8706279c5089b49f58c20cf07b60dd4fa4b8a900b7a48c317c219773e819c45ebda04140b4d349bb92dc5c9d544486b319cb56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/790b1deac908d95285df61985dc275d0e1811051fc734661fb1b47f98e028a77e29910fcd90b80a65159db4c55b5263a2ce9c68489dc8c614e202cee26138575", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36b6ee293c8e2197f5e1da35d52aa821eed7c044b76e1af99fd1d40ba286251e47b73cda4bc8bae130229da83cda7493d64c7c0c890ec5994675ba0e366d2715"} build_url:https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/dbq1KF0qQCuNoB5ep89k0Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/771f8d980f717080c2caac793a8706279c5089b49f58c20cf07b60dd4fa4b8a900b7a48c317c219773e819c45ebda04140b4d349bb92dc5c9d544486b319cb56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/790b1deac908d95285df61985dc275d0e1811051fc734661fb1b47f98e028a77e29910fcd90b80a65159db4c55b5263a2ce9c68489dc8c614e202cee26138575", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36b6ee293c8e2197f5e1da35d52aa821eed7c044b76e1af99fd1d40ba286251e47b73cda4bc8bae130229da83cda7493d64c7c0c890ec5994675ba0e366d2715"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 08:10:37.378091) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 08:10:37.378410) ========= 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.9PMmS37nqy/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.ye1yxAodxt/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.005339 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 08:10:37.428811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 08:10:37.429087) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 08:10:37.430386) ========= ========= Total master_lag: 0.22 =========