builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0049 starttime: 1457539302.63 results: success (0) buildid: 20160309052235 builduid: 08bcc61c798948e09e7e6039d6090b7a revision: ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:42.635361) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:42.635834) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:42.636191) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005588 basedir: '/builds/slave/test' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:42.691938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:42.695279) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.241165) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.241483) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.005211 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.279661) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.279999) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.280375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.280655) ========= 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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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-03-09 08:01:43-- 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% 165M=0s 2016-03-09 08:01:43 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096844 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.405633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.406374) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.018318 ========= master_lag: 0.21 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.630567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:43.630921) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 --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 ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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-03-09 08:01:43,716 truncating revision to first 12 chars 2016-03-09 08:01:43,716 Setting DEBUG logging. 2016-03-09 08:01:43,716 attempt 1/10 2016-03-09 08:01:43,716 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ee8f304d9d62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-09 08:01:44,321 unpacking tar archive at: fx-team-ee8f304d9d62/testing/mozharness/ program finished with exit code 0 elapsedTime=0.882579 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:44.551298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:44.551647) ========= echo ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'ee8f304d9d624328cfd126dd7ed7aa6e35df5b52'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 program finished with exit code 0 elapsedTime=0.005210 script_repo_revision: 'ee8f304d9d624328cfd126dd7ed7aa6e35df5b52' ========= master_lag: 0.20 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:44.752919) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:44.753248) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-09 08:01:44.785299) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-03-09 08:01:44.785568) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:01:44 INFO - MultiFileLogger online at 20160309 08:01:44 in /builds/slave/test 08:01:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:01:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:01:44 INFO - {'append_to_log': False, 08:01:44 INFO - 'base_work_dir': '/builds/slave/test', 08:01:44 INFO - 'blob_upload_branch': 'Fx-Team', 08:01:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:01:44 INFO - 'branch': 'Fx-Team', 08:01:44 INFO - 'buildbot_json_path': 'buildprops.json', 08:01:44 INFO - 'config_files': ('talos/mac_config.py',), 08:01:44 INFO - 'default_actions': ('clobber', 08:01:44 INFO - 'read-buildbot-config', 08:01:44 INFO - 'download-and-extract', 08:01:44 INFO - 'populate-webroot', 08:01:44 INFO - 'create-virtualenv', 08:01:44 INFO - 'install', 08:01:44 INFO - 'run-tests'), 08:01:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:01:44 INFO - 'download_minidump_stackwalk': True, 08:01:44 INFO - 'download_symbols': 'ondemand', 08:01:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:01:44 INFO - 'tooltool.py': '/tools/tooltool.py', 08:01:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:01:44 INFO - '/tools/misc-python/virtualenv.py')}, 08:01:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:01:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:01:44 INFO - 'installer_path': 'installer.exe', 08:01:44 INFO - 'log_level': 'info', 08:01:44 INFO - 'log_name': 'talos', 08:01:44 INFO - 'log_to_console': True, 08:01:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:01:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:01:44 INFO - 'opt_config_files': (), 08:01:44 INFO - 'pip_index': False, 08:01:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:44 INFO - 'cmd': ('bash', 08:01:44 INFO - '-c', 08:01:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:44 INFO - 'enabled': True, 08:01:44 INFO - 'halt_on_failure': False, 08:01:44 INFO - 'name': 'check_screen_resolution'},), 08:01:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:44 INFO - 'cmd': ('bash', 08:01:44 INFO - '-c', 08:01:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:44 INFO - 'enabled': True, 08:01:44 INFO - 'halt_on_failure': False, 08:01:44 INFO - 'name': 'check_screen_resolution'},), 08:01:44 INFO - 'run_cmd_checks_enabled': True, 08:01:44 INFO - 'sps_profile': False, 08:01:44 INFO - 'sps_profile_interval': 0, 08:01:44 INFO - 'suite': 'dromaeojs-e10s', 08:01:44 INFO - 'system_bits': '32', 08:01:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:01:44 INFO - 'title': 't-yosemite-r7-0049', 08:01:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:01:44 INFO - 'use_talos_json': True, 08:01:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:01:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:01:44 INFO - 'work_dir': 'build'} 08:01:44 INFO - ##### 08:01:44 INFO - ##### Running clobber step. 08:01:44 INFO - ##### 08:01:44 INFO - Running pre-action listener: _resource_record_pre_action 08:01:44 INFO - Running main action method: clobber 08:01:44 INFO - rmtree: /builds/slave/test/build 08:01:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:01:46 INFO - Running post-action listener: _resource_record_post_action 08:01:46 INFO - ##### 08:01:46 INFO - ##### Running read-buildbot-config step. 08:01:46 INFO - ##### 08:01:46 INFO - Running pre-action listener: _resource_record_pre_action 08:01:46 INFO - Running main action method: read_buildbot_config 08:01:46 INFO - Using buildbot properties: 08:01:46 INFO - { 08:01:46 INFO - "project": "", 08:01:46 INFO - "product": "firefox", 08:01:46 INFO - "script_repo_revision": "production", 08:01:46 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 08:01:46 INFO - "repository": "", 08:01:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 08:01:46 INFO - "buildid": "20160309052235", 08:01:46 INFO - "pgo_build": "False", 08:01:46 INFO - "basedir": "/builds/slave/test", 08:01:46 INFO - "buildnumber": 479, 08:01:46 INFO - "slavename": "t-yosemite-r7-0049", 08:01:46 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:01:46 INFO - "platform": "yosemite_r7", 08:01:46 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 08:01:46 INFO - "branch": "fx-team", 08:01:46 INFO - "repo_path": "integration/fx-team", 08:01:46 INFO - "revision": "ee8f304d9d624328cfd126dd7ed7aa6e35df5b52", 08:01:46 INFO - "stage_platform": "macosx64", 08:01:46 INFO - "builduid": "08bcc61c798948e09e7e6039d6090b7a", 08:01:46 INFO - "slavebuilddir": "test" 08:01:46 INFO - } 08:01:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 08:01:46 INFO - Running post-action listener: _resource_record_post_action 08:01:46 INFO - ##### 08:01:46 INFO - ##### Running download-and-extract step. 08:01:46 INFO - ##### 08:01:46 INFO - Running pre-action listener: _resource_record_pre_action 08:01:46 INFO - Running main action method: download_and_extract 08:01:46 INFO - mkdir: /builds/slave/test/build/tests 08:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:01:46 INFO - https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:01:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:01:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:01:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 08:01:47 INFO - Downloaded 1199 bytes. 08:01:47 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:01:47 INFO - Using the following test package requirements: 08:01:47 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:01:47 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 08:01:47 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 08:01:47 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'jsshell-mac.zip'], 08:01:47 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 08:01:47 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:01:47 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 08:01:47 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 08:01:47 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 08:01:47 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:01:47 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 08:01:47 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:47 INFO - https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:01:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:01:49 INFO - Downloaded 17865962 bytes. 08:01:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:01:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:01:52 INFO - Return code: 0 08:01:52 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:52 INFO - https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:01:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:01:57 INFO - Downloaded 17865962 bytes. 08:01:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:01:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:02:00 INFO - Return code: 0 08:02: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')]} 08:02:00 INFO - https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:02:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:02:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:02:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:02:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:02:01 INFO - Downloaded 10845161 bytes. 08:02:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:02:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:02:01 INFO - Return code: 0 08:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:01 INFO - https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:02:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:02:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:02:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 08:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 08:02:05 INFO - Downloaded 110924498 bytes. 08:02:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:02:05 INFO - mkdir: /builds/slave/test/properties 08:02:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:05 INFO - Contents: 08:02:05 INFO - build_url:https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:02:05 INFO - Running post-action listener: _resource_record_post_action 08:02:05 INFO - Running post-action listener: set_extra_try_arguments 08:02:05 INFO - ##### 08:02:05 INFO - ##### Running populate-webroot step. 08:02:05 INFO - ##### 08:02:05 INFO - Running pre-action listener: _resource_record_pre_action 08:02:05 INFO - Running main action method: populate_webroot 08:02:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:05 INFO - '--tppagecycles', 08:02:05 INFO - '7'], 08:02:05 INFO - 'tests': ['tsvgm']}}, 08:02:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:02:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 08:02:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'tests': ['damp', 'tps']}, 08:02:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['damp', 'tps']}, 08:02:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:02:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:05 INFO - 'other': {'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'other_l64': {'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'other_nol64': {'tests': ['a11yr', 08:02:05 INFO - 'ts_paint', 08:02:05 INFO - 'tpaint', 08:02:05 INFO - 'sessionrestore', 08:02:05 INFO - 'sessionrestore_no_auto_restore', 08:02:05 INFO - 'tabpaint']}, 08:02:05 INFO - 'svgr': {'tests': ['tsvgx', 08:02:05 INFO - 'tsvgr_opacity', 08:02:05 INFO - 'tart', 08:02:05 INFO - 'tscrollx', 08:02:05 INFO - 'cart']}, 08:02:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['tsvgx', 08:02:05 INFO - 'tsvgr_opacity', 08:02:05 INFO - 'tart', 08:02:05 INFO - 'tscrollx', 08:02:05 INFO - 'cart']}, 08:02:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'tests': ['tp5o']}, 08:02:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'talos_options': ['--e10s'], 08:02:05 INFO - 'tests': ['tp5o']}, 08:02:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'talos_options': ['--xperf_path', 08:02:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:05 INFO - 'tests': ['tp5n']}, 08:02:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:05 INFO - 'talos_options': ['--e10s', 08:02:05 INFO - '--xperf_path', 08:02:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:05 INFO - 'tests': ['tp5n']}}, 08:02:05 INFO - 'talos.zip': {'path': '', 08:02:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:05 INFO - Running post-action listener: _resource_record_post_action 08:02:05 INFO - ##### 08:02:05 INFO - ##### Running create-virtualenv step. 08:02:05 INFO - ##### 08:02:05 INFO - Running pre-action listener: _resource_record_pre_action 08:02:05 INFO - Running main action method: create_virtualenv 08:02:05 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:05 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 08:02:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:05 INFO - Using real prefix '/tools/python27' 08:02:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:06 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:09 INFO - Installing pip.................done. 08:02:09 INFO - Return code: 0 08:02:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:10 INFO - 'HOME': '/Users/cltbld', 08:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:10 INFO - 'LOGNAME': 'cltbld', 08:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:10 INFO - 'MOZ_NO_REMOTE': '1', 08:02:10 INFO - 'NO_EM_RESTART': '1', 08:02:10 INFO - 'PAGER': '/bin/cat', 08:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:10 INFO - 'PWD': '/builds/slave/test', 08:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:10 INFO - 'SHELL': '/bin/bash', 08:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:10 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:10 INFO - 'USER': 'cltbld', 08:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:10 INFO - 'XPC_FLAGS': '0x0', 08:02:10 INFO - 'XPC_SERVICE_NAME': '0', 08:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:10 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 08:02:10 INFO - Cleaning up... 08:02:10 INFO - Return code: 0 08:02:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:10 INFO - 'HOME': '/Users/cltbld', 08:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:10 INFO - 'LOGNAME': 'cltbld', 08:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:10 INFO - 'MOZ_NO_REMOTE': '1', 08:02:10 INFO - 'NO_EM_RESTART': '1', 08:02:10 INFO - 'PAGER': '/bin/cat', 08:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:10 INFO - 'PWD': '/builds/slave/test', 08:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:10 INFO - 'SHELL': '/bin/bash', 08:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:10 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:10 INFO - 'USER': 'cltbld', 08:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:10 INFO - 'XPC_FLAGS': '0x0', 08:02:10 INFO - 'XPC_SERVICE_NAME': '0', 08:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:10 INFO - Downloading/unpacking psutil>=0.7.1 08:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:13 INFO - Installing collected packages: psutil 08:02:13 INFO - Running setup.py install for psutil 08:02:13 INFO - building 'psutil._psutil_osx' extension 08:02:13 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 08:02:14 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 08:02:14 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 08:02:14 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 08:02:14 INFO - building 'psutil._psutil_posix' extension 08:02:14 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 08:02:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:02:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:02:14 INFO - ^ 08:02:14 INFO - 1 warning generated. 08:02:14 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 08:02:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:14 INFO - Successfully installed psutil 08:02:14 INFO - Cleaning up... 08:02:14 INFO - Return code: 0 08:02:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:02:14 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')]} 08:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:14 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')]} 08:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:14 INFO - 'HOME': '/Users/cltbld', 08:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:14 INFO - 'LOGNAME': 'cltbld', 08:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:14 INFO - 'MOZ_NO_REMOTE': '1', 08:02:14 INFO - 'NO_EM_RESTART': '1', 08:02:14 INFO - 'PAGER': '/bin/cat', 08:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:14 INFO - 'PWD': '/builds/slave/test', 08:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:14 INFO - 'SHELL': '/bin/bash', 08:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:14 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:14 INFO - 'USER': 'cltbld', 08:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:14 INFO - 'XPC_FLAGS': '0x0', 08:02:14 INFO - 'XPC_SERVICE_NAME': '0', 08:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:02:14 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 08:02:14 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 08:02:14 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 08:02:14 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 08:02:14 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 08:02:14 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 08:02:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:02:17 INFO - Installing collected packages: mozsystemmonitor 08:02:17 INFO - Running setup.py install for mozsystemmonitor 08:02:17 INFO - Successfully installed mozsystemmonitor 08:02:17 INFO - Cleaning up... 08:02:17 INFO - Return code: 0 08:02:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:17 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 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:17 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 08:02:17 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 08:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:17 INFO - 'HOME': '/Users/cltbld', 08:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:17 INFO - 'LOGNAME': 'cltbld', 08:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:17 INFO - 'MOZ_NO_REMOTE': '1', 08:02:17 INFO - 'NO_EM_RESTART': '1', 08:02:17 INFO - 'PAGER': '/bin/cat', 08:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:17 INFO - 'PWD': '/builds/slave/test', 08:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:17 INFO - 'SHELL': '/bin/bash', 08:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:17 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:17 INFO - 'USER': 'cltbld', 08:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:17 INFO - 'XPC_FLAGS': '0x0', 08:02:17 INFO - 'XPC_SERVICE_NAME': '0', 08:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:17 INFO - Downloading/unpacking blobuploader==1.2.4 08:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:19 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:20 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 08:02:21 INFO - Downloading docopt-0.6.1.tar.gz 08:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:21 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:21 INFO - Running setup.py install for blobuploader 08:02:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:21 INFO - Running setup.py install for requests 08:02:21 INFO - Running setup.py install for docopt 08:02:22 INFO - Successfully installed blobuploader requests docopt 08:02:22 INFO - Cleaning up... 08:02:22 INFO - Return code: 0 08:02:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:22 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')]} 08:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:22 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')]} 08:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:22 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 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:22 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 08:02:22 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 08:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:22 INFO - 'HOME': '/Users/cltbld', 08:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:22 INFO - 'LOGNAME': 'cltbld', 08:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:22 INFO - 'MOZ_NO_REMOTE': '1', 08:02:22 INFO - 'NO_EM_RESTART': '1', 08:02:22 INFO - 'PAGER': '/bin/cat', 08:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:22 INFO - 'PWD': '/builds/slave/test', 08:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:22 INFO - 'SHELL': '/bin/bash', 08:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:22 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:22 INFO - 'USER': 'cltbld', 08:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:22 INFO - 'XPC_FLAGS': '0x0', 08:02:22 INFO - 'XPC_SERVICE_NAME': '0', 08:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-Ykllp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-l08m9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-reOLmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-_DHZkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-v9nD4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-b9sjkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:22 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-andr5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-sN7x2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-9puvQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-VjFg_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-OHW2bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-S0yuAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-uta5e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-LRqsB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-w4bdvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:23 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-3wr5vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:24 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-bHBW4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:24 INFO - Running setup.py install for manifestparser 08:02:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:24 INFO - Running setup.py install for mozcrash 08:02:24 INFO - Running setup.py install for mozdebug 08:02:24 INFO - Running setup.py install for mozdevice 08:02:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:24 INFO - Running setup.py install for mozfile 08:02:24 INFO - Running setup.py install for mozhttpd 08:02:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:24 INFO - Running setup.py install for mozinfo 08:02:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Running setup.py install for mozInstall 08:02:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Running setup.py install for mozleak 08:02:25 INFO - Running setup.py install for mozlog 08:02:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Running setup.py install for moznetwork 08:02:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:25 INFO - Running setup.py install for mozprocess 08:02:25 INFO - Running setup.py install for mozprofile 08:02:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Running setup.py install for mozrunner 08:02:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Running setup.py install for mozscreenshot 08:02:26 INFO - Running setup.py install for moztest 08:02:26 INFO - Running setup.py install for mozversion 08:02:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:26 INFO - Cleaning up... 08:02:26 INFO - Return code: 0 08:02:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:26 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 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:26 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 08:02:26 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 08:02:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:26 INFO - 'HOME': '/Users/cltbld', 08:02:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:26 INFO - 'LOGNAME': 'cltbld', 08:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:26 INFO - 'MOZ_NO_REMOTE': '1', 08:02:26 INFO - 'NO_EM_RESTART': '1', 08:02:26 INFO - 'PAGER': '/bin/cat', 08:02:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:26 INFO - 'PWD': '/builds/slave/test', 08:02:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:26 INFO - 'SHELL': '/bin/bash', 08:02:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:26 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:26 INFO - 'USER': 'cltbld', 08:02:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:26 INFO - 'XPC_FLAGS': '0x0', 08:02:26 INFO - 'XPC_SERVICE_NAME': '0', 08:02:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:26 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-ik6r5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-c3RGaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-UU0vwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-OWV2MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-PJYBgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-zn9hrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-Dz0iJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-rPRvDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-q_fHLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:27 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)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-BtyrCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:27 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-ysnY8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-oj1Vox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-89jdJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-bj0ZQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-zcV5QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-rwwysj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:28 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)) 08:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:28 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-gv9BDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:28 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)) 08:02:28 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)) 08:02:28 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)) 08:02:28 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)) 08:02:28 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)) 08:02:28 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)) 08:02:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:31 INFO - Downloading blessings-1.6.tar.gz 08:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:31 INFO - Installing collected packages: blessings 08:02:31 INFO - Running setup.py install for blessings 08:02:31 INFO - Successfully installed blessings 08:02:31 INFO - Cleaning up... 08:02:31 INFO - Return code: 0 08:02:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:31 INFO - Reading from file tmpfile_stdout 08:02:31 INFO - Current package versions: 08:02:31 INFO - blessings == 1.6 08:02:31 INFO - blobuploader == 1.2.4 08:02:31 INFO - docopt == 0.6.1 08:02:31 INFO - manifestparser == 1.1 08:02:31 INFO - mozInstall == 1.12 08:02:31 INFO - mozcrash == 0.17 08:02:31 INFO - mozdebug == 0.1 08:02:31 INFO - mozdevice == 0.48 08:02:31 INFO - mozfile == 1.2 08:02:31 INFO - mozhttpd == 0.7 08:02:31 INFO - mozinfo == 0.9 08:02:31 INFO - mozleak == 0.1 08:02:31 INFO - mozlog == 3.1 08:02:31 INFO - moznetwork == 0.27 08:02:31 INFO - mozprocess == 0.22 08:02:31 INFO - mozprofile == 0.28 08:02:31 INFO - mozrunner == 6.11 08:02:31 INFO - mozscreenshot == 0.1 08:02:31 INFO - mozsystemmonitor == 0.0 08:02:31 INFO - moztest == 0.7 08:02:31 INFO - mozversion == 1.4 08:02:31 INFO - psutil == 3.1.1 08:02:31 INFO - requests == 1.2.3 08:02:31 INFO - wsgiref == 0.1.2 08:02:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:31 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 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:31 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 08:02:31 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 08:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:31 INFO - 'HOME': '/Users/cltbld', 08:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:31 INFO - 'LOGNAME': 'cltbld', 08:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:31 INFO - 'MOZ_NO_REMOTE': '1', 08:02:31 INFO - 'NO_EM_RESTART': '1', 08:02:31 INFO - 'PAGER': '/bin/cat', 08:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:31 INFO - 'PWD': '/builds/slave/test', 08:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:31 INFO - 'SHELL': '/bin/bash', 08:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:31 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:31 INFO - 'USER': 'cltbld', 08:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:31 INFO - 'XPC_FLAGS': '0x0', 08:02:31 INFO - 'XPC_SERVICE_NAME': '0', 08:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 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)) 08:02:32 INFO - Cleaning up... 08:02:32 INFO - Return code: 0 08:02:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:32 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')]} 08:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:32 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')]} 08:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:32 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 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:32 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 08:02:32 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 08:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:32 INFO - 'HOME': '/Users/cltbld', 08:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:32 INFO - 'LOGNAME': 'cltbld', 08:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:32 INFO - 'MOZ_NO_REMOTE': '1', 08:02:32 INFO - 'NO_EM_RESTART': '1', 08:02:32 INFO - 'PAGER': '/bin/cat', 08:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:32 INFO - 'PWD': '/builds/slave/test', 08:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:32 INFO - 'SHELL': '/bin/bash', 08:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:32 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:32 INFO - 'USER': 'cltbld', 08:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:32 INFO - 'XPC_FLAGS': '0x0', 08:02:32 INFO - 'XPC_SERVICE_NAME': '0', 08:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:32 INFO - Downloading/unpacking jsonschema 08:02:32 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 08:02:32 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 08:02:32 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 08:02:32 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 08:02:32 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 08:02:32 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 08:02:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:34 INFO - Downloading/unpacking functools32 (from jsonschema) 08:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:35 INFO - warning: no files found matching '*.txt' 08:02:35 INFO - no previously-included directories found matching 'build' 08:02:35 INFO - no previously-included directories found matching 'dist' 08:02:35 INFO - no previously-included directories found matching '.git*' 08:02:35 INFO - Installing collected packages: jsonschema, functools32 08:02:35 INFO - Running setup.py install for functools32 08:02:35 INFO - warning: no files found matching '*.txt' 08:02:35 INFO - no previously-included directories found matching 'build' 08:02:35 INFO - no previously-included directories found matching 'dist' 08:02:35 INFO - no previously-included directories found matching '.git*' 08:02:35 INFO - Successfully installed jsonschema functools32 08:02:35 INFO - Cleaning up... 08:02:35 INFO - Return code: 0 08:02:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108535a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8381584140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, '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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 08:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:35 INFO - 'HOME': '/Users/cltbld', 08:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:35 INFO - 'LOGNAME': 'cltbld', 08:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:35 INFO - 'MOZ_NO_REMOTE': '1', 08:02:35 INFO - 'NO_EM_RESTART': '1', 08:02:35 INFO - 'PAGER': '/bin/cat', 08:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:35 INFO - 'PWD': '/builds/slave/test', 08:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:35 INFO - 'SHELL': '/bin/bash', 08:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:02:35 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:35 INFO - 'USER': 'cltbld', 08:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:35 INFO - 'XPC_FLAGS': '0x0', 08:02:35 INFO - 'XPC_SERVICE_NAME': '0', 08:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:35 INFO - Downloading/unpacking flake8 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:02:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:02:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:02:39 INFO - no previously-included directories found matching 'docs/_build' 08:02:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:02:39 INFO - Running setup.py install for pep8 08:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:02:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:02:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:02:39 INFO - no previously-included directories found matching 'docs/_build' 08:02:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:02:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:02:39 INFO - Cleaning up... 08:02:39 INFO - Return code: 0 08:02:39 INFO - Running post-action listener: _resource_record_post_action 08:02:39 INFO - Running post-action listener: _start_resource_monitoring 08:02:39 INFO - Starting resource monitoring. 08:02:39 INFO - ##### 08:02:39 INFO - ##### Running install step. 08:02:39 INFO - ##### 08:02:39 INFO - Running pre-action listener: _resource_record_pre_action 08:02:39 INFO - Running main action method: install 08:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:39 INFO - Reading from file tmpfile_stdout 08:02:39 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:40 INFO - Reading from file tmpfile_stdout 08:02:40 INFO - Output received: 08:02:40 INFO - Usage: mozinstall [options] installer 08:02:40 INFO - Options: 08:02:40 INFO - -h, --help show this help message and exit 08:02:40 INFO - -d DEST, --destination=DEST 08:02:40 INFO - Directory to install application into. [default: 08:02:40 INFO - "/builds/slave/test"] 08:02:40 INFO - --app=APP Application being installed. [default: firefox] 08:02:40 INFO - mkdir: /builds/slave/test/build/application 08:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:03:13 INFO - Reading from file tmpfile_stdout 08:03:13 INFO - Output received: 08:03:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:03:13 INFO - Running post-action listener: _resource_record_post_action 08:03:13 INFO - ##### 08:03:13 INFO - ##### Running run-tests step. 08:03:13 INFO - ##### 08:03:13 INFO - Running pre-action listener: _resource_record_pre_action 08:03:13 INFO - Running main action method: run_tests 08:03:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:13 INFO - 2016-03-09 08:03:13.554 screenresolution[1917:12391] starting screenresolution argv=screenresolution get 08:03:13 INFO - 2016-03-09 08:03:13.557 screenresolution[1917:12391] Display 0: 1600x1200x32@60 08:03:13 INFO - 2016-03-09 08:03:13.563 screenresolution[1918:12393] starting screenresolution argv=screenresolution list 08:03:13 INFO - Available Modes on Display 0 08:03:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:13 INFO - Intel Iris: 08:03:13 INFO - Chipset Model: Intel Iris 08:03:13 INFO - Type: GPU 08:03:13 INFO - Bus: Built-In 08:03:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:13 INFO - Vendor: Intel (0x8086) 08:03:13 INFO - Device ID: 0x0a2e 08:03:13 INFO - Revision ID: 0x0009 08:03:13 INFO - Displays: 08:03:13 INFO - Display: 08:03:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:13 INFO - Display Serial Number: 1600x1200 60 08:03:13 INFO - Main Display: Yes 08:03:13 INFO - Mirror: Off 08:03:13 INFO - Online: Yes 08:03:13 INFO - Rotation: Supported 08:03:13 INFO - Return code: 0 08:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:13 INFO - Python 2.7.3 08:03:13 INFO - Return code: 0 08:03:13 INFO - grabbing minidump binary from tooltool 08:03:13 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')]} 08:03:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d9d30>, '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 0x1081fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:13 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 08:03:13 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 08:03:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:13 INFO - Return code: 0 08:03:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:03:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:03:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:03:15 INFO - Return code: 0 08:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMjRmgV1OC/Render', 08:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:15 INFO - 'HOME': '/Users/cltbld', 08:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:15 INFO - 'LOGNAME': 'cltbld', 08:03:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:15 INFO - 'MOZ_NO_REMOTE': '1', 08:03:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:15 INFO - 'NO_EM_RESTART': '1', 08:03:15 INFO - 'PAGER': '/bin/cat', 08:03:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:15 INFO - 'PWD': '/builds/slave/test', 08:03:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:15 INFO - 'SHELL': '/bin/bash', 08:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHOcvXIPet/Listeners', 08:03:15 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 08:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:15 INFO - 'USER': 'cltbld', 08:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:15 INFO - 'XPC_FLAGS': '0x0', 08:03:15 INFO - 'XPC_SERVICE_NAME': '0', 08:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:03:15 INFO - mozversion application_buildid: 20160309052235 08:03:15 INFO - mozversion application_changeset: ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 08:03:15 INFO - mozversion application_display_name: Nightly 08:03:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:15 INFO - mozversion application_name: Firefox 08:03:15 INFO - mozversion application_remotingname: firefox 08:03:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:03:15 INFO - mozversion application_vendor: Mozilla 08:03:15 INFO - mozversion application_version: 48.0a1 08:03:15 INFO - mozversion platform_buildid: 20160309052235 08:03:15 INFO - mozversion platform_changeset: ee8f304d9d624328cfd126dd7ed7aa6e35df5b52 08:03:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:03:15 INFO - mozversion platform_version: 48.0a1 08:03:15 INFO - using testdate: 1457539395 08:03:15 INFO - actual date: 1457539395 08:03:15 INFO - starting webserver on 'localhost:49836' 08:03:15 INFO - SUITE-START | Running 2 tests 08:03:15 INFO - TEST-START | dromaeo_css 08:03:15 INFO - operating with platform_type : mac_ 08:03:15 INFO - Initialising browser for dromaeo_css test... 08:03:15 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpRjI2ni/profile http://localhost:49836/getInfo.html) 08:03:18 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 08:03:18 INFO - PROCESS | 1936 | colorDepth:24 08:03:18 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 08:03:18 INFO - PROCESS | 1936 | __metrics 08:03:18 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2851: TypeError: this.tabs is undefined 08:03:19 INFO - TEST-INFO | 1936: exit 0 08:03:19 INFO - Browser initialized. 08:03:19 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:19 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpRjI2ni/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:31 INFO - PROCESS | 1940 | RSS: Main: 255537152 08:03:31 INFO - PROCESS | 1940 | 08:04:20 INFO - PROCESS | 1940 | 1457539460713 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] 08:06:31 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49836/tests/dromaeo/cssquery-ext.html) 08:06:33 INFO - PROCESS | 1940 | RSS: Main: 268763136 08:06:33 INFO - PROCESS | 1940 | 08:09:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-ext.html (next: http://localhost:49836/tests/dromaeo/cssquery-jquery.html) 08:09:35 INFO - PROCESS | 1940 | RSS: Main: 280342528 08:09:35 INFO - PROCESS | 1940 | 08:12:37 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49836/tests/dromaeo/cssquery-mootools.html) 08:12:38 INFO - PROCESS | 1940 | RSS: Main: 281899008 08:12:38 INFO - PROCESS | 1940 | 08:15:39 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49836/tests/dromaeo/cssquery-prototype.html) 08:15:40 INFO - PROCESS | 1940 | RSS: Main: 282898432 08:15:40 INFO - PROCESS | 1940 | 08:18:43 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49836/tests/dromaeo/cssquery-yui.html) 08:18:43 INFO - PROCESS | 1940 | RSS: Main: 285880320 08:18:43 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49836/tests/dromaeo/cssquery-yui.html (next: http://localhost:49836/tests/dromaeo/cssquery-dojo.html) 08:21:46 INFO - PROCESS | 1940 | RSS: Main: 288927744 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | __start_tp_report 08:21:46 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 08:21:46 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 08:21:46 INFO - PROCESS | 1940 | |i|pagename|runs| 08:21:46 INFO - PROCESS | 1940 | |0;dojo.html;3451.7066049385803;3466.757326987111;3493.860245590175;3534.7702399344057;3543.9114022149442;5797.507211886995;5806.9709651451685;5809.331926828416;5878.323992740836;6016.789412370568;4657.953420465794;5724.084146536555;5892.675902825343;5922.407759224076;5926.318473375563;4344.543822898598;5239.633225674204;5316.787328506855;5373.301470808789;5380.91928621071;5266.131088370415;5414.756335964871;5466.972665136669;5500.477454641803;5527.364353099405;5198.740062996835;5263.552598029172;5337.946620533784;5343.438938911398;5424.105022671254;5294.655847369909;5297.602679799031;5307.893842123174;5318.335208098988;5321;5289.391719952217;5295.549878260353;5308;5309.548688361494;5355.6251062425645;4967.850964471072;5381.730913454351;5731.283589551306;5751.3961034091235;5805;5175;5611.186377975211;6165.506759459233;6294.527910406738;6327.620342779448;6301.873962520724;6320.525960552977;6327.493800495995;6338.778142764981;6347.968260158716;5827.446392592697;5914.645121292693;5919.67441790697;5924.2298501195;5931.555133364928;6306.180196574416;6368.395002474757;6433.453156481752;6455.096286519891;6463.289038205794;6199.628022318675;6211.844703882349;6254.21822272216;6269.5611307208055;6284.308726040132;6215.471684906834;6358.682065896779;6360.968195158995;6368.14030105939;6371.203599550056;6122.886104953835;6144.341027922525;6181.289151747516;6232.376762323822;6254.718537665816;5121.436641969381;6281.59169653971;6293.874122517524;6310.873782524323;6322.241331040304;4563.726176429424;4577.22187228172;4896.436909755353;4976.502349765067;5022.146235140035;4356.455443069616;4426.15902978433;4449.9555004450185;4458.063806600585;4500.572445617661;4898.363212782387;4909.705417674951;5900.586958912835;5951.732172052181;5962.880742385215;6191.81424557264;6228.5017198624;6293.30773614902;6321.241451025906;6330.746770129143;5232.319798426181;5802.21670074543;5964.926313263568;5995.040793473024;6031.547633927473;5464.972675136599;5509.118541033496;5520.475554822286;5522.116461366162;5540.778368865281;6305.306416294296;6416.165898433361;6482.189726284215;6519.608823470607;6525.869482610321;6314.336994615499;6315.652639104894;6327.557071004985;6353.7140828661895;6367.777127800505;6497.220333560003;6499.675016249074;6505.991571306455;6515.869682606227;6516.120323756232;6116.396640261158;6178.969105154445;6224.688765561795;6244.0321750128805;6302.684865756785;5552.833414997556;5986.6707331097205;6267.122602835516;6315.3053164152825;6356.682165891594;6238.906416403667;6285.402886725754;6295.244570651551;6299.559030867796;6309.968450157903;6088.817335479943;6117.816465506042;6179.721912514037;6231.937680623317;6282.780102696383;6033.607815491979;6066.666333351708;6109.20580324555;6165.815025549241;6184.721687523982;6191.938080619317;6211.440970312694;6230.532710046674;6262.937370626418;6283.748650053946;5113.590912727047;6131.325554188947;6135.07955838608;6184.845378865384;6207 08:21:46 INFO - PROCESS | 1940 | |1;ext.html;444.42225107360423;494.24380697532774;540.8810061786405;549.3901769036366;632.8196464007759;32417.86537471186;37849.810750946206;37995.43006854896;38678.226435471275;38757;40132.197356052864;40429.79785101078;40653.59346406535;40738;40748.79625601875;9424.29317801166;9460.716178514656;9616.230701543895;9623.230141588656;9648.71053868385;9535.713928582154;9640.75898102546;9705.611775529005;9729.951350243238;9759.121679048883;9725.6596019139;9758.707238782848;9797.608095676165;9799.706008819747;9821.950890245538;9642.228621710246;9669.033096690346;9683.612655493771;9728.17310528606;9777.022297770238;9567.617295308179;9690.370125941861;9700.36947598411;9724.465154416504;9728.464934428604;32434.6756532434;39384.60615393838;39614.60385396138;39963;40083;2831.405404864981;3121.687831216883;3131.7024882636115;3270.672932706734;3360.12636714456;27551.760170792364;27666.585001224998;27971.300717482023;28070.578941315896;28270;2707.2013755942044;2725.6865460471904;2756.8208066475613;2774.3064233941514;2848.9715102848913;447688;452534;453300;455181.9999999967;456672;67884;68328;68839;68954.3104568958;69008.3099169012;72341;72431;74960.25039749642;75511;75600;16963.491095267163;17080;17185.05482198491;17207.1396430178;17211.225494852762;12437.129400941847;12725.745485090247;12965.22208667483;12971.481140754453;12997.15518491295;18317.267309307746;21737.673934890674;22013.66979495309;22035.228766993077;22295.88852055762;9355.345125841126;9544.99777523364;9608.66369677058;9625.32622716417;9646.565904534173;951.7715748220377;1003.4781913404988;1019.3527110285022;1068.70610582089;1082.4804094034907;27898.860505697343;29732.702672973424;30039.249018774706;30282;30400.39199216003;1071.5767271927546;1081.1999120650662;1087.6628245243999;1091.3833683968533;1095.430376204385;10876.72808179786;14593.489227876973;15121.848781512264;15171;15503.84496155024;499833;512357;512947;513204;514050;47968.76015619969;58801.70599146977;61007.6949615258;61046.08430873541;61138.69430652818;1427.215031732543;1461.2328527522923;1471.198196982647;1477.364733164728;1482.7775833625042;29384;29475.852620736758;29701.851490742407;30066.69933300639;30321;46206.613801586005;51064.999999998516;54238;54402.45597543974;54447.72776136094;410249;464825;478906;481875;486071;31509.897153600432;31600.525992109677;32073;32432.35135297328;32447;42964.14071718611;42997.785011074746;45196.77401612971;46466.53533464622;46645.53354466548;38046.61953380431;38258.426123608726;38318.042048949;38424.61575384322;39099;37309.25381492409;37618.247635046595;37739.43390849084;38034.8098259518;38147.809260953516;36079;37387.62612373842;37599;37719.245615087;38096.61903381041;32752.836235818668;33416;33793;33801.661983379854;33903.66096339005;24266.87866560656;24664;24928;24983.194156048095;25303.493930120927 08:21:46 INFO - PROCESS | 1940 | |2;jquery.html;327.4057585482348;352.7830384313648;356.11150180300154;365.43175362311604;367.91905780728246;16776.580585485368;19203.231870725172;20130.396088117344;20322.89838550805;20375.49061273465;12471.875281247207;16210.432634857782;19564.999999999964;20481.4879628009;20625.27811526597;5451.863703407426;5677.120046392795;5721.828345149632;5729.05470597353;5731.426857314256;5412.891742165174;5692.430756924296;5713.514351280146;5777.855553611173;5778.393268706788;5589.608727389084;5614.578906582003;5636.351819540744;5641.435856414346;5671.461211184951;5369.973150134224;5506.889862202774;5616.550675945915;5693.686847223401;5704.743286552115;4891.461939186686;4904.852854414374;4905.166121759274;4915.21356582945;4930.531599498042;24780.380490487703;25138.37154071163;25417;25577;25606.231813045637;2068.927587534429;2169.110664627495;2248;2470.7035155781423;2710.227585138226;12093.637190884288;14819.629509262248;15476.148811815345;15526.146061966587;15604.765928511082;1867.1224524473475;1880.172724001435;1929.0644037641732;1939.5151212196952;2173.8913054347217;105614;114557;120023.19965200529;125203;127453;19296.71054934177;19757.703634445206;19837;19838.70241946372;20295.695564566544;29268.707312927025;29546.852265738533;29803.701962980525;29873;29877.40245195084;7992.120866704658;8248.83502329962;8253.215944485264;8299.004119505698;8355.206255405727;8363.28912042471;8363.665453381796;8421.663133474593;8463.53450560225;8507.61715722794;4335.674824388184;4340.739555626673;4365.716228445141;4401.273789824707;4405.515393306734;1646.0864220357578;1851.5556266495944;1884.877482963603;1891.5460289530413;1911.120884393167;817.4808996287281;946.9195118414874;974.8001659659752;982.8476586129161;1028.1698576533781;15875;19321.710174347398;20162.798372016387;20426.795732042785;20591.8970405147;905.7011186308503;915.766479547711;953.2278854128178;1019.5259204469957;1049.7008352619466;10440.477976101165;10793;10822.35065896033;10851.021425012457;10862.34825910448;572354;610738;620303;620953;625572;116051.41974290075;119209;120604;121143;124493;945;950.8827128124426;1012.7265638277623;1048.2871647279924;1051.358671210561;17500.387486437914;17545.122743863012;18081;18360.540986475444;18733.90633046826;52881.94236115333;54476.18285725638;54744.45255547394;57162.7141864288;57237.71381143068;80355;81561;81993.18006819855;82806;83283.16716832753;7529.849403012018;7700.460967732205;7862.842743144991;7914.841703165789;8225.95887020561;10964.835527467254;12898;12937.482500700244;13351.399187036412;13454.865451345362;11148.832767508657;11161.72095697614;11230.905213956432;11275.66173014811;11374;11037.06184974254;11145.996860282614;11160.888391115985;11197.440127993405;11306.643202815714;11162.49768760424;11472.02487788514;11812.76374472523;11944.880551194725;11946.163768536118;7828.099768526692;7857.489263198103;7926.088499822594;8025.59872006409;8248.381371397048;9138.817223655622;10473.895261047292;10969.725756856142;11015.228933974546;11129.16531260175 08:21:46 INFO - PROCESS | 1940 | |3;mootools.html;1417.0930604413174;1646.7035933531968;1885.4343696890928;1992.6214019336326;1996.9700454493177;7992.280694737473;8610.354223433253;8764.561771911403;8948.507832069241;8963.282937365011;8611.91388086119;8635;8737.86893196601;8749.693760718375;8752.212300892917;2469.456719521707;2480.4543000539943;2492;2499.8875050622764;2511.7864981476596;2439.134107391877;2498.4003839078673;2500.824942254043;2507.674002379688;2513.899444022246;2474.9010039598393;2489.4772097859473;2504.686914135733;2520.3699075231193;2522.5585522533574;2495.1142344467726;2497.438076432806;2519.7984161267054;2522.7477252274807;2524.129175434472;2476.789472894788;2488;2511.4223728542356;2514.245726282108;2529.064246228889;8794.912050879473;8824.735257942271;8839.16027977341;8879.955600222022;8940.46357218569;5390.51485366319;5411.21537377082;5497.505224529771;5580.86047848803;5585.916211256835;3612.458131280329;3689.9262014759825;3714.609965953563;3753.906152346186;3755.9436608450897;1049.4490392543898;1062.054771253585;1065.9093977011964;1071.6570697376842;1078.6710053433699;10273.383596984206;11849.822252666218;11960.102992275528;12138.907498325281;12176.878231217752;4811.398575178103;4882.926756098661;4971.37857767779;4997.250412438163;4998.825041123543;10141.94929025365;10217.386956782617;10285;10304.845427318596;10364.896351036545;5415.6479828811;5458.344998600114;5515.972420137954;5524.66851988882;5543.251661025791;4442.000549876252;4838.419389673261;4938.111139994836;4952.777125029383;4967.205247160437;3943.3493473577114;4003.619656132663;4048.1701251243426;4055.655269302142;4062.5531191568903;1299;1306.6994591244068;1311.4491913396396;1325.6818363592668;1329.1891945912985;1382.1499777636682;1385.2104300548792;1396.392569232367;1413.2227274998709;1416.1927701210418;8102.594870256464;8294.419390642597;8359.665613375519;8456.06983231844;8461.2807911327;454.59086821859927;492.44599825196656;521.5749164430977;524.8163142899955;525.6268049684691;6282.057691346243;6335.461485773761;6362.872742545123;6375.171227740364;6401.807945761635;10418.906014868482;10850.29473084247;10947.83578246327;11015.779684406267;11042.723931901606;10519.84220236697;10814.405207713495;10834.02493775564;10866.348019118877;10868.72828179286;8196.83606327882;8330.458520196167;8411.453255538492;8443.324534037261;8458.407911446262;7875.921240787748;7988.800279993046;8023.277904988346;8057.476264042818;8091.676332946851;9589;9590.712278631652;9702.611895524382;9747.122758951728;9964.202863770735;9672.83925928893;9958.103770660675;9987.60049598008;10100.242481813746;10166.491675416348;6918.515703900679;7221.747238846615;7454.627268636655;7709.537427754352;7812;8151.062627797879;8207.794805129919;8219.465734727224;8255.669773209005;8264.876026859481;7262.745803896838;7364.3003914628025;7373.225811289737;7455.739049133254;7545.547267163988;7169.928300716926;7197.928020719726;7217.7473788417155;7219.808731559234;7248.673809678683;6563.015547667888;6655.201375834931;7594.5063570867715;7704.460687751804;7706.190849960898;6918.896216556856;6921.2732663069655;6939.757108501381;6961.199462061725;6988.685509152202;5901.911471327848;6940.7917762467205;7034.402075823612;7102.928970710227;7137.214906360193 08:21:46 INFO - PROCESS | 1940 | |4;prototype.html;326.555883997763;329.1458664764935;335.0751924687861;337.9611344695361;338.72563223788734;5406;6805.319468053192;6869.107016087908;6886.690098945547;6903.964405339189;6732.865342693155;6752.864942701155;6755.7973260802255;6763.560368576039;6782.457403407728;4817.710937343753;4847.781849816749;4989.675671081377;5019.749012549376;5021.297018417425;4985.975070124662;4993.101241776479;5007.574356179729;5017.824376146836;5026.698398096126;4922.68002579833;4942.18453955097;4942.456329803718;4961.231009193562;4979.701217926918;4911.778969946343;4932.975335123319;4942.011597680443;4942.20924652054;5053.4946505349535;3805.8667946621736;4013.237484877864;4307;4308.547602501723;4328.394024836525;5132.794688212504;6231;6493.93506064938;6531.706073226716;6553.836154096138;4744.928826067578;5657.632253903483;5667.149927510865;5719.25649665545;5741.425857414225;6082.452579267887;6155.261368635747;7009.649517524103;7302.598357090358;7343.889841652326;3520.806355650438;3686.9262614747554;4618.1918164321105;4644.651651126179;4651.023285110131;7314.5611263323835;7702.46082774206;7734.651940662684;7750.457467977188;7752.341051010614;3780.640839120279;4606.792694328763;4709.905801883943;4710.69380490267;4723.149833030087;4757.9286310705365;4799.112164249625;4807.302941073524;4828.537445902545;4832.347633069561;5790.652560846363;5830.591858569859;5836.299644042657;5846.795362162389;5856.1508581255475;7339.045924029842;7340;7359.632018399058;7360.374368178657;7392.630368481555;1571.2929181868205;1612.9112898790445;1646.415522489523;1654.693881631902;1681.3862940027047;1046.7801761630144;1057.629829559648;1067.215596536545;1069.8020866139634;1070.3203465799274;6715;6715.7985260442265;6725.8654826903185;6735.8989615155815;6745.561538499982;6071.423214794588;7513.887291690629;7533.1336896257635;7538.3592394645975;7543.9622801886735;6692.397684208445;6731.596104233761;6750.763723269662;6751.56114852533;6754.5609535380045;3904.941425878614;3954.426608141861;3984.4620976168426;4001.91996160081;4023.0143614814556;7655.846883062308;7788.299053085249;8000.439969202216;8073;8074.757757267292;7925.128235894047;7951.085625153066;7963.203679632106;7968.282854543119;7997.880031799411;7980.800479988046;8001.919980800234;8009.799755006172;8017.438779285394;8022.799430014296;7244.420446364384;7538.849223015399;7560.092788865371;7641.083070031632;7655.6937722490475;7400.816759056199;7406.370458511087;7442.62786860644;7527.774166775005;7549.50928189666;7984.081830589324;7987.680492780224;7990.560519171501;7993.760187194393;8011.799705007422;3808.619138086169;4719.480857105647;4723.858284251478;4726.078414709099;4742.762861856962;7301.160366557914;7533.698652053857;7559.28186822251;7596.81007974805;7598.35413989795;7305.634718263959;7317.524360916524;7327.0841144856895;7328.523645962995;7354.779356619096;5729.799457018984;7281.470891112714;7291.161516425679;7317.597532135784;7392.667329970272;5808.5062769665055;7100.147982242164;7112.679929403293;7155.3202445767565;7157.0695809546505;6991;7207.315305046012;7230.710771569078;7270.200277969525;7322.194558598443;5473.644213126134;6152.369622050071;7166.745819481465;7171.964140179266;7184.497085203986 08:21:46 INFO - PROCESS | 1940 | |5;yui.html;616.8334549671589;665.873484038033;715.8425146467775;731.7731503233998;754.1628792040834;15045;15369.001014934021;15425.074495530276;15469.45856895009;15479.303431345588;11378.487968041438;13817.032807703465;14186.787198192009;14313.141211527316;15985.120818355015;889.6485888074207;911.0161026091838;911.5168960450972;911.9544022798833;913.4884464699757;729.8941653460245;896.4979611417627;903.6475774447971;912.3066469483207;919.5632074764467;816.2572059425923;966.1739212972913;966.463612694954;968.6319198704517;970.3886551472563;943.1652987106391;955.5699935029264;959.040959040959;961.2550273538029;961.485605201214;704.3977399323555;711.5090587494612;712.5190496414899;715.0025714128814;717.4188906985306;15217.923910380488;15265.6183595411;15282.235888205545;15370.538883833502;15415.229238538028;12495.312757798318;12537.874621253854;12546.74906501874;12782.169159004636;12834.229946203257;9831.115199632068;9881.505924703735;9914.752131196778;10012.64955726546;10345.689629311133;3429.1941393772445;3459.8962031138853;3461.6192218855904;3467.930641387183;3477.2523907359787;80339;80507.19492805055;80809.59595201987;81080.18919810845;81549.18450815475;7820.712816589385;9149.856267966505;9274.06523468584;9408.95295523518;9504.809903801885;20962.47593810167;21327.36017919465;21342;21377.78622213789;21396.465088372915;7269.418446524265;7347.742829000959;7413.2957369049855;7427.071616047994;7497.10034795828;4062.5937406259136;4122.9381559276635;4155.709100362946;4166.6250037496775;4174.144300418407;2996.685348038468;3002.774791890617;3375.6624337566045;3579.284143171376;3805.5919309854976;589.6521052578998;591.4381337729175;660.8017594721564;660.9966950165218;670.792054463118;521.3326941514867;521.8147557617067;591.1989254560095;591.6597956175217;605.7122866638312;7953.522788632585;8180.141085186089;8312.33501319893;8427.831443371098;8429.620667069996;528.9709066001407;542.3166809819577;573.931128264611;589.669784920446;602.9819105426844;3760.7367484276383;3837.0982819037504;4059.106996460774;4093.7543747374657;4174.060836311805;87199.69200461998;93222;95577.52211239039;95927;98259.50870245746;72144.63927680328;73766;74142;74834.62582687051;75952.62023689847;66583.00125498121;68555;68887.65556172187;68940.96588551176;69132.654336729;8312.709055183039;8415.074341822272;8492.532910689724;8530.78673033179;8556.700515481929;36560.45159322559;47029.764851175525;47165.34921277876;47300.526994730986;48723.371279728366;64262.71474570576;64825.67587162034;65375.34624653692;66064.33935660582;67929;9133.543322833964;10864.782704345711;10954.726131846448;11063.50214240345;11317.377544234816;9294.72115836526;11967.700807479883;11980.700482488008;12010.63968080924;12146.089043321963;11116.333020018825;11274.492647830702;11320.37737924422;11509.654710358702;11810.232334898204;11245.325280483197;11315.830262545904;11362.091032717197;11420.828687569527;11677.240979336317;11215.775684486427;11221.775564488828;11222.943885280521;11295.830562541403;11526.654200374001;9583.329166958247;9610.471424071744;9841.950790246003;9905;9963.850542242017;9517.428954262767;9682.177014953808;9706;9737;9753.658621948198 08:21:46 INFO - PROCESS | 1940 | __end_tp_report 08:21:46 INFO - PROCESS | 1940 | __start_cc_report 08:21:46 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,367 08:21:46 INFO - PROCESS | 1940 | __end_cc_report 08:21:46 INFO - PROCESS | 1940 | __startTimestamp1457540506331__endTimestamp 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | ------- Summary: start ------- 08:21:46 INFO - PROCESS | 1940 | Number of tests: 6 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5791.65 Median:6066.67 stddev:678.42 (11.2%) stddev-sans-first:655.19 08:21:46 INFO - PROCESS | 1940 | Values: 3451.7 3466.8 3493.9 3534.8 3543.9 5797.5 5807.0 5809.3 5878.3 6016.8 4658.0 5724.1 5892.7 5922.4 5926.3 4344.5 5239.6 5316.8 5373.3 5380.9 5266.1 5414.8 5467.0 5500.5 5527.4 5198.7 5263.6 5337.9 5343.4 5424.1 5294.7 5297.6 5307.9 5318.3 5321.0 5289.4 5295.5 5308.0 5309.5 5355.6 4967.9 5381.7 5731.3 5751.4 5805.0 5175.0 5611.2 6165.5 6294.5 6327.6 6301.9 6320.5 6327.5 6338.8 6348.0 5827.4 5914.6 5919.7 5924.2 5931.6 6306.2 6368.4 6433.5 6455.1 6463.3 6199.6 6211.8 6254.2 6269.6 6284.3 6215.5 6358.7 6361.0 6368.1 6371.2 6122.9 6144.3 6181.3 6232.4 6254.7 5121.4 6281.6 6293.9 6310.9 6322.2 4563.7 4577.2 4896.4 4976.5 5022.1 4356.5 4426.2 4450.0 4458.1 4500.6 4898.4 4909.7 5900.6 5951.7 5962.9 6191.8 6228.5 6293.3 6321.2 6330.7 5232.3 5802.2 5964.9 5995.0 6031.5 5465.0 5509.1 5520.5 5522.1 5540.8 6305.3 6416.2 6482.2 6519.6 6525.9 6314.3 6315.7 6327.6 6353.7 6367.8 6497.2 6499.7 6506.0 6515.9 6516.1 6116.4 6179.0 6224.7 6244.0 6302.7 5552.8 5986.7 6267.1 6315.3 6356.7 6238.9 6285.4 6295.2 6299.6 6310.0 6088.8 6117.8 6179.7 6231.9 6282.8 6033.6 6066.7 6109.2 6165.8 6184.7 6191.9 6211.4 6230.5 6262.9 6283.7 5113.6 6131.3 6135.1 6184.8 6207.0 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:62954.58 Median:38371.33 stddev:126603.33 (329.9%) stddev-sans-first:126871.49 08:21:46 INFO - PROCESS | 1940 | Values: 444.4 494.2 540.9 549.4 632.8 32417.9 37849.8 37995.4 38678.2 38757.0 40132.2 40429.8 40653.6 40738.0 40748.8 9424.3 9460.7 9616.2 9623.2 9648.7 9535.7 9640.8 9705.6 9730.0 9759.1 9725.7 9758.7 9797.6 9799.7 9822.0 9642.2 9669.0 9683.6 9728.2 9777.0 9567.6 9690.4 9700.4 9724.5 9728.5 32434.7 39384.6 39614.6 39963.0 40083.0 2831.4 3121.7 3131.7 3270.7 3360.1 27551.8 27666.6 27971.3 28070.6 28270.0 2707.2 2725.7 2756.8 2774.3 2849.0 447688.0 452534.0 453300.0 455182.0 456672.0 67884.0 68328.0 68839.0 68954.3 69008.3 72341.0 72431.0 74960.3 75511.0 75600.0 16963.5 17080.0 17185.1 17207.1 17211.2 12437.1 12725.7 12965.2 12971.5 12997.2 18317.3 21737.7 22013.7 22035.2 22295.9 9355.3 9545.0 9608.7 9625.3 9646.6 951.8 1003.5 1019.4 1068.7 1082.5 27898.9 29732.7 30039.2 30282.0 30400.4 1071.6 1081.2 1087.7 1091.4 1095.4 10876.7 14593.5 15121.8 15171.0 15503.8 499833.0 512357.0 512947.0 513204.0 514050.0 47968.8 58801.7 61007.7 61046.1 61138.7 1427.2 1461.2 1471.2 1477.4 1482.8 29384.0 29475.9 29701.9 30066.7 30321.0 46206.6 51065.0 54238.0 54402.5 54447.7 410249.0 464825.0 478906.0 481875.0 486071.0 31509.9 31600.5 32073.0 32432.4 32447.0 42964.1 42997.8 45196.8 46466.5 46645.5 38046.6 38258.4 38318.0 38424.6 39099.0 37309.3 37618.2 37739.4 38034.8 38147.8 36079.0 37387.6 37599.0 37719.2 38096.6 32752.8 33416.0 33793.0 33801.7 33903.7 24266.9 24664.0 24928.0 24983.2 25303.5 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:35888.77 Median:25277.69 stddev:101557.83 (401.8%) stddev-sans-first:101807.63 08:21:46 INFO - PROCESS | 1940 | Values: 327.4 352.8 356.1 365.4 367.9 16776.6 19203.2 20130.4 20322.9 20375.5 12471.9 16210.4 19565.0 20481.5 20625.3 5451.9 5677.1 5721.8 5729.1 5731.4 5412.9 5692.4 5713.5 5777.9 5778.4 5589.6 5614.6 5636.4 5641.4 5671.5 5370.0 5506.9 5616.6 5693.7 5704.7 4891.5 4904.9 4905.2 4915.2 4930.5 24780.4 25138.4 25417.0 25577.0 25606.2 2068.9 2169.1 2248.0 2470.7 2710.2 12093.6 14819.6 15476.1 15526.1 15604.8 1867.1 1880.2 1929.1 1939.5 2173.9 105614.0 114557.0 120023.2 125203.0 127453.0 19296.7 19757.7 19837.0 19838.7 20295.7 29268.7 29546.9 29803.7 29873.0 29877.4 7992.1 8248.8 8253.2 8299.0 8355.2 8363.3 8363.7 8421.7 8463.5 8507.6 4335.7 4340.7 4365.7 4401.3 4405.5 1646.1 1851.6 1884.9 1891.5 1911.1 817.5 946.9 974.8 982.8 1028.2 15875.0 19321.7 20162.8 20426.8 20591.9 905.7 915.8 953.2 1019.5 1049.7 10440.5 10793.0 10822.4 10851.0 10862.3 572354.0 610738.0 620303.0 620953.0 625572.0 116051.4 119209.0 120604.0 121143.0 124493.0 945.0 950.9 1012.7 1048.3 1051.4 17500.4 17545.1 18081.0 18360.5 18733.9 52881.9 54476.2 54744.5 57162.7 57237.7 80355.0 81561.0 81993.2 82806.0 83283.2 7529.8 7700.5 7862.8 7914.8 8226.0 10964.8 12898.0 12937.5 13351.4 13454.9 11148.8 11161.7 11230.9 11275.7 11374.0 11037.1 11146.0 11160.9 11197.4 11306.6 11162.5 11472.0 11812.8 11944.9 11946.2 7828.1 7857.5 7926.1 8025.6 8248.4 9138.8 10473.9 10969.7 11015.2 11129.2 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:6079.37 Median:525.22 stddev:3205.58 (610.3%) stddev-sans-first:3195.41 08:21:46 INFO - PROCESS | 1940 | Values: 1417.1 1646.7 1885.4 1992.6 1997.0 7992.3 8610.4 8764.6 8948.5 8963.3 8611.9 8635.0 8737.9 8749.7 8752.2 2469.5 2480.5 2492.0 2499.9 2511.8 2439.1 2498.4 2500.8 2507.7 2513.9 2474.9 2489.5 2504.7 2520.4 2522.6 2495.1 2497.4 2519.8 2522.7 2524.1 2476.8 2488.0 2511.4 2514.2 2529.1 8794.9 8824.7 8839.2 8880.0 8940.5 5390.5 5411.2 5497.5 5580.9 5585.9 3612.5 3689.9 3714.6 3753.9 3755.9 1049.4 1062.1 1065.9 1071.7 1078.7 10273.4 11849.8 11960.1 12138.9 12176.9 4811.4 4882.9 4971.4 4997.3 4998.8 10141.9 10217.4 10285.0 10304.8 10364.9 5415.6 5458.3 5516.0 5524.7 5543.3 4442.0 4838.4 4938.1 4952.8 4967.2 3943.3 4003.6 4048.2 4055.7 4062.6 1299.0 1306.7 1311.4 1325.7 1329.2 1382.1 1385.2 1396.4 1413.2 1416.2 8102.6 8294.4 8359.7 8456.1 8461.3 454.6 492.4 521.6 524.8 525.6 6282.1 6335.5 6362.9 6375.2 6401.8 10418.9 10850.3 10947.8 11015.8 11042.7 10519.8 10814.4 10834.0 10866.3 10868.7 8196.8 8330.5 8411.5 8443.3 8458.4 7875.9 7988.8 8023.3 8057.5 8091.7 9589.0 9590.7 9702.6 9747.1 9964.2 9672.8 9958.1 9987.6 10100.2 10166.5 6918.5 7221.7 7454.6 7709.5 7812.0 8151.1 8207.8 8219.5 8255.7 8264.9 7262.7 7364.3 7373.2 7455.7 7545.5 7169.9 7197.9 7217.7 7219.8 7248.7 6563.0 6655.2 7594.5 7704.5 7706.2 6918.9 6921.3 6939.8 6961.2 6988.7 5901.9 6940.8 7034.4 7102.9 7137.2 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:5884.96 Median:6720.83 stddev:1947.73 (29.0%) stddev-sans-first:1907.99 08:21:46 INFO - PROCESS | 1940 | Values: 326.6 329.1 335.1 338.0 338.7 5406.0 6805.3 6869.1 6886.7 6904.0 6732.9 6752.9 6755.8 6763.6 6782.5 4817.7 4847.8 4989.7 5019.7 5021.3 4986.0 4993.1 5007.6 5017.8 5026.7 4922.7 4942.2 4942.5 4961.2 4979.7 4911.8 4933.0 4942.0 4942.2 5053.5 3805.9 4013.2 4307.0 4308.5 4328.4 5132.8 6231.0 6493.9 6531.7 6553.8 4744.9 5657.6 5667.1 5719.3 5741.4 6082.5 6155.3 7009.6 7302.6 7343.9 3520.8 3686.9 4618.2 4644.7 4651.0 7314.6 7702.5 7734.7 7750.5 7752.3 3780.6 4606.8 4709.9 4710.7 4723.1 4757.9 4799.1 4807.3 4828.5 4832.3 5790.7 5830.6 5836.3 5846.8 5856.2 7339.0 7340.0 7359.6 7360.4 7392.6 1571.3 1612.9 1646.4 1654.7 1681.4 1046.8 1057.6 1067.2 1069.8 1070.3 6715.0 6715.8 6725.9 6735.9 6745.6 6071.4 7513.9 7533.1 7538.4 7544.0 6692.4 6731.6 6750.8 6751.6 6754.6 3904.9 3954.4 3984.5 4001.9 4023.0 7655.8 7788.3 8000.4 8073.0 8074.8 7925.1 7951.1 7963.2 7968.3 7997.9 7980.8 8001.9 8009.8 8017.4 8022.8 7244.4 7538.8 7560.1 7641.1 7655.7 7400.8 7406.4 7442.6 7527.8 7549.5 7984.1 7987.7 7990.6 7993.8 8011.8 3808.6 4719.5 4723.9 4726.1 4742.8 7301.2 7533.7 7559.3 7596.8 7598.4 7305.6 7317.5 7327.1 7328.5 7354.8 5729.8 7281.5 7291.2 7317.6 7392.7 5808.5 7100.1 7112.7 7155.3 7157.1 6991.0 7207.3 7230.7 7270.2 7322.2 5473.6 6152.4 7166.7 7172.0 7184.5 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:17991.49 Median:33018.07 stddev:25271.13 (76.5%) stddev-sans-first:25308.35 08:21:46 INFO - PROCESS | 1940 | Values: 616.8 665.9 715.8 731.8 754.2 15045.0 15369.0 15425.1 15469.5 15479.3 11378.5 13817.0 14186.8 14313.1 15985.1 889.6 911.0 911.5 912.0 913.5 729.9 896.5 903.6 912.3 919.6 816.3 966.2 966.5 968.6 970.4 943.2 955.6 959.0 961.3 961.5 704.4 711.5 712.5 715.0 717.4 15217.9 15265.6 15282.2 15370.5 15415.2 12495.3 12537.9 12546.7 12782.2 12834.2 9831.1 9881.5 9914.8 10012.6 10345.7 3429.2 3459.9 3461.6 3467.9 3477.3 80339.0 80507.2 80809.6 81080.2 81549.2 7820.7 9149.9 9274.1 9409.0 9504.8 20962.5 21327.4 21342.0 21377.8 21396.5 7269.4 7347.7 7413.3 7427.1 7497.1 4062.6 4122.9 4155.7 4166.6 4174.1 2996.7 3002.8 3375.7 3579.3 3805.6 589.7 591.4 660.8 661.0 670.8 521.3 521.8 591.2 591.7 605.7 7953.5 8180.1 8312.3 8427.8 8429.6 529.0 542.3 573.9 589.7 603.0 3760.7 3837.1 4059.1 4093.8 4174.1 87199.7 93222.0 95577.5 95927.0 98259.5 72144.6 73766.0 74142.0 74834.6 75952.6 66583.0 68555.0 68887.7 68941.0 69132.7 8312.7 8415.1 8492.5 8530.8 8556.7 36560.5 47029.8 47165.3 47300.5 48723.4 64262.7 64825.7 65375.3 66064.3 67929.0 9133.5 10864.8 10954.7 11063.5 11317.4 9294.7 11967.7 11980.7 12010.6 12146.1 11116.3 11274.5 11320.4 11509.7 11810.2 11245.3 11315.8 11362.1 11420.8 11677.2 11215.8 11221.8 11222.9 11295.8 11526.7 9583.3 9610.5 9842.0 9905.0 9963.9 9517.4 9682.2 9706.0 9737.0 9753.7 08:21:46 INFO - PROCESS | 1940 | -------- Summary: end -------- 08:21:46 INFO - PROCESS | 1940 | 08:21:46 INFO - TEST-INFO | 1940: exit 0 08:21:46 INFO - TEST-OK | dromaeo_css | took 1111595ms 08:21:46 INFO - TEST-START | kraken 08:21:46 INFO - operating with platform_type : mac_ 08:21:46 INFO - Initialising browser for kraken test... 08:21:46 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpOG8CxN/profile http://localhost:49836/getInfo.html) 08:21:49 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 08:21:49 INFO - PROCESS | 1946 | colorDepth:24 08:21:49 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 08:21:49 INFO - PROCESS | 1946 | __metrics 08:21:49 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2851: TypeError: this.tabs is undefined 08:21:50 INFO - TEST-INFO | 1946: exit 0 08:21:50 INFO - Browser initialized. 08:21:50 INFO - Running cycle 1/1 for kraken test... 08:21:50 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpOG8CxN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:02 INFO - PROCESS | 1949 | RSS: Main: 256122880 08:22:02 INFO - PROCESS | 1949 | 08:22:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49836/tests/kraken/driver.html?testName=audio-beat-detection) 08:22:05 INFO - PROCESS | 1949 | RSS: Main: 268312576 08:22:05 INFO - PROCESS | 1949 | 08:22:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49836/tests/kraken/driver.html?testName=audio-dft) 08:22:08 INFO - PROCESS | 1949 | RSS: Main: 276492288 08:22:08 INFO - PROCESS | 1949 | 08:22:12 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49836/tests/kraken/driver.html?testName=audio-fft) 08:22:12 INFO - PROCESS | 1949 | RSS: Main: 280592384 08:22:12 INFO - PROCESS | 1949 | 08:22:14 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49836/tests/kraken/driver.html?testName=audio-oscillator) 08:22:15 INFO - PROCESS | 1949 | RSS: Main: 280420352 08:22:15 INFO - PROCESS | 1949 | 08:22:17 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49836/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:18 INFO - PROCESS | 1949 | RSS: Main: 281419776 08:22:18 INFO - PROCESS | 1949 | 08:22:20 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49836/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:21 INFO - PROCESS | 1949 | RSS: Main: 282955776 08:22:21 INFO - PROCESS | 1949 | 08:22:24 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49836/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:24 INFO - PROCESS | 1949 | RSS: Main: 281710592 08:22:24 INFO - PROCESS | 1949 | 08:22:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49836/tests/kraken/driver.html?testName=json-parse-financial) 08:22:28 INFO - PROCESS | 1949 | RSS: Main: 283381760 08:22:28 INFO - PROCESS | 1949 | 08:22:30 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49836/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:30 INFO - PROCESS | 1949 | RSS: Main: 283828224 08:22:30 INFO - PROCESS | 1949 | 08:22:33 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:33 INFO - PROCESS | 1949 | RSS: Main: 281952256 08:22:33 INFO - PROCESS | 1949 | 08:22:35 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:36 INFO - PROCESS | 1949 | RSS: Main: 282673152 08:22:36 INFO - PROCESS | 1949 | 08:22:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:39 INFO - PROCESS | 1949 | RSS: Main: 282472448 08:22:39 INFO - PROCESS | 1949 | 08:22:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:43 INFO - PROCESS | 1949 | RSS: Main: 282570752 08:22:43 INFO - PROCESS | 1949 | 08:22:44 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49836/tests/kraken/driver.html?testName=ai-astar) 08:22:45 INFO - PROCESS | 1949 | RSS: Main: 283836416 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | __start_tp_report 08:22:45 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 08:22:45 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 08:22:45 INFO - PROCESS | 1949 | |i|pagename|runs| 08:22:45 INFO - PROCESS | 1949 | |0;ai-astar;113;109;115;115;115;127;131;119;96;167 08:22:45 INFO - PROCESS | 1949 | |1;audio-beat-detection;147;124;146;152;125;160;144;125;188;115 08:22:45 INFO - PROCESS | 1949 | |2;audio-dft;193;203;199;205;206;206;232;240;278;218 08:22:45 INFO - PROCESS | 1949 | |3;audio-fft;69;67;74;120;55;56;58;58;95;55 08:22:45 INFO - PROCESS | 1949 | |4;audio-oscillator;84;87;109;107;118;126;142;79;80;119 08:22:45 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;98;119;90;87;90;89;128;86;88;86 08:22:45 INFO - PROCESS | 1949 | |6;imaging-darkroom;94;95;94;94;95;93;94;95;94;94 08:22:45 INFO - PROCESS | 1949 | |7;imaging-desaturate;122;120;129;135;144;128;143;153;142;91 08:22:45 INFO - PROCESS | 1949 | |8;json-parse-financial;57;55;56;55;57;54;59;66;50;50 08:22:45 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;57;97;58;74;100;112;104;85;89;102 08:22:45 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;89;86;84;116;111;70;108;114;112;111 08:22:45 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;130;129;143;118;153;138;136;122;130;142 08:22:45 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;178;149;141;139;141;144;143;143;134;148 08:22:45 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;69;70;66;69;65;86;79;70;59;67 08:22:45 INFO - PROCESS | 1949 | __end_tp_report 08:22:45 INFO - PROCESS | 1949 | __start_cc_report 08:22:45 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,949 08:22:45 INFO - PROCESS | 1949 | __end_cc_report 08:22:45 INFO - PROCESS | 1949 | __startTimestamp1457540565010__endTimestamp 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | ------- Summary: start ------- 08:22:45 INFO - PROCESS | 1949 | Number of tests: 14 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:120.70 Median:123.00 stddev:18.84 (15.3%) stddev-sans-first:19.78 08:22:45 INFO - PROCESS | 1949 | Values: 113.0 109.0 115.0 115.0 115.0 127.0 131.0 119.0 96.0 167.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:146.50 stddev:21.63 (14.8%) stddev-sans-first:22.89 08:22:45 INFO - PROCESS | 1949 | Values: 147.0 124.0 146.0 152.0 125.0 160.0 144.0 125.0 188.0 115.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:218.00 Median:212.00 stddev:25.71 (12.1%) stddev-sans-first:25.63 08:22:45 INFO - PROCESS | 1949 | Values: 193.0 203.0 199.0 205.0 206.0 206.0 232.0 240.0 278.0 218.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:70.70 Median:62.50 stddev:21.29 (34.1%) stddev-sans-first:22.57 08:22:45 INFO - PROCESS | 1949 | Values: 69.0 67.0 74.0 120.0 55.0 56.0 58.0 58.0 95.0 55.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:110.50 stddev:21.76 (19.7%) stddev-sans-first:21.70 08:22:45 INFO - PROCESS | 1949 | Values: 84.0 87.0 109.0 107.0 118.0 126.0 142.0 79.0 80.0 119.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:88.50 stddev:14.99 (16.9%) stddev-sans-first:15.89 08:22:45 INFO - PROCESS | 1949 | Values: 98.0 119.0 90.0 87.0 90.0 89.0 128.0 86.0 88.0 86.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 08:22:45 INFO - PROCESS | 1949 | Values: 94.0 95.0 94.0 94.0 95.0 93.0 94.0 95.0 94.0 94.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:130.70 Median:142.50 stddev:17.47 (12.3%) stddev-sans-first:18.25 08:22:45 INFO - PROCESS | 1949 | Values: 122.0 120.0 129.0 135.0 144.0 128.0 143.0 153.0 142.0 91.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:4.58 (8.1%) stddev-sans-first:4.84 08:22:45 INFO - PROCESS | 1949 | Values: 57.0 55.0 56.0 55.0 57.0 54.0 59.0 66.0 50.0 50.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:87.80 Median:66.00 stddev:19.21 (29.1%) stddev-sans-first:16.83 08:22:45 INFO - PROCESS | 1949 | Values: 57.0 97.0 58.0 74.0 100.0 112.0 104.0 85.0 89.0 102.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:100.10 Median:93.00 stddev:16.24 (17.5%) stddev-sans-first:16.73 08:22:45 INFO - PROCESS | 1949 | Values: 89.0 86.0 84.0 116.0 111.0 70.0 108.0 114.0 112.0 111.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:137.00 stddev:10.45 (7.6%) stddev-sans-first:10.98 08:22:45 INFO - PROCESS | 1949 | Values: 130.0 129.0 143.0 118.0 153.0 138.0 136.0 122.0 130.0 142.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.00 Median:143.50 stddev:12.03 (8.4%) stddev-sans-first:4.53 08:22:45 INFO - PROCESS | 1949 | Values: 178.0 149.0 141.0 139.0 141.0 144.0 143.0 143.0 134.0 148.0 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:69.50 stddev:7.53 (10.8%) stddev-sans-first:7.98 08:22:45 INFO - PROCESS | 1949 | Values: 69.0 70.0 66.0 69.0 65.0 86.0 79.0 70.0 59.0 67.0 08:22:45 INFO - PROCESS | 1949 | -------- Summary: end -------- 08:22:45 INFO - PROCESS | 1949 | 08:22:45 INFO - TEST-INFO | 1949: exit 0 08:22:45 INFO - TEST-OK | kraken | took 58880ms 08:22:45 INFO - SUITE-END | took 1170s 08:22:46 INFO - Completed test suite (00:19:30) 08:22:46 INFO - javascript benchmark 08:22:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3451.7066049385803, 3466.757326987111, 3493.860245590175, 3534.7702399344057, 3543.9114022149442, 5797.507211886995, 5806.9709651451685, 5809.331926828416, 5878.323992740836, 6016.789412370568, 4657.953420465794, 5724.084146536555, 5892.675902825343, 5922.407759224076, 5926.318473375563, 4344.543822898598, 5239.633225674204, 5316.787328506855, 5373.301470808789, 5380.91928621071, 5266.131088370415, 5414.756335964871, 5466.972665136669, 5500.477454641803, 5527.364353099405, 5198.740062996835, 5263.552598029172, 5337.946620533784, 5343.438938911398, 5424.105022671254, 5294.655847369909, 5297.602679799031, 5307.893842123174, 5318.335208098988, 5321.0, 5289.391719952217, 5295.549878260353, 5308.0, 5309.548688361494, 5355.6251062425645, 4967.850964471072, 5381.730913454351, 5731.283589551306, 5751.3961034091235, 5805.0, 5175.0, 5611.186377975211, 6165.506759459233, 6294.527910406738, 6327.620342779448, 6301.873962520724, 6320.525960552977, 6327.493800495995, 6338.778142764981, 6347.968260158716, 5827.446392592697, 5914.645121292693, 5919.67441790697, 5924.2298501195, 5931.555133364928, 6306.180196574416, 6368.395002474757, 6433.453156481752, 6455.096286519891, 6463.289038205794, 6199.628022318675, 6211.844703882349, 6254.21822272216, 6269.5611307208055, 6284.308726040132, 6215.471684906834, 6358.682065896779, 6360.968195158995, 6368.14030105939, 6371.203599550056, 6122.886104953835, 6144.341027922525, 6181.289151747516, 6232.376762323822, 6254.718537665816, 5121.436641969381, 6281.59169653971, 6293.874122517524, 6310.873782524323, 6322.241331040304, 4563.726176429424, 4577.22187228172, 4896.436909755353, 4976.502349765067, 5022.146235140035, 4356.455443069616, 4426.15902978433, 4449.9555004450185, 4458.063806600585, 4500.572445617661, 4898.363212782387, 4909.705417674951, 5900.586958912835, 5951.732172052181, 5962.880742385215, 6191.81424557264, 6228.5017198624, 6293.30773614902, 6321.241451025906, 6330.746770129143, 5232.319798426181, 5802.21670074543, 5964.926313263568, 5995.040793473024, 6031.547633927473, 5464.972675136599, 5509.118541033496, 5520.475554822286, 5522.116461366162, 5540.778368865281, 6305.306416294296, 6416.165898433361, 6482.189726284215, 6519.608823470607, 6525.869482610321, 6314.336994615499, 6315.652639104894, 6327.557071004985, 6353.7140828661895, 6367.777127800505, 6497.220333560003, 6499.675016249074, 6505.991571306455, 6515.869682606227, 6516.120323756232, 6116.396640261158, 6178.969105154445, 6224.688765561795, 6244.0321750128805, 6302.684865756785, 5552.833414997556, 5986.6707331097205, 6267.122602835516, 6315.3053164152825, 6356.682165891594, 6238.906416403667, 6285.402886725754, 6295.244570651551, 6299.559030867796, 6309.968450157903, 6088.817335479943, 6117.816465506042, 6179.721912514037, 6231.937680623317, 6282.780102696383, 6033.607815491979, 6066.666333351708, 6109.20580324555, 6165.815025549241, 6184.721687523982, 6191.938080619317, 6211.440970312694, 6230.532710046674, 6262.937370626418, 6283.748650053946, 5113.590912727047, 6131.325554188947, 6135.07955838608, 6184.845378865384, 6207.0], "unit": "score", "name": "dojo.html", "value": 5750.475433304021}, {"lowerIsBetter": false, "replicates": [444.42225107360423, 494.24380697532774, 540.8810061786405, 549.3901769036366, 632.8196464007759, 32417.86537471186, 37849.810750946206, 37995.43006854896, 38678.226435471275, 38757.0, 40132.197356052864, 40429.79785101078, 40653.59346406535, 40738.0, 40748.79625601875, 9424.29317801166, 9460.716178514656, 9616.230701543895, 9623.230141588656, 9648.71053868385, 9535.713928582154, 9640.75898102546, 9705.611775529005, 9729.951350243238, 9759.121679048883, 9725.6596019139, 9758.707238782848, 9797.608095676165, 9799.706008819747, 9821.950890245538, 9642.228621710246, 9669.033096690346, 9683.612655493771, 9728.17310528606, 9777.022297770238, 9567.617295308179, 9690.370125941861, 9700.36947598411, 9724.465154416504, 9728.464934428604, 32434.6756532434, 39384.60615393838, 39614.60385396138, 39963.0, 40083.0, 2831.405404864981, 3121.687831216883, 3131.7024882636115, 3270.672932706734, 3360.12636714456, 27551.760170792364, 27666.585001224998, 27971.300717482023, 28070.578941315896, 28270.0, 2707.2013755942044, 2725.6865460471904, 2756.8208066475613, 2774.3064233941514, 2848.9715102848913, 447688.0, 452534.0, 453300.0, 455181.9999999967, 456672.0, 67884.0, 68328.0, 68839.0, 68954.3104568958, 69008.3099169012, 72341.0, 72431.0, 74960.25039749642, 75511.0, 75600.0, 16963.491095267163, 17080.0, 17185.05482198491, 17207.1396430178, 17211.225494852762, 12437.129400941847, 12725.745485090247, 12965.22208667483, 12971.481140754453, 12997.15518491295, 18317.267309307746, 21737.673934890674, 22013.66979495309, 22035.228766993077, 22295.88852055762, 9355.345125841126, 9544.99777523364, 9608.66369677058, 9625.32622716417, 9646.565904534173, 951.7715748220377, 1003.4781913404988, 1019.3527110285022, 1068.70610582089, 1082.4804094034907, 27898.860505697343, 29732.702672973424, 30039.249018774706, 30282.0, 30400.39199216003, 1071.5767271927546, 1081.1999120650662, 1087.6628245243999, 1091.3833683968533, 1095.430376204385, 10876.72808179786, 14593.489227876973, 15121.848781512264, 15171.0, 15503.84496155024, 499833.0, 512357.0, 512947.0, 513204.0, 514050.0, 47968.76015619969, 58801.70599146977, 61007.6949615258, 61046.08430873541, 61138.69430652818, 1427.215031732543, 1461.2328527522923, 1471.198196982647, 1477.364733164728, 1482.7775833625042, 29384.0, 29475.852620736758, 29701.851490742407, 30066.69933300639, 30321.0, 46206.613801586005, 51064.999999998516, 54238.0, 54402.45597543974, 54447.72776136094, 410249.0, 464825.0, 478906.0, 481875.0, 486071.0, 31509.897153600432, 31600.525992109677, 32073.0, 32432.35135297328, 32447.0, 42964.14071718611, 42997.785011074746, 45196.77401612971, 46466.53533464622, 46645.53354466548, 38046.61953380431, 38258.426123608726, 38318.042048949, 38424.61575384322, 39099.0, 37309.25381492409, 37618.247635046595, 37739.43390849084, 38034.8098259518, 38147.809260953516, 36079.0, 37387.62612373842, 37599.0, 37719.245615087, 38096.61903381041, 32752.836235818668, 33416.0, 33793.0, 33801.661983379854, 33903.66096339005, 24266.87866560656, 24664.0, 24928.0, 24983.194156048095, 25303.493930120927], "unit": "score", "name": "ext.html", "value": 19945.052091511196}, {"lowerIsBetter": false, "replicates": [327.4057585482348, 352.7830384313648, 356.11150180300154, 365.43175362311604, 367.91905780728246, 16776.580585485368, 19203.231870725172, 20130.396088117344, 20322.89838550805, 20375.49061273465, 12471.875281247207, 16210.432634857782, 19564.999999999964, 20481.4879628009, 20625.27811526597, 5451.863703407426, 5677.120046392795, 5721.828345149632, 5729.05470597353, 5731.426857314256, 5412.891742165174, 5692.430756924296, 5713.514351280146, 5777.855553611173, 5778.393268706788, 5589.608727389084, 5614.578906582003, 5636.351819540744, 5641.435856414346, 5671.461211184951, 5369.973150134224, 5506.889862202774, 5616.550675945915, 5693.686847223401, 5704.743286552115, 4891.461939186686, 4904.852854414374, 4905.166121759274, 4915.21356582945, 4930.531599498042, 24780.380490487703, 25138.37154071163, 25417.0, 25577.0, 25606.231813045637, 2068.927587534429, 2169.110664627495, 2248.0, 2470.7035155781423, 2710.227585138226, 12093.637190884288, 14819.629509262248, 15476.148811815345, 15526.146061966587, 15604.765928511082, 1867.1224524473475, 1880.172724001435, 1929.0644037641732, 1939.5151212196952, 2173.8913054347217, 105614.0, 114557.0, 120023.19965200529, 125203.0, 127453.0, 19296.71054934177, 19757.703634445206, 19837.0, 19838.70241946372, 20295.695564566544, 29268.707312927025, 29546.852265738533, 29803.701962980525, 29873.0, 29877.40245195084, 7992.120866704658, 8248.83502329962, 8253.215944485264, 8299.004119505698, 8355.206255405727, 8363.28912042471, 8363.665453381796, 8421.663133474593, 8463.53450560225, 8507.61715722794, 4335.674824388184, 4340.739555626673, 4365.716228445141, 4401.273789824707, 4405.515393306734, 1646.0864220357578, 1851.5556266495944, 1884.877482963603, 1891.5460289530413, 1911.120884393167, 817.4808996287281, 946.9195118414874, 974.8001659659752, 982.8476586129161, 1028.1698576533781, 15875.0, 19321.710174347398, 20162.798372016387, 20426.795732042785, 20591.8970405147, 905.7011186308503, 915.766479547711, 953.2278854128178, 1019.5259204469957, 1049.7008352619466, 10440.477976101165, 10793.0, 10822.35065896033, 10851.021425012457, 10862.34825910448, 572354.0, 610738.0, 620303.0, 620953.0, 625572.0, 116051.41974290075, 119209.0, 120604.0, 121143.0, 124493.0, 945.0, 950.8827128124426, 1012.7265638277623, 1048.2871647279924, 1051.358671210561, 17500.387486437914, 17545.122743863012, 18081.0, 18360.540986475444, 18733.90633046826, 52881.94236115333, 54476.18285725638, 54744.45255547394, 57162.7141864288, 57237.71381143068, 80355.0, 81561.0, 81993.18006819855, 82806.0, 83283.16716832753, 7529.849403012018, 7700.460967732205, 7862.842743144991, 7914.841703165789, 8225.95887020561, 10964.835527467254, 12898.0, 12937.482500700244, 13351.399187036412, 13454.865451345362, 11148.832767508657, 11161.72095697614, 11230.905213956432, 11275.66173014811, 11374.0, 11037.06184974254, 11145.996860282614, 11160.888391115985, 11197.440127993405, 11306.643202815714, 11162.49768760424, 11472.02487788514, 11812.76374472523, 11944.880551194725, 11946.163768536118, 7828.099768526692, 7857.489263198103, 7926.088499822594, 8025.59872006409, 8248.381371397048, 9138.817223655622, 10473.895261047292, 10969.725756856142, 11015.228933974546, 11129.16531260175], "unit": "score", "name": "jquery.html", "value": 9844.149893463435}, {"lowerIsBetter": false, "replicates": [1417.0930604413174, 1646.7035933531968, 1885.4343696890928, 1992.6214019336326, 1996.9700454493177, 7992.280694737473, 8610.354223433253, 8764.561771911403, 8948.507832069241, 8963.282937365011, 8611.91388086119, 8635.0, 8737.86893196601, 8749.693760718375, 8752.212300892917, 2469.456719521707, 2480.4543000539943, 2492.0, 2499.8875050622764, 2511.7864981476596, 2439.134107391877, 2498.4003839078673, 2500.824942254043, 2507.674002379688, 2513.899444022246, 2474.9010039598393, 2489.4772097859473, 2504.686914135733, 2520.3699075231193, 2522.5585522533574, 2495.1142344467726, 2497.438076432806, 2519.7984161267054, 2522.7477252274807, 2524.129175434472, 2476.789472894788, 2488.0, 2511.4223728542356, 2514.245726282108, 2529.064246228889, 8794.912050879473, 8824.735257942271, 8839.16027977341, 8879.955600222022, 8940.46357218569, 5390.51485366319, 5411.21537377082, 5497.505224529771, 5580.86047848803, 5585.916211256835, 3612.458131280329, 3689.9262014759825, 3714.609965953563, 3753.906152346186, 3755.9436608450897, 1049.4490392543898, 1062.054771253585, 1065.9093977011964, 1071.6570697376842, 1078.6710053433699, 10273.383596984206, 11849.822252666218, 11960.102992275528, 12138.907498325281, 12176.878231217752, 4811.398575178103, 4882.926756098661, 4971.37857767779, 4997.250412438163, 4998.825041123543, 10141.94929025365, 10217.386956782617, 10285.0, 10304.845427318596, 10364.896351036545, 5415.6479828811, 5458.344998600114, 5515.972420137954, 5524.66851988882, 5543.251661025791, 4442.000549876252, 4838.419389673261, 4938.111139994836, 4952.777125029383, 4967.205247160437, 3943.3493473577114, 4003.619656132663, 4048.1701251243426, 4055.655269302142, 4062.5531191568903, 1299.0, 1306.6994591244068, 1311.4491913396396, 1325.6818363592668, 1329.1891945912985, 1382.1499777636682, 1385.2104300548792, 1396.392569232367, 1413.2227274998709, 1416.1927701210418, 8102.594870256464, 8294.419390642597, 8359.665613375519, 8456.06983231844, 8461.2807911327, 454.59086821859927, 492.44599825196656, 521.5749164430977, 524.8163142899955, 525.6268049684691, 6282.057691346243, 6335.461485773761, 6362.872742545123, 6375.171227740364, 6401.807945761635, 10418.906014868482, 10850.29473084247, 10947.83578246327, 11015.779684406267, 11042.723931901606, 10519.84220236697, 10814.405207713495, 10834.02493775564, 10866.348019118877, 10868.72828179286, 8196.83606327882, 8330.458520196167, 8411.453255538492, 8443.324534037261, 8458.407911446262, 7875.921240787748, 7988.800279993046, 8023.277904988346, 8057.476264042818, 8091.676332946851, 9589.0, 9590.712278631652, 9702.611895524382, 9747.122758951728, 9964.202863770735, 9672.83925928893, 9958.103770660675, 9987.60049598008, 10100.242481813746, 10166.491675416348, 6918.515703900679, 7221.747238846615, 7454.627268636655, 7709.537427754352, 7812.0, 8151.062627797879, 8207.794805129919, 8219.465734727224, 8255.669773209005, 8264.876026859481, 7262.745803896838, 7364.3003914628025, 7373.225811289737, 7455.739049133254, 7545.547267163988, 7169.928300716926, 7197.928020719726, 7217.7473788417155, 7219.808731559234, 7248.673809678683, 6563.015547667888, 6655.201375834931, 7594.5063570867715, 7704.460687751804, 7706.190849960898, 6918.896216556856, 6921.2732663069655, 6939.757108501381, 6961.199462061725, 6988.685509152202, 5901.911471327848, 6940.7917762467205, 7034.402075823612, 7102.928970710227, 7137.214906360193], "unit": "score", "name": "mootools.html", "value": 4892.579702251365}, {"lowerIsBetter": false, "replicates": [326.555883997763, 329.1458664764935, 335.0751924687861, 337.9611344695361, 338.72563223788734, 5406.0, 6805.319468053192, 6869.107016087908, 6886.690098945547, 6903.964405339189, 6732.865342693155, 6752.864942701155, 6755.7973260802255, 6763.560368576039, 6782.457403407728, 4817.710937343753, 4847.781849816749, 4989.675671081377, 5019.749012549376, 5021.297018417425, 4985.975070124662, 4993.101241776479, 5007.574356179729, 5017.824376146836, 5026.698398096126, 4922.68002579833, 4942.18453955097, 4942.456329803718, 4961.231009193562, 4979.701217926918, 4911.778969946343, 4932.975335123319, 4942.011597680443, 4942.20924652054, 5053.4946505349535, 3805.8667946621736, 4013.237484877864, 4307.0, 4308.547602501723, 4328.394024836525, 5132.794688212504, 6231.0, 6493.93506064938, 6531.706073226716, 6553.836154096138, 4744.928826067578, 5657.632253903483, 5667.149927510865, 5719.25649665545, 5741.425857414225, 6082.452579267887, 6155.261368635747, 7009.649517524103, 7302.598357090358, 7343.889841652326, 3520.806355650438, 3686.9262614747554, 4618.1918164321105, 4644.651651126179, 4651.023285110131, 7314.5611263323835, 7702.46082774206, 7734.651940662684, 7750.457467977188, 7752.341051010614, 3780.640839120279, 4606.792694328763, 4709.905801883943, 4710.69380490267, 4723.149833030087, 4757.9286310705365, 4799.112164249625, 4807.302941073524, 4828.537445902545, 4832.347633069561, 5790.652560846363, 5830.591858569859, 5836.299644042657, 5846.795362162389, 5856.1508581255475, 7339.045924029842, 7340.0, 7359.632018399058, 7360.374368178657, 7392.630368481555, 1571.2929181868205, 1612.9112898790445, 1646.415522489523, 1654.693881631902, 1681.3862940027047, 1046.7801761630144, 1057.629829559648, 1067.215596536545, 1069.8020866139634, 1070.3203465799274, 6715.0, 6715.7985260442265, 6725.8654826903185, 6735.8989615155815, 6745.561538499982, 6071.423214794588, 7513.887291690629, 7533.1336896257635, 7538.3592394645975, 7543.9622801886735, 6692.397684208445, 6731.596104233761, 6750.763723269662, 6751.56114852533, 6754.5609535380045, 3904.941425878614, 3954.426608141861, 3984.4620976168426, 4001.91996160081, 4023.0143614814556, 7655.846883062308, 7788.299053085249, 8000.439969202216, 8073.0, 8074.757757267292, 7925.128235894047, 7951.085625153066, 7963.203679632106, 7968.282854543119, 7997.880031799411, 7980.800479988046, 8001.919980800234, 8009.799755006172, 8017.438779285394, 8022.799430014296, 7244.420446364384, 7538.849223015399, 7560.092788865371, 7641.083070031632, 7655.6937722490475, 7400.816759056199, 7406.370458511087, 7442.62786860644, 7527.774166775005, 7549.50928189666, 7984.081830589324, 7987.680492780224, 7990.560519171501, 7993.760187194393, 8011.799705007422, 3808.619138086169, 4719.480857105647, 4723.858284251478, 4726.078414709099, 4742.762861856962, 7301.160366557914, 7533.698652053857, 7559.28186822251, 7596.81007974805, 7598.35413989795, 7305.634718263959, 7317.524360916524, 7327.0841144856895, 7328.523645962995, 7354.779356619096, 5729.799457018984, 7281.470891112714, 7291.161516425679, 7317.597532135784, 7392.667329970272, 5808.5062769665055, 7100.147982242164, 7112.679929403293, 7155.3202445767565, 7157.0695809546505, 6991.0, 7207.315305046012, 7230.710771569078, 7270.200277969525, 7322.194558598443, 5473.644213126134, 6152.369622050071, 7166.745819481465, 7171.964140179266, 7184.497085203986], "unit": "score", "name": "prototype.html", "value": 5236.057443997326}, {"lowerIsBetter": false, "replicates": [616.8334549671589, 665.873484038033, 715.8425146467775, 731.7731503233998, 754.1628792040834, 15045.0, 15369.001014934021, 15425.074495530276, 15469.45856895009, 15479.303431345588, 11378.487968041438, 13817.032807703465, 14186.787198192009, 14313.141211527316, 15985.120818355015, 889.6485888074207, 911.0161026091838, 911.5168960450972, 911.9544022798833, 913.4884464699757, 729.8941653460245, 896.4979611417627, 903.6475774447971, 912.3066469483207, 919.5632074764467, 816.2572059425923, 966.1739212972913, 966.463612694954, 968.6319198704517, 970.3886551472563, 943.1652987106391, 955.5699935029264, 959.040959040959, 961.2550273538029, 961.485605201214, 704.3977399323555, 711.5090587494612, 712.5190496414899, 715.0025714128814, 717.4188906985306, 15217.923910380488, 15265.6183595411, 15282.235888205545, 15370.538883833502, 15415.229238538028, 12495.312757798318, 12537.874621253854, 12546.74906501874, 12782.169159004636, 12834.229946203257, 9831.115199632068, 9881.505924703735, 9914.752131196778, 10012.64955726546, 10345.689629311133, 3429.1941393772445, 3459.8962031138853, 3461.6192218855904, 3467.930641387183, 3477.2523907359787, 80339.0, 80507.19492805055, 80809.59595201987, 81080.18919810845, 81549.18450815475, 7820.712816589385, 9149.856267966505, 9274.06523468584, 9408.95295523518, 9504.809903801885, 20962.47593810167, 21327.36017919465, 21342.0, 21377.78622213789, 21396.465088372915, 7269.418446524265, 7347.742829000959, 7413.2957369049855, 7427.071616047994, 7497.10034795828, 4062.5937406259136, 4122.9381559276635, 4155.709100362946, 4166.6250037496775, 4174.144300418407, 2996.685348038468, 3002.774791890617, 3375.6624337566045, 3579.284143171376, 3805.5919309854976, 589.6521052578998, 591.4381337729175, 660.8017594721564, 660.9966950165218, 670.792054463118, 521.3326941514867, 521.8147557617067, 591.1989254560095, 591.6597956175217, 605.7122866638312, 7953.522788632585, 8180.141085186089, 8312.33501319893, 8427.831443371098, 8429.620667069996, 528.9709066001407, 542.3166809819577, 573.931128264611, 589.669784920446, 602.9819105426844, 3760.7367484276383, 3837.0982819037504, 4059.106996460774, 4093.7543747374657, 4174.060836311805, 87199.69200461998, 93222.0, 95577.52211239039, 95927.0, 98259.50870245746, 72144.63927680328, 73766.0, 74142.0, 74834.62582687051, 75952.62023689847, 66583.00125498121, 68555.0, 68887.65556172187, 68940.96588551176, 69132.654336729, 8312.709055183039, 8415.074341822272, 8492.532910689724, 8530.78673033179, 8556.700515481929, 36560.45159322559, 47029.764851175525, 47165.34921277876, 47300.526994730986, 48723.371279728366, 64262.71474570576, 64825.67587162034, 65375.34624653692, 66064.33935660582, 67929.0, 9133.543322833964, 10864.782704345711, 10954.726131846448, 11063.50214240345, 11317.377544234816, 9294.72115836526, 11967.700807479883, 11980.700482488008, 12010.63968080924, 12146.089043321963, 11116.333020018825, 11274.492647830702, 11320.37737924422, 11509.654710358702, 11810.232334898204, 11245.325280483197, 11315.830262545904, 11362.091032717197, 11420.828687569527, 11677.240979336317, 11215.775684486427, 11221.775564488828, 11222.943885280521, 11295.830562541403, 11526.654200374001, 9583.329166958247, 9610.471424071744, 9841.950790246003, 9905.0, 9963.850542242017, 9517.428954262767, 9682.177014953808, 9706.0, 9737.0, 9753.658621948198], "unit": "score", "name": "yui.html", "value": 6833.858614144458}], "name": "dromaeo_css", "value": 7632.382324457716}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 115.0, 115.0, 115.0, 127.0, 131.0, 119.0, 96.0, 167.0], "unit": "score", "name": "ai-astar", "value": 120.7}, {"lowerIsBetter": true, "replicates": [147.0, 124.0, 146.0, 152.0, 125.0, 160.0, 144.0, 125.0, 188.0, 115.0], "unit": "score", "name": "audio-beat-detection", "value": 142.6}, {"lowerIsBetter": true, "replicates": [193.0, 203.0, 199.0, 205.0, 206.0, 206.0, 232.0, 240.0, 278.0, 218.0], "unit": "score", "name": "audio-dft", "value": 218.0}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 74.0, 120.0, 55.0, 56.0, 58.0, 58.0, 95.0, 55.0], "unit": "score", "name": "audio-fft", "value": 70.7}, {"lowerIsBetter": true, "replicates": [84.0, 87.0, 109.0, 107.0, 118.0, 126.0, 142.0, 79.0, 80.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 105.1}, {"lowerIsBetter": true, "replicates": [98.0, 119.0, 90.0, 87.0, 90.0, 89.0, 128.0, 86.0, 88.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 96.1}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 93.0, 94.0, 95.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 94.2}, {"lowerIsBetter": true, "replicates": [122.0, 120.0, 129.0, 135.0, 144.0, 128.0, 143.0, 153.0, 142.0, 91.0], "unit": "score", "name": "imaging-desaturate", "value": 130.7}, {"lowerIsBetter": true, "replicates": [57.0, 55.0, 56.0, 55.0, 57.0, 54.0, 59.0, 66.0, 50.0, 50.0], "unit": "score", "name": "json-parse-financial", "value": 55.9}, {"lowerIsBetter": true, "replicates": [57.0, 97.0, 58.0, 74.0, 100.0, 112.0, 104.0, 85.0, 89.0, 102.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 87.8}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 84.0, 116.0, 111.0, 70.0, 108.0, 114.0, 112.0, 111.0], "unit": "score", "name": "stanford-crypto-aes", "value": 100.1}, {"lowerIsBetter": true, "replicates": [130.0, 129.0, 143.0, 118.0, 153.0, 138.0, 136.0, 122.0, 130.0, 142.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 134.1}, {"lowerIsBetter": true, "replicates": [178.0, 149.0, 141.0, 139.0, 141.0, 144.0, 143.0, 143.0, 134.0, 148.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.0}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 66.0, 69.0, 65.0, 86.0, 79.0, 70.0, 59.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.0}], "name": "kraken", "value": 1572.0}]} 08:22:46 INFO - Return code: 0 08:22:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:22:46 ERROR - # TBPL SUCCESS # 08:22:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:46 INFO - 2016-03-09 08:22:46.347 screenresolution[1954:15133] starting screenresolution argv=screenresolution get 08:22:46 INFO - 2016-03-09 08:22:46.349 screenresolution[1954:15133] Display 0: 1600x1200x32@60 08:22:46 INFO - 2016-03-09 08:22:46.355 screenresolution[1955:15136] starting screenresolution argv=screenresolution list 08:22:46 INFO - Available Modes on Display 0 08:22:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:46 INFO - Intel Iris: 08:22:46 INFO - Chipset Model: Intel Iris 08:22:46 INFO - Type: GPU 08:22:46 INFO - Bus: Built-In 08:22:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:46 INFO - Vendor: Intel (0x8086) 08:22:46 INFO - Device ID: 0x0a2e 08:22:46 INFO - Revision ID: 0x0009 08:22:46 INFO - Displays: 08:22:46 INFO - Display: 08:22:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:46 INFO - Display Serial Number: 1600x1200 60 08:22:46 INFO - Main Display: Yes 08:22:46 INFO - Mirror: Off 08:22:46 INFO - Online: Yes 08:22:46 INFO - Rotation: Supported 08:22:46 INFO - Return code: 0 08:22:46 INFO - Running post-action listener: _resource_record_post_action 08:22:46 INFO - Running post-run listener: _resource_record_post_run 08:22:47 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 3112960; Write bytes: 706816000; Read time: 43; Write time: 2284 08:22:47 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382462464; Write bytes: 376944640; Read time: 28506; Write time: 679 08:22:47 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 3112960; Write bytes: 302952448; Read time: 43; Write time: 1549 08:22:47 INFO - Running post-run listener: _upload_blobber_files 08:22:47 INFO - Blob upload gear active. 08:22:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:22:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:22:47 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'] 08:22:47 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 08:22:48 INFO - (blobuploader) - INFO - Open directory for files ... 08:22:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:22:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:22:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:48 INFO - (blobuploader) - INFO - Done attempting. 08:22:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:22:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:22:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:50 INFO - (blobuploader) - INFO - Done attempting. 08:22:50 INFO - (blobuploader) - INFO - Iteration through files over. 08:22:50 INFO - Return code: 0 08:22:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:22:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:22:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbe7fabcba75589cd25932298d1e52bf9b165c7eb180a832aa918e7060d6b682babd94efea4da9b5325c451e019f631de52b9f66c93ba8484e4e60c3dca5e91c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/410ff19a20239ce568ac07d8e48a636c5512874c8cd7584377134a060096402c5857a7d8710efa7c9cd46b9a1ccc3f7cf48edb49ea4804b347c266a590fae862"} 08:22:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:22:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:22:50 INFO - Contents: 08:22:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbe7fabcba75589cd25932298d1e52bf9b165c7eb180a832aa918e7060d6b682babd94efea4da9b5325c451e019f631de52b9f66c93ba8484e4e60c3dca5e91c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/410ff19a20239ce568ac07d8e48a636c5512874c8cd7584377134a060096402c5857a7d8710efa7c9cd46b9a1ccc3f7cf48edb49ea4804b347c266a590fae862"} 08:22:50 INFO - Running post-run listener: copy_logs_to_upload_dir 08:22:50 INFO - Copying logs to upload dir... 08:22:50 INFO - mkdir: /builds/slave/test/build/upload/logs 08:22:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.327878 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-03-09 08:22:50.148988) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-09 08:22:50.157732) ========= 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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbe7fabcba75589cd25932298d1e52bf9b165c7eb180a832aa918e7060d6b682babd94efea4da9b5325c451e019f631de52b9f66c93ba8484e4e60c3dca5e91c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/410ff19a20239ce568ac07d8e48a636c5512874c8cd7584377134a060096402c5857a7d8710efa7c9cd46b9a1ccc3f7cf48edb49ea4804b347c266a590fae862"} build_url:https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/SmXFYNzeRJiLRcn8yPtKfA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbe7fabcba75589cd25932298d1e52bf9b165c7eb180a832aa918e7060d6b682babd94efea4da9b5325c451e019f631de52b9f66c93ba8484e4e60c3dca5e91c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/410ff19a20239ce568ac07d8e48a636c5512874c8cd7584377134a060096402c5857a7d8710efa7c9cd46b9a1ccc3f7cf48edb49ea4804b347c266a590fae862"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-09 08:22:50.388403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:22:50.388732) ========= 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.ZMjRmgV1OC/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.jHOcvXIPet/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 08:22:50.437038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-09 08:22:50.437379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-09 08:22:50.437842) ========= ========= Total master_lag: 0.85 =========