builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0076 starttime: 1463421071.02 results: success (0) buildid: 20160516095902 builduid: 119e0603021d484e8dc9eed77b91182d revision: de80d1838a8db73172f7c09f57a3ecc0267f47e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.019664) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.020146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.020432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.182669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.183072) ========= 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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-05-16 10:51:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-05-16 10:51:11 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.579359 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.784526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:11.784837) ========= 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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.027383 ========= master_lag: 0.49 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:12.305858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 10:51:12.306167) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev de80d1838a8db73172f7c09f57a3ecc0267f47e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev de80d1838a8db73172f7c09f57a3ecc0267f47e5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-05-16 10:51:12,395 truncating revision to first 12 chars 2016-05-16 10:51:12,395 Setting DEBUG logging. 2016-05-16 10:51:12,395 attempt 1/10 2016-05-16 10:51:12,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/de80d1838a8d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 10:51:13,599 unpacking tar archive at: fx-team-de80d1838a8d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.442921 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 10:51:13.785033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:13.785348) ========= script_repo_revision: de80d1838a8db73172f7c09f57a3ecc0267f47e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:13.785766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:13.786033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 10:51:13.799907) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-16 10:51:13.800261) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:13 INFO - MultiFileLogger online at 20160516 10:51:13 in /builds/slave/test 10:51:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:51:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:51:13 INFO - {'append_to_log': False, 10:51:13 INFO - 'base_work_dir': '/builds/slave/test', 10:51:13 INFO - 'blob_upload_branch': 'Fx-Team', 10:51:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:51:13 INFO - 'branch': 'Fx-Team', 10:51:13 INFO - 'buildbot_json_path': 'buildprops.json', 10:51:13 INFO - 'config_files': ('talos/mac_config.py',), 10:51:13 INFO - 'default_actions': ('clobber', 10:51:13 INFO - 'read-buildbot-config', 10:51:13 INFO - 'download-and-extract', 10:51:13 INFO - 'populate-webroot', 10:51:13 INFO - 'create-virtualenv', 10:51:13 INFO - 'install', 10:51:13 INFO - 'run-tests'), 10:51:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:51:13 INFO - 'download_minidump_stackwalk': True, 10:51:13 INFO - 'download_symbols': 'ondemand', 10:51:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:51:13 INFO - 'tooltool.py': '/tools/tooltool.py', 10:51:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:51:13 INFO - '/tools/misc-python/virtualenv.py')}, 10:51:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:51:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:51:13 INFO - 'installer_path': 'installer.exe', 10:51:13 INFO - 'log_level': 'info', 10:51:13 INFO - 'log_name': 'talos', 10:51:13 INFO - 'log_to_console': True, 10:51:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:51:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:51:13 INFO - 'opt_config_files': (), 10:51:13 INFO - 'pip_index': False, 10:51:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:13 INFO - 'cmd': ('bash', 10:51:13 INFO - '-c', 10:51:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:13 INFO - 'enabled': True, 10:51:13 INFO - 'halt_on_failure': False, 10:51:13 INFO - 'name': 'check_screen_resolution'},), 10:51:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:13 INFO - 'cmd': ('bash', 10:51:13 INFO - '-c', 10:51:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:13 INFO - 'enabled': True, 10:51:13 INFO - 'halt_on_failure': False, 10:51:13 INFO - 'name': 'check_screen_resolution'},), 10:51:13 INFO - 'run_cmd_checks_enabled': True, 10:51:13 INFO - 'sps_profile': False, 10:51:13 INFO - 'sps_profile_interval': 0, 10:51:13 INFO - 'suite': 'dromaeojs', 10:51:13 INFO - 'system_bits': '32', 10:51:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:51:13 INFO - 'title': 't-yosemite-r7-0076', 10:51:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:51:13 INFO - 'use_talos_json': True, 10:51:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:51:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:51:13 INFO - 'work_dir': 'build'} 10:51:13 INFO - ##### 10:51:13 INFO - ##### Running clobber step. 10:51:13 INFO - ##### 10:51:13 INFO - Running pre-action listener: _resource_record_pre_action 10:51:13 INFO - Running main action method: clobber 10:51:13 INFO - rmtree: /builds/slave/test/build 10:51:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:51:15 INFO - Running post-action listener: _resource_record_post_action 10:51:15 INFO - ##### 10:51:15 INFO - ##### Finished clobber step (success) 10:51:15 INFO - ##### 10:51:15 INFO - ##### 10:51:15 INFO - ##### Running read-buildbot-config step. 10:51:15 INFO - ##### 10:51:15 INFO - Running pre-action listener: _resource_record_pre_action 10:51:15 INFO - Running main action method: read_buildbot_config 10:51:15 INFO - Using buildbot properties: 10:51:15 INFO - { 10:51:15 INFO - "project": "", 10:51:15 INFO - "product": "firefox", 10:51:15 INFO - "script_repo_revision": "production", 10:51:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:51:15 INFO - "repository": "", 10:51:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:51:15 INFO - "buildid": "20160516095902", 10:51:15 INFO - "pgo_build": "False", 10:51:15 INFO - "basedir": "/builds/slave/test", 10:51:15 INFO - "buildnumber": 11, 10:51:15 INFO - "slavename": "t-yosemite-r7-0076", 10:51:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:51:15 INFO - "platform": "yosemite_r7", 10:51:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:51:15 INFO - "branch": "fx-team", 10:51:15 INFO - "repo_path": "integration/fx-team", 10:51:15 INFO - "revision": "de80d1838a8db73172f7c09f57a3ecc0267f47e5", 10:51:15 INFO - "stage_platform": "macosx64", 10:51:15 INFO - "builduid": "119e0603021d484e8dc9eed77b91182d", 10:51:15 INFO - "slavebuilddir": "test" 10:51:15 INFO - } 10:51:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:51:15 INFO - Running post-action listener: _resource_record_post_action 10:51:15 INFO - ##### 10:51:15 INFO - ##### Finished read-buildbot-config step (success) 10:51:15 INFO - ##### 10:51:15 INFO - ##### 10:51:15 INFO - ##### Running download-and-extract step. 10:51:15 INFO - ##### 10:51:15 INFO - Running pre-action listener: _resource_record_pre_action 10:51:15 INFO - Running main action method: download_and_extract 10:51:15 INFO - mkdir: /builds/slave/test/build/tests 10:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:15 INFO - https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:51:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:51:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:51:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:51:19 INFO - Downloaded 1199 bytes. 10:51:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:51:19 INFO - Using the following test package requirements: 10:51:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:51:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:51:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:51:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'jsshell-mac.zip'], 10:51:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:51:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:51:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:51:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:51:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:51:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:51:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:51:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:19 INFO - https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:51:20 INFO - Downloaded 18267620 bytes. 10:51:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:51:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:51:23 INFO - Return code: 0 10:51:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:51:23 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')]} 10:51:23 INFO - https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:51:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:51:25 INFO - Downloaded 18267620 bytes. 10:51:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:51:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:51:29 INFO - Return code: 0 10:51:29 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')]} 10:51:29 INFO - https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:51:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:51:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:51:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:51:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:51:31 INFO - Downloaded 10840194 bytes. 10:51:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:51:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:51:31 INFO - Return code: 0 10:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:31 INFO - https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:51:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:51:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:51:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:51:38 INFO - Downloaded 110049116 bytes. 10:51:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:51:38 INFO - mkdir: /builds/slave/test/properties 10:51:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:51:38 INFO - Writing to file /builds/slave/test/properties/build_url 10:51:38 INFO - Contents: 10:51:38 INFO - build_url:https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:51:39 INFO - Running post-action listener: _resource_record_post_action 10:51:39 INFO - Running post-action listener: set_extra_try_arguments 10:51:39 INFO - ##### 10:51:39 INFO - ##### Finished download-and-extract step (success) 10:51:39 INFO - ##### 10:51:39 INFO - ##### 10:51:39 INFO - ##### Running populate-webroot step. 10:51:39 INFO - ##### 10:51:39 INFO - Running pre-action listener: _resource_record_pre_action 10:51:39 INFO - Running main action method: populate_webroot 10:51:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:51:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:51:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:51:39 INFO - '--tppagecycles', 10:51:39 INFO - '7'], 10:51:39 INFO - 'tests': ['tsvgm']}}, 10:51:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:51:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:51:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:51:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:51:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['damp', 'tps']}, 10:51:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'tests': ['damp', 'tps']}, 10:51:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['dromaeo_dom']}, 10:51:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:51:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'other-e10s': {'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['a11yr', 10:51:39 INFO - 'ts_paint', 10:51:39 INFO - 'tpaint', 10:51:39 INFO - 'sessionrestore', 10:51:39 INFO - 'sessionrestore_no_auto_restore', 10:51:39 INFO - 'tabpaint']}, 10:51:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['tsvgx', 10:51:39 INFO - 'tsvgr_opacity', 10:51:39 INFO - 'tart', 10:51:39 INFO - 'tscrollx', 10:51:39 INFO - 'cart']}, 10:51:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:51:39 INFO - 'tsvgr_opacity', 10:51:39 INFO - 'tart', 10:51:39 INFO - 'tscrollx', 10:51:39 INFO - 'cart']}, 10:51:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'talos_options': ['--disable-e10s'], 10:51:39 INFO - 'tests': ['tp5o']}, 10:51:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'tests': ['tp5o']}, 10:51:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'talos_options': ['--disable-e10s', 10:51:39 INFO - '--xperf_path', 10:51:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:39 INFO - 'tests': ['tp5n']}, 10:51:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:39 INFO - 'talos_options': ['--xperf_path', 10:51:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:39 INFO - 'tests': ['tp5n']}}, 10:51:39 INFO - 'talos.zip': {'path': '', 10:51:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:51:39 INFO - Running post-action listener: _resource_record_post_action 10:51:39 INFO - ##### 10:51:39 INFO - ##### Finished populate-webroot step (success) 10:51:39 INFO - ##### 10:51:39 INFO - ##### 10:51:39 INFO - ##### Running create-virtualenv step. 10:51:39 INFO - ##### 10:51:39 INFO - Running pre-action listener: _resource_record_pre_action 10:51:39 INFO - Running main action method: create_virtualenv 10:51:39 INFO - Creating virtualenv /builds/slave/test/build/venv 10:51:39 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 10:51:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:51:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:51:39 INFO - Using real prefix '/tools/python27' 10:51:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:51:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:51:43 INFO - Installing pip.................done. 10:51:43 INFO - Return code: 0 10:51:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:51:43 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')]} 10:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:43 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')]} 10:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:43 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:43 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 10:51:43 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 10:51:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:51:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:43 INFO - 'HOME': '/Users/cltbld', 10:51:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:43 INFO - 'LOGNAME': 'cltbld', 10:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:43 INFO - 'MOZ_NO_REMOTE': '1', 10:51:43 INFO - 'NO_EM_RESTART': '1', 10:51:43 INFO - 'PAGER': '/bin/cat', 10:51:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:43 INFO - 'PWD': '/builds/slave/test', 10:51:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:43 INFO - 'SHELL': '/bin/bash', 10:51:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:51:43 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:51:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:43 INFO - 'USER': 'cltbld', 10:51:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:43 INFO - 'XPC_FLAGS': '0x0', 10:51:43 INFO - 'XPC_SERVICE_NAME': '0', 10:51:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:43 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 10:51:43 INFO - Cleaning up... 10:51:43 INFO - Return code: 0 10:51:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:51:43 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')]} 10:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:43 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')]} 10:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:51:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:51:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:51:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:43 INFO - 'HOME': '/Users/cltbld', 10:51:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:43 INFO - 'LOGNAME': 'cltbld', 10:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:43 INFO - 'MOZ_NO_REMOTE': '1', 10:51:43 INFO - 'NO_EM_RESTART': '1', 10:51:43 INFO - 'PAGER': '/bin/cat', 10:51:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:43 INFO - 'PWD': '/builds/slave/test', 10:51:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:43 INFO - 'SHELL': '/bin/bash', 10:51:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:51:43 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:51:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:43 INFO - 'USER': 'cltbld', 10:51:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:43 INFO - 'XPC_FLAGS': '0x0', 10:51:43 INFO - 'XPC_SERVICE_NAME': '0', 10:51:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:43 INFO - Downloading/unpacking psutil>=3.1.1 10:51:43 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 10:51:43 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 10:51:43 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 10:51:43 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 10:51:43 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 10:51:43 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 10:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:51:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:46 INFO - Installing collected packages: psutil 10:51:46 INFO - Running setup.py install for psutil 10:51:46 INFO - building 'psutil._psutil_osx' extension 10:51:46 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 10:51:47 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 10:51:47 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 10:51:47 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 10:51:47 INFO - building 'psutil._psutil_posix' extension 10:51:47 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 10:51:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:51:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:51:47 INFO - ^ 10:51:47 INFO - 1 warning generated. 10:51:47 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 10:51:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:47 INFO - Successfully installed psutil 10:51:47 INFO - Cleaning up... 10:51:48 INFO - Return code: 0 10:51:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:48 INFO - 'HOME': '/Users/cltbld', 10:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:48 INFO - 'LOGNAME': 'cltbld', 10:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:48 INFO - 'MOZ_NO_REMOTE': '1', 10:51:48 INFO - 'NO_EM_RESTART': '1', 10:51:48 INFO - 'PAGER': '/bin/cat', 10:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:48 INFO - 'PWD': '/builds/slave/test', 10:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:48 INFO - 'SHELL': '/bin/bash', 10:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:51:48 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:48 INFO - 'USER': 'cltbld', 10:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:48 INFO - 'XPC_FLAGS': '0x0', 10:51:48 INFO - 'XPC_SERVICE_NAME': '0', 10:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:51:48 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 10:51:48 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 10:51:48 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 10:51:48 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 10:51:48 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 10:51:48 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 10:51:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:51:50 INFO - Installing collected packages: mozsystemmonitor 10:51:50 INFO - Running setup.py install for mozsystemmonitor 10:51:50 INFO - Successfully installed mozsystemmonitor 10:51:50 INFO - Cleaning up... 10:51:51 INFO - Return code: 0 10:51:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:51 INFO - 'HOME': '/Users/cltbld', 10:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:51 INFO - 'LOGNAME': 'cltbld', 10:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:51 INFO - 'MOZ_NO_REMOTE': '1', 10:51:51 INFO - 'NO_EM_RESTART': '1', 10:51:51 INFO - 'PAGER': '/bin/cat', 10:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:51 INFO - 'PWD': '/builds/slave/test', 10:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:51 INFO - 'SHELL': '/bin/bash', 10:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:51:51 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:51 INFO - 'USER': 'cltbld', 10:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:51 INFO - 'XPC_FLAGS': '0x0', 10:51:51 INFO - 'XPC_SERVICE_NAME': '0', 10:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:51:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:51:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:51:53 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 10:51:53 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 10:51:53 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 10:51:53 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 10:51:53 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 10:51:53 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 10:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:51:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:51: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 10:51: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 10:51: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 10:51: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 10:51: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 10:51: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 10:51:54 INFO - Downloading docopt-0.6.1.tar.gz 10:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:51:55 INFO - Installing collected packages: blobuploader, requests, docopt 10:51:55 INFO - Running setup.py install for blobuploader 10:51:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:51:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:51:55 INFO - Running setup.py install for requests 10:51:55 INFO - Running setup.py install for docopt 10:51:55 INFO - Successfully installed blobuploader requests docopt 10:51:55 INFO - Cleaning up... 10:51:55 INFO - Return code: 0 10:51:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51:55 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')]} 10:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:55 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')]} 10:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:55 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:51:55 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 10:51:55 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 10:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:55 INFO - 'HOME': '/Users/cltbld', 10:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:55 INFO - 'LOGNAME': 'cltbld', 10:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:55 INFO - 'MOZ_NO_REMOTE': '1', 10:51:55 INFO - 'NO_EM_RESTART': '1', 10:51:55 INFO - 'PAGER': '/bin/cat', 10:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:55 INFO - 'PWD': '/builds/slave/test', 10:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:55 INFO - 'SHELL': '/bin/bash', 10:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:51:55 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:55 INFO - 'USER': 'cltbld', 10:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:55 INFO - 'XPC_FLAGS': '0x0', 10:51:55 INFO - 'XPC_SERVICE_NAME': '0', 10:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-29DEa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-5Xof9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-I1WZgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-pl73_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-ueFEH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-bii6T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-obHJVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-1RQhDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:51:56 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-iOBYwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-u0TSdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-oV0HrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-y29ugr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-g8Dcn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-SxF9Xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-h1DUkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-0w2CZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:51:57 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-L_D1K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:51:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:51:57 INFO - Running setup.py install for manifestparser 10:51:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:51:58 INFO - Running setup.py install for mozcrash 10:51:58 INFO - Running setup.py install for mozdebug 10:51:58 INFO - Running setup.py install for mozdevice 10:51:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:51:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:51:58 INFO - Running setup.py install for mozfile 10:51:58 INFO - Running setup.py install for mozhttpd 10:51:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:51:58 INFO - Running setup.py install for mozinfo 10:51:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:51:58 INFO - Running setup.py install for mozInstall 10:51:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Running setup.py install for mozleak 10:51:59 INFO - Running setup.py install for mozlog 10:51:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Running setup.py install for moznetwork 10:51:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Running setup.py install for mozprocess 10:51:59 INFO - Running setup.py install for mozprofile 10:51:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:51:59 INFO - Running setup.py install for mozrunner 10:52:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:52:00 INFO - Running setup.py install for mozscreenshot 10:52:00 INFO - Running setup.py install for moztest 10:52:00 INFO - Running setup.py install for mozversion 10:52:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:52:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:52:00 INFO - Cleaning up... 10:52:00 INFO - Return code: 0 10:52:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:00 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:52:00 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 10:52:00 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 10:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:00 INFO - 'HOME': '/Users/cltbld', 10:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:00 INFO - 'LOGNAME': 'cltbld', 10:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:00 INFO - 'MOZ_NO_REMOTE': '1', 10:52:00 INFO - 'NO_EM_RESTART': '1', 10:52:00 INFO - 'PAGER': '/bin/cat', 10:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:00 INFO - 'PWD': '/builds/slave/test', 10:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:00 INFO - 'SHELL': '/bin/bash', 10:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:52:00 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:00 INFO - 'USER': 'cltbld', 10:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:00 INFO - 'XPC_FLAGS': '0x0', 10:52:00 INFO - 'XPC_SERVICE_NAME': '0', 10:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:00 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-b7by0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:00 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)) 10:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:00 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-c7BQ7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:00 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)) 10:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:00 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-lAooE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-RtuplC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-1di3Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-qIhZ8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-CmrQNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-QOstCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-lMA2xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-HghzYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-4RFGDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:01 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)) 10:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:01 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-kATktG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:02 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)) 10:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:02 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Oyn4fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:02 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)) 10:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:02 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-kcEqaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:02 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)) 10:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:02 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-CyNBhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:02 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)) 10:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:02 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-_wMDRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:02 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)) 10:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:02 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Y1WPSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:02 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)) 10:52:02 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)) 10:52:02 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)) 10:52:02 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)) 10:52:02 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)) 10:52:02 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)) 10:52:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:52:02 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 10:52:02 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 10:52:02 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 10:52:02 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 10:52:02 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 10:52:02 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 10:52:05 INFO - Downloading blessings-1.6.tar.gz 10:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:52:05 INFO - Installing collected packages: blessings 10:52:05 INFO - Running setup.py install for blessings 10:52:05 INFO - Successfully installed blessings 10:52:05 INFO - Cleaning up... 10:52:05 INFO - Return code: 0 10:52:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:52:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:05 INFO - Reading from file tmpfile_stdout 10:52:05 INFO - Current package versions: 10:52:05 INFO - blessings == 1.6 10:52:05 INFO - blobuploader == 1.2.4 10:52:05 INFO - docopt == 0.6.1 10:52:05 INFO - manifestparser == 1.1 10:52:05 INFO - mozInstall == 1.12 10:52:05 INFO - mozcrash == 0.17 10:52:05 INFO - mozdebug == 0.1 10:52:05 INFO - mozdevice == 0.48 10:52:05 INFO - mozfile == 1.2 10:52:05 INFO - mozhttpd == 0.7 10:52:05 INFO - mozinfo == 0.9 10:52:05 INFO - mozleak == 0.1 10:52:05 INFO - mozlog == 3.2 10:52:05 INFO - moznetwork == 0.27 10:52:05 INFO - mozprocess == 0.22 10:52:05 INFO - mozprofile == 0.28 10:52:05 INFO - mozrunner == 6.11 10:52:05 INFO - mozscreenshot == 0.1 10:52:05 INFO - mozsystemmonitor == 0.3 10:52:05 INFO - moztest == 0.7 10:52:05 INFO - mozversion == 1.4 10:52:05 INFO - psutil == 3.1.1 10:52:05 INFO - requests == 1.2.3 10:52:05 INFO - wsgiref == 0.1.2 10:52:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:05 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:52:05 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 10:52:05 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 10:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:05 INFO - 'HOME': '/Users/cltbld', 10:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:05 INFO - 'LOGNAME': 'cltbld', 10:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:05 INFO - 'MOZ_NO_REMOTE': '1', 10:52:05 INFO - 'NO_EM_RESTART': '1', 10:52:05 INFO - 'PAGER': '/bin/cat', 10:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:05 INFO - 'PWD': '/builds/slave/test', 10:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:05 INFO - 'SHELL': '/bin/bash', 10:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:52:05 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:05 INFO - 'USER': 'cltbld', 10:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:05 INFO - 'XPC_FLAGS': '0x0', 10:52:05 INFO - 'XPC_SERVICE_NAME': '0', 10:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 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)) 10:52:05 INFO - Cleaning up... 10:52:05 INFO - Return code: 0 10:52:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:52:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:52:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:06 INFO - 'HOME': '/Users/cltbld', 10:52:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:06 INFO - 'LOGNAME': 'cltbld', 10:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:06 INFO - 'MOZ_NO_REMOTE': '1', 10:52:06 INFO - 'NO_EM_RESTART': '1', 10:52:06 INFO - 'PAGER': '/bin/cat', 10:52:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:06 INFO - 'PWD': '/builds/slave/test', 10:52:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:06 INFO - 'SHELL': '/bin/bash', 10:52:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:52:06 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:52:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:06 INFO - 'USER': 'cltbld', 10:52:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:06 INFO - 'XPC_FLAGS': '0x0', 10:52:06 INFO - 'XPC_SERVICE_NAME': '0', 10:52:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:06 INFO - Downloading/unpacking jsonschema 10:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:08 INFO - Downloading/unpacking functools32 (from jsonschema) 10:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:09 INFO - warning: no files found matching '*.txt' 10:52:09 INFO - no previously-included directories found matching 'build' 10:52:09 INFO - no previously-included directories found matching 'dist' 10:52:09 INFO - no previously-included directories found matching '.git*' 10:52:09 INFO - Installing collected packages: jsonschema, functools32 10:52:09 INFO - Running setup.py install for functools32 10:52:09 INFO - warning: no files found matching '*.txt' 10:52:09 INFO - no previously-included directories found matching 'build' 10:52:09 INFO - no previously-included directories found matching 'dist' 10:52:09 INFO - no previously-included directories found matching '.git*' 10:52:09 INFO - Successfully installed jsonschema functools32 10:52:09 INFO - Cleaning up... 10:52:09 INFO - Return code: 0 10:52:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c924ba450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, '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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 10:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:09 INFO - 'HOME': '/Users/cltbld', 10:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:09 INFO - 'LOGNAME': 'cltbld', 10:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:09 INFO - 'MOZ_NO_REMOTE': '1', 10:52:09 INFO - 'NO_EM_RESTART': '1', 10:52:09 INFO - 'PAGER': '/bin/cat', 10:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:09 INFO - 'PWD': '/builds/slave/test', 10:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:09 INFO - 'SHELL': '/bin/bash', 10:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:52:09 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:09 INFO - 'USER': 'cltbld', 10:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:09 INFO - 'XPC_FLAGS': '0x0', 10:52:09 INFO - 'XPC_SERVICE_NAME': '0', 10:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:09 INFO - Downloading/unpacking flake8 10:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:52:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:52:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:52:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:13 INFO - no previously-included directories found matching 'docs/_build' 10:52:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:52:13 INFO - Running setup.py install for pep8 10:52:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:13 INFO - no previously-included directories found matching 'docs/_build' 10:52:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:52:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:52:13 INFO - Cleaning up... 10:52:13 INFO - Return code: 0 10:52:13 INFO - Running post-action listener: _resource_record_post_action 10:52:13 INFO - Running post-action listener: _start_resource_monitoring 10:52:13 INFO - Starting resource monitoring. 10:52:13 INFO - ##### 10:52:13 INFO - ##### Finished create-virtualenv step (success) 10:52:13 INFO - ##### 10:52:13 INFO - ##### 10:52:13 INFO - ##### Running install step. 10:52:13 INFO - ##### 10:52:13 INFO - Running pre-action listener: _resource_record_pre_action 10:52:13 INFO - Running main action method: install 10:52:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:14 INFO - Reading from file tmpfile_stdout 10:52:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:52:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:52:14 INFO - Reading from file tmpfile_stdout 10:52:14 INFO - Output received: 10:52:14 INFO - Usage: mozinstall [options] installer 10:52:14 INFO - Options: 10:52:14 INFO - -h, --help show this help message and exit 10:52:14 INFO - -d DEST, --destination=DEST 10:52:14 INFO - Directory to install application into. [default: 10:52:14 INFO - "/builds/slave/test"] 10:52:14 INFO - --app=APP Application being installed. [default: firefox] 10:52:14 INFO - mkdir: /builds/slave/test/build/application 10:52:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:52:48 INFO - Reading from file tmpfile_stdout 10:52:48 INFO - Output received: 10:52:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:52:48 INFO - Running post-action listener: _resource_record_post_action 10:52:48 INFO - ##### 10:52:48 INFO - ##### Finished install step (success) 10:52:48 INFO - ##### 10:52:48 INFO - ##### 10:52:48 INFO - ##### Running run-tests step. 10:52:48 INFO - ##### 10:52:48 INFO - Running pre-action listener: _resource_record_pre_action 10:52:48 INFO - Running main action method: run_tests 10:52:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:48 INFO - 2016-05-16 10:52:48.848 screenresolution[1650:8301] starting screenresolution argv=screenresolution get 10:52:48 INFO - 2016-05-16 10:52:48.851 screenresolution[1650:8301] Display 0: 1600x1200x32@60 10:52:48 INFO - 2016-05-16 10:52:48.856 screenresolution[1651:8303] starting screenresolution argv=screenresolution list 10:52:48 INFO - Available Modes on Display 0 10:52:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:49 INFO - Intel Iris: 10:52:49 INFO - Chipset Model: Intel Iris 10:52:49 INFO - Type: GPU 10:52:49 INFO - Bus: Built-In 10:52:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:49 INFO - Vendor: Intel (0x8086) 10:52:49 INFO - Device ID: 0x0a2e 10:52:49 INFO - Revision ID: 0x0009 10:52:49 INFO - Displays: 10:52:49 INFO - Display: 10:52:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:49 INFO - Display Serial Number: 1600x1200 60 10:52:49 INFO - Main Display: Yes 10:52:49 INFO - Mirror: Off 10:52:49 INFO - Online: Yes 10:52:49 INFO - Rotation: Supported 10:52:49 INFO - Return code: 0 10:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:52:49 INFO - Python 2.7.3 10:52:49 INFO - Return code: 0 10:52:49 INFO - grabbing minidump binary from tooltool 10:52:49 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')]} 10:52:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f55d30>, '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 0x108d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:52:49 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 10:52:49 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 10:52:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:52:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:52:49 INFO - Return code: 0 10:52:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:52:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:52:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:52:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:52:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:52:50 INFO - Return code: 0 10:52:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 10:52:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --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 10:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v6l2U4xRYc/Render', 10:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:50 INFO - 'HOME': '/Users/cltbld', 10:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:50 INFO - 'LOGNAME': 'cltbld', 10:52:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:52:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:50 INFO - 'MOZ_NO_REMOTE': '1', 10:52:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:52:50 INFO - 'NO_EM_RESTART': '1', 10:52:50 INFO - 'PAGER': '/bin/cat', 10:52:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:50 INFO - 'PWD': '/builds/slave/test', 10:52:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:50 INFO - 'SHELL': '/bin/bash', 10:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pv9HYZTefe/Listeners', 10:52:50 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 10:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:50 INFO - 'USER': 'cltbld', 10:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:50 INFO - 'XPC_FLAGS': '0x0', 10:52:50 INFO - 'XPC_SERVICE_NAME': '0', 10:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 10:52:50 INFO - mozversion application_buildid: 20160516095902 10:52:50 INFO - mozversion application_changeset: de80d1838a8db73172f7c09f57a3ecc0267f47e5 10:52:50 INFO - mozversion application_display_name: Nightly 10:52:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:52:50 INFO - mozversion application_name: Firefox 10:52:50 INFO - mozversion application_remotingname: firefox 10:52:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:52:50 INFO - mozversion application_vendor: Mozilla 10:52:50 INFO - mozversion application_version: 49.0a1 10:52:50 INFO - mozversion platform_buildid: 20160516095902 10:52:50 INFO - mozversion platform_changeset: de80d1838a8db73172f7c09f57a3ecc0267f47e5 10:52:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:52:50 INFO - mozversion platform_version: 49.0a1 10:52:50 INFO - using testdate: 1463421170 10:52:50 INFO - actual date: 1463421170 10:52:50 INFO - starting webserver on 'localhost:49275' 10:52:50 INFO - SUITE-START | Running 2 tests 10:52:50 INFO - TEST-START | dromaeo_css 10:52:50 INFO - operating with platform_type : mac_ 10:52:50 INFO - Initialising browser for dromaeo_css test... 10:52:50 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpUdE9BM/profile http://localhost:49275/getInfo.html) 10:52:52 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 10:52:52 INFO - PROCESS | 1669 | colorDepth:24 10:52:52 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 10:52:52 INFO - PROCESS | 1669 | __metrics 10:52:53 INFO - PROCESS | 1669 | 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" 10:52:53 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:52:53 INFO - TEST-INFO | 1669: exit 0 10:52:53 INFO - Browser initialized. 10:52:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:52:53 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpUdE9BM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:05 INFO - PROCESS | 1672 | RSS: Main: 239046656 10:53:05 INFO - PROCESS | 1672 | 10:53:57 INFO - PROCESS | 1672 | 1463421237498 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] 10:56:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:56:10 INFO - PROCESS | 1672 | RSS: Main: 434548736 10:56:10 INFO - PROCESS | 1672 | 10:59:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:59:13 INFO - PROCESS | 1672 | RSS: Main: 377286656 10:59:13 INFO - PROCESS | 1672 | 11:02:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 11:02:16 INFO - PROCESS | 1672 | RSS: Main: 367599616 11:02:16 INFO - PROCESS | 1672 | 11:05:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 11:05:19 INFO - PROCESS | 1672 | RSS: Main: 367902720 11:05:19 INFO - PROCESS | 1672 | 11:08:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 11:08:23 INFO - PROCESS | 1672 | RSS: Main: 334356480 11:08:23 INFO - PROCESS | 1672 | 11:11:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 11:11:27 INFO - PROCESS | 1672 | RSS: Main: 406855680 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | __start_tp_report 11:11:27 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 11:11:27 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 11:11:27 INFO - PROCESS | 1672 | |i|pagename|runs| 11:11:27 INFO - PROCESS | 1672 | |0;dojo.html;3439.0542600784784;3446.517487551743;3447.8448469818873;3477.4783782432614;3518.208403109299;5667.376588575264;5695.800646977356;5708.372079071304;5799.449052340026;5836.941630583693;4813.8796530086765;5748.109043098327;5853.8829223415505;5874.412558744123;5916.0830071338905;5089.821856235032;5120;5179.818706345279;5187.559057480119;5275.235090911816;5266.131088370415;5297.8940421191555;5306.257124002643;5323.920141197866;5342.2253773202865;5102.770375333119;5116.539511443951;5125.3080834087295;5129.384473863161;5147.485251474823;5134.0758663440565;5148.176291793333;5155.61332900034;5160.225966105077;5199.454057323965;5141.305923700327;5176.50823171798;5209.374875014984;5220.660657057317;5229.267902493654;5527;5613.270274864282;5653.9151912721345;5671.120976248688;5675.233843431125;6185.8144255672405;6199.225096862892;6217.906731399033;6227.159333489966;6238.688065596747;6194.690265486708;6217.658028808413;6218.626882387026;6238.594491358047;6239.750409983595;5735.598508104435;5806.216160818278;5857.736401861926;5869.647821130746;5892.911606325909;6298.244210694746;6307.684615769193;6316.336784637547;6324.525660575383;6325.430711236011;6053.727582258809;6191.504679625619;6194.318624951252;6206.348333425016;6219.626822390581;6117.553376797837;6175.135481032659;6241.906371404341;6257.968710156511;6261.123442717932;6167.013277875519;6202.224721909762;6212.192414986023;6212.502999760009;6221.5333849960525;6133.977404287557;6213.502919766408;6218.19163508741;6225.066240064027;6225.688715564203;4869.805207791719;4880.975595122072;4900.5099490051425;4908.190148625508;4936.531029552187;4365.323374876899;4366.541513141075;4375.1031038637;4375.934360984587;4403.075354175659;5629.380768115504;5764.13537969308;5775.104858746901;5801.419858014165;5805.063907365347;6212.534059945522;6236.719347629368;6239.656818874914;6260.968695156495;6272.749090036439;5764.048931926185;5775.047117225611;5785.537157027512;5793.015187418149;5810.099434587646;5296.25852380667;5390.218418329398;5412.972935135299;5423.294971653739;5424.511793938565;6243.250809902932;6272.435480806749;6288.465480434214;6297.811065668037;6324.083007963727;6271.3728627136925;6299;6302.653354065387;6306.747730090745;6313.842153946188;6271;6291.874162516816;6322.747090116528;6342.556021078664;6347.587406818542;6093.75624974996;6098.0086717533295;6117.388261173847;6186.938130618816;6198.039303907902;6262.968685156545;6266.342034086538;6283.59156654816;6286.779962701284;6292.433680968735;5975.073863551156;6017.3681763414215;6104.11490333908;6107.847303817262;6126.663033533198;5921.289445266596;6079.118527813531;6121.663308518075;6126.540509461719;6141.662208578571;5928.703564821828;5951.791687290924;5995.040793473024;5998.190244317136;6009.549283803644;6148.78479253224;6149.169862068564;6152.077188421773;6173.012318029094;6180.907286390798;5832.24180856486;5904.996150654313;5924.140999555127;5976.252968378953;5978.641281523122 11:11:27 INFO - PROCESS | 1672 | |1;ext.html;520.8828013696917;527.9467462412487;533.3280067115436;561.4553882733746;569.6667449542018;35662.64337356626;40618.796906015465;41983.580164198385;42497.36253956193;42649.99999999996;36972;43356.78321608395;43820.56179438205;43990.340144897775;44082.33876491855;8667.306615470747;9763.951180244125;9989.100980911717;11727.882721172808;11965.940170299136;11767.999720023809;11884.643460696192;11885.40572971348;11887.346195959219;11890.989265912407;11813;11853.822192667118;11861.584844530444;11863.7627247455;11876.346800925945;10230.283880128394;11789.7642047159;11855.28098425732;11861.762764744743;11865.5847045353;9724.076212759777;11104.888951110466;11302.378369189692;11684.591039313584;11854.229475084092;40356.59643403558;40575.78272651825;40588.39117413211;40591.79704101461;40624.39063414051;3181.984090079558;3248.7076163145193;3332.8833490827706;3511.6839484446523;3821.1975485147873;28644.092355812718;28669.426611467654;28811.85594072016;28871.711282887114;29052;2689.9596506052426;2707.1472486166795;2714.484247992875;2833.6457942757156;2915.3440475892753;455072.144949857;480817;493172;494435;494955;58920.705396472746;59313;59330.40669593335;59360.812783744084;59402.405975939684;63307;63402.73194536176;63464.36535634677;63468.73062538723;63589.36410635927;15197.316120774592;16727.91636041812;16907.830921690627;16960.15199240033;16967.49097527076;11994.760104797855;12293.385330733428;12293.69265768345;12297.938510307391;13028.218306901616;15552.92223538875;15697.921510392374;16036.518904432885;16063.437779677655;16100.516984490485;6503.577267477599;6612.471002319803;6682.866342673216;6714.597124172566;6733.494987875929;898.8247291778041;1003.8745156855393;1027.753339198587;1098.741795678015;1134.5802053240354;28284;29319.680614372406;29571.556426653617;30394.54408183879;30430;1130.423484023154;1137.7553825927466;1203.524607779922;1228.0850766179253;1238.7150955280335;15222.771658425132;15300.846991530165;15459.988026342093;15486.303116359566;15777.368905243891;663301.6834915795;664707.6764616146;670023;670376;671307;59460.70269648624;59781.103283450786;59820.700896496106;59835;59898.70050649806;1338.5248236875768;1460.5107289058285;1505.8644721975074;1516.3934426229596;1524.5654988328274;27720.02979895694;30439.389093234928;30447.194100737408;30780.692193077783;31105.222369440038;47721;51164.74417628037;51301.48698512967;51425.7428712854;51474.42627868517;549280;551947;552107;552133;552355;37616.059598510255;38482.80758596301;39356.606433935296;39393.80303098466;39397.60602393939;45768.54231457776;45938.540614593425;46175.728971357894;46188.7690561545;46427.76786116048;31000.22499437532;34328.970130896116;36474;36614.63385366112;38976.02559936024;31310.686893130776;37042.259154816216;37781.43327850029;37867.81066094652;37965;36969.63030369662;37199.07002324964;37397.252054959295;37440.438393424665;38423.423648645854;32855.01434956955;34486.655133448345;34766;34934;34998.65001349954;21429.89285053565;22971.081156753542;23800.88099559491;24956.12653557117;25325.240242792745 11:11:27 INFO - PROCESS | 1672 | |2;jquery.html;372.5343320848939;389.6415297925908;399.38812951586476;400.0897957147489;400.12572529024084;12307.446164922578;18739.625207495843;20199.798002019976;21284.787152128472;21291;16543.25555350009;18810.905945470287;19972.101255443504;20190.39428817134;20525;5943.821685349436;6119.755209791603;6146.0473626587955;6186.969065154668;6191.81424557264;6032.27612686476;6056.091586262074;6068.817935461943;6082.391760823926;6087.665178415185;5825.825225243249;6047.21386219793;6053.364396738345;6065;6076.4834989025985;5921.319048309457;5967.433093856077;6051;6056.303525094627;6061.45446909778;5276.630635855492;5286.550643195333;5298.841034768963;5308.734563271821;5310.867228319323;23328.883355583283;25165;26284.86857565719;26580.202593922215;26695.599566006524;2003.2187446895723;2032.89835508224;2066.7209926659857;2269.841111122222;2331.988340058289;14349.641258968504;15001.849981500154;15237.771433428508;15402.46091386796;15452.278125139392;1571.7720930465132;1607.1803380276144;1625.2280166920618;1676.8993860368416;1685.4522280258966;67883.66058169727;80281.59859200666;83577.58211208905;119132;123796.04815807356;11215;11552;11556.768864622829;11727.824082638766;11731.237469564621;14943;15084;15098.39606415753;15110.168940708147;15131.697366052616;5950.672713000827;6771.830704232433;6834.1457317835275;7884.688430859053;8297.460665056751;8534.061253262136;8913.108689131035;8922.787796393293;9030.86453703195;9041.63833446668;4600.60894823937;4672.859814205579;4685.929711054337;4692.249240121564;4770.379850619467;1952.6290004899024;1980.5147738804085;1981.791911849235;1984.7122167285663;1988.9303874364327;852.8997593104949;868.1188593577524;887.587271918561;891.7057371067532;1033.390299723167;17829.821701783076;18010.90994545019;18278.903265804096;20842.89578552128;20883.582328353346;842.3219308456751;855.0756413067325;954.5227386306847;998.3510718033336;1099.8515200447998;10339.896601033894;10955.616553420592;10969.780604387868;10991.725206869893;11049.33703977764;602378;609230;618978;623085;624474;75085;113212;138887;142429.28785356006;143869.28065359607;921.1525396635112;1020.1787561013456;1024.6874703215442;1049.2445439283704;1050.8581341518798;18165.81834181641;18322.26710931548;18481.630367392307;18621.882687038822;18691.90654046721;50231;51063;51561.48438515717;51886.44340669786;51900.74049629728;74662;77449;78383.21616783759;78840.60579697065;79364;6922.440352154094;8126.146754590683;8127.512349259064;8275.420720549744;8415.4109212357;9151.130642588943;10579.735506612089;10991.945040274748;11241.156913231376;12349.19730217533;10929.508172132115;10932.6720198391;10963.232573719763;11025.889741102807;11211.159163062639;9557.808843823224;10540.15678745714;10731.839022414513;10912.945435272773;10966.342019478856;11113.388763617755;11225.382603956861;11246;11437.885621144014;11561.30632162073;8052.275295223458;8080.0707918590115;8096.473729207582;8278.668853245803;8283.668653253802;7826.334761545105;7926.920730792849;8221.465604735673;8246.505209687439;10481.056704896597 11:11:27 INFO - PROCESS | 1672 | |3;mootools.html;1441.6251774538616;1592.1084192852004;1631.8939268947518;1873.6065426260484;1990.243707391191;7966.044074711035;8715.91284087159;8966.148215919486;8968.775780605472;9075.818483630323;8717.956410217956;8915.286777057836;8916.46501209928;8927.24118449931;8932.821343573125;2147.914083436661;2215.2246713650256;2215.357546311576;2397.280815755271;2427.3688840901405;2204.0853045985896;2237.194609940428;2385.546746118232;2465.235776909155;2465.272744540356;2373.264288070695;2374.845635033726;2379.916702915398;2380.214529205367;2383.7377888432256;2311.919082832101;2355.1756885090253;2357.08073851199;2357.8821058946983;2358.811295096388;2146.2488129154826;2263.739669937962;2310.90756369745;2357.8585284882793;2417.4439878827966;8835.469871807712;8892.688755893578;8917.33120015995;8935.64257429702;8945.821083578292;5314.7608357624;5465.72671366434;5467.617266791286;5471.972640136813;5472.835814925558;3437.8281085945855;3449.120474279043;3502.667246611568;3536.1159710072484;3542.079059444537;986.363795351994;994.6966175316526;999.0958182844537;1005.9497025148713;1007.1539906478594;11726.179167458282;11735.471903764266;11744.882551174464;11758.470868810839;11767.823482647767;4219.725717828332;4754.405699287589;4798.688085274446;4909.533594308535;4932.26016097581;10045.045720656672;10046.849297260547;10068.137394582025;10070.345427547187;10098.545565449425;5396.271503346998;5442.972785136049;5450.809221677222;5451.890962180813;5454.427285135003;4239.660827133822;4525.117602067631;4812.590929770939;5016.372953380827;5030;3622.5471816022996;3810.1998580298446;3920.666743326792;3927.4501569780255;3928.9214215715524;1057.360297020307;1225.4852961756085;1226.4358395138158;1234.5432190089725;1236.4683186229827;842.2125312832521;844.923956843887;879.5030807593689;894.6645008121955;950.5199874063553;5410.48600382963;5465.9180112298345;5473.397926228112;5681;5706.600537962303;845.3701992015865;900.1853322742928;954.4702690006974;1017.1862509991977;1055.9260851740457;3398.2693720850134;3562.251927095339;3679.5216621839527;3762.830672619739;3788.0719223790347;10492.527836247387;10767.730806729738;10807.243492955418;10816.945915270373;10831.512581933832;10719.356838589709;10821.56713731442;10829.350238985686;10837.512311945982;10856.891431085745;7642.579658118746;7968.203179682101;8042.396820238506;8436.620352084172;8442.240198382053;5428.67427954324;5570.91643625354;5622.8313150605545;5680.977424063708;5734.856628584319;9731.61073557078;9839.163671088038;10153.238507111848;10157.035081667229;10236.846447303147;10148.137408320375;10225.386476811414;10331.896681033093;10344.896551034695;10382.688519344432;7016.719331226694;7136.785896423115;7480.476366654282;7480.738174163878;7573.318401343685;7608.467407281437;7864.567448790142;7867.567283799446;7950.960245198969;7957.204279572228;6747.993738974842;6836.794896153124;7383;7463.888041679488;7663.118741344817;7333.926660733325;7415.073115860518;7462.738804141829;7569.962150189213;7595.620218989139;6895.6552172392185;6909.412699920563;6938.410235129869;6946.992686060594;6967.54710943787;4636.976815115903;4787.832425865078;4812.230043193073;4844.806207751651;4976.104301225814;4600.7469589172915;4806.759662016815;4870.269459581091;4919.778609962632;5073.974630126826 11:11:27 INFO - PROCESS | 1672 | |4;prototype.html;309.5975232198142;319.173341046689;323.104999152348;326.4157158685952;329.1524324863477;4746.928796068062;5410.56715462763;5431.7012564309;5459.454054594544;5478.890422191564;4508.7971041303135;4551.613112885409;5271.262023316739;5364.229804164626;5444.3194600674915;3907.7264591478465;3938.3022629498887;4027.335489644205;4055.695822813286;4140.441040459545;4070.8778736637946;4097.262492751303;4099.959000410003;4102.681984678662;4115.32097203961;3541.5041894134843;3972.36442169253;4046.6358027777496;4061.512618485786;4062.614051665087;3356.865725370982;3575.8199794067896;3931.606839316074;4087.591240875918;4123.092919557707;2997.220722612115;3229.8708051677904;3255.7395408367274;3523.94714079289;3551.875684351061;5221.660592061504;5245.134552798783;5280;5280.023195708768;5291.894162116774;4202.810873510699;4337.84817531388;4575.4738205106505;4638.350630911675;4708.811647534095;5020.397552293748;5031.899362012776;5640.971795141039;5677.347105082927;5687.744051517691;3440.587129544446;3829.291581057513;3870.4968354113785;3877.4377715231135;3921.9604806275365;5111.309973153614;5335.813246536352;5455.890882182373;5509.531689806371;5978.192943952554;3550.751447398709;3800.7529510581726;3866.7486613370043;3921.313770090223;3922.5489068756888;3758.5489741231227;3965.8300801263695;3978.244133614604;3995.780232087263;4015.7992100394863;3899.6490315871706;4148.066684995852;4225.690036088822;4642.2340313847835;4672.971946171905;4816.34979277793;5284.207368894697;5448.536874365644;5560.833175004756;5738.454846789548;1090.8382572560197;1158.5250047480492;1215.3619349841226;1291.9547815826588;1362.7955806629086;750.9812254693676;782.9586649755811;789.4394979564458;793.4448480977234;830.3938125168661;4555.817767289338;5315.069862774077;5337.973310133502;5340.706261155596;5381.035921888561;5179.067767801756;5453.481919217668;5862.443067908542;5862.853428664317;5891.7937872175125;4778.904421911542;4875.853724388274;5297.357819076099;5327.627066105413;5387.164989426645;2845.331347133422;2930;3011.217083558247;3030.409035256464;3042.75247148668;5344.43883392246;5394.541463975606;5464.7814087436855;5589.35722391922;6066.696665166724;5449.891002179934;5749.712514374264;5995.100734889714;5995.940040599626;6043.637381757108;5590.972045139748;5995.760169593254;6024.2288547268745;6060.090986351995;6072.993868035351;5038.722870242172;5445.564354851682;5684.488396044376;5711.801825658054;5857.326407463196;4867.561919427269;5452.4286592282115;5711.372258906192;5760.279965004374;5776.682282474504;5353.411124776194;5455.708602387086;5799.362070172362;5977.701114944149;6100.267967843887;3758.9060273493383;3921.2157568485845;3931.60683931616;4076.2458945094068;4086.223617512663;5015.573676237561;5219.4780521947505;5899.637206792087;5926.7925622603;5965.135055416853;4984.053029924447;5410.891782164256;5699.544036477155;5753.769849206153;5807.448292412214;5356.544693701079;5741.1388291756575;5747.339056008445;5751.281089863767;5753.698520591629;5223.876866473726;5563.276774019513;5586.52514536269;5638.308507447831;5641.548676105819;4813.470518243061;5304.681719096867;5530.529904958124;5666.320041595035;5776.537876969916;4804.615630749601;5280.419153892992;5523.978064058162;5528.585356098389;5533.916991245215 11:11:27 INFO - PROCESS | 1672 | |5;yui.html;645.7158850105953;671.8454755406253;694.8645014222226;730.7259777583406;742.5038547327136;13827.930860345698;14529.491467798629;14546.981711280214;14547.563573092812;15472.458463953764;16315;16624.83375166248;16682.749758753627;16685.416010439636;16706.498805035837;720.178995944621;917.1104029091804;918.8575770755527;926.4811705444939;926.763675262807;743.9851202975938;899.3299991506304;928.6656803550717;930.1767935377172;930.4835816122084;955.4649396338073;956.3592393096642;961.5865177973469;963.1716723617683;964.2719746591314;758.1735907860459;955.8040601676639;957.34421920984;958.8897276813186;959.4195511715382;687.7970998555438;709.5458906299972;711.0329951266274;711.2993701204309;712.1276436365463;14193.929030354886;14220.075695079786;14228.715425691427;14229.359678814479;14246.287685615782;12156.452959616838;12204.877951220464;12248.387580620933;12521.248725076524;12569.183003104768;10854.837177442345;10864.478972943714;11394.094505901025;11634;12134.514619415213;4029.738067025634;4081.410049827716;4169.187008533338;4172.624463798273;4282.336237883133;63234;80397.59801201014;82825;83753.74369384465;83952;9301.302402319718;9514.04859514043;9573.664921727706;9579.616815327308;9605.03949605034;20578.971051447366;20586.176552938014;20633.89683051575;20667.966601670156;20676;6871.725130994804;6886.896696549556;7016.614086225307;7053.858922821618;7080.964595177094;3314.486254630536;3359.983200084033;3865.555461122007;3896.902577435587;3897.2205558888336;2894.884204631833;3373.2916087621384;3485.9651403486146;3487.755857089979;3492.35391452577;613.9877202455926;619.1177571959939;622.1662971617964;639.5874660843823;641.9101325814296;494.1451289269512;507.14799137448705;509.2717414097876;516.6202840911926;585.7071464267866;7462.067241594801;7474.177840437547;7600.695972161162;7915.208479152038;7981.241782030698;450.4054647864787;469.90710907991775;496.4142312071718;552.6960171905436;556.504517885877;3529.6470352964498;3603.2253065591026;3677.191017976041;3763.8306276217636;3801.258754542893;84501;91672;93275.53362233145;93330;93506.59740103903;75565;75828.62085689536;80088.59955700184;80515.59742201252;82101.58949205215;71970.9204361935;72982;73182;73192.26807731854;73538.26461735314;7142.142942846892;7306.41548676094;7361.374283185989;7371.815704607213;7393.8890916637365;41217.793911030254;45494.28094395548;46227.76886115682;47029.529704702516;48337.54987350385;53477.930441391734;59289.703551481965;61147;61159.082613759936;61172.69413652903;9419.623215071595;9444.150026497648;9481.810363792547;9490.28822838276;9866.703998880044;8640.56797160152;10238.283320167517;10319.380837149794;10571.418571978615;10621.256512044083;9646.083622055894;9686;9730.41617502952;9830.655927042519;10025.949870250603;8839.646414143619;9718.611255549698;9789.070038346346;9850.064243896819;10020.498975051363;9570.712878613653;9573.9042609573;9581.760455988657;9630.374025688308;9657.71026869195;8338.291245244221;8537.786555336166;8573.957130214309;8593.742187734379;8692.391532592657;8306;8352.665893364197;8381.832363352576;8461.661533538589;8653.221210091122 11:11:27 INFO - PROCESS | 1672 | __end_tp_report 11:11:27 INFO - PROCESS | 1672 | __start_cc_report 11:11:27 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2196 11:11:27 INFO - PROCESS | 1672 | __end_cc_report 11:11:27 INFO - PROCESS | 1672 | __startTimestamp1463422287049__endTimestamp 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | ------- Summary: start ------- 11:11:27 INFO - PROCESS | 1672 | Number of tests: 6 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5750.87 Median:5976.25 stddev:639.06 (10.7%) stddev-sans-first:614.75 11:11:27 INFO - PROCESS | 1672 | Values: 3439.1 3446.5 3447.8 3477.5 3518.2 5667.4 5695.8 5708.4 5799.4 5836.9 4813.9 5748.1 5853.9 5874.4 5916.1 5089.8 5120.0 5179.8 5187.6 5275.2 5266.1 5297.9 5306.3 5323.9 5342.2 5102.8 5116.5 5125.3 5129.4 5147.5 5134.1 5148.2 5155.6 5160.2 5199.5 5141.3 5176.5 5209.4 5220.7 5229.3 5527.0 5613.3 5653.9 5671.1 5675.2 6185.8 6199.2 6217.9 6227.2 6238.7 6194.7 6217.7 6218.6 6238.6 6239.8 5735.6 5806.2 5857.7 5869.6 5892.9 6298.2 6307.7 6316.3 6324.5 6325.4 6053.7 6191.5 6194.3 6206.3 6219.6 6117.6 6175.1 6241.9 6258.0 6261.1 6167.0 6202.2 6212.2 6212.5 6221.5 6134.0 6213.5 6218.2 6225.1 6225.7 4869.8 4881.0 4900.5 4908.2 4936.5 4365.3 4366.5 4375.1 4375.9 4403.1 5629.4 5764.1 5775.1 5801.4 5805.1 6212.5 6236.7 6239.7 6261.0 6272.7 5764.0 5775.0 5785.5 5793.0 5810.1 5296.3 5390.2 5413.0 5423.3 5424.5 6243.3 6272.4 6288.5 6297.8 6324.1 6271.4 6299.0 6302.7 6306.7 6313.8 6271.0 6291.9 6322.7 6342.6 6347.6 6093.8 6098.0 6117.4 6186.9 6198.0 6263.0 6266.3 6283.6 6286.8 6292.4 5975.1 6017.4 6104.1 6107.8 6126.7 5921.3 6079.1 6121.7 6126.5 6141.7 5928.7 5951.8 5995.0 5998.2 6009.5 6148.8 6149.2 6152.1 6173.0 6180.9 5832.2 5905.0 5924.1 5976.3 5978.6 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:70417.28 Median:19255.17 stddev:153040.60 (794.8%) stddev-sans-first:153379.94 11:11:27 INFO - PROCESS | 1672 | Values: 520.9 527.9 533.3 561.5 569.7 35662.6 40618.8 41983.6 42497.4 42650.0 36972.0 43356.8 43820.6 43990.3 44082.3 8667.3 9764.0 9989.1 11727.9 11965.9 11768.0 11884.6 11885.4 11887.3 11891.0 11813.0 11853.8 11861.6 11863.8 11876.3 10230.3 11789.8 11855.3 11861.8 11865.6 9724.1 11104.9 11302.4 11684.6 11854.2 40356.6 40575.8 40588.4 40591.8 40624.4 3182.0 3248.7 3332.9 3511.7 3821.2 28644.1 28669.4 28811.9 28871.7 29052.0 2690.0 2707.1 2714.5 2833.6 2915.3 455072.1 480817.0 493172.0 494435.0 494955.0 58920.7 59313.0 59330.4 59360.8 59402.4 63307.0 63402.7 63464.4 63468.7 63589.4 15197.3 16727.9 16907.8 16960.2 16967.5 11994.8 12293.4 12293.7 12297.9 13028.2 15552.9 15697.9 16036.5 16063.4 16100.5 6503.6 6612.5 6682.9 6714.6 6733.5 898.8 1003.9 1027.8 1098.7 1134.6 28284.0 29319.7 29571.6 30394.5 30430.0 1130.4 1137.8 1203.5 1228.1 1238.7 15222.8 15300.8 15460.0 15486.3 15777.4 663301.7 664707.7 670023.0 670376.0 671307.0 59460.7 59781.1 59820.7 59835.0 59898.7 1338.5 1460.5 1505.9 1516.4 1524.6 27720.0 30439.4 30447.2 30780.7 31105.2 47721.0 51164.7 51301.5 51425.7 51474.4 549280.0 551947.0 552107.0 552133.0 552355.0 37616.1 38482.8 39356.6 39393.8 39397.6 45768.5 45938.5 46175.7 46188.8 46427.8 31000.2 34329.0 36474.0 36614.6 38976.0 31310.7 37042.3 37781.4 37867.8 37965.0 36969.6 37199.1 37397.3 37440.4 38423.4 32855.0 34486.7 34766.0 34934.0 34998.7 21429.9 22971.1 23800.9 24956.1 25325.2 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:34548.97 Median:13534.07 stddev:102192.90 (755.1%) stddev-sans-first:102447.36 11:11:27 INFO - PROCESS | 1672 | Values: 372.5 389.6 399.4 400.1 400.1 12307.4 18739.6 20199.8 21284.8 21291.0 16543.3 18810.9 19972.1 20190.4 20525.0 5943.8 6119.8 6146.0 6187.0 6191.8 6032.3 6056.1 6068.8 6082.4 6087.7 5825.8 6047.2 6053.4 6065.0 6076.5 5921.3 5967.4 6051.0 6056.3 6061.5 5276.6 5286.6 5298.8 5308.7 5310.9 23328.9 25165.0 26284.9 26580.2 26695.6 2003.2 2032.9 2066.7 2269.8 2332.0 14349.6 15001.8 15237.8 15402.5 15452.3 1571.8 1607.2 1625.2 1676.9 1685.5 67883.7 80281.6 83577.6 119132.0 123796.0 11215.0 11552.0 11556.8 11727.8 11731.2 14943.0 15084.0 15098.4 15110.2 15131.7 5950.7 6771.8 6834.1 7884.7 8297.5 8534.1 8913.1 8922.8 9030.9 9041.6 4600.6 4672.9 4685.9 4692.2 4770.4 1952.6 1980.5 1981.8 1984.7 1988.9 852.9 868.1 887.6 891.7 1033.4 17829.8 18010.9 18278.9 20842.9 20883.6 842.3 855.1 954.5 998.4 1099.9 10339.9 10955.6 10969.8 10991.7 11049.3 602378.0 609230.0 618978.0 623085.0 624474.0 75085.0 113212.0 138887.0 142429.3 143869.3 921.2 1020.2 1024.7 1049.2 1050.9 18165.8 18322.3 18481.6 18621.9 18691.9 50231.0 51063.0 51561.5 51886.4 51900.7 74662.0 77449.0 78383.2 78840.6 79364.0 6922.4 8126.1 8127.5 8275.4 8415.4 9151.1 10579.7 10991.9 11241.2 12349.2 10929.5 10932.7 10963.2 11025.9 11211.2 9557.8 10540.2 10731.8 10912.9 10966.3 11113.4 11225.4 11246.0 11437.9 11561.3 8052.3 8080.1 8096.5 8278.7 8283.7 7826.3 7926.9 8221.5 8246.5 10481.1 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5684.45 Median:4809.49 stddev:3234.16 (67.2%) stddev-sans-first:3227.51 11:11:27 INFO - PROCESS | 1672 | Values: 1441.6 1592.1 1631.9 1873.6 1990.2 7966.0 8715.9 8966.1 8968.8 9075.8 8718.0 8915.3 8916.5 8927.2 8932.8 2147.9 2215.2 2215.4 2397.3 2427.4 2204.1 2237.2 2385.5 2465.2 2465.3 2373.3 2374.8 2379.9 2380.2 2383.7 2311.9 2355.2 2357.1 2357.9 2358.8 2146.2 2263.7 2310.9 2357.9 2417.4 8835.5 8892.7 8917.3 8935.6 8945.8 5314.8 5465.7 5467.6 5472.0 5472.8 3437.8 3449.1 3502.7 3536.1 3542.1 986.4 994.7 999.1 1005.9 1007.2 11726.2 11735.5 11744.9 11758.5 11767.8 4219.7 4754.4 4798.7 4909.5 4932.3 10045.0 10046.8 10068.1 10070.3 10098.5 5396.3 5443.0 5450.8 5451.9 5454.4 4239.7 4525.1 4812.6 5016.4 5030.0 3622.5 3810.2 3920.7 3927.5 3928.9 1057.4 1225.5 1226.4 1234.5 1236.5 842.2 844.9 879.5 894.7 950.5 5410.5 5465.9 5473.4 5681.0 5706.6 845.4 900.2 954.5 1017.2 1055.9 3398.3 3562.3 3679.5 3762.8 3788.1 10492.5 10767.7 10807.2 10816.9 10831.5 10719.4 10821.6 10829.4 10837.5 10856.9 7642.6 7968.2 8042.4 8436.6 8442.2 5428.7 5570.9 5622.8 5681.0 5734.9 9731.6 9839.2 10153.2 10157.0 10236.8 10148.1 10225.4 10331.9 10344.9 10382.7 7016.7 7136.8 7480.5 7480.7 7573.3 7608.5 7864.6 7867.6 7951.0 7957.2 6748.0 6836.8 7383.0 7463.9 7663.1 7333.9 7415.1 7462.7 7570.0 7595.6 6895.7 6909.4 6938.4 6947.0 6967.5 4637.0 4787.8 4812.2 4844.8 4976.1 4600.7 4806.8 4870.3 4919.8 5074.0 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4552.00 Median:5258.20 stddev:1415.61 (26.9%) stddev-sans-first:1383.31 11:11:27 INFO - PROCESS | 1672 | Values: 309.6 319.2 323.1 326.4 329.2 4746.9 5410.6 5431.7 5459.5 5478.9 4508.8 4551.6 5271.3 5364.2 5444.3 3907.7 3938.3 4027.3 4055.7 4140.4 4070.9 4097.3 4100.0 4102.7 4115.3 3541.5 3972.4 4046.6 4061.5 4062.6 3356.9 3575.8 3931.6 4087.6 4123.1 2997.2 3229.9 3255.7 3523.9 3551.9 5221.7 5245.1 5280.0 5280.0 5291.9 4202.8 4337.8 4575.5 4638.4 4708.8 5020.4 5031.9 5641.0 5677.3 5687.7 3440.6 3829.3 3870.5 3877.4 3922.0 5111.3 5335.8 5455.9 5509.5 5978.2 3550.8 3800.8 3866.7 3921.3 3922.5 3758.5 3965.8 3978.2 3995.8 4015.8 3899.6 4148.1 4225.7 4642.2 4673.0 4816.3 5284.2 5448.5 5560.8 5738.5 1090.8 1158.5 1215.4 1292.0 1362.8 751.0 783.0 789.4 793.4 830.4 4555.8 5315.1 5338.0 5340.7 5381.0 5179.1 5453.5 5862.4 5862.9 5891.8 4778.9 4875.9 5297.4 5327.6 5387.2 2845.3 2930.0 3011.2 3030.4 3042.8 5344.4 5394.5 5464.8 5589.4 6066.7 5449.9 5749.7 5995.1 5995.9 6043.6 5591.0 5995.8 6024.2 6060.1 6073.0 5038.7 5445.6 5684.5 5711.8 5857.3 4867.6 5452.4 5711.4 5760.3 5776.7 5353.4 5455.7 5799.4 5977.7 6100.3 3758.9 3921.2 3931.6 4076.2 4086.2 5015.6 5219.5 5899.6 5926.8 5965.1 4984.1 5410.9 5699.5 5753.8 5807.4 5356.5 5741.1 5747.3 5751.3 5753.7 5223.9 5563.3 5586.5 5638.3 5641.5 4813.5 5304.7 5530.5 5666.3 5776.5 4804.6 5280.4 5524.0 5528.6 5533.9 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17644.90 Median:3895.26 stddev:25287.73 (649.2%) stddev-sans-first:25326.45 11:11:27 INFO - PROCESS | 1672 | Values: 645.7 671.8 694.9 730.7 742.5 13827.9 14529.5 14547.0 14547.6 15472.5 16315.0 16624.8 16682.7 16685.4 16706.5 720.2 917.1 918.9 926.5 926.8 744.0 899.3 928.7 930.2 930.5 955.5 956.4 961.6 963.2 964.3 758.2 955.8 957.3 958.9 959.4 687.8 709.5 711.0 711.3 712.1 14193.9 14220.1 14228.7 14229.4 14246.3 12156.5 12204.9 12248.4 12521.2 12569.2 10854.8 10864.5 11394.1 11634.0 12134.5 4029.7 4081.4 4169.2 4172.6 4282.3 63234.0 80397.6 82825.0 83753.7 83952.0 9301.3 9514.0 9573.7 9579.6 9605.0 20579.0 20586.2 20633.9 20668.0 20676.0 6871.7 6886.9 7016.6 7053.9 7081.0 3314.5 3360.0 3865.6 3896.9 3897.2 2894.9 3373.3 3486.0 3487.8 3492.4 614.0 619.1 622.2 639.6 641.9 494.1 507.1 509.3 516.6 585.7 7462.1 7474.2 7600.7 7915.2 7981.2 450.4 469.9 496.4 552.7 556.5 3529.6 3603.2 3677.2 3763.8 3801.3 84501.0 91672.0 93275.5 93330.0 93506.6 75565.0 75828.6 80088.6 80515.6 82101.6 71970.9 72982.0 73182.0 73192.3 73538.3 7142.1 7306.4 7361.4 7371.8 7393.9 41217.8 45494.3 46227.8 47029.5 48337.5 53477.9 59289.7 61147.0 61159.1 61172.7 9419.6 9444.2 9481.8 9490.3 9866.7 8640.6 10238.3 10319.4 10571.4 10621.3 9646.1 9686.0 9730.4 9830.7 10025.9 8839.6 9718.6 9789.1 9850.1 10020.5 9570.7 9573.9 9581.8 9630.4 9657.7 8338.3 8537.8 8574.0 8593.7 8692.4 8306.0 8352.7 8381.8 8461.7 8653.2 11:11:27 INFO - PROCESS | 1672 | -------- Summary: end -------- 11:11:27 INFO - PROCESS | 1672 | 11:11:27 INFO - TEST-INFO | 1672: exit 0 11:11:27 INFO - TEST-OK | dromaeo_css | took 1117059ms 11:11:27 INFO - TEST-START | kraken 11:11:27 INFO - operating with platform_type : mac_ 11:11:27 INFO - Initialising browser for kraken test... 11:11:27 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpv2Q4Mg/profile http://localhost:49275/getInfo.html) 11:11:29 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 11:11:29 INFO - PROCESS | 1678 | colorDepth:24 11:11:29 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 11:11:29 INFO - PROCESS | 1678 | __metrics 11:11:29 INFO - PROCESS | 1678 | 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" 11:11:29 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:11:30 INFO - TEST-INFO | 1678: exit 0 11:11:30 INFO - Browser initialized. 11:11:30 INFO - Running cycle 1/1 for kraken test... 11:11:30 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpv2Q4Mg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:42 INFO - PROCESS | 1680 | RSS: Main: 239280128 11:11:42 INFO - PROCESS | 1680 | 11:11:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 11:11:46 INFO - PROCESS | 1680 | RSS: Main: 288096256 11:11:46 INFO - PROCESS | 1680 | 11:11:48 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 11:11:49 INFO - PROCESS | 1680 | RSS: Main: 296312832 11:11:49 INFO - PROCESS | 1680 | 11:11:53 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 11:11:53 INFO - PROCESS | 1680 | RSS: Main: 386613248 11:11:53 INFO - PROCESS | 1680 | 11:11:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 11:11:56 INFO - PROCESS | 1680 | RSS: Main: 285872128 11:11:56 INFO - PROCESS | 1680 | 11:11:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:11:59 INFO - PROCESS | 1680 | RSS: Main: 289574912 11:11:59 INFO - PROCESS | 1680 | 11:12:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 11:12:03 INFO - PROCESS | 1680 | RSS: Main: 330993664 11:12:03 INFO - PROCESS | 1680 | 11:12:06 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 11:12:06 INFO - PROCESS | 1680 | RSS: Main: 332357632 11:12:06 INFO - PROCESS | 1680 | 11:12:09 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 11:12:10 INFO - PROCESS | 1680 | RSS: Main: 330502144 11:12:10 INFO - PROCESS | 1680 | 11:12:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:12:12 INFO - PROCESS | 1680 | RSS: Main: 316710912 11:12:12 INFO - PROCESS | 1680 | 11:12:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:12:15 INFO - PROCESS | 1680 | RSS: Main: 323047424 11:12:15 INFO - PROCESS | 1680 | 11:12:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:12:18 INFO - PROCESS | 1680 | RSS: Main: 325644288 11:12:18 INFO - PROCESS | 1680 | 11:12:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:12:22 INFO - PROCESS | 1680 | RSS: Main: 329859072 11:12:22 INFO - PROCESS | 1680 | 11:12:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:12:25 INFO - PROCESS | 1680 | RSS: Main: 327630848 11:12:25 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 11:12:27 INFO - PROCESS | 1680 | RSS: Main: 374198272 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | __start_tp_report 11:12:27 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 11:12:27 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 11:12:27 INFO - PROCESS | 1680 | |i|pagename|runs| 11:12:27 INFO - PROCESS | 1680 | |0;ai-astar;112;106;112;112;112;111;112;112;113;110 11:12:27 INFO - PROCESS | 1680 | |1;audio-beat-detection;157;121;141;196;117;138;138;119;167;123 11:12:27 INFO - PROCESS | 1680 | |2;audio-dft;188;186;185;184;185;181;183;184;255;231 11:12:27 INFO - PROCESS | 1680 | |3;audio-fft;79;73;57;126;57;60;57;59;111;56 11:12:27 INFO - PROCESS | 1680 | |4;audio-oscillator;94;101;134;80;80;77;132;107;63;134 11:12:27 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;91;87;90;87;90;86;90;87;90;87 11:12:27 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;96;95;95;95;96;96;94;95 11:12:27 INFO - PROCESS | 1680 | |7;imaging-desaturate;86;85;86;87;88;84;86;86;89;86 11:12:27 INFO - PROCESS | 1680 | |8;json-parse-financial;56;55;57;61;56;56;57;120;55;56 11:12:27 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;62;65;76;75;71;68;68;68;69;67 11:12:27 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;86;83;106;99;103;77;82;85;83;80 11:12:27 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;139;137;133;139;140;144;141;147;236;143 11:12:27 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;165;210;205;163;163;163;163;163;167;160 11:12:27 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;66;67;66;67;67;65;66;65;65;64 11:12:27 INFO - PROCESS | 1680 | __end_tp_report 11:12:27 INFO - PROCESS | 1680 | __start_cc_report 11:12:27 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2316 11:12:27 INFO - PROCESS | 1680 | __end_cc_report 11:12:27 INFO - PROCESS | 1680 | __startTimestamp1463422347571__endTimestamp 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | ------- Summary: start ------- 11:12:27 INFO - PROCESS | 1680 | Number of tests: 14 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.99 (1.8%) stddev-sans-first:2.09 11:12:27 INFO - PROCESS | 1680 | Values: 112.0 106.0 112.0 112.0 112.0 111.0 112.0 112.0 113.0 110.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:139.50 stddev:25.29 (18.1%) stddev-sans-first:26.21 11:12:27 INFO - PROCESS | 1680 | Values: 157.0 121.0 141.0 196.0 117.0 138.0 138.0 119.0 167.0 123.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:196.20 Median:185.50 stddev:25.37 (13.7%) stddev-sans-first:26.74 11:12:27 INFO - PROCESS | 1680 | Values: 188.0 186.0 185.0 184.0 185.0 181.0 183.0 184.0 255.0 231.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.00 stddev:25.18 (43.4%) stddev-sans-first:26.63 11:12:27 INFO - PROCESS | 1680 | Values: 79.0 73.0 57.0 126.0 57.0 60.0 57.0 59.0 111.0 56.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:100.20 Median:70.00 stddev:26.08 (37.3%) stddev-sans-first:27.56 11:12:27 INFO - PROCESS | 1680 | Values: 94.0 101.0 134.0 80.0 80.0 77.0 132.0 107.0 63.0 134.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:90.00 stddev:1.84 (2.0%) stddev-sans-first:1.72 11:12:27 INFO - PROCESS | 1680 | Values: 91.0 87.0 90.0 87.0 90.0 86.0 90.0 87.0 90.0 87.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:12:27 INFO - PROCESS | 1680 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 94.0 95.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:86.00 stddev:1.42 (1.6%) stddev-sans-first:1.50 11:12:27 INFO - PROCESS | 1680 | Values: 86.0 85.0 86.0 87.0 88.0 84.0 86.0 86.0 89.0 86.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:56.00 stddev:20.14 (36.0%) stddev-sans-first:21.20 11:12:27 INFO - PROCESS | 1680 | Values: 56.0 55.0 57.0 61.0 56.0 56.0 57.0 120.0 55.0 56.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:68.50 stddev:4.23 (6.2%) stddev-sans-first:3.67 11:12:27 INFO - PROCESS | 1680 | Values: 62.0 65.0 76.0 75.0 71.0 68.0 68.0 68.0 69.0 67.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:83.00 stddev:10.29 (12.4%) stddev-sans-first:10.87 11:12:27 INFO - PROCESS | 1680 | Values: 86.0 83.0 106.0 99.0 103.0 77.0 82.0 85.0 83.0 80.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:142.00 stddev:30.50 (21.5%) stddev-sans-first:32.09 11:12:27 INFO - PROCESS | 1680 | Values: 139.0 137.0 133.0 139.0 140.0 144.0 141.0 147.0 236.0 143.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:164.00 stddev:18.72 (11.4%) stddev-sans-first:19.68 11:12:27 INFO - PROCESS | 1680 | Values: 165.0 210.0 205.0 163.0 163.0 163.0 163.0 163.0 167.0 160.0 11:12:27 INFO - PROCESS | 1680 | 11:12:27 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 11:12:27 INFO - PROCESS | 1680 | Values: 66.0 67.0 66.0 67.0 67.0 65.0 66.0 65.0 65.0 64.0 11:12:27 INFO - PROCESS | 1680 | -------- Summary: end -------- 11:12:27 INFO - PROCESS | 1680 | 11:12:28 INFO - TEST-INFO | 1680: exit 0 11:12:28 INFO - TEST-OK | kraken | took 60791ms 11:12:28 INFO - SUITE-END | took 1177s 11:12:28 INFO - Completed test suite (00:19:38) 11:12:28 INFO - javascript benchmark 11:12:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3439.0542600784784, 3446.517487551743, 3447.8448469818873, 3477.4783782432614, 3518.208403109299, 5667.376588575264, 5695.800646977356, 5708.372079071304, 5799.449052340026, 5836.941630583693, 4813.8796530086765, 5748.109043098327, 5853.8829223415505, 5874.412558744123, 5916.0830071338905, 5089.821856235032, 5120.0, 5179.818706345279, 5187.559057480119, 5275.235090911816, 5266.131088370415, 5297.8940421191555, 5306.257124002643, 5323.920141197866, 5342.2253773202865, 5102.770375333119, 5116.539511443951, 5125.3080834087295, 5129.384473863161, 5147.485251474823, 5134.0758663440565, 5148.176291793333, 5155.61332900034, 5160.225966105077, 5199.454057323965, 5141.305923700327, 5176.50823171798, 5209.374875014984, 5220.660657057317, 5229.267902493654, 5527.0, 5613.270274864282, 5653.9151912721345, 5671.120976248688, 5675.233843431125, 6185.8144255672405, 6199.225096862892, 6217.906731399033, 6227.159333489966, 6238.688065596747, 6194.690265486708, 6217.658028808413, 6218.626882387026, 6238.594491358047, 6239.750409983595, 5735.598508104435, 5806.216160818278, 5857.736401861926, 5869.647821130746, 5892.911606325909, 6298.244210694746, 6307.684615769193, 6316.336784637547, 6324.525660575383, 6325.430711236011, 6053.727582258809, 6191.504679625619, 6194.318624951252, 6206.348333425016, 6219.626822390581, 6117.553376797837, 6175.135481032659, 6241.906371404341, 6257.968710156511, 6261.123442717932, 6167.013277875519, 6202.224721909762, 6212.192414986023, 6212.502999760009, 6221.5333849960525, 6133.977404287557, 6213.502919766408, 6218.19163508741, 6225.066240064027, 6225.688715564203, 4869.805207791719, 4880.975595122072, 4900.5099490051425, 4908.190148625508, 4936.531029552187, 4365.323374876899, 4366.541513141075, 4375.1031038637, 4375.934360984587, 4403.075354175659, 5629.380768115504, 5764.13537969308, 5775.104858746901, 5801.419858014165, 5805.063907365347, 6212.534059945522, 6236.719347629368, 6239.656818874914, 6260.968695156495, 6272.749090036439, 5764.048931926185, 5775.047117225611, 5785.537157027512, 5793.015187418149, 5810.099434587646, 5296.25852380667, 5390.218418329398, 5412.972935135299, 5423.294971653739, 5424.511793938565, 6243.250809902932, 6272.435480806749, 6288.465480434214, 6297.811065668037, 6324.083007963727, 6271.3728627136925, 6299.0, 6302.653354065387, 6306.747730090745, 6313.842153946188, 6271.0, 6291.874162516816, 6322.747090116528, 6342.556021078664, 6347.587406818542, 6093.75624974996, 6098.0086717533295, 6117.388261173847, 6186.938130618816, 6198.039303907902, 6262.968685156545, 6266.342034086538, 6283.59156654816, 6286.779962701284, 6292.433680968735, 5975.073863551156, 6017.3681763414215, 6104.11490333908, 6107.847303817262, 6126.663033533198, 5921.289445266596, 6079.118527813531, 6121.663308518075, 6126.540509461719, 6141.662208578571, 5928.703564821828, 5951.791687290924, 5995.040793473024, 5998.190244317136, 6009.549283803644, 6148.78479253224, 6149.169862068564, 6152.077188421773, 6173.012318029094, 6180.907286390798, 5832.24180856486, 5904.996150654313, 5924.140999555127, 5976.252968378953, 5978.641281523122], "value": 5710.07865101968, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.8828013696917, 527.9467462412487, 533.3280067115436, 561.4553882733746, 569.6667449542018, 35662.64337356626, 40618.796906015465, 41983.580164198385, 42497.36253956193, 42649.99999999996, 36972.0, 43356.78321608395, 43820.56179438205, 43990.340144897775, 44082.33876491855, 8667.306615470747, 9763.951180244125, 9989.100980911717, 11727.882721172808, 11965.940170299136, 11767.999720023809, 11884.643460696192, 11885.40572971348, 11887.346195959219, 11890.989265912407, 11813.0, 11853.822192667118, 11861.584844530444, 11863.7627247455, 11876.346800925945, 10230.283880128394, 11789.7642047159, 11855.28098425732, 11861.762764744743, 11865.5847045353, 9724.076212759777, 11104.888951110466, 11302.378369189692, 11684.591039313584, 11854.229475084092, 40356.59643403558, 40575.78272651825, 40588.39117413211, 40591.79704101461, 40624.39063414051, 3181.984090079558, 3248.7076163145193, 3332.8833490827706, 3511.6839484446523, 3821.1975485147873, 28644.092355812718, 28669.426611467654, 28811.85594072016, 28871.711282887114, 29052.0, 2689.9596506052426, 2707.1472486166795, 2714.484247992875, 2833.6457942757156, 2915.3440475892753, 455072.144949857, 480817.0, 493172.0, 494435.0, 494955.0, 58920.705396472746, 59313.0, 59330.40669593335, 59360.812783744084, 59402.405975939684, 63307.0, 63402.73194536176, 63464.36535634677, 63468.73062538723, 63589.36410635927, 15197.316120774592, 16727.91636041812, 16907.830921690627, 16960.15199240033, 16967.49097527076, 11994.760104797855, 12293.385330733428, 12293.69265768345, 12297.938510307391, 13028.218306901616, 15552.92223538875, 15697.921510392374, 16036.518904432885, 16063.437779677655, 16100.516984490485, 6503.577267477599, 6612.471002319803, 6682.866342673216, 6714.597124172566, 6733.494987875929, 898.8247291778041, 1003.8745156855393, 1027.753339198587, 1098.741795678015, 1134.5802053240354, 28284.0, 29319.680614372406, 29571.556426653617, 30394.54408183879, 30430.0, 1130.423484023154, 1137.7553825927466, 1203.524607779922, 1228.0850766179253, 1238.7150955280335, 15222.771658425132, 15300.846991530165, 15459.988026342093, 15486.303116359566, 15777.368905243891, 663301.6834915795, 664707.6764616146, 670023.0, 670376.0, 671307.0, 59460.70269648624, 59781.103283450786, 59820.700896496106, 59835.0, 59898.70050649806, 1338.5248236875768, 1460.5107289058285, 1505.8644721975074, 1516.3934426229596, 1524.5654988328274, 27720.02979895694, 30439.389093234928, 30447.194100737408, 30780.692193077783, 31105.222369440038, 47721.0, 51164.74417628037, 51301.48698512967, 51425.7428712854, 51474.42627868517, 549280.0, 551947.0, 552107.0, 552133.0, 552355.0, 37616.059598510255, 38482.80758596301, 39356.606433935296, 39393.80303098466, 39397.60602393939, 45768.54231457776, 45938.540614593425, 46175.728971357894, 46188.7690561545, 46427.76786116048, 31000.22499437532, 34328.970130896116, 36474.0, 36614.63385366112, 38976.02559936024, 31310.686893130776, 37042.259154816216, 37781.43327850029, 37867.81066094652, 37965.0, 36969.63030369662, 37199.07002324964, 37397.252054959295, 37440.438393424665, 38423.423648645854, 32855.01434956955, 34486.655133448345, 34766.0, 34934.0, 34998.65001349954, 21429.89285053565, 22971.081156753542, 23800.88099559491, 24956.12653557117, 25325.240242792745], "value": 20441.31942699792, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.5343320848939, 389.6415297925908, 399.38812951586476, 400.0897957147489, 400.12572529024084, 12307.446164922578, 18739.625207495843, 20199.798002019976, 21284.787152128472, 21291.0, 16543.25555350009, 18810.905945470287, 19972.101255443504, 20190.39428817134, 20525.0, 5943.821685349436, 6119.755209791603, 6146.0473626587955, 6186.969065154668, 6191.81424557264, 6032.27612686476, 6056.091586262074, 6068.817935461943, 6082.391760823926, 6087.665178415185, 5825.825225243249, 6047.21386219793, 6053.364396738345, 6065.0, 6076.4834989025985, 5921.319048309457, 5967.433093856077, 6051.0, 6056.303525094627, 6061.45446909778, 5276.630635855492, 5286.550643195333, 5298.841034768963, 5308.734563271821, 5310.867228319323, 23328.883355583283, 25165.0, 26284.86857565719, 26580.202593922215, 26695.599566006524, 2003.2187446895723, 2032.89835508224, 2066.7209926659857, 2269.841111122222, 2331.988340058289, 14349.641258968504, 15001.849981500154, 15237.771433428508, 15402.46091386796, 15452.278125139392, 1571.7720930465132, 1607.1803380276144, 1625.2280166920618, 1676.8993860368416, 1685.4522280258966, 67883.66058169727, 80281.59859200666, 83577.58211208905, 119132.0, 123796.04815807356, 11215.0, 11552.0, 11556.768864622829, 11727.824082638766, 11731.237469564621, 14943.0, 15084.0, 15098.39606415753, 15110.168940708147, 15131.697366052616, 5950.672713000827, 6771.830704232433, 6834.1457317835275, 7884.688430859053, 8297.460665056751, 8534.061253262136, 8913.108689131035, 8922.787796393293, 9030.86453703195, 9041.63833446668, 4600.60894823937, 4672.859814205579, 4685.929711054337, 4692.249240121564, 4770.379850619467, 1952.6290004899024, 1980.5147738804085, 1981.791911849235, 1984.7122167285663, 1988.9303874364327, 852.8997593104949, 868.1188593577524, 887.587271918561, 891.7057371067532, 1033.390299723167, 17829.821701783076, 18010.90994545019, 18278.903265804096, 20842.89578552128, 20883.582328353346, 842.3219308456751, 855.0756413067325, 954.5227386306847, 998.3510718033336, 1099.8515200447998, 10339.896601033894, 10955.616553420592, 10969.780604387868, 10991.725206869893, 11049.33703977764, 602378.0, 609230.0, 618978.0, 623085.0, 624474.0, 75085.0, 113212.0, 138887.0, 142429.28785356006, 143869.28065359607, 921.1525396635112, 1020.1787561013456, 1024.6874703215442, 1049.2445439283704, 1050.8581341518798, 18165.81834181641, 18322.26710931548, 18481.630367392307, 18621.882687038822, 18691.90654046721, 50231.0, 51063.0, 51561.48438515717, 51886.44340669786, 51900.74049629728, 74662.0, 77449.0, 78383.21616783759, 78840.60579697065, 79364.0, 6922.440352154094, 8126.146754590683, 8127.512349259064, 8275.420720549744, 8415.4109212357, 9151.130642588943, 10579.735506612089, 10991.945040274748, 11241.156913231376, 12349.19730217533, 10929.508172132115, 10932.6720198391, 10963.232573719763, 11025.889741102807, 11211.159163062639, 9557.808843823224, 10540.15678745714, 10731.839022414513, 10912.945435272773, 10966.342019478856, 11113.388763617755, 11225.382603956861, 11246.0, 11437.885621144014, 11561.30632162073, 8052.275295223458, 8080.0707918590115, 8096.473729207582, 8278.668853245803, 8283.668653253802, 7826.334761545105, 7926.920730792849, 8221.465604735673, 8246.505209687439, 10481.056704896597], "value": 9397.007238654913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.6251774538616, 1592.1084192852004, 1631.8939268947518, 1873.6065426260484, 1990.243707391191, 7966.044074711035, 8715.91284087159, 8966.148215919486, 8968.775780605472, 9075.818483630323, 8717.956410217956, 8915.286777057836, 8916.46501209928, 8927.24118449931, 8932.821343573125, 2147.914083436661, 2215.2246713650256, 2215.357546311576, 2397.280815755271, 2427.3688840901405, 2204.0853045985896, 2237.194609940428, 2385.546746118232, 2465.235776909155, 2465.272744540356, 2373.264288070695, 2374.845635033726, 2379.916702915398, 2380.214529205367, 2383.7377888432256, 2311.919082832101, 2355.1756885090253, 2357.08073851199, 2357.8821058946983, 2358.811295096388, 2146.2488129154826, 2263.739669937962, 2310.90756369745, 2357.8585284882793, 2417.4439878827966, 8835.469871807712, 8892.688755893578, 8917.33120015995, 8935.64257429702, 8945.821083578292, 5314.7608357624, 5465.72671366434, 5467.617266791286, 5471.972640136813, 5472.835814925558, 3437.8281085945855, 3449.120474279043, 3502.667246611568, 3536.1159710072484, 3542.079059444537, 986.363795351994, 994.6966175316526, 999.0958182844537, 1005.9497025148713, 1007.1539906478594, 11726.179167458282, 11735.471903764266, 11744.882551174464, 11758.470868810839, 11767.823482647767, 4219.725717828332, 4754.405699287589, 4798.688085274446, 4909.533594308535, 4932.26016097581, 10045.045720656672, 10046.849297260547, 10068.137394582025, 10070.345427547187, 10098.545565449425, 5396.271503346998, 5442.972785136049, 5450.809221677222, 5451.890962180813, 5454.427285135003, 4239.660827133822, 4525.117602067631, 4812.590929770939, 5016.372953380827, 5030.0, 3622.5471816022996, 3810.1998580298446, 3920.666743326792, 3927.4501569780255, 3928.9214215715524, 1057.360297020307, 1225.4852961756085, 1226.4358395138158, 1234.5432190089725, 1236.4683186229827, 842.2125312832521, 844.923956843887, 879.5030807593689, 894.6645008121955, 950.5199874063553, 5410.48600382963, 5465.9180112298345, 5473.397926228112, 5681.0, 5706.600537962303, 845.3701992015865, 900.1853322742928, 954.4702690006974, 1017.1862509991977, 1055.9260851740457, 3398.2693720850134, 3562.251927095339, 3679.5216621839527, 3762.830672619739, 3788.0719223790347, 10492.527836247387, 10767.730806729738, 10807.243492955418, 10816.945915270373, 10831.512581933832, 10719.356838589709, 10821.56713731442, 10829.350238985686, 10837.512311945982, 10856.891431085745, 7642.579658118746, 7968.203179682101, 8042.396820238506, 8436.620352084172, 8442.240198382053, 5428.67427954324, 5570.91643625354, 5622.8313150605545, 5680.977424063708, 5734.856628584319, 9731.61073557078, 9839.163671088038, 10153.238507111848, 10157.035081667229, 10236.846447303147, 10148.137408320375, 10225.386476811414, 10331.896681033093, 10344.896551034695, 10382.688519344432, 7016.719331226694, 7136.785896423115, 7480.476366654282, 7480.738174163878, 7573.318401343685, 7608.467407281437, 7864.567448790142, 7867.567283799446, 7950.960245198969, 7957.204279572228, 6747.993738974842, 6836.794896153124, 7383.0, 7463.888041679488, 7663.118741344817, 7333.926660733325, 7415.073115860518, 7462.738804141829, 7569.962150189213, 7595.620218989139, 6895.6552172392185, 6909.412699920563, 6938.410235129869, 6946.992686060594, 6967.54710943787, 4636.976815115903, 4787.832425865078, 4812.230043193073, 4844.806207751651, 4976.104301225814, 4600.7469589172915, 4806.759662016815, 4870.269459581091, 4919.778609962632, 5073.974630126826], "value": 4546.50444176425, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.5975232198142, 319.173341046689, 323.104999152348, 326.4157158685952, 329.1524324863477, 4746.928796068062, 5410.56715462763, 5431.7012564309, 5459.454054594544, 5478.890422191564, 4508.7971041303135, 4551.613112885409, 5271.262023316739, 5364.229804164626, 5444.3194600674915, 3907.7264591478465, 3938.3022629498887, 4027.335489644205, 4055.695822813286, 4140.441040459545, 4070.8778736637946, 4097.262492751303, 4099.959000410003, 4102.681984678662, 4115.32097203961, 3541.5041894134843, 3972.36442169253, 4046.6358027777496, 4061.512618485786, 4062.614051665087, 3356.865725370982, 3575.8199794067896, 3931.606839316074, 4087.591240875918, 4123.092919557707, 2997.220722612115, 3229.8708051677904, 3255.7395408367274, 3523.94714079289, 3551.875684351061, 5221.660592061504, 5245.134552798783, 5280.0, 5280.023195708768, 5291.894162116774, 4202.810873510699, 4337.84817531388, 4575.4738205106505, 4638.350630911675, 4708.811647534095, 5020.397552293748, 5031.899362012776, 5640.971795141039, 5677.347105082927, 5687.744051517691, 3440.587129544446, 3829.291581057513, 3870.4968354113785, 3877.4377715231135, 3921.9604806275365, 5111.309973153614, 5335.813246536352, 5455.890882182373, 5509.531689806371, 5978.192943952554, 3550.751447398709, 3800.7529510581726, 3866.7486613370043, 3921.313770090223, 3922.5489068756888, 3758.5489741231227, 3965.8300801263695, 3978.244133614604, 3995.780232087263, 4015.7992100394863, 3899.6490315871706, 4148.066684995852, 4225.690036088822, 4642.2340313847835, 4672.971946171905, 4816.34979277793, 5284.207368894697, 5448.536874365644, 5560.833175004756, 5738.454846789548, 1090.8382572560197, 1158.5250047480492, 1215.3619349841226, 1291.9547815826588, 1362.7955806629086, 750.9812254693676, 782.9586649755811, 789.4394979564458, 793.4448480977234, 830.3938125168661, 4555.817767289338, 5315.069862774077, 5337.973310133502, 5340.706261155596, 5381.035921888561, 5179.067767801756, 5453.481919217668, 5862.443067908542, 5862.853428664317, 5891.7937872175125, 4778.904421911542, 4875.853724388274, 5297.357819076099, 5327.627066105413, 5387.164989426645, 2845.331347133422, 2930.0, 3011.217083558247, 3030.409035256464, 3042.75247148668, 5344.43883392246, 5394.541463975606, 5464.7814087436855, 5589.35722391922, 6066.696665166724, 5449.891002179934, 5749.712514374264, 5995.100734889714, 5995.940040599626, 6043.637381757108, 5590.972045139748, 5995.760169593254, 6024.2288547268745, 6060.090986351995, 6072.993868035351, 5038.722870242172, 5445.564354851682, 5684.488396044376, 5711.801825658054, 5857.326407463196, 4867.561919427269, 5452.4286592282115, 5711.372258906192, 5760.279965004374, 5776.682282474504, 5353.411124776194, 5455.708602387086, 5799.362070172362, 5977.701114944149, 6100.267967843887, 3758.9060273493383, 3921.2157568485845, 3931.60683931616, 4076.2458945094068, 4086.223617512663, 5015.573676237561, 5219.4780521947505, 5899.637206792087, 5926.7925622603, 5965.135055416853, 4984.053029924447, 5410.891782164256, 5699.544036477155, 5753.769849206153, 5807.448292412214, 5356.544693701079, 5741.1388291756575, 5747.339056008445, 5751.281089863767, 5753.698520591629, 5223.876866473726, 5563.276774019513, 5586.52514536269, 5638.308507447831, 5641.548676105819, 4813.470518243061, 5304.681719096867, 5530.529904958124, 5666.320041595035, 5776.537876969916, 4804.615630749601, 5280.419153892992, 5523.978064058162, 5528.585356098389, 5533.916991245215], "value": 4091.232920584994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.7158850105953, 671.8454755406253, 694.8645014222226, 730.7259777583406, 742.5038547327136, 13827.930860345698, 14529.491467798629, 14546.981711280214, 14547.563573092812, 15472.458463953764, 16315.0, 16624.83375166248, 16682.749758753627, 16685.416010439636, 16706.498805035837, 720.178995944621, 917.1104029091804, 918.8575770755527, 926.4811705444939, 926.763675262807, 743.9851202975938, 899.3299991506304, 928.6656803550717, 930.1767935377172, 930.4835816122084, 955.4649396338073, 956.3592393096642, 961.5865177973469, 963.1716723617683, 964.2719746591314, 758.1735907860459, 955.8040601676639, 957.34421920984, 958.8897276813186, 959.4195511715382, 687.7970998555438, 709.5458906299972, 711.0329951266274, 711.2993701204309, 712.1276436365463, 14193.929030354886, 14220.075695079786, 14228.715425691427, 14229.359678814479, 14246.287685615782, 12156.452959616838, 12204.877951220464, 12248.387580620933, 12521.248725076524, 12569.183003104768, 10854.837177442345, 10864.478972943714, 11394.094505901025, 11634.0, 12134.514619415213, 4029.738067025634, 4081.410049827716, 4169.187008533338, 4172.624463798273, 4282.336237883133, 63234.0, 80397.59801201014, 82825.0, 83753.74369384465, 83952.0, 9301.302402319718, 9514.04859514043, 9573.664921727706, 9579.616815327308, 9605.03949605034, 20578.971051447366, 20586.176552938014, 20633.89683051575, 20667.966601670156, 20676.0, 6871.725130994804, 6886.896696549556, 7016.614086225307, 7053.858922821618, 7080.964595177094, 3314.486254630536, 3359.983200084033, 3865.555461122007, 3896.902577435587, 3897.2205558888336, 2894.884204631833, 3373.2916087621384, 3485.9651403486146, 3487.755857089979, 3492.35391452577, 613.9877202455926, 619.1177571959939, 622.1662971617964, 639.5874660843823, 641.9101325814296, 494.1451289269512, 507.14799137448705, 509.2717414097876, 516.6202840911926, 585.7071464267866, 7462.067241594801, 7474.177840437547, 7600.695972161162, 7915.208479152038, 7981.241782030698, 450.4054647864787, 469.90710907991775, 496.4142312071718, 552.6960171905436, 556.504517885877, 3529.6470352964498, 3603.2253065591026, 3677.191017976041, 3763.8306276217636, 3801.258754542893, 84501.0, 91672.0, 93275.53362233145, 93330.0, 93506.59740103903, 75565.0, 75828.62085689536, 80088.59955700184, 80515.59742201252, 82101.58949205215, 71970.9204361935, 72982.0, 73182.0, 73192.26807731854, 73538.26461735314, 7142.142942846892, 7306.41548676094, 7361.374283185989, 7371.815704607213, 7393.8890916637365, 41217.793911030254, 45494.28094395548, 46227.76886115682, 47029.529704702516, 48337.54987350385, 53477.930441391734, 59289.703551481965, 61147.0, 61159.082613759936, 61172.69413652903, 9419.623215071595, 9444.150026497648, 9481.810363792547, 9490.28822838276, 9866.703998880044, 8640.56797160152, 10238.283320167517, 10319.380837149794, 10571.418571978615, 10621.256512044083, 9646.083622055894, 9686.0, 9730.41617502952, 9830.655927042519, 10025.949870250603, 8839.646414143619, 9718.611255549698, 9789.070038346346, 9850.064243896819, 10020.498975051363, 9570.712878613653, 9573.9042609573, 9581.760455988657, 9630.374025688308, 9657.71026869195, 8338.291245244221, 8537.786555336166, 8573.957130214309, 8593.742187734379, 8692.391532592657, 8306.0, 8352.665893364197, 8381.832363352576, 8461.661533538589, 8653.221210091122], "value": 6574.924827996856, "unit": "score"}], "name": "dromaeo_css", "value": 7154.869313890094, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 113.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 121.0, 141.0, 196.0, 117.0, 138.0, 138.0, 119.0, 167.0, 123.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 185.0, 184.0, 185.0, 181.0, 183.0, 184.0, 255.0, 231.0], "value": 196.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 126.0, 57.0, 60.0, 57.0, 59.0, 111.0, 56.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 134.0, 80.0, 80.0, 77.0, 132.0, 107.0, 63.0, 134.0], "value": 100.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 90.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 87.0, 88.0, 84.0, 86.0, 86.0, 89.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 61.0, 56.0, 56.0, 57.0, 120.0, 55.0, 56.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 76.0, 75.0, 71.0, 68.0, 68.0, 68.0, 69.0, 67.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 106.0, 99.0, 103.0, 77.0, 82.0, 85.0, 83.0, 80.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 133.0, 139.0, 140.0, 144.0, 141.0, 147.0, 236.0, 143.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 210.0, 205.0, 163.0, 163.0, 163.0, 163.0, 163.0, 167.0, 160.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 65.0, 66.0, 65.0, 65.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1500.9, "alertThreshold": 2.0}]} 11:12:28 INFO - Return code: 0 11:12:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:12:28 ERROR - # TBPL SUCCESS # 11:12:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:12:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:12:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:12:28 INFO - 2016-05-16 11:12:28.895 screenresolution[1685:10506] starting screenresolution argv=screenresolution get 11:12:28 INFO - 2016-05-16 11:12:28.898 screenresolution[1685:10506] Display 0: 1600x1200x32@60 11:12:28 INFO - 2016-05-16 11:12:28.904 screenresolution[1686:10509] starting screenresolution argv=screenresolution list 11:12:28 INFO - Available Modes on Display 0 11:12:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:12:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:12:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:12:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:12:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:12:29 INFO - Intel Iris: 11:12:29 INFO - Chipset Model: Intel Iris 11:12:29 INFO - Type: GPU 11:12:29 INFO - Bus: Built-In 11:12:29 INFO - VRAM (Dynamic, Max): 1536 MB 11:12:29 INFO - Vendor: Intel (0x8086) 11:12:29 INFO - Device ID: 0x0a2e 11:12:29 INFO - Revision ID: 0x0009 11:12:29 INFO - Displays: 11:12:29 INFO - Display: 11:12:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:12:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:12:29 INFO - Display Serial Number: 1600x1200 60 11:12:29 INFO - Main Display: Yes 11:12:29 INFO - Mirror: Off 11:12:29 INFO - Online: Yes 11:12:29 INFO - Rotation: Supported 11:12:29 INFO - Return code: 0 11:12:29 INFO - Running post-action listener: _resource_record_post_action 11:12:29 INFO - ##### 11:12:29 INFO - ##### Finished run-tests step (success) 11:12:29 INFO - ##### 11:12:29 INFO - Running post-run listener: _resource_record_post_run 11:12:29 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 32853504; Write bytes: 696852992; Read time: 320; Write time: 2149 11:12:29 INFO - TinderboxPrint: CPU usage
53.8% 11:12:29 INFO - TinderboxPrint: I/O read bytes / time
32,853,504 / 320 11:12:29 INFO - TinderboxPrint: I/O write bytes / time
696,852,992 / 2,149 11:12:29 INFO - TinderboxPrint: CPU idle
2,245.5 (46.2%) 11:12:29 INFO - TinderboxPrint: CPU system
968.6 (19.9%) 11:12:29 INFO - TinderboxPrint: CPU user
1,643.5 (33.8%) 11:12:29 INFO - TinderboxPrint: Swap in / out
423,952,384 / 0 11:12:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 376293888; Write bytes: 371033088; Read time: 28877; Write time: 641 11:12:29 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31927808; Write bytes: 298855936; Read time: 307; Write time: 1454 11:12:29 INFO - Running post-run listener: _upload_blobber_files 11:12:29 INFO - Blob upload gear active. 11:12:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:12:29 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 11:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:12:30 INFO - (blobuploader) - INFO - Open directory for files ... 11:12:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:12:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:12:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:31 INFO - (blobuploader) - INFO - Done attempting. 11:12:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:12:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:12:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:32 INFO - (blobuploader) - INFO - Done attempting. 11:12:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:12:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:12:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:34 INFO - (blobuploader) - INFO - Done attempting. 11:12:34 INFO - (blobuploader) - INFO - Iteration through files over. 11:12:34 INFO - Return code: 0 11:12:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:12:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:12:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb57361e51116f863a6b72475e7ec5787b0806a663b72be9709cba6fdacc15deb605c8bc475934df568c7caa3870ae1df2b9c9bb22e66413bbbd8ee2cf786ee6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/311259251eb21e63833562cdf5d5fcad56cee3a2ebe76e4ce0222a16605d9d8ee87af2859b1b0e8d2101c8bae543d9ef4ef2cbbdaebc0fe96acd519d7cadc14e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb8a86bf5c4ac43030fd2e1565a8ae477d4b48dc514fa9158cc8b7cd4035b88318634c891ed215e4389594eb4e29e93ba558d823ee44deed36392ea80e4007d1"} 11:12:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:12:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:12:34 INFO - Contents: 11:12:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb57361e51116f863a6b72475e7ec5787b0806a663b72be9709cba6fdacc15deb605c8bc475934df568c7caa3870ae1df2b9c9bb22e66413bbbd8ee2cf786ee6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/311259251eb21e63833562cdf5d5fcad56cee3a2ebe76e4ce0222a16605d9d8ee87af2859b1b0e8d2101c8bae543d9ef4ef2cbbdaebc0fe96acd519d7cadc14e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb8a86bf5c4ac43030fd2e1565a8ae477d4b48dc514fa9158cc8b7cd4035b88318634c891ed215e4389594eb4e29e93ba558d823ee44deed36392ea80e4007d1"} 11:12:34 INFO - Running post-run listener: copy_logs_to_upload_dir 11:12:34 INFO - Copying logs to upload dir... 11:12:34 INFO - mkdir: /builds/slave/test/build/upload/logs 11:12:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.977759 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-16 11:12:34.820586) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:12:34.824109) ========= 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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb57361e51116f863a6b72475e7ec5787b0806a663b72be9709cba6fdacc15deb605c8bc475934df568c7caa3870ae1df2b9c9bb22e66413bbbd8ee2cf786ee6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/311259251eb21e63833562cdf5d5fcad56cee3a2ebe76e4ce0222a16605d9d8ee87af2859b1b0e8d2101c8bae543d9ef4ef2cbbdaebc0fe96acd519d7cadc14e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb8a86bf5c4ac43030fd2e1565a8ae477d4b48dc514fa9158cc8b7cd4035b88318634c891ed215e4389594eb4e29e93ba558d823ee44deed36392ea80e4007d1"} build_url:https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011098 build_url: 'https://queue.taskcluster.net/v1/task/I8C8TsMyTcyE0Dh8RKS1Cg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb57361e51116f863a6b72475e7ec5787b0806a663b72be9709cba6fdacc15deb605c8bc475934df568c7caa3870ae1df2b9c9bb22e66413bbbd8ee2cf786ee6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/311259251eb21e63833562cdf5d5fcad56cee3a2ebe76e4ce0222a16605d9d8ee87af2859b1b0e8d2101c8bae543d9ef4ef2cbbdaebc0fe96acd519d7cadc14e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb8a86bf5c4ac43030fd2e1565a8ae477d4b48dc514fa9158cc8b7cd4035b88318634c891ed215e4389594eb4e29e93ba558d823ee44deed36392ea80e4007d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:12:34.854456) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:12:34.854947) ========= 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.v6l2U4xRYc/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.Pv9HYZTefe/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.005089 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:12:34.904316) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:12:34.904654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:12:34.905000) ========= ========= Total master_lag: 0.66 =========