builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0302 starttime: 1464909008.13 results: success (0) buildid: 20160602140837 builduid: 893fdbbbce3544769bc98c5128e2bb0b revision: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.128257) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.128606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.128884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.213491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.213824) ========= 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.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-06-02 16:10:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.42M=0.002s 2016-06-02 16:10:08 (7.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.561353 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.801534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.801819) ========= 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.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.020790 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.851428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:08.851744) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea --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-beta --rev 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-06-02 16:10:08,938 truncating revision to first 12 chars 2016-06-02 16:10:08,938 Setting DEBUG logging. 2016-06-02 16:10:08,938 attempt 1/10 2016-06-02 16:10:08,938 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6e2399cf03e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 16:10:09,568 unpacking tar archive at: mozilla-beta-6e2399cf03e2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913256 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:09.790713) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:09.790993) ========= script_repo_revision: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:09.791357) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:09.791617) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 16:10:09.812577) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-02 16:10:09.813080) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:09 INFO - MultiFileLogger online at 20160602 16:10:09 in /builds/slave/test 16:10:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:10:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:09 INFO - {'append_to_log': False, 16:10:09 INFO - 'base_work_dir': '/builds/slave/test', 16:10:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:10:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:09 INFO - 'branch': 'Mozilla-Beta', 16:10:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:09 INFO - 'config_files': ('talos/mac_config.py',), 16:10:09 INFO - 'default_actions': ('clobber', 16:10:09 INFO - 'read-buildbot-config', 16:10:09 INFO - 'download-and-extract', 16:10:09 INFO - 'populate-webroot', 16:10:09 INFO - 'create-virtualenv', 16:10:09 INFO - 'install', 16:10:09 INFO - 'run-tests'), 16:10:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:09 INFO - 'download_minidump_stackwalk': True, 16:10:09 INFO - 'download_symbols': 'ondemand', 16:10:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:09 INFO - 'installer_path': 'installer.exe', 16:10:09 INFO - 'log_level': 'info', 16:10:09 INFO - 'log_name': 'talos', 16:10:09 INFO - 'log_to_console': True, 16:10:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:10 INFO - 'opt_config_files': (), 16:10:10 INFO - 'pip_index': False, 16:10:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:10 INFO - 'cmd': ('bash', 16:10:10 INFO - '-c', 16:10:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:10 INFO - 'enabled': True, 16:10:10 INFO - 'halt_on_failure': False, 16:10:10 INFO - 'name': 'check_screen_resolution'},), 16:10:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:10 INFO - 'cmd': ('bash', 16:10:10 INFO - '-c', 16:10:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:10 INFO - 'enabled': True, 16:10:10 INFO - 'halt_on_failure': False, 16:10:10 INFO - 'name': 'check_screen_resolution'},), 16:10:10 INFO - 'run_cmd_checks_enabled': True, 16:10:10 INFO - 'sps_profile': False, 16:10:10 INFO - 'sps_profile_interval': 0, 16:10:10 INFO - 'suite': 'dromaeojs', 16:10:10 INFO - 'system_bits': '32', 16:10:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:10 INFO - 'title': 't-yosemite-r7-0302', 16:10:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:10 INFO - 'use_talos_json': True, 16:10:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:10 INFO - 'work_dir': 'build'} 16:10:10 INFO - ##### 16:10:10 INFO - ##### Running clobber step. 16:10:10 INFO - ##### 16:10:10 INFO - Running pre-action listener: _resource_record_pre_action 16:10:10 INFO - Running main action method: clobber 16:10:10 INFO - rmtree: /builds/slave/test/build 16:10:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:11 INFO - Running post-action listener: _resource_record_post_action 16:10:11 INFO - ##### 16:10:11 INFO - ##### Running read-buildbot-config step. 16:10:11 INFO - ##### 16:10:11 INFO - Running pre-action listener: _resource_record_pre_action 16:10:11 INFO - Running main action method: read_buildbot_config 16:10:11 INFO - Using buildbot properties: 16:10:11 INFO - { 16:10:11 INFO - "project": "", 16:10:12 INFO - "product": "firefox", 16:10:12 INFO - "script_repo_revision": "production", 16:10:12 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:10:12 INFO - "repository": "", 16:10:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:10:12 INFO - "buildid": "20160602140837", 16:10:12 INFO - "pgo_build": "False", 16:10:12 INFO - "basedir": "/builds/slave/test", 16:10:12 INFO - "buildnumber": 30, 16:10:12 INFO - "slavename": "t-yosemite-r7-0302", 16:10:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:10:12 INFO - "platform": "yosemite_r7", 16:10:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:10:12 INFO - "branch": "mozilla-beta", 16:10:12 INFO - "repo_path": "releases/mozilla-beta", 16:10:12 INFO - "revision": "6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea", 16:10:12 INFO - "stage_platform": "macosx64", 16:10:12 INFO - "builduid": "893fdbbbce3544769bc98c5128e2bb0b", 16:10:12 INFO - "slavebuilddir": "test" 16:10:12 INFO - } 16:10:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:10:12 INFO - Running post-action listener: _resource_record_post_action 16:10:12 INFO - ##### 16:10:12 INFO - ##### Running download-and-extract step. 16:10:12 INFO - ##### 16:10:12 INFO - Running pre-action listener: _resource_record_pre_action 16:10:12 INFO - Running main action method: download_and_extract 16:10:12 INFO - mkdir: /builds/slave/test/build/tests 16:10:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:12 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:10:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:10:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:10:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:10:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:10:17 INFO - Downloaded 1241 bytes. 16:10:17 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:10:17 INFO - Using the following test package requirements: 16:10:17 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:10:17 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:10:17 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:10:17 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:10:17 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:10:17 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:10:17 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:10:17 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:10:17 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:10:17 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:10:17 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:10:17 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:10:17 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:10:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:17 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:10:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:10:18 INFO - Downloaded 17816944 bytes. 16:10:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:10:21 INFO - Return code: 0 16:10:21 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:21 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:10:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:10:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:10:22 INFO - Downloaded 17816944 bytes. 16:10:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:10:26 INFO - Return code: 0 16:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:26 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:10:27 INFO - Downloaded 10845194 bytes. 16:10:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:10:27 INFO - Return code: 0 16:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:27 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:10:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:10:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:10:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:10:30 INFO - Downloaded 88466458 bytes. 16:10:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:10:30 INFO - mkdir: /builds/slave/test/properties 16:10:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:30 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:30 INFO - Contents: 16:10:30 INFO - build_url:https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:10:31 INFO - Running post-action listener: _resource_record_post_action 16:10:31 INFO - Running post-action listener: set_extra_try_arguments 16:10:31 INFO - ##### 16:10:31 INFO - ##### Running populate-webroot step. 16:10:31 INFO - ##### 16:10:31 INFO - Running pre-action listener: _resource_record_pre_action 16:10:31 INFO - Running main action method: populate_webroot 16:10:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:31 INFO - '--tppagecycles', 16:10:31 INFO - '7'], 16:10:31 INFO - 'tests': ['tsvgm']}}, 16:10:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:10:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:10:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['damp', 'tps']}, 16:10:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['damp', 'tps']}, 16:10:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:10:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:31 INFO - 'other': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other_l64': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other_nol64': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'svgr': {'tests': ['tsvgx', 16:10:31 INFO - 'tsvgr_opacity', 16:10:31 INFO - 'tart', 16:10:31 INFO - 'tscrollx', 16:10:31 INFO - 'cart']}, 16:10:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['tsvgx', 16:10:31 INFO - 'tsvgr_opacity', 16:10:31 INFO - 'tart', 16:10:31 INFO - 'tscrollx', 16:10:31 INFO - 'cart']}, 16:10:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['tp5o']}, 16:10:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--e10s'], 16:10:31 INFO - 'tests': ['tp5o']}, 16:10:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--xperf_path', 16:10:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:31 INFO - 'tests': ['tp5n']}, 16:10:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--e10s', 16:10:31 INFO - '--xperf_path', 16:10:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:31 INFO - 'tests': ['tp5n']}}, 16:10:31 INFO - 'talos.zip': {'path': '', 16:10:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:31 INFO - Running post-action listener: _resource_record_post_action 16:10:31 INFO - ##### 16:10:31 INFO - ##### Running create-virtualenv step. 16:10:31 INFO - ##### 16:10:31 INFO - Running pre-action listener: _resource_record_pre_action 16:10:31 INFO - Running main action method: create_virtualenv 16:10:31 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:31 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 16:10:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:31 INFO - Using real prefix '/tools/python27' 16:10:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:32 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:34 INFO - Installing pip.................done. 16:10:34 INFO - Return code: 0 16:10:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 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 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:34 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 16:10:34 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 16:10:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:34 INFO - 'HOME': '/Users/cltbld', 16:10:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:34 INFO - 'LOGNAME': 'cltbld', 16:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:34 INFO - 'MOZ_NO_REMOTE': '1', 16:10:34 INFO - 'NO_EM_RESTART': '1', 16:10:34 INFO - 'PAGER': '/bin/cat', 16:10:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:34 INFO - 'PWD': '/builds/slave/test', 16:10:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:34 INFO - 'SHELL': '/bin/bash', 16:10:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:34 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:34 INFO - 'USER': 'cltbld', 16:10:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:34 INFO - 'XPC_FLAGS': '0x0', 16:10:34 INFO - 'XPC_SERVICE_NAME': '0', 16:10:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:35 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 16:10:35 INFO - Cleaning up... 16:10:35 INFO - Return code: 0 16:10:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:10:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:35 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>=0.7.1'] in /builds/slave/test/build 16:10:35 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>=0.7.1 16:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:35 INFO - 'HOME': '/Users/cltbld', 16:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:35 INFO - 'LOGNAME': 'cltbld', 16:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:35 INFO - 'MOZ_NO_REMOTE': '1', 16:10:35 INFO - 'NO_EM_RESTART': '1', 16:10:35 INFO - 'PAGER': '/bin/cat', 16:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:35 INFO - 'PWD': '/builds/slave/test', 16:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:35 INFO - 'SHELL': '/bin/bash', 16:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:35 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:35 INFO - 'USER': 'cltbld', 16:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:35 INFO - 'XPC_FLAGS': '0x0', 16:10:35 INFO - 'XPC_SERVICE_NAME': '0', 16:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:35 INFO - Downloading/unpacking psutil>=0.7.1 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:38 INFO - Installing collected packages: psutil 16:10:38 INFO - Running setup.py install for psutil 16:10:38 INFO - building 'psutil._psutil_osx' extension 16:10:38 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 16:10:39 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 16:10:39 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 16:10:39 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 16:10:39 INFO - building 'psutil._psutil_posix' extension 16:10:39 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 16:10:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:39 INFO - ^ 16:10:39 INFO - 1 warning generated. 16:10:39 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 16:10:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:39 INFO - Successfully installed psutil 16:10:39 INFO - Cleaning up... 16:10:39 INFO - Return code: 0 16:10:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:10:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:39 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:39 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.0.0'] in /builds/slave/test/build 16:10:39 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.0.0 16:10:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:39 INFO - 'HOME': '/Users/cltbld', 16:10:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:39 INFO - 'LOGNAME': 'cltbld', 16:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:39 INFO - 'MOZ_NO_REMOTE': '1', 16:10:39 INFO - 'NO_EM_RESTART': '1', 16:10:39 INFO - 'PAGER': '/bin/cat', 16:10:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:39 INFO - 'PWD': '/builds/slave/test', 16:10:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:39 INFO - 'SHELL': '/bin/bash', 16:10:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:39 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:39 INFO - 'USER': 'cltbld', 16:10:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:39 INFO - 'XPC_FLAGS': '0x0', 16:10:39 INFO - 'XPC_SERVICE_NAME': '0', 16:10:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:10:39 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 16:10:39 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 16:10:39 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 16:10:39 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 16:10:39 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 16:10:39 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 16:10:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:10:42 INFO - Installing collected packages: mozsystemmonitor 16:10:42 INFO - Running setup.py install for mozsystemmonitor 16:10:42 INFO - Successfully installed mozsystemmonitor 16:10:42 INFO - Cleaning up... 16:10:42 INFO - Return code: 0 16:10:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:42 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 16:10:42 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 16:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:42 INFO - 'HOME': '/Users/cltbld', 16:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:42 INFO - 'LOGNAME': 'cltbld', 16:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:42 INFO - 'MOZ_NO_REMOTE': '1', 16:10:42 INFO - 'NO_EM_RESTART': '1', 16:10:42 INFO - 'PAGER': '/bin/cat', 16:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:42 INFO - 'PWD': '/builds/slave/test', 16:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:42 INFO - 'SHELL': '/bin/bash', 16:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:42 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:42 INFO - 'USER': 'cltbld', 16:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:42 INFO - 'XPC_FLAGS': '0x0', 16:10:42 INFO - 'XPC_SERVICE_NAME': '0', 16:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:45 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:46 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 16:10:46 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 16:10:46 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 16:10:46 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 16:10:46 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 16:10:46 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 16:10:46 INFO - Downloading docopt-0.6.1.tar.gz 16:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:46 INFO - Running setup.py install for blobuploader 16:10:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:46 INFO - Running setup.py install for requests 16:10:47 INFO - Running setup.py install for docopt 16:10:47 INFO - Successfully installed blobuploader requests docopt 16:10:47 INFO - Cleaning up... 16:10:47 INFO - Return code: 0 16:10:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 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 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:47 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 16:10:47 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 16:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:47 INFO - 'HOME': '/Users/cltbld', 16:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:47 INFO - 'LOGNAME': 'cltbld', 16:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:47 INFO - 'MOZ_NO_REMOTE': '1', 16:10:47 INFO - 'NO_EM_RESTART': '1', 16:10:47 INFO - 'PAGER': '/bin/cat', 16:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:47 INFO - 'PWD': '/builds/slave/test', 16:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:47 INFO - 'SHELL': '/bin/bash', 16:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:47 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:47 INFO - 'USER': 'cltbld', 16:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:47 INFO - 'XPC_FLAGS': '0x0', 16:10:47 INFO - 'XPC_SERVICE_NAME': '0', 16:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:47 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-wQloba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:47 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-O01mIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:47 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-3uSQGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Rat4Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-2c8_J2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-pRmMNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-0WZFRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-_nWJSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-vPKUTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-7o3PYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-QQLmJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:48 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-NLBmiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:49 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-e_SXpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:49 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-gFsXML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:49 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-LI6q3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:49 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-_ZbdRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:49 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-jtLHO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:49 INFO - Running setup.py install for manifestparser 16:10:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:49 INFO - Running setup.py install for mozcrash 16:10:49 INFO - Running setup.py install for mozdebug 16:10:49 INFO - Running setup.py install for mozdevice 16:10:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozfile 16:10:50 INFO - Running setup.py install for mozhttpd 16:10:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozinfo 16:10:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozInstall 16:10:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozleak 16:10:50 INFO - Running setup.py install for mozlog 16:10:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for moznetwork 16:10:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for mozprocess 16:10:51 INFO - Running setup.py install for mozprofile 16:10:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for mozrunner 16:10:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for mozscreenshot 16:10:51 INFO - Running setup.py install for moztest 16:10:51 INFO - Running setup.py install for mozversion 16:10:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:52 INFO - Cleaning up... 16:10:52 INFO - Return code: 0 16:10:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:52 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 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:52 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 16:10:52 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 16:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:52 INFO - 'HOME': '/Users/cltbld', 16:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:52 INFO - 'LOGNAME': 'cltbld', 16:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:52 INFO - 'MOZ_NO_REMOTE': '1', 16:10:52 INFO - 'NO_EM_RESTART': '1', 16:10:52 INFO - 'PAGER': '/bin/cat', 16:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:52 INFO - 'PWD': '/builds/slave/test', 16:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:52 INFO - 'SHELL': '/bin/bash', 16:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:52 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:52 INFO - 'USER': 'cltbld', 16:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:52 INFO - 'XPC_FLAGS': '0x0', 16:10:52 INFO - 'XPC_SERVICE_NAME': '0', 16:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-l0k556-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-mXeNuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-iyE_q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-5nMJPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-PzWnN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-98O13K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-YXqHYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-veKq75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-4ARz3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-YqlZQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-9IyBWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-V_PtTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-PiifJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-mkaPFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-3cCp7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:53 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-r4S2Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:54 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)) 16:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:54 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-hhxSgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:54 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)) 16:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:54 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)) 16:10:54 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)) 16:10:54 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)) 16:10:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:54 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 16:10:54 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 16:10:54 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 16:10:54 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 16:10:54 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 16:10:54 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 16:10:56 INFO - Downloading blessings-1.6.tar.gz 16:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:56 INFO - Installing collected packages: blessings 16:10:56 INFO - Running setup.py install for blessings 16:10:56 INFO - Successfully installed blessings 16:10:56 INFO - Cleaning up... 16:10:56 INFO - Return code: 0 16:10:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:57 INFO - Reading from file tmpfile_stdout 16:10:57 INFO - Current package versions: 16:10:57 INFO - blessings == 1.6 16:10:57 INFO - blobuploader == 1.2.4 16:10:57 INFO - docopt == 0.6.1 16:10:57 INFO - manifestparser == 1.1 16:10:57 INFO - mozInstall == 1.12 16:10:57 INFO - mozcrash == 0.17 16:10:57 INFO - mozdebug == 0.1 16:10:57 INFO - mozdevice == 0.48 16:10:57 INFO - mozfile == 1.2 16:10:57 INFO - mozhttpd == 0.7 16:10:57 INFO - mozinfo == 0.9 16:10:57 INFO - mozleak == 0.1 16:10:57 INFO - mozlog == 3.1 16:10:57 INFO - moznetwork == 0.27 16:10:57 INFO - mozprocess == 0.22 16:10:57 INFO - mozprofile == 0.28 16:10:57 INFO - mozrunner == 6.11 16:10:57 INFO - mozscreenshot == 0.1 16:10:57 INFO - mozsystemmonitor == 0.0 16:10:57 INFO - moztest == 0.7 16:10:57 INFO - mozversion == 1.4 16:10:57 INFO - psutil == 3.1.1 16:10:57 INFO - requests == 1.2.3 16:10:57 INFO - wsgiref == 0.1.2 16:10:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 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 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:57 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 16:10:57 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 16:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:57 INFO - 'HOME': '/Users/cltbld', 16:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:57 INFO - 'LOGNAME': 'cltbld', 16:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:57 INFO - 'MOZ_NO_REMOTE': '1', 16:10:57 INFO - 'NO_EM_RESTART': '1', 16:10:57 INFO - 'PAGER': '/bin/cat', 16:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:57 INFO - 'PWD': '/builds/slave/test', 16:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:57 INFO - 'SHELL': '/bin/bash', 16:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:57 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:57 INFO - 'USER': 'cltbld', 16:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:57 INFO - 'XPC_FLAGS': '0x0', 16:10:57 INFO - 'XPC_SERVICE_NAME': '0', 16:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 INFO - Cleaning up... 16:10:57 INFO - Return code: 0 16:10:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:57 INFO - 'HOME': '/Users/cltbld', 16:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:57 INFO - 'LOGNAME': 'cltbld', 16:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:57 INFO - 'MOZ_NO_REMOTE': '1', 16:10:57 INFO - 'NO_EM_RESTART': '1', 16:10:57 INFO - 'PAGER': '/bin/cat', 16:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:57 INFO - 'PWD': '/builds/slave/test', 16:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:57 INFO - 'SHELL': '/bin/bash', 16:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:10:57 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:57 INFO - 'USER': 'cltbld', 16:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:57 INFO - 'XPC_FLAGS': '0x0', 16:10:57 INFO - 'XPC_SERVICE_NAME': '0', 16:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:57 INFO - Downloading/unpacking jsonschema 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:11:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:00 INFO - Downloading/unpacking functools32 (from jsonschema) 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:00 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:00 INFO - warning: no files found matching '*.txt' 16:11:00 INFO - no previously-included directories found matching 'build' 16:11:00 INFO - no previously-included directories found matching 'dist' 16:11:00 INFO - no previously-included directories found matching '.git*' 16:11:00 INFO - Installing collected packages: jsonschema, functools32 16:11:00 INFO - Running setup.py install for functools32 16:11:00 INFO - warning: no files found matching '*.txt' 16:11:00 INFO - no previously-included directories found matching 'build' 16:11:00 INFO - no previously-included directories found matching 'dist' 16:11:00 INFO - no previously-included directories found matching '.git*' 16:11:01 INFO - Successfully installed jsonschema functools32 16:11:01 INFO - Cleaning up... 16:11:01 INFO - Return code: 0 16:11:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:11:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:01 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42056e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, '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.LBjAoxbshg/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.km36bxtkZo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 16:11:01 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', 'flake8'] in /builds/slave/test/build 16:11:01 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 flake8 16:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:01 INFO - 'HOME': '/Users/cltbld', 16:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:01 INFO - 'LOGNAME': 'cltbld', 16:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:01 INFO - 'MOZ_NO_REMOTE': '1', 16:11:01 INFO - 'NO_EM_RESTART': '1', 16:11:01 INFO - 'PAGER': '/bin/cat', 16:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:01 INFO - 'PWD': '/builds/slave/test', 16:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:01 INFO - 'SHELL': '/bin/bash', 16:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:11:01 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:01 INFO - 'USER': 'cltbld', 16:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:01 INFO - 'XPC_FLAGS': '0x0', 16:11:01 INFO - 'XPC_SERVICE_NAME': '0', 16:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:01 INFO - Downloading/unpacking flake8 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:11:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:11:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:11:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:11:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:11:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:11:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:11:05 INFO - no previously-included directories found matching 'docs/_build' 16:11:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:11:05 INFO - Running setup.py install for pep8 16:11:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:11:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:11:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:11:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:11:05 INFO - no previously-included directories found matching 'docs/_build' 16:11:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:11:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:11:05 INFO - Cleaning up... 16:11:05 INFO - Return code: 0 16:11:05 INFO - Running post-action listener: _resource_record_post_action 16:11:05 INFO - Running post-action listener: _start_resource_monitoring 16:11:05 INFO - Starting resource monitoring. 16:11:05 INFO - ##### 16:11:05 INFO - ##### Running install step. 16:11:05 INFO - ##### 16:11:05 INFO - Running pre-action listener: _resource_record_pre_action 16:11:05 INFO - Running main action method: install 16:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:05 INFO - Reading from file tmpfile_stdout 16:11:05 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:05 INFO - Reading from file tmpfile_stdout 16:11:05 INFO - Output received: 16:11:05 INFO - Usage: mozinstall [options] installer 16:11:05 INFO - Options: 16:11:05 INFO - -h, --help show this help message and exit 16:11:05 INFO - -d DEST, --destination=DEST 16:11:05 INFO - Directory to install application into. [default: 16:11:05 INFO - "/builds/slave/test"] 16:11:05 INFO - --app=APP Application being installed. [default: firefox] 16:11:05 INFO - mkdir: /builds/slave/test/build/application 16:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:11:28 INFO - Reading from file tmpfile_stdout 16:11:28 INFO - Output received: 16:11:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:11:28 INFO - Running post-action listener: _resource_record_post_action 16:11:28 INFO - ##### 16:11:28 INFO - ##### Running run-tests step. 16:11:28 INFO - ##### 16:11:28 INFO - Running pre-action listener: _resource_record_pre_action 16:11:28 INFO - Running main action method: run_tests 16:11:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:28 INFO - 2016-06-02 16:11:28.184 screenresolution[3184:30846] starting screenresolution argv=screenresolution get 16:11:28 INFO - 2016-06-02 16:11:28.187 screenresolution[3184:30846] Display 0: 1600x1200x32@60 16:11:28 INFO - 2016-06-02 16:11:28.193 screenresolution[3185:30848] starting screenresolution argv=screenresolution list 16:11:28 INFO - Available Modes on Display 0 16:11:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:28 INFO - Intel Iris: 16:11:28 INFO - Chipset Model: Intel Iris 16:11:28 INFO - Type: GPU 16:11:28 INFO - Bus: Built-In 16:11:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:28 INFO - Vendor: Intel (0x8086) 16:11:28 INFO - Device ID: 0x0a2e 16:11:28 INFO - Revision ID: 0x0009 16:11:28 INFO - Displays: 16:11:28 INFO - Display: 16:11:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:28 INFO - Display Serial Number: 1600x1200 60 16:11:28 INFO - Main Display: Yes 16:11:28 INFO - Mirror: Off 16:11:28 INFO - Online: Yes 16:11:28 INFO - Rotation: Supported 16:11:28 INFO - Return code: 0 16:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:28 INFO - Python 2.7.3 16:11:28 INFO - Return code: 0 16:11:28 INFO - grabbing minidump binary from tooltool 16:11:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:28 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fda9d30>, '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 0x10fc0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fda0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:28 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 16:11:28 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 16:11:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:28 INFO - Return code: 0 16:11:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:11:29 INFO - Return code: 0 16:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0302 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LBjAoxbshg/Render', 16:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:29 INFO - 'HOME': '/Users/cltbld', 16:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:29 INFO - 'LOGNAME': 'cltbld', 16:11:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:29 INFO - 'MOZ_NO_REMOTE': '1', 16:11:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:29 INFO - 'NO_EM_RESTART': '1', 16:11:29 INFO - 'PAGER': '/bin/cat', 16:11:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:29 INFO - 'PWD': '/builds/slave/test', 16:11:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:29 INFO - 'SHELL': '/bin/bash', 16:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.km36bxtkZo/Listeners', 16:11:29 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 16:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:29 INFO - 'USER': 'cltbld', 16:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:29 INFO - 'XPC_FLAGS': '0x0', 16:11:29 INFO - 'XPC_SERVICE_NAME': '0', 16:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:11:29 INFO - mozversion application_buildid: 20160602140837 16:11:29 INFO - mozversion application_changeset: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea 16:11:29 INFO - mozversion application_display_name: Firefox 16:11:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:29 INFO - mozversion application_name: Firefox 16:11:29 INFO - mozversion application_remotingname: firefox 16:11:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:11:29 INFO - mozversion application_vendor: Mozilla 16:11:29 INFO - mozversion application_version: 47.0 16:11:29 INFO - mozversion platform_buildid: 20160602140837 16:11:29 INFO - mozversion platform_changeset: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea 16:11:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:11:29 INFO - mozversion platform_version: 47.0 16:11:29 INFO - using testdate: 1464909089 16:11:29 INFO - actual date: 1464909089 16:11:29 INFO - starting webserver on 'localhost:59251' 16:11:29 INFO - SUITE-START | Running 2 tests 16:11:29 INFO - TEST-START | dromaeo_css 16:11:29 INFO - operating with platform_type : mac_ 16:11:29 INFO - Initialising browser for dromaeo_css test... 16:11:30 INFO - TEST-INFO | started process 3203 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmp024NaL/profile http://localhost:59251/getInfo.html) 16:11:32 INFO - PROCESS | 3203 | __metrics Screen width/height:1600/1200 16:11:32 INFO - PROCESS | 3203 | colorDepth:24 16:11:32 INFO - PROCESS | 3203 | Browser inner width/height: 1024/689 16:11:32 INFO - PROCESS | 3203 | __metrics 16:11:32 INFO - PROCESS | 3203 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:11:32 INFO - PROCESS | 3203 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:33 INFO - TEST-INFO | 3203: exit 0 16:11:33 INFO - Browser initialized. 16:11:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:33 INFO - TEST-INFO | started process 3207 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmp024NaL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:44 INFO - PROCESS | 3207 | RSS: Main: 157011968 16:11:44 INFO - PROCESS | 3207 | 16:12:35 INFO - PROCESS | 3207 | 1464909155595 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] 16:14:45 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59251/tests/dromaeo/cssquery-ext.html) 16:14:46 INFO - PROCESS | 3207 | RSS: Main: 286273536 16:14:46 INFO - PROCESS | 3207 | 16:17:49 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-ext.html (next: http://localhost:59251/tests/dromaeo/cssquery-jquery.html) 16:17:49 INFO - PROCESS | 3207 | RSS: Main: 291373056 16:17:49 INFO - PROCESS | 3207 | 16:20:51 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59251/tests/dromaeo/cssquery-mootools.html) 16:20:52 INFO - PROCESS | 3207 | RSS: Main: 298233856 16:20:52 INFO - PROCESS | 3207 | 16:23:54 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59251/tests/dromaeo/cssquery-prototype.html) 16:23:54 INFO - PROCESS | 3207 | RSS: Main: 256966656 16:23:54 INFO - PROCESS | 3207 | 16:26:57 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59251/tests/dromaeo/cssquery-yui.html) 16:26:58 INFO - PROCESS | 3207 | RSS: Main: 257269760 16:26:58 INFO - PROCESS | 3207 | 16:30:00 INFO - PROCESS | 3207 | Cycle 1(1): loaded http://localhost:59251/tests/dromaeo/cssquery-yui.html (next: http://localhost:59251/tests/dromaeo/cssquery-dojo.html) 16:30:01 INFO - PROCESS | 3207 | RSS: Main: 302010368 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | __start_tp_report 16:30:01 INFO - PROCESS | 3207 | _x_x_mozilla_page_load 16:30:01 INFO - PROCESS | 3207 | _x_x_mozilla_page_load_details 16:30:01 INFO - PROCESS | 3207 | |i|pagename|runs| 16:30:01 INFO - PROCESS | 3207 | |0;dojo.html;3526.488659144424;3582.982085089574;3588.80261585613;3615.981920090399;3659.1401020760118;5814;5827.880725238499;5833.970830145854;5846.970765146174;5869.325027621825;5801.738921748521;5860.706964651761;5861.472467477926;5866.296044474669;5866.941330586693;4478.104379124188;5188.455212202721;5219.5041471060185;5267.209918512215;5300.231466437364;5366.382865970424;5383.650062745909;5384.40771515133;5389.6496727712765;5394.487523685263;5315.707636080014;5334.199870019509;5362.624616276862;5369.355677318727;5373.516383525482;5370.865728356784;5398.082326004589;5409.540189083933;5416.241726158341;5424.864378390533;5133.922991155136;5308.469153084699;5315.867103322448;5320.813771517978;5339.41266460689;5664.54683625309;5701.6864072476;5729.4843464088435;5729.914051289234;5742.28221472316;6219.626822390625;6344.016677415008;6354.3963323484195;6359.077933699634;6368.745250189987;6264.968675156595;6293.87412251757;6328.493720502349;6337.239531256233;6338.651374174418;5956.315023772278;5977.581569290108;5979.043353063489;5982.910256346071;5988.101784732282;5333.359996800331;6192.133101365813;6285.434310911948;6347.619142851444;6349.682515874188;6166.321704612489;6216.968915155395;6230.875382492325;6245.406686364788;6339.207599050119;6320.873582528324;6321.715522801485;6326.746930122836;6342.080398342307;6350.809475715736;6300.684965751694;6344.953082741296;6347.333529979378;6350.7777227796805;6380.106785050097;6196.194494715748;6289.150964619809;6324.557280990379;6349.238091429058;6371.808845734636;4995.575376093073;5011.899762004739;5020.096382651085;5021.020900924284;5022;4010.2981978153707;4563.178627847019;4568.588827005586;4569.748663823521;4573.862784116482;5986.73059712314;6012.518998480111;6026.879462410728;6035.185249991283;6037.215162028996;6298.622082675054;6300.842478938063;6300.936990630035;6302;6315.905261421082;5910.8817823643285;5916.349201587908;5986.760529578768;6004.519638428916;6018.759249630053;5469.507744302952;5488;5493.917591236134;5496.560275177976;5509.531689806411;6261.62430254168;6332.1135041094285;6361.077643741632;6375.6174629522375;6389.616623002635;6239.656818875005;6305.5901366411035;6322.462590679844;6334.176557047738;6352.968235158794;6367.267764207176;6369.9681501592195;6380.170577825039;6381.1704478417505;6382.106505089384;6169.01295792671;6335.556511044183;6337.77817776394;6361.840953976188;6375.585586936834;5258.2375555544995;6165.075238714228;6217.160683307877;6323.08315294289;6331.2085989251345;6178.382161783786;6208.348123446973;6228.4705800005695;6244.281907580505;6253.96873015632;6119.265688117454;6163.106349579196;6208.906866397098;6209.975354066709;6240.594361366497;4971;6052.970994930961;6068.575199735976;6080.635161890301;6100.298465676504;5111.539961403491;6234.657093859882;6249;6253.937460625336;6262.3111457738705;5990.820275391745;6086.573939824347;6100.993336099494;6111.816645500642;6130.172426722335 16:30:01 INFO - PROCESS | 3207 | |1;ext.html;448.4282539761803;578.648854923753;583.7489879351878;661.2792056658242;676.5724321681254;30930.226744331376;39953;40169;40184.19631607366;40187.59812401879;42976.35535466971;43390;43393.78303108488;43548.34677479832;43549.78225108878;9908.653197138101;10130.986901309883;10141.391516509033;10153.035461631132;10157.28898977072;9874.851877221847;10026.899731002706;10027.548760305805;10042.19662427004;10043.69868903934;10017.599296028151;10094.545745441474;10094.949525252363;10095.899041009568;10111.949440252789;9949.204063674924;9970.601175952954;10016;10048.095671389538;10101.69694909154;9840.753981150458;9975.75060623483;9989.950050249776;9996.200303975736;10003.299769016172;42638;42674.359884601756;42756.358654620206;42779.57220427818;42861.78569107166;3214.1161180675267;3459.2562599041325;3626.0753507855593;3799.3351163546545;3964.1278918637854;29093.41813163746;29534.852325738448;29956.101316960525;29963.400731985457;30441.934532291485;2745.8215216011095;2753.6144939708256;2810.339570201001;2820.19624407043;2835.4045650413436;486021;488071;488321.11678883113;489228;489570;47255.5274447258;64537;65567.34432655708;65767.67116164484;65839;47382.5261747385;47519.287210691866;47554;47624.523754762704;47625;17181.91409042947;17546.736798948026;17556.736648950275;17557.385491507735;17561.85847919881;14061.437542498186;14130;14160;14164.645883852987;14177.645558860904;15131.167785771668;15145.939784215;15219.467318643794;15336.309866056054;15355.923220383827;6259.7496100156395;6375.904361434582;6419.720999045365;6425.839354016187;6433.163688720437;1000.6741068084817;1022.9693109206736;1023.8054769593754;1024.2113572549094;1149.672343382132;29491.705082949324;30721.924732634696;30777.69222307793;31139.37721245608;31591.52612710811;1097.6103483263487;1167.3871217610822;1178.3695722788268;1183.9940800295944;1191.1007189571992;15530.922345388426;15594.064356138668;15642.68714625701;15801;15934.9203253983;542451;546629;549276;550929.4907050958;551233;62387;62867.685661572315;63093.68453157705;63116;63133;1330.4412146898326;1417.8723914847712;1470.8455612160783;1545.4436402894949;1558.680470503544;29233.269168270963;29526.55710164392;29613.85193074021;29784.702152978192;30379;47245.76377118092;47492.52507475019;47571.048579027534;47611.52388476071;47650.76174619105;354223;370912;371291;371306.1434692809;373420;29994.95017674371;34051.25436201966;34414.82792586021;34749.30501389907;35344.64655353517;49502.75248623734;50093.747656308886;50423;50710.746446267534;51902.480975191276;28618;36088.097797555274;36570.634293657786;36878.63121368752;38606.718428932734;36823.815880920425;36887.44668830023;37094.62905370912;37826.810865945496;37845.62154378531;37743.05642358853;39998.000049998984;40520.78437646875;41368;41584.584154158074;31712.524312134876;33393.99818005464;33454.33091338208;33545;33585.16037099092;22951.88524057369;24708.876455617607;26103.608445872946;26241.34396640023;26832 16:30:01 INFO - PROCESS | 3207 | |2;jquery.html;316.4983501150676;372.2443439817171;376.23248572911245;388.60168327464345;391;20522.38432847015;21575.56848863022;22253;23265.83670816461;23303.417414564643;22764.430889227777;22788.88605556974;23011.42471438215;23044.76955230447;23339.416514587145;5399.460053994609;5782.624129431573;6091.451769340759;6111.908321375183;6112.235970503687;6110.8472288192925;6199.163112979758;6234.345393733661;6250.562460627758;6261.937380626204;5871.178035075071;6061.181740465047;6063.454289113979;6187.814365569017;6196.938030619681;5993.101034844786;6010.068439391879;6038.245219347582;6050.636961782263;6055.152278680988;5083.745812709387;5242.951409718072;5255.921161182585;5372.113601255788;5381.892362152774;27443.86278068617;27655.170344889684;27738.861305693543;27858.303542411602;28072.719272807215;2294.0365046680436;2353.4351755578537;2433.671454353675;2483.3170878008514;2832.815866968661;15455.127155845905;15644.530664079983;15740.842591574166;15789.052656840626;16296.674066518603;1622.594351412147;1697.609549803552;1727.8531324837404;1760.4366602687146;1796.2276221224868;104318;105191;106283;107113.46443267734;107410.92589074165;10826.891731082746;11064.28082174656;11072.224944253825;11075.889241107487;11261.887381126247;13681.863181368259;13689.904807615367;13713.931430342784;13740;13760.761531462216;8909.866352004725;8933.955330223307;8934.329925255503;8978.955105224431;9033.277337812959;9296.628134874665;9330.253579713606;9358.625654973861;9370.250379969586;9449;5792.913106303409;5986.790462333797;6359.491240700733;6366.26787919386;6371.076193951851;1812.7643406357354;1842.824931631493;1854.2860998515669;1865.9160337784833;1885.283592234942;955.350361754014;966.8936416994125;1012.8936461671566;1030.953607087683;1039.9844002339971;20974.580508389747;21872.23447179373;22015.669764953538;22222.777772222395;22253.443663908532;1014.5535964175739;1034.8758149022015;1049.7690508088208;1078.4338222433128;1093.491526440209;11599.304041757521;11847.29970267389;11922.10584206188;12006.51973921051;12027;660065;668681;669915;685251;689951;100280;104312.47843760732;107926.46036769765;109473;110486.44756776326;1082.2965072702807;1090.1442367741286;1102.536934487517;1104.4588151805558;1107.3355986408092;18710.158042888375;19273.51816204606;19476.61046779028;19548.02316343821;19601.901990489958;47229.76385118053;49287.01425971389;49302.50697492979;49450.75274623604;51672.224916627034;45112.09775804531;61408.385916140265;66130.33869661242;66249;66401.33598663952;7986.960065199637;8030.23712747288;8095.71664991746;8147.388945828968;8183.304419124441;11931.76136477283;12766.87233127694;13078.411471483996;13211.207327560378;13318.600441986757;11539.769204615694;11680.883191167979;11791.941040295087;11864.288142711464;11934;11391.259568128045;11519.481623326803;11582.536698531965;11646.475908583965;11716;11682.4742886572;11826.46780894879;11859.762804744028;11951.342676152548;12009.159358845147;8107.270345668918;8132.43072984909;8231.316426495981;8517.318614510948;8599.871001934851;8713.695020674499;10066.899331006596;11209.495572699085;12016.519339226334;12133.029357651543 16:30:01 INFO - PROCESS | 3207 | |3;mootools.html;1438.1586771738532;1464.341046529062;1668.0408764960148;1974.4570243183123;2117.703521506988;7652.846943061136;8870.467771933689;9105.317101217403;9106.362554621179;9133.954330228356;8657.567121643924;9059.320550958688;9074.682386116487;9075.319351048682;9087.363884528086;2523.470071285032;2552.310876063471;2553.565893798059;2554.1060628779965;2563.269468201563;2482.3173627252468;2572.935676608081;2577.1495406515896;2578.7808036316937;2589.8316609420326;2109.3566462229014;2574.8197626166175;2577.36854470654;2577.9871100644564;2578.8065895057853;2518.055729101587;2551.2728872271405;2554.4124851284123;2559.25781523358;2567.0886835173433;2525.1540733854;2526.12848567244;2536.8985240590355;2540.720520742717;2572.742725727431;9109.99790023097;9193.678221262291;9203.171714545657;9207.585658645377;9258.074192580756;5570.693611851347;5723.399043100456;5729.226554415142;5737.053386191274;5779.075347944308;3242.6757324267383;3692.390755525335;3744.588095309514;3823.50294461721;3826.8086595670384;1060.204846365226;1080.2330345454736;1082.2802836114054;1089.5260561655625;1112.3270421395102;12560.937195314056;12570.245785252913;12584.622461326175;12586.43361048755;12590.559330423483;4939.975300123477;4955.876103097451;5013.498650134958;5055.747212639353;5087.491250874883;10468;10503.52734126966;10527.947360263302;10532.052115309505;10541.420221887869;5735.053716136874;5735.2257445245195;5746.971265143731;5749.7700091996685;5775.768969241268;4615.376924115201;4685.344051832746;4723.5984941279685;4728.1252968201;5188.014277287303;3694.150345420568;3812.7902965336616;4011.819468123883;4017.055991841915;4029.476168098187;1322.8941684665203;1348.7841945288706;1350.6488313038485;1360.462617266194;1361.4486133116104;1190.2382475215868;1212.933288669114;1221.407617305606;1411.5130280053365;1442.9134251944918;8374.748757537283;8415.074341822394;8441.986961564651;8449.23956843887;8654.048054713976;991.6529214774771;1064.5209655654987;1249.9125061245807;1252.136026141959;1256.6983923858209;6379.298277189506;6418.19772528434;6461.838454038687;6471.255805582418;6485.092087107809;10924.508397122148;11225.66323010311;11306.547738090549;11326.660200194008;11377.54489820398;10852.186086043579;11103.611373601885;11141;11264.83102753443;11276.379799111128;8733.126687331216;8755.124487551195;8757.47455152693;8796.736097917073;8821.029686734453;8137.79655508617;8142.226488483585;8149.9185008151535;8243.134470880472;8244.340452763885;9584.04159584064;9932.602695892083;9975.60097596088;10045.447500387549;10164.136048435967;10044.54799534008;10374.740631484274;10388.324758890649;10389.792204156025;10601.41692206936;6922;7113.893291600527;7608.771736847903;7769.028871391076;7796;8336.291415229773;8494.362922780692;8551.486910785372;8557.529335886586;8565.143485651384;7468.215837337001;7531.284527969834;7588.962055189689;7705.113911900204;7712.768616941501;7449.851002980018;7524.774256772305;7537.397008239437;7570.697172113054;7671.309582137635;8006.999125109362;8036;8082.515049097073;8095.352371810359;8098.959505202672;7260.491765576359;7301.379382752525;7321.853562928818;7330.157031941395;7358.742444014434;7397.149327827368;7412.96293518529;7443.218462061622;7466.328030477283;7468.626568671653 16:30:01 INFO - PROCESS | 3207 | |4;prototype.html;336.71715758762645;339.7468885680167;342.4657534246574;346.1346633416459;350.4060617253754;6657.36755008274;6675.7663481778145;6684.16447944007;6722.630255335955;6725.428338591226;5639.069553523664;6410.743570257184;6618.966905165479;6642.368974947384;6664.533482656216;4070.613291737295;4744.786484608184;4853.174960256747;4875.512448755132;4989.276554899556;4933.309336692848;4980.800767969277;4992.450830408652;5001.949590585981;5025.824096156635;4921.581665558432;4922.753862306889;4935.506449355072;4941.3329200558;4945.109880221558;4765.761711914391;4796.944672172117;4827.782749776251;4924.482929292444;4963.131451995886;3928.5482169550583;4290.592393722592;4293.48478182617;4293.549177336397;4318.935215971763;6416;6446.129772480749;6455.160829092188;6460.967695161541;6484.902726459107;5881.3530511643685;5883;5883.235179426647;5884.058550631921;5886.6173698709445;6754.223264324567;7076.9292307076785;7078.539894906815;7080.575165490036;7092;4762.952370476286;4795.064962332347;4797.808087676523;4811.566958973709;4813.66304358695;6023.909641355427;7298.306660867209;7470.47706660534;7470.47706660534;7477.925220747832;4814.855554333376;4885.560299573055;4899.461059283476;4903.87740306488;4914.778834952435;4964.726940018334;4974.482782751295;4981.12830254704;4987.10232158215;4987.276844857548;4845.63657725672;5661.405552417019;5818.2436283282905;5878.764849406061;5883.382244864313;7110.786676399716;7115.89326160108;7121.038659780863;7141.250168732208;7160.997460355555;1696.787901512311;1736.8957862528289;1742.3814545836299;1742.4162905426824;1744.328433553066;939.9248060155171;1060.316096118015;1104.1442881766695;1119.8600174978128;1148.0299147220753;6709.329067093349;6717.395434410829;6737.898931516032;6738.898916516257;6769.39075483199;5905.818836232771;7305.963470182722;7308.488405811542;7340.522866013692;7378.631068446556;6700.262971073178;6707.262201157869;6721.596704197764;6732.225794033651;6734.0572319875255;3708.810423036187;3833.6549710526187;3874.2057878134915;3880.611938806097;3882.611738826095;6320.5891617044745;7497.025386699803;7572.886406703904;7614.961925190339;7645.503042302233;7437.590932498764;7481.812954676177;7486.176520582731;7497.62511874415;7512.21121782216;6640.738259730636;7133.072700548999;7586.24137586248;7588.734394296173;7615.771526854203;7017.508774385744;7248.637568121679;7258.237885021997;7280.162781280221;7283.963580182065;7095.361417472453;7127.287271272831;7181.245969173162;7181.497295189287;7200.783976480714;6144.771045790771;7545.396368290633;7568.8486230276185;7574.621268936421;7602.429817763579;4084.7549147051272;4651.604613607746;4915.336429582104;4918.114739346952;4927.556519913225;6968.860622787617;7227.096612923385;7229.060222171084;7244.710211591478;7252.5648461092505;5754.395788442153;7012.263712310134;7020.719171233093;7037.120359955005;7057;6948.6525673715105;6974.37230649244;6977.546459480118;7005;7008.404285635778;6817.318268173143;6818.352256535622;6834.453243740588;6834.829129271808;6837.62393068386;6673.9666301668185;6995.685194166376;6998.720051197895;7005.684744186626;7018.298170182941;6714.764983225564;6804.353586409283;6876.656167191521;6881.380675739208;6893.862122757417 16:30:01 INFO - PROCESS | 3207 | |5;yui.html;694.2190036209265;715.3490323805335;735.5329365852685;781.9960900195499;786.5950676213207;15439.84560154398;15558;15604.375824966988;15607.141607211599;15615.921920390396;13589.93205033976;14144.929275353634;14351.712965740679;14351.856481435183;14366.856331436682;789.0846617923211;966.4974213409023;983.7343917142367;984.2618036472645;984.8375018122001;983.4148681534475;988;992.707151390338;993.696922438656;995.512199022481;848.7284069097891;863.5164307987548;1038.8857225705167;1045.5190612318304;1046.0219694585587;1007.4005966449951;1026.0509029148009;1029.927905046647;1031.242037102729;1033.8035773203067;766.7431410477496;770.768769369187;770.9460337776358;772.7488566215947;773.4740376543948;15259;15279.61800954985;15318.770218446733;15346.923265383602;15372.231388430533;11672.1829471937;12800.103992720513;13074;13358.064935454522;13503.66240843977;10359.689209323733;10373.325733827352;10474.42390668513;10526.78946421075;10715.464226788707;3524.6299138590593;3628.256207477461;3636.9272614547563;3666.7433279670436;3688.1517251032415;83235.58382208111;83319.91700207483;84980;85173.57413212956;85262;9746.853797193047;9834.950825245827;9862.654807081717;10015.499225038864;10048.447335396624;23270.76729232686;23289.41776455568;23344.53310933806;23398.298051058497;23416.7658323418;7600.50596711212;7719.614019299012;7841.529508229524;7908.802279942933;7965.8805117923275;4210.347396153601;4236.343366778093;4341.500727416387;4343.804528796211;4369.235383807821;3632.019354774249;3715.535558055243;3722.3671975764187;3733.4026555750947;3738.0467980664757;631.5863109663086;643.4839776473964;659.4790115808457;662.6610291026897;670.7719375412286;571.907998272362;573.7102763104606;607.9787207447719;620.1411045701735;633.2812258087105;6758.729650814011;8245.979734964973;8450.154984501623;8501.787455313543;8527.01628923319;549.6042849148606;563.4140493886313;609.338867328953;613.1048668943304;622.8287221014257;4269.252880745857;4293.484781826201;4338.110687309094;4355.077189611135;4367.5632436756705;93504;93901;95154;95238;95249.52375238079;77217;78436;78616.60691696504;80658.3868322642;82721.58639206765;71820.81502197385;72373.638131809;73201;74870.62564687141;75831.62084189654;8545.914540854512;8672.609732561923;8912.955435222782;8938.642454301755;9044.291810688166;49947.75026124846;50404.73988150326;50554.74722626364;50835.74582127214;52348.738256308476;66119;71403.64298178475;71646.28353716397;71980.56038879088;72710.63644681743;10924.071453926505;10934.617288395186;11111.888881111085;11242.213045086766;12101.576444824388;9811.754706132404;10840.038366539695;11631.302121872715;12145.939270303945;12222.415723348675;9739.659111931049;11734.70663233426;11848.170628055954;12167.513299467922;12262.386880655753;11842.703932401759;11905;11917;12228.816567751313;12330.445129968994;11588.420578971187;11611.825822612836;11752.70618234551;11803.350815704875;11935.224210426295;9324.580393882427;9545.427274363561;9570.521473926137;9733.853992189981;9805.656802011896;9922.603095876084;9945.502724863873;9978.251631127549;9988.550515226687;10036.598536058767 16:30:01 INFO - PROCESS | 3207 | __end_tp_report 16:30:01 INFO - PROCESS | 3207 | __start_cc_report 16:30:01 INFO - PROCESS | 3207 | _x_x_mozilla_cycle_collect,1009 16:30:01 INFO - PROCESS | 3207 | __end_cc_report 16:30:01 INFO - PROCESS | 3207 | __startTimestamp1464910201121__endTimestamp 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | ------- Summary: start ------- 16:30:01 INFO - PROCESS | 3207 | Number of tests: 6 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#0] dojo.html Cycles:165 Average:5832.92 Median:6080.64 stddev:636.03 (10.5%) stddev-sans-first:611.70 16:30:01 INFO - PROCESS | 3207 | Values: 3526.5 3583.0 3588.8 3616.0 3659.1 5814.0 5827.9 5834.0 5847.0 5869.3 5801.7 5860.7 5861.5 5866.3 5866.9 4478.1 5188.5 5219.5 5267.2 5300.2 5366.4 5383.7 5384.4 5389.6 5394.5 5315.7 5334.2 5362.6 5369.4 5373.5 5370.9 5398.1 5409.5 5416.2 5424.9 5133.9 5308.5 5315.9 5320.8 5339.4 5664.5 5701.7 5729.5 5729.9 5742.3 6219.6 6344.0 6354.4 6359.1 6368.7 6265.0 6293.9 6328.5 6337.2 6338.7 5956.3 5977.6 5979.0 5982.9 5988.1 5333.4 6192.1 6285.4 6347.6 6349.7 6166.3 6217.0 6230.9 6245.4 6339.2 6320.9 6321.7 6326.7 6342.1 6350.8 6300.7 6345.0 6347.3 6350.8 6380.1 6196.2 6289.2 6324.6 6349.2 6371.8 4995.6 5011.9 5020.1 5021.0 5022.0 4010.3 4563.2 4568.6 4569.7 4573.9 5986.7 6012.5 6026.9 6035.2 6037.2 6298.6 6300.8 6300.9 6302.0 6315.9 5910.9 5916.3 5986.8 6004.5 6018.8 5469.5 5488.0 5493.9 5496.6 5509.5 6261.6 6332.1 6361.1 6375.6 6389.6 6239.7 6305.6 6322.5 6334.2 6353.0 6367.3 6370.0 6380.2 6381.2 6382.1 6169.0 6335.6 6337.8 6361.8 6375.6 5258.2 6165.1 6217.2 6323.1 6331.2 6178.4 6208.3 6228.5 6244.3 6254.0 6119.3 6163.1 6208.9 6210.0 6240.6 4971.0 6053.0 6068.6 6080.6 6100.3 5111.5 6234.7 6249.0 6253.9 6262.3 5990.8 6086.6 6101.0 6111.8 6130.2 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#1] ext.html Cycles:180 Average:61788.85 Median:194783.82 stddev:126018.65 (64.7%) stddev-sans-first:126288.01 16:30:01 INFO - PROCESS | 3207 | Values: 448.4 578.6 583.7 661.3 676.6 30930.2 39953.0 40169.0 40184.2 40187.6 42976.4 43390.0 43393.8 43548.3 43549.8 9908.7 10131.0 10141.4 10153.0 10157.3 9874.9 10026.9 10027.5 10042.2 10043.7 10017.6 10094.5 10094.9 10095.9 10111.9 9949.2 9970.6 10016.0 10048.1 10101.7 9840.8 9975.8 9990.0 9996.2 10003.3 42638.0 42674.4 42756.4 42779.6 42861.8 3214.1 3459.3 3626.1 3799.3 3964.1 29093.4 29534.9 29956.1 29963.4 30441.9 2745.8 2753.6 2810.3 2820.2 2835.4 486021.0 488071.0 488321.1 489228.0 489570.0 47255.5 64537.0 65567.3 65767.7 65839.0 47382.5 47519.3 47554.0 47624.5 47625.0 17181.9 17546.7 17556.7 17557.4 17561.9 14061.4 14130.0 14160.0 14164.6 14177.6 15131.2 15145.9 15219.5 15336.3 15355.9 6259.7 6375.9 6419.7 6425.8 6433.2 1000.7 1023.0 1023.8 1024.2 1149.7 29491.7 30721.9 30777.7 31139.4 31591.5 1097.6 1167.4 1178.4 1184.0 1191.1 15530.9 15594.1 15642.7 15801.0 15934.9 542451.0 546629.0 549276.0 550929.5 551233.0 62387.0 62867.7 63093.7 63116.0 63133.0 1330.4 1417.9 1470.8 1545.4 1558.7 29233.3 29526.6 29613.9 29784.7 30379.0 47245.8 47492.5 47571.0 47611.5 47650.8 354223.0 370912.0 371291.0 371306.1 373420.0 29995.0 34051.3 34414.8 34749.3 35344.6 49502.8 50093.7 50423.0 50710.7 51902.5 28618.0 36088.1 36570.6 36878.6 38606.7 36823.8 36887.4 37094.6 37826.8 37845.6 37743.1 39998.0 40520.8 41368.0 41584.6 31712.5 33394.0 33454.3 33545.0 33585.2 22951.9 24708.9 26103.6 26241.3 26832.0 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#2] jquery.html Cycles:180 Average:36199.67 Median:22776.66 stddev:111074.39 (487.7%) stddev-sans-first:111353.30 16:30:01 INFO - PROCESS | 3207 | Values: 316.5 372.2 376.2 388.6 391.0 20522.4 21575.6 22253.0 23265.8 23303.4 22764.4 22788.9 23011.4 23044.8 23339.4 5399.5 5782.6 6091.5 6111.9 6112.2 6110.8 6199.2 6234.3 6250.6 6261.9 5871.2 6061.2 6063.5 6187.8 6196.9 5993.1 6010.1 6038.2 6050.6 6055.2 5083.7 5243.0 5255.9 5372.1 5381.9 27443.9 27655.2 27738.9 27858.3 28072.7 2294.0 2353.4 2433.7 2483.3 2832.8 15455.1 15644.5 15740.8 15789.1 16296.7 1622.6 1697.6 1727.9 1760.4 1796.2 104318.0 105191.0 106283.0 107113.5 107410.9 10826.9 11064.3 11072.2 11075.9 11261.9 13681.9 13689.9 13713.9 13740.0 13760.8 8909.9 8934.0 8934.3 8979.0 9033.3 9296.6 9330.3 9358.6 9370.3 9449.0 5792.9 5986.8 6359.5 6366.3 6371.1 1812.8 1842.8 1854.3 1865.9 1885.3 955.4 966.9 1012.9 1031.0 1040.0 20974.6 21872.2 22015.7 22222.8 22253.4 1014.6 1034.9 1049.8 1078.4 1093.5 11599.3 11847.3 11922.1 12006.5 12027.0 660065.0 668681.0 669915.0 685251.0 689951.0 100280.0 104312.5 107926.5 109473.0 110486.4 1082.3 1090.1 1102.5 1104.5 1107.3 18710.2 19273.5 19476.6 19548.0 19601.9 47229.8 49287.0 49302.5 49450.8 51672.2 45112.1 61408.4 66130.3 66249.0 66401.3 7987.0 8030.2 8095.7 8147.4 8183.3 11931.8 12766.9 13078.4 13211.2 13318.6 11539.8 11680.9 11791.9 11864.3 11934.0 11391.3 11519.5 11582.5 11646.5 11716.0 11682.5 11826.5 11859.8 11951.3 12009.2 8107.3 8132.4 8231.3 8517.3 8599.9 8713.7 10066.9 11209.5 12016.5 12133.0 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#3] mootools.html Cycles:180 Average:6295.35 Median:4947.93 stddev:3336.18 (67.4%) stddev-sans-first:3325.56 16:30:01 INFO - PROCESS | 3207 | Values: 1438.2 1464.3 1668.0 1974.5 2117.7 7652.8 8870.5 9105.3 9106.4 9134.0 8657.6 9059.3 9074.7 9075.3 9087.4 2523.5 2552.3 2553.6 2554.1 2563.3 2482.3 2572.9 2577.1 2578.8 2589.8 2109.4 2574.8 2577.4 2578.0 2578.8 2518.1 2551.3 2554.4 2559.3 2567.1 2525.2 2526.1 2536.9 2540.7 2572.7 9110.0 9193.7 9203.2 9207.6 9258.1 5570.7 5723.4 5729.2 5737.1 5779.1 3242.7 3692.4 3744.6 3823.5 3826.8 1060.2 1080.2 1082.3 1089.5 1112.3 12560.9 12570.2 12584.6 12586.4 12590.6 4940.0 4955.9 5013.5 5055.7 5087.5 10468.0 10503.5 10527.9 10532.1 10541.4 5735.1 5735.2 5747.0 5749.8 5775.8 4615.4 4685.3 4723.6 4728.1 5188.0 3694.2 3812.8 4011.8 4017.1 4029.5 1322.9 1348.8 1350.6 1360.5 1361.4 1190.2 1212.9 1221.4 1411.5 1442.9 8374.7 8415.1 8442.0 8449.2 8654.0 991.7 1064.5 1249.9 1252.1 1256.7 6379.3 6418.2 6461.8 6471.3 6485.1 10924.5 11225.7 11306.5 11326.7 11377.5 10852.2 11103.6 11141.0 11264.8 11276.4 8733.1 8755.1 8757.5 8796.7 8821.0 8137.8 8142.2 8149.9 8243.1 8244.3 9584.0 9932.6 9975.6 10045.4 10164.1 10044.5 10374.7 10388.3 10389.8 10601.4 6922.0 7113.9 7608.8 7769.0 7796.0 8336.3 8494.4 8551.5 8557.5 8565.1 7468.2 7531.3 7589.0 7705.1 7712.8 7449.9 7524.8 7537.4 7570.7 7671.3 8007.0 8036.0 8082.5 8095.4 8099.0 7260.5 7301.4 7321.9 7330.2 7358.7 7397.1 7413.0 7443.2 7466.3 7468.6 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#4] prototype.html Cycles:180 Average:5783.98 Median:6669.25 stddev:1797.39 (27.0%) stddev-sans-first:1755.32 16:30:01 INFO - PROCESS | 3207 | Values: 336.7 339.7 342.5 346.1 350.4 6657.4 6675.8 6684.2 6722.6 6725.4 5639.1 6410.7 6619.0 6642.4 6664.5 4070.6 4744.8 4853.2 4875.5 4989.3 4933.3 4980.8 4992.5 5001.9 5025.8 4921.6 4922.8 4935.5 4941.3 4945.1 4765.8 4796.9 4827.8 4924.5 4963.1 3928.5 4290.6 4293.5 4293.5 4318.9 6416.0 6446.1 6455.2 6461.0 6484.9 5881.4 5883.0 5883.2 5884.1 5886.6 6754.2 7076.9 7078.5 7080.6 7092.0 4763.0 4795.1 4797.8 4811.6 4813.7 6023.9 7298.3 7470.5 7470.5 7477.9 4814.9 4885.6 4899.5 4903.9 4914.8 4964.7 4974.5 4981.1 4987.1 4987.3 4845.6 5661.4 5818.2 5878.8 5883.4 7110.8 7115.9 7121.0 7141.3 7161.0 1696.8 1736.9 1742.4 1742.4 1744.3 939.9 1060.3 1104.1 1119.9 1148.0 6709.3 6717.4 6737.9 6738.9 6769.4 5905.8 7306.0 7308.5 7340.5 7378.6 6700.3 6707.3 6721.6 6732.2 6734.1 3708.8 3833.7 3874.2 3880.6 3882.6 6320.6 7497.0 7572.9 7615.0 7645.5 7437.6 7481.8 7486.2 7497.6 7512.2 6640.7 7133.1 7586.2 7588.7 7615.8 7017.5 7248.6 7258.2 7280.2 7284.0 7095.4 7127.3 7181.2 7181.5 7200.8 6144.8 7545.4 7568.8 7574.6 7602.4 4084.8 4651.6 4915.3 4918.1 4927.6 6968.9 7227.1 7229.1 7244.7 7252.6 5754.4 7012.3 7020.7 7037.1 7057.0 6948.7 6974.4 6977.5 7005.0 7008.4 6817.3 6818.4 6834.5 6834.8 6837.6 6674.0 6995.7 6998.7 7005.7 7018.3 6714.8 6804.4 6876.7 6881.4 6893.9 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - PROCESS | 3207 | [#5] yui.html Cycles:180 Average:18913.19 Median:567.66 stddev:26574.76 (4681.4%) stddev-sans-first:26614.10 16:30:01 INFO - PROCESS | 3207 | Values: 694.2 715.3 735.5 782.0 786.6 15439.8 15558.0 15604.4 15607.1 15615.9 13589.9 14144.9 14351.7 14351.9 14366.9 789.1 966.5 983.7 984.3 984.8 983.4 988.0 992.7 993.7 995.5 848.7 863.5 1038.9 1045.5 1046.0 1007.4 1026.1 1029.9 1031.2 1033.8 766.7 770.8 770.9 772.7 773.5 15259.0 15279.6 15318.8 15346.9 15372.2 11672.2 12800.1 13074.0 13358.1 13503.7 10359.7 10373.3 10474.4 10526.8 10715.5 3524.6 3628.3 3636.9 3666.7 3688.2 83235.6 83319.9 84980.0 85173.6 85262.0 9746.9 9835.0 9862.7 10015.5 10048.4 23270.8 23289.4 23344.5 23398.3 23416.8 7600.5 7719.6 7841.5 7908.8 7965.9 4210.3 4236.3 4341.5 4343.8 4369.2 3632.0 3715.5 3722.4 3733.4 3738.0 631.6 643.5 659.5 662.7 670.8 571.9 573.7 608.0 620.1 633.3 6758.7 8246.0 8450.2 8501.8 8527.0 549.6 563.4 609.3 613.1 622.8 4269.3 4293.5 4338.1 4355.1 4367.6 93504.0 93901.0 95154.0 95238.0 95249.5 77217.0 78436.0 78616.6 80658.4 82721.6 71820.8 72373.6 73201.0 74870.6 75831.6 8545.9 8672.6 8913.0 8938.6 9044.3 49947.8 50404.7 50554.7 50835.7 52348.7 66119.0 71403.6 71646.3 71980.6 72710.6 10924.1 10934.6 11111.9 11242.2 12101.6 9811.8 10840.0 11631.3 12145.9 12222.4 9739.7 11734.7 11848.2 12167.5 12262.4 11842.7 11905.0 11917.0 12228.8 12330.4 11588.4 11611.8 11752.7 11803.4 11935.2 9324.6 9545.4 9570.5 9733.9 9805.7 9922.6 9945.5 9978.3 9988.6 10036.6 16:30:01 INFO - PROCESS | 3207 | -------- Summary: end -------- 16:30:01 INFO - PROCESS | 3207 | 16:30:01 INFO - TEST-INFO | 3207: exit 0 16:30:01 INFO - TEST-OK | dromaeo_css | took 1111720ms 16:30:01 INFO - TEST-START | kraken 16:30:01 INFO - operating with platform_type : mac_ 16:30:01 INFO - Initialising browser for kraken test... 16:30:01 INFO - TEST-INFO | started process 3320 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmptmx9du/profile http://localhost:59251/getInfo.html) 16:30:03 INFO - PROCESS | 3320 | __metrics Screen width/height:1600/1200 16:30:03 INFO - PROCESS | 3320 | colorDepth:24 16:30:03 INFO - PROCESS | 3320 | Browser inner width/height: 1024/689 16:30:03 INFO - PROCESS | 3320 | __metrics 16:30:03 INFO - PROCESS | 3320 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:30:03 INFO - PROCESS | 3320 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:30:04 INFO - TEST-INFO | 3320: exit 0 16:30:04 INFO - Browser initialized. 16:30:04 INFO - Running cycle 1/1 for kraken test... 16:30:04 INFO - TEST-INFO | started process 3324 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmptmx9du/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:16 INFO - PROCESS | 3324 | RSS: Main: 157048832 16:30:16 INFO - PROCESS | 3324 | 16:30:19 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59251/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:19 INFO - PROCESS | 3324 | RSS: Main: 218652672 16:30:19 INFO - PROCESS | 3324 | 16:30:22 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59251/tests/kraken/driver.html?testName=audio-dft) 16:30:22 INFO - PROCESS | 3324 | RSS: Main: 228724736 16:30:22 INFO - PROCESS | 3324 | 16:30:26 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59251/tests/kraken/driver.html?testName=audio-fft) 16:30:26 INFO - PROCESS | 3324 | RSS: Main: 218394624 16:30:26 INFO - PROCESS | 3324 | 16:30:28 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59251/tests/kraken/driver.html?testName=audio-oscillator) 16:30:29 INFO - PROCESS | 3324 | RSS: Main: 219131904 16:30:29 INFO - PROCESS | 3324 | 16:30:31 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:32 INFO - PROCESS | 3324 | RSS: Main: 218849280 16:30:32 INFO - PROCESS | 3324 | 16:30:34 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59251/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:35 INFO - PROCESS | 3324 | RSS: Main: 218976256 16:30:35 INFO - PROCESS | 3324 | 16:30:38 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59251/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:38 INFO - PROCESS | 3324 | RSS: Main: 220307456 16:30:38 INFO - PROCESS | 3324 | 16:30:41 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59251/tests/kraken/driver.html?testName=json-parse-financial) 16:30:42 INFO - PROCESS | 3324 | RSS: Main: 220483584 16:30:42 INFO - PROCESS | 3324 | 16:30:43 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:44 INFO - PROCESS | 3324 | RSS: Main: 255283200 16:30:44 INFO - PROCESS | 3324 | 16:30:46 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:46 INFO - PROCESS | 3324 | RSS: Main: 256315392 16:30:46 INFO - PROCESS | 3324 | 16:30:48 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:49 INFO - PROCESS | 3324 | RSS: Main: 261279744 16:30:49 INFO - PROCESS | 3324 | 16:30:51 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:52 INFO - PROCESS | 3324 | RSS: Main: 262684672 16:30:52 INFO - PROCESS | 3324 | 16:30:54 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:55 INFO - PROCESS | 3324 | RSS: Main: 262316032 16:30:55 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | Cycle 1(1): loaded http://localhost:59251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59251/tests/kraken/driver.html?testName=ai-astar) 16:30:57 INFO - PROCESS | 3324 | RSS: Main: 325169152 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | __start_tp_report 16:30:57 INFO - PROCESS | 3324 | _x_x_mozilla_page_load 16:30:57 INFO - PROCESS | 3324 | _x_x_mozilla_page_load_details 16:30:57 INFO - PROCESS | 3324 | |i|pagename|runs| 16:30:57 INFO - PROCESS | 3324 | |0;ai-astar;109;159;164;135;109;111;127;110;115;159 16:30:57 INFO - PROCESS | 3324 | |1;audio-beat-detection;153;120;146;184;137;139;116;176;186;149 16:30:57 INFO - PROCESS | 3324 | |2;audio-dft;188;182;185;185;187;183;183;186;185;186 16:30:57 INFO - PROCESS | 3324 | |3;audio-fft;75;83;73;121;73;81;83;83;125;47 16:30:57 INFO - PROCESS | 3324 | |4;audio-oscillator;89;89;130;77;80;81;120;77;78;121 16:30:57 INFO - PROCESS | 3324 | |5;imaging-gaussian-blur;87;83;87;86;88;83;98;129;87;84 16:30:57 INFO - PROCESS | 3324 | |6;imaging-darkroom;94;93;93;92;92;93;93;92;93;93 16:30:57 INFO - PROCESS | 3324 | |7;imaging-desaturate;102;141;84;87;86;85;84;90;86;71 16:30:57 INFO - PROCESS | 3324 | |8;json-parse-financial;55;58;58;58;58;58;57;56;68;54 16:30:57 INFO - PROCESS | 3324 | |9;json-stringify-tinderbox;62;63;63;75;73;70;65;66;66;67 16:30:57 INFO - PROCESS | 3324 | |10;stanford-crypto-aes;82;80;81;80;81;79;83;81;81;86 16:30:57 INFO - PROCESS | 3324 | |11;stanford-crypto-ccm;129;125;119;127;117;122;120;122;126;128 16:30:57 INFO - PROCESS | 3324 | |12;stanford-crypto-pbkdf2;130;119;135;135;135;120;134;134;134;131 16:30:57 INFO - PROCESS | 3324 | |13;stanford-crypto-sha256-iterative;66;66;68;66;65;69;88;87;66;64 16:30:57 INFO - PROCESS | 3324 | __end_tp_report 16:30:57 INFO - PROCESS | 3324 | __start_cc_report 16:30:57 INFO - PROCESS | 3324 | _x_x_mozilla_cycle_collect,1245 16:30:57 INFO - PROCESS | 3324 | __end_cc_report 16:30:57 INFO - PROCESS | 3324 | __startTimestamp1464910257454__endTimestamp 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | ------- Summary: start ------- 16:30:57 INFO - PROCESS | 3324 | Number of tests: 14 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#0] ai-astar Cycles:10 Average:129.80 Median:131.00 stddev:22.95 (17.5%) stddev-sans-first:23.07 16:30:57 INFO - PROCESS | 3324 | Values: 109.0 159.0 164.0 135.0 109.0 111.0 127.0 110.0 115.0 159.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:151.00 stddev:24.71 (16.4%) stddev-sans-first:26.20 16:30:57 INFO - PROCESS | 3324 | Values: 153.0 120.0 146.0 184.0 137.0 139.0 116.0 176.0 186.0 149.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.89 (1.0%) stddev-sans-first:1.66 16:30:57 INFO - PROCESS | 3324 | Values: 188.0 182.0 185.0 185.0 187.0 183.0 183.0 186.0 185.0 186.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#3] audio-fft Cycles:10 Average:84.40 Median:78.00 stddev:22.98 (29.5%) stddev-sans-first:24.12 16:30:57 INFO - PROCESS | 3324 | Values: 75.0 83.0 73.0 121.0 73.0 81.0 83.0 83.0 125.0 47.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:79.00 stddev:20.95 (26.5%) stddev-sans-first:22.13 16:30:57 INFO - PROCESS | 3324 | Values: 89.0 89.0 130.0 77.0 80.0 81.0 120.0 77.0 78.0 121.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:87.00 stddev:13.95 (16.0%) stddev-sans-first:14.71 16:30:57 INFO - PROCESS | 3324 | Values: 87.0 83.0 87.0 86.0 88.0 83.0 98.0 129.0 87.0 84.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 16:30:57 INFO - PROCESS | 3324 | Values: 94.0 93.0 93.0 92.0 92.0 93.0 93.0 92.0 93.0 93.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:85.50 stddev:18.91 (22.1%) stddev-sans-first:19.68 16:30:57 INFO - PROCESS | 3324 | Values: 102.0 141.0 84.0 87.0 86.0 85.0 84.0 90.0 86.0 71.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:3.80 (6.6%) stddev-sans-first:3.87 16:30:57 INFO - PROCESS | 3324 | Values: 55.0 58.0 58.0 58.0 58.0 58.0 57.0 56.0 68.0 54.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:66.50 stddev:4.37 (6.6%) stddev-sans-first:4.25 16:30:57 INFO - PROCESS | 3324 | Values: 62.0 63.0 63.0 75.0 73.0 70.0 65.0 66.0 66.0 67.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:1.96 (2.4%) stddev-sans-first:2.06 16:30:57 INFO - PROCESS | 3324 | Values: 82.0 80.0 81.0 80.0 81.0 79.0 83.0 81.0 81.0 86.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:125.50 stddev:4.09 (3.3%) stddev-sans-first:3.82 16:30:57 INFO - PROCESS | 3324 | Values: 129.0 125.0 119.0 127.0 117.0 122.0 120.0 122.0 126.0 128.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.70 Median:134.00 stddev:6.15 (4.6%) stddev-sans-first:6.51 16:30:57 INFO - PROCESS | 3324 | Values: 130.0 119.0 135.0 135.0 135.0 120.0 134.0 134.0 134.0 131.0 16:30:57 INFO - PROCESS | 3324 | 16:30:57 INFO - PROCESS | 3324 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:67.00 stddev:9.07 (13.5%) stddev-sans-first:9.47 16:30:57 INFO - PROCESS | 3324 | Values: 66.0 66.0 68.0 66.0 65.0 69.0 88.0 87.0 66.0 64.0 16:30:57 INFO - PROCESS | 3324 | -------- Summary: end -------- 16:30:57 INFO - PROCESS | 3324 | 16:30:58 INFO - TEST-INFO | 3324: exit 0 16:30:58 INFO - TEST-OK | kraken | took 56514ms 16:30:58 INFO - SUITE-END | took 1168s 16:30:58 INFO - Completed test suite (00:19:28) 16:30:58 INFO - javascript benchmark 16:30:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3526.488659144424, 3582.982085089574, 3588.80261585613, 3615.981920090399, 3659.1401020760118, 5814.0, 5827.880725238499, 5833.970830145854, 5846.970765146174, 5869.325027621825, 5801.738921748521, 5860.706964651761, 5861.472467477926, 5866.296044474669, 5866.941330586693, 4478.104379124188, 5188.455212202721, 5219.5041471060185, 5267.209918512215, 5300.231466437364, 5366.382865970424, 5383.650062745909, 5384.40771515133, 5389.6496727712765, 5394.487523685263, 5315.707636080014, 5334.199870019509, 5362.624616276862, 5369.355677318727, 5373.516383525482, 5370.865728356784, 5398.082326004589, 5409.540189083933, 5416.241726158341, 5424.864378390533, 5133.922991155136, 5308.469153084699, 5315.867103322448, 5320.813771517978, 5339.41266460689, 5664.54683625309, 5701.6864072476, 5729.4843464088435, 5729.914051289234, 5742.28221472316, 6219.626822390625, 6344.016677415008, 6354.3963323484195, 6359.077933699634, 6368.745250189987, 6264.968675156595, 6293.87412251757, 6328.493720502349, 6337.239531256233, 6338.651374174418, 5956.315023772278, 5977.581569290108, 5979.043353063489, 5982.910256346071, 5988.101784732282, 5333.359996800331, 6192.133101365813, 6285.434310911948, 6347.619142851444, 6349.682515874188, 6166.321704612489, 6216.968915155395, 6230.875382492325, 6245.406686364788, 6339.207599050119, 6320.873582528324, 6321.715522801485, 6326.746930122836, 6342.080398342307, 6350.809475715736, 6300.684965751694, 6344.953082741296, 6347.333529979378, 6350.7777227796805, 6380.106785050097, 6196.194494715748, 6289.150964619809, 6324.557280990379, 6349.238091429058, 6371.808845734636, 4995.575376093073, 5011.899762004739, 5020.096382651085, 5021.020900924284, 5022.0, 4010.2981978153707, 4563.178627847019, 4568.588827005586, 4569.748663823521, 4573.862784116482, 5986.73059712314, 6012.518998480111, 6026.879462410728, 6035.185249991283, 6037.215162028996, 6298.622082675054, 6300.842478938063, 6300.936990630035, 6302.0, 6315.905261421082, 5910.8817823643285, 5916.349201587908, 5986.760529578768, 6004.519638428916, 6018.759249630053, 5469.507744302952, 5488.0, 5493.917591236134, 5496.560275177976, 5509.531689806411, 6261.62430254168, 6332.1135041094285, 6361.077643741632, 6375.6174629522375, 6389.616623002635, 6239.656818875005, 6305.5901366411035, 6322.462590679844, 6334.176557047738, 6352.968235158794, 6367.267764207176, 6369.9681501592195, 6380.170577825039, 6381.1704478417505, 6382.106505089384, 6169.01295792671, 6335.556511044183, 6337.77817776394, 6361.840953976188, 6375.585586936834, 5258.2375555544995, 6165.075238714228, 6217.160683307877, 6323.08315294289, 6331.2085989251345, 6178.382161783786, 6208.348123446973, 6228.4705800005695, 6244.281907580505, 6253.96873015632, 6119.265688117454, 6163.106349579196, 6208.906866397098, 6209.975354066709, 6240.594361366497, 4971.0, 6052.970994930961, 6068.575199735976, 6080.635161890301, 6100.298465676504, 5111.539961403491, 6234.657093859882, 6249.0, 6253.937460625336, 6262.3111457738705, 5990.820275391745, 6086.573939824347, 6100.993336099494, 6111.816645500642, 6130.172426722335], "unit": "score", "name": "dojo.html", "value": 5795.199767445358}, {"lowerIsBetter": false, "replicates": [448.4282539761803, 578.648854923753, 583.7489879351878, 661.2792056658242, 676.5724321681254, 30930.226744331376, 39953.0, 40169.0, 40184.19631607366, 40187.59812401879, 42976.35535466971, 43390.0, 43393.78303108488, 43548.34677479832, 43549.78225108878, 9908.653197138101, 10130.986901309883, 10141.391516509033, 10153.035461631132, 10157.28898977072, 9874.851877221847, 10026.899731002706, 10027.548760305805, 10042.19662427004, 10043.69868903934, 10017.599296028151, 10094.545745441474, 10094.949525252363, 10095.899041009568, 10111.949440252789, 9949.204063674924, 9970.601175952954, 10016.0, 10048.095671389538, 10101.69694909154, 9840.753981150458, 9975.75060623483, 9989.950050249776, 9996.200303975736, 10003.299769016172, 42638.0, 42674.359884601756, 42756.358654620206, 42779.57220427818, 42861.78569107166, 3214.1161180675267, 3459.2562599041325, 3626.0753507855593, 3799.3351163546545, 3964.1278918637854, 29093.41813163746, 29534.852325738448, 29956.101316960525, 29963.400731985457, 30441.934532291485, 2745.8215216011095, 2753.6144939708256, 2810.339570201001, 2820.19624407043, 2835.4045650413436, 486021.0, 488071.0, 488321.11678883113, 489228.0, 489570.0, 47255.5274447258, 64537.0, 65567.34432655708, 65767.67116164484, 65839.0, 47382.5261747385, 47519.287210691866, 47554.0, 47624.523754762704, 47625.0, 17181.91409042947, 17546.736798948026, 17556.736648950275, 17557.385491507735, 17561.85847919881, 14061.437542498186, 14130.0, 14160.0, 14164.645883852987, 14177.645558860904, 15131.167785771668, 15145.939784215, 15219.467318643794, 15336.309866056054, 15355.923220383827, 6259.7496100156395, 6375.904361434582, 6419.720999045365, 6425.839354016187, 6433.163688720437, 1000.6741068084817, 1022.9693109206736, 1023.8054769593754, 1024.2113572549094, 1149.672343382132, 29491.705082949324, 30721.924732634696, 30777.69222307793, 31139.37721245608, 31591.52612710811, 1097.6103483263487, 1167.3871217610822, 1178.3695722788268, 1183.9940800295944, 1191.1007189571992, 15530.922345388426, 15594.064356138668, 15642.68714625701, 15801.0, 15934.9203253983, 542451.0, 546629.0, 549276.0, 550929.4907050958, 551233.0, 62387.0, 62867.685661572315, 63093.68453157705, 63116.0, 63133.0, 1330.4412146898326, 1417.8723914847712, 1470.8455612160783, 1545.4436402894949, 1558.680470503544, 29233.269168270963, 29526.55710164392, 29613.85193074021, 29784.702152978192, 30379.0, 47245.76377118092, 47492.52507475019, 47571.048579027534, 47611.52388476071, 47650.76174619105, 354223.0, 370912.0, 371291.0, 371306.1434692809, 373420.0, 29994.95017674371, 34051.25436201966, 34414.82792586021, 34749.30501389907, 35344.64655353517, 49502.75248623734, 50093.747656308886, 50423.0, 50710.746446267534, 51902.480975191276, 28618.0, 36088.097797555274, 36570.634293657786, 36878.63121368752, 38606.718428932734, 36823.815880920425, 36887.44668830023, 37094.62905370912, 37826.810865945496, 37845.62154378531, 37743.05642358853, 39998.000049998984, 40520.78437646875, 41368.0, 41584.584154158074, 31712.524312134876, 33393.99818005464, 33454.33091338208, 33545.0, 33585.16037099092, 22951.88524057369, 24708.876455617607, 26103.608445872946, 26241.34396640023, 26832.0], "unit": "score", "name": "ext.html", "value": 19843.817521123718}, {"lowerIsBetter": false, "replicates": [316.4983501150676, 372.2443439817171, 376.23248572911245, 388.60168327464345, 391.0, 20522.38432847015, 21575.56848863022, 22253.0, 23265.83670816461, 23303.417414564643, 22764.430889227777, 22788.88605556974, 23011.42471438215, 23044.76955230447, 23339.416514587145, 5399.460053994609, 5782.624129431573, 6091.451769340759, 6111.908321375183, 6112.235970503687, 6110.8472288192925, 6199.163112979758, 6234.345393733661, 6250.562460627758, 6261.937380626204, 5871.178035075071, 6061.181740465047, 6063.454289113979, 6187.814365569017, 6196.938030619681, 5993.101034844786, 6010.068439391879, 6038.245219347582, 6050.636961782263, 6055.152278680988, 5083.745812709387, 5242.951409718072, 5255.921161182585, 5372.113601255788, 5381.892362152774, 27443.86278068617, 27655.170344889684, 27738.861305693543, 27858.303542411602, 28072.719272807215, 2294.0365046680436, 2353.4351755578537, 2433.671454353675, 2483.3170878008514, 2832.815866968661, 15455.127155845905, 15644.530664079983, 15740.842591574166, 15789.052656840626, 16296.674066518603, 1622.594351412147, 1697.609549803552, 1727.8531324837404, 1760.4366602687146, 1796.2276221224868, 104318.0, 105191.0, 106283.0, 107113.46443267734, 107410.92589074165, 10826.891731082746, 11064.28082174656, 11072.224944253825, 11075.889241107487, 11261.887381126247, 13681.863181368259, 13689.904807615367, 13713.931430342784, 13740.0, 13760.761531462216, 8909.866352004725, 8933.955330223307, 8934.329925255503, 8978.955105224431, 9033.277337812959, 9296.628134874665, 9330.253579713606, 9358.625654973861, 9370.250379969586, 9449.0, 5792.913106303409, 5986.790462333797, 6359.491240700733, 6366.26787919386, 6371.076193951851, 1812.7643406357354, 1842.824931631493, 1854.2860998515669, 1865.9160337784833, 1885.283592234942, 955.350361754014, 966.8936416994125, 1012.8936461671566, 1030.953607087683, 1039.9844002339971, 20974.580508389747, 21872.23447179373, 22015.669764953538, 22222.777772222395, 22253.443663908532, 1014.5535964175739, 1034.8758149022015, 1049.7690508088208, 1078.4338222433128, 1093.491526440209, 11599.304041757521, 11847.29970267389, 11922.10584206188, 12006.51973921051, 12027.0, 660065.0, 668681.0, 669915.0, 685251.0, 689951.0, 100280.0, 104312.47843760732, 107926.46036769765, 109473.0, 110486.44756776326, 1082.2965072702807, 1090.1442367741286, 1102.536934487517, 1104.4588151805558, 1107.3355986408092, 18710.158042888375, 19273.51816204606, 19476.61046779028, 19548.02316343821, 19601.901990489958, 47229.76385118053, 49287.01425971389, 49302.50697492979, 49450.75274623604, 51672.224916627034, 45112.09775804531, 61408.385916140265, 66130.33869661242, 66249.0, 66401.33598663952, 7986.960065199637, 8030.23712747288, 8095.71664991746, 8147.388945828968, 8183.304419124441, 11931.76136477283, 12766.87233127694, 13078.411471483996, 13211.207327560378, 13318.600441986757, 11539.769204615694, 11680.883191167979, 11791.941040295087, 11864.288142711464, 11934.0, 11391.259568128045, 11519.481623326803, 11582.536698531965, 11646.475908583965, 11716.0, 11682.4742886572, 11826.46780894879, 11859.762804744028, 11951.342676152548, 12009.159358845147, 8107.270345668918, 8132.43072984909, 8231.316426495981, 8517.318614510948, 8599.871001934851, 8713.695020674499, 10066.899331006596, 11209.495572699085, 12016.519339226334, 12133.029357651543], "unit": "score", "name": "jquery.html", "value": 9915.298819168456}, {"lowerIsBetter": false, "replicates": [1438.1586771738532, 1464.341046529062, 1668.0408764960148, 1974.4570243183123, 2117.703521506988, 7652.846943061136, 8870.467771933689, 9105.317101217403, 9106.362554621179, 9133.954330228356, 8657.567121643924, 9059.320550958688, 9074.682386116487, 9075.319351048682, 9087.363884528086, 2523.470071285032, 2552.310876063471, 2553.565893798059, 2554.1060628779965, 2563.269468201563, 2482.3173627252468, 2572.935676608081, 2577.1495406515896, 2578.7808036316937, 2589.8316609420326, 2109.3566462229014, 2574.8197626166175, 2577.36854470654, 2577.9871100644564, 2578.8065895057853, 2518.055729101587, 2551.2728872271405, 2554.4124851284123, 2559.25781523358, 2567.0886835173433, 2525.1540733854, 2526.12848567244, 2536.8985240590355, 2540.720520742717, 2572.742725727431, 9109.99790023097, 9193.678221262291, 9203.171714545657, 9207.585658645377, 9258.074192580756, 5570.693611851347, 5723.399043100456, 5729.226554415142, 5737.053386191274, 5779.075347944308, 3242.6757324267383, 3692.390755525335, 3744.588095309514, 3823.50294461721, 3826.8086595670384, 1060.204846365226, 1080.2330345454736, 1082.2802836114054, 1089.5260561655625, 1112.3270421395102, 12560.937195314056, 12570.245785252913, 12584.622461326175, 12586.43361048755, 12590.559330423483, 4939.975300123477, 4955.876103097451, 5013.498650134958, 5055.747212639353, 5087.491250874883, 10468.0, 10503.52734126966, 10527.947360263302, 10532.052115309505, 10541.420221887869, 5735.053716136874, 5735.2257445245195, 5746.971265143731, 5749.7700091996685, 5775.768969241268, 4615.376924115201, 4685.344051832746, 4723.5984941279685, 4728.1252968201, 5188.014277287303, 3694.150345420568, 3812.7902965336616, 4011.819468123883, 4017.055991841915, 4029.476168098187, 1322.8941684665203, 1348.7841945288706, 1350.6488313038485, 1360.462617266194, 1361.4486133116104, 1190.2382475215868, 1212.933288669114, 1221.407617305606, 1411.5130280053365, 1442.9134251944918, 8374.748757537283, 8415.074341822394, 8441.986961564651, 8449.23956843887, 8654.048054713976, 991.6529214774771, 1064.5209655654987, 1249.9125061245807, 1252.136026141959, 1256.6983923858209, 6379.298277189506, 6418.19772528434, 6461.838454038687, 6471.255805582418, 6485.092087107809, 10924.508397122148, 11225.66323010311, 11306.547738090549, 11326.660200194008, 11377.54489820398, 10852.186086043579, 11103.611373601885, 11141.0, 11264.83102753443, 11276.379799111128, 8733.126687331216, 8755.124487551195, 8757.47455152693, 8796.736097917073, 8821.029686734453, 8137.79655508617, 8142.226488483585, 8149.9185008151535, 8243.134470880472, 8244.340452763885, 9584.04159584064, 9932.602695892083, 9975.60097596088, 10045.447500387549, 10164.136048435967, 10044.54799534008, 10374.740631484274, 10388.324758890649, 10389.792204156025, 10601.41692206936, 6922.0, 7113.893291600527, 7608.771736847903, 7769.028871391076, 7796.0, 8336.291415229773, 8494.362922780692, 8551.486910785372, 8557.529335886586, 8565.143485651384, 7468.215837337001, 7531.284527969834, 7588.962055189689, 7705.113911900204, 7712.768616941501, 7449.851002980018, 7524.774256772305, 7537.397008239437, 7570.697172113054, 7671.309582137635, 8006.999125109362, 8036.0, 8082.515049097073, 8095.352371810359, 8098.959505202672, 7260.491765576359, 7301.379382752525, 7321.853562928818, 7330.157031941395, 7358.742444014434, 7397.149327827368, 7412.96293518529, 7443.218462061622, 7466.328030477283, 7468.626568671653], "unit": "score", "name": "mootools.html", "value": 5123.327792749548}, {"lowerIsBetter": false, "replicates": [336.71715758762645, 339.7468885680167, 342.4657534246574, 346.1346633416459, 350.4060617253754, 6657.36755008274, 6675.7663481778145, 6684.16447944007, 6722.630255335955, 6725.428338591226, 5639.069553523664, 6410.743570257184, 6618.966905165479, 6642.368974947384, 6664.533482656216, 4070.613291737295, 4744.786484608184, 4853.174960256747, 4875.512448755132, 4989.276554899556, 4933.309336692848, 4980.800767969277, 4992.450830408652, 5001.949590585981, 5025.824096156635, 4921.581665558432, 4922.753862306889, 4935.506449355072, 4941.3329200558, 4945.109880221558, 4765.761711914391, 4796.944672172117, 4827.782749776251, 4924.482929292444, 4963.131451995886, 3928.5482169550583, 4290.592393722592, 4293.48478182617, 4293.549177336397, 4318.935215971763, 6416.0, 6446.129772480749, 6455.160829092188, 6460.967695161541, 6484.902726459107, 5881.3530511643685, 5883.0, 5883.235179426647, 5884.058550631921, 5886.6173698709445, 6754.223264324567, 7076.9292307076785, 7078.539894906815, 7080.575165490036, 7092.0, 4762.952370476286, 4795.064962332347, 4797.808087676523, 4811.566958973709, 4813.66304358695, 6023.909641355427, 7298.306660867209, 7470.47706660534, 7470.47706660534, 7477.925220747832, 4814.855554333376, 4885.560299573055, 4899.461059283476, 4903.87740306488, 4914.778834952435, 4964.726940018334, 4974.482782751295, 4981.12830254704, 4987.10232158215, 4987.276844857548, 4845.63657725672, 5661.405552417019, 5818.2436283282905, 5878.764849406061, 5883.382244864313, 7110.786676399716, 7115.89326160108, 7121.038659780863, 7141.250168732208, 7160.997460355555, 1696.787901512311, 1736.8957862528289, 1742.3814545836299, 1742.4162905426824, 1744.328433553066, 939.9248060155171, 1060.316096118015, 1104.1442881766695, 1119.8600174978128, 1148.0299147220753, 6709.329067093349, 6717.395434410829, 6737.898931516032, 6738.898916516257, 6769.39075483199, 5905.818836232771, 7305.963470182722, 7308.488405811542, 7340.522866013692, 7378.631068446556, 6700.262971073178, 6707.262201157869, 6721.596704197764, 6732.225794033651, 6734.0572319875255, 3708.810423036187, 3833.6549710526187, 3874.2057878134915, 3880.611938806097, 3882.611738826095, 6320.5891617044745, 7497.025386699803, 7572.886406703904, 7614.961925190339, 7645.503042302233, 7437.590932498764, 7481.812954676177, 7486.176520582731, 7497.62511874415, 7512.21121782216, 6640.738259730636, 7133.072700548999, 7586.24137586248, 7588.734394296173, 7615.771526854203, 7017.508774385744, 7248.637568121679, 7258.237885021997, 7280.162781280221, 7283.963580182065, 7095.361417472453, 7127.287271272831, 7181.245969173162, 7181.497295189287, 7200.783976480714, 6144.771045790771, 7545.396368290633, 7568.8486230276185, 7574.621268936421, 7602.429817763579, 4084.7549147051272, 4651.604613607746, 4915.336429582104, 4918.114739346952, 4927.556519913225, 6968.860622787617, 7227.096612923385, 7229.060222171084, 7244.710211591478, 7252.5648461092505, 5754.395788442153, 7012.263712310134, 7020.719171233093, 7037.120359955005, 7057.0, 6948.6525673715105, 6974.37230649244, 6977.546459480118, 7005.0, 7008.404285635778, 6817.318268173143, 6818.352256535622, 6834.453243740588, 6834.829129271808, 6837.62393068386, 6673.9666301668185, 6995.685194166376, 6998.720051197895, 7005.684744186626, 7018.298170182941, 6714.764983225564, 6804.353586409283, 6876.656167191521, 6881.380675739208, 6893.862122757417], "unit": "score", "name": "prototype.html", "value": 5189.068320847322}, {"lowerIsBetter": false, "replicates": [694.2190036209265, 715.3490323805335, 735.5329365852685, 781.9960900195499, 786.5950676213207, 15439.84560154398, 15558.0, 15604.375824966988, 15607.141607211599, 15615.921920390396, 13589.93205033976, 14144.929275353634, 14351.712965740679, 14351.856481435183, 14366.856331436682, 789.0846617923211, 966.4974213409023, 983.7343917142367, 984.2618036472645, 984.8375018122001, 983.4148681534475, 988.0, 992.707151390338, 993.696922438656, 995.512199022481, 848.7284069097891, 863.5164307987548, 1038.8857225705167, 1045.5190612318304, 1046.0219694585587, 1007.4005966449951, 1026.0509029148009, 1029.927905046647, 1031.242037102729, 1033.8035773203067, 766.7431410477496, 770.768769369187, 770.9460337776358, 772.7488566215947, 773.4740376543948, 15259.0, 15279.61800954985, 15318.770218446733, 15346.923265383602, 15372.231388430533, 11672.1829471937, 12800.103992720513, 13074.0, 13358.064935454522, 13503.66240843977, 10359.689209323733, 10373.325733827352, 10474.42390668513, 10526.78946421075, 10715.464226788707, 3524.6299138590593, 3628.256207477461, 3636.9272614547563, 3666.7433279670436, 3688.1517251032415, 83235.58382208111, 83319.91700207483, 84980.0, 85173.57413212956, 85262.0, 9746.853797193047, 9834.950825245827, 9862.654807081717, 10015.499225038864, 10048.447335396624, 23270.76729232686, 23289.41776455568, 23344.53310933806, 23398.298051058497, 23416.7658323418, 7600.50596711212, 7719.614019299012, 7841.529508229524, 7908.802279942933, 7965.8805117923275, 4210.347396153601, 4236.343366778093, 4341.500727416387, 4343.804528796211, 4369.235383807821, 3632.019354774249, 3715.535558055243, 3722.3671975764187, 3733.4026555750947, 3738.0467980664757, 631.5863109663086, 643.4839776473964, 659.4790115808457, 662.6610291026897, 670.7719375412286, 571.907998272362, 573.7102763104606, 607.9787207447719, 620.1411045701735, 633.2812258087105, 6758.729650814011, 8245.979734964973, 8450.154984501623, 8501.787455313543, 8527.01628923319, 549.6042849148606, 563.4140493886313, 609.338867328953, 613.1048668943304, 622.8287221014257, 4269.252880745857, 4293.484781826201, 4338.110687309094, 4355.077189611135, 4367.5632436756705, 93504.0, 93901.0, 95154.0, 95238.0, 95249.52375238079, 77217.0, 78436.0, 78616.60691696504, 80658.3868322642, 82721.58639206765, 71820.81502197385, 72373.638131809, 73201.0, 74870.62564687141, 75831.62084189654, 8545.914540854512, 8672.609732561923, 8912.955435222782, 8938.642454301755, 9044.291810688166, 49947.75026124846, 50404.73988150326, 50554.74722626364, 50835.74582127214, 52348.738256308476, 66119.0, 71403.64298178475, 71646.28353716397, 71980.56038879088, 72710.63644681743, 10924.071453926505, 10934.617288395186, 11111.888881111085, 11242.213045086766, 12101.576444824388, 9811.754706132404, 10840.038366539695, 11631.302121872715, 12145.939270303945, 12222.415723348675, 9739.659111931049, 11734.70663233426, 11848.170628055954, 12167.513299467922, 12262.386880655753, 11842.703932401759, 11905.0, 11917.0, 12228.816567751313, 12330.445129968994, 11588.420578971187, 11611.825822612836, 11752.70618234551, 11803.350815704875, 11935.224210426295, 9324.580393882427, 9545.427274363561, 9570.521473926137, 9733.853992189981, 9805.656802011896, 9922.603095876084, 9945.502724863873, 9978.251631127549, 9988.550515226687, 10036.598536058767], "unit": "score", "name": "yui.html", "value": 7165.415547587006}], "name": "dromaeo_css", "value": 7753.2893789982945}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 159.0, 164.0, 135.0, 109.0, 111.0, 127.0, 110.0, 115.0, 159.0], "unit": "score", "name": "ai-astar", "value": 129.8}, {"lowerIsBetter": true, "replicates": [153.0, 120.0, 146.0, 184.0, 137.0, 139.0, 116.0, 176.0, 186.0, 149.0], "unit": "score", "name": "audio-beat-detection", "value": 150.6}, {"lowerIsBetter": true, "replicates": [188.0, 182.0, 185.0, 185.0, 187.0, 183.0, 183.0, 186.0, 185.0, 186.0], "unit": "score", "name": "audio-dft", "value": 185.0}, {"lowerIsBetter": true, "replicates": [75.0, 83.0, 73.0, 121.0, 73.0, 81.0, 83.0, 83.0, 125.0, 47.0], "unit": "score", "name": "audio-fft", "value": 84.4}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 130.0, 77.0, 80.0, 81.0, 120.0, 77.0, 78.0, 121.0], "unit": "score", "name": "audio-oscillator", "value": 94.2}, {"lowerIsBetter": true, "replicates": [87.0, 83.0, 87.0, 86.0, 88.0, 83.0, 98.0, 129.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 91.2}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 92.8}, {"lowerIsBetter": true, "replicates": [102.0, 141.0, 84.0, 87.0, 86.0, 85.0, 84.0, 90.0, 86.0, 71.0], "unit": "score", "name": "imaging-desaturate", "value": 91.6}, {"lowerIsBetter": true, "replicates": [55.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0, 56.0, 68.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 58.0}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 63.0, 75.0, 73.0, 70.0, 65.0, 66.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.0}, {"lowerIsBetter": true, "replicates": [82.0, 80.0, 81.0, 80.0, 81.0, 79.0, 83.0, 81.0, 81.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.4}, {"lowerIsBetter": true, "replicates": [129.0, 125.0, 119.0, 127.0, 117.0, 122.0, 120.0, 122.0, 126.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.5}, {"lowerIsBetter": true, "replicates": [130.0, 119.0, 135.0, 135.0, 135.0, 120.0, 134.0, 134.0, 134.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.7}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 68.0, 66.0, 65.0, 69.0, 88.0, 87.0, 66.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.5}], "name": "kraken", "value": 1450.7}]} 16:30:58 INFO - Return code: 0 16:30:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:30:58 INFO - # TBPL SUCCESS # 16:30:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:58 INFO - 2016-06-02 16:30:58.780 screenresolution[3333:32950] starting screenresolution argv=screenresolution get 16:30:58 INFO - 2016-06-02 16:30:58.783 screenresolution[3333:32950] Display 0: 1600x1200x32@60 16:30:58 INFO - 2016-06-02 16:30:58.789 screenresolution[3334:32953] starting screenresolution argv=screenresolution list 16:30:58 INFO - Available Modes on Display 0 16:30:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:59 INFO - Intel Iris: 16:30:59 INFO - Chipset Model: Intel Iris 16:30:59 INFO - Type: GPU 16:30:59 INFO - Bus: Built-In 16:30:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:59 INFO - Vendor: Intel (0x8086) 16:30:59 INFO - Device ID: 0x0a2e 16:30:59 INFO - Revision ID: 0x0009 16:30:59 INFO - Displays: 16:30:59 INFO - Display: 16:30:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:59 INFO - Display Serial Number: 1600x1200 60 16:30:59 INFO - Main Display: Yes 16:30:59 INFO - Mirror: Off 16:30:59 INFO - Online: Yes 16:30:59 INFO - Rotation: Supported 16:30:59 INFO - Return code: 0 16:30:59 INFO - Running post-action listener: _resource_record_post_action 16:30:59 INFO - Running post-run listener: _resource_record_post_run 16:31:00 INFO - Total resource usage - Wall time: 1193s; CPU: 52.0%; Read bytes: 102400; Write bytes: 511172096; Read time: 2; Write time: 1538 16:31:00 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198459904; Write bytes: 196251648; Read time: 18033; Write time: 407 16:31:00 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 102400; Write bytes: 304958976; Read time: 2; Write time: 1096 16:31:00 INFO - Running post-run listener: _upload_blobber_files 16:31:00 INFO - Blob upload gear active. 16:31:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:31:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:31:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:31:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:31:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:01 INFO - (blobuploader) - INFO - Done attempting. 16:31:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:31:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:02 INFO - (blobuploader) - INFO - Done attempting. 16:31:02 INFO - (blobuploader) - INFO - Iteration through files over. 16:31:02 INFO - Return code: 0 16:31:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:31:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:31:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/073771d6c4c770f229b993e1d4e97ea9ebc2ed49e8b69a958df6b801fff005ade58d5b10be9bb7f64c367b14cc17bc7936ac3a0f9119367b8599c76a9d510dfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/91974fbf713aa444cdc20d92d20c5434b6f30fc3cb49c10624e52a25c84471eb88f777533295a339eef28d70e4f26fcd91b81f213470fdcbf25f592ce6b4e776"} 16:31:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:31:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:31:02 INFO - Contents: 16:31:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/073771d6c4c770f229b993e1d4e97ea9ebc2ed49e8b69a958df6b801fff005ade58d5b10be9bb7f64c367b14cc17bc7936ac3a0f9119367b8599c76a9d510dfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/91974fbf713aa444cdc20d92d20c5434b6f30fc3cb49c10624e52a25c84471eb88f777533295a339eef28d70e4f26fcd91b81f213470fdcbf25f592ce6b4e776"} 16:31:02 INFO - Running post-run listener: copy_logs_to_upload_dir 16:31:02 INFO - Copying logs to upload dir... 16:31:02 INFO - mkdir: /builds/slave/test/build/upload/logs 16:31:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.307704 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-02 16:31:02.165939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 16:31:02.169272) ========= 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.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/073771d6c4c770f229b993e1d4e97ea9ebc2ed49e8b69a958df6b801fff005ade58d5b10be9bb7f64c367b14cc17bc7936ac3a0f9119367b8599c76a9d510dfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/91974fbf713aa444cdc20d92d20c5434b6f30fc3cb49c10624e52a25c84471eb88f777533295a339eef28d70e4f26fcd91b81f213470fdcbf25f592ce6b4e776"} build_url:https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010601 build_url: 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/073771d6c4c770f229b993e1d4e97ea9ebc2ed49e8b69a958df6b801fff005ade58d5b10be9bb7f64c367b14cc17bc7936ac3a0f9119367b8599c76a9d510dfd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/91974fbf713aa444cdc20d92d20c5434b6f30fc3cb49c10624e52a25c84471eb88f777533295a339eef28d70e4f26fcd91b81f213470fdcbf25f592ce6b4e776"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 16:31:02.201394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:31:02.202025) ========= 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.LBjAoxbshg/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.km36bxtkZo/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.005326 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:31:02.255158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 16:31:02.255938) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 16:31:02.256758) ========= ========= Total master_lag: 0.20 =========