builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0283 starttime: 1469534378.22 results: success (0) buildid: 20160726030004 builduid: 13d3519e426e4293aa95d6e8190263d2 revision: 864194eff6a935c3001586820d2e5fac08726048 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:38.218663) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:38.219039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-07-26 04:59:38.219328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-07-26 04:59:46.344337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:46.344712) ========= 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.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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-07-26 04:59:46-- 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% 9.07M=0.001s 2016-07-26 04:59:46 (9.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:46.515904) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:46.516226) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.031134 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:46.568160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 04:59:46.568483) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 864194eff6a935c3001586820d2e5fac08726048 --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 864194eff6a935c3001586820d2e5fac08726048 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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-07-26 04:59:46,653 truncating revision to first 12 chars 2016-07-26 04:59:46,653 Setting DEBUG logging. 2016-07-26 04:59:46,654 attempt 1/10 2016-07-26 04:59:46,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/864194eff6a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 04:59:48,281 unpacking tar archive at: fx-team-864194eff6a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.908696 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 04:59:48.499719) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:48.500056) ========= script_repo_revision: 864194eff6a935c3001586820d2e5fac08726048 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:48.500486) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:48.500778) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 04:59:48.519830) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-26 04:59:48.520298) ========= /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.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 04:59:48 INFO - MultiFileLogger online at 20160726 04:59:48 in /builds/slave/test 04:59:48 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 04:59:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:48 INFO - {'append_to_log': False, 04:59:48 INFO - 'base_work_dir': '/builds/slave/test', 04:59:48 INFO - 'blob_upload_branch': 'Fx-Team', 04:59:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:48 INFO - 'branch': 'Fx-Team', 04:59:48 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:48 INFO - 'config_files': ('talos/mac_config.py',), 04:59:48 INFO - 'default_actions': ('clobber', 04:59:48 INFO - 'read-buildbot-config', 04:59:48 INFO - 'download-and-extract', 04:59:48 INFO - 'populate-webroot', 04:59:48 INFO - 'create-virtualenv', 04:59:48 INFO - 'install', 04:59:48 INFO - 'run-tests'), 04:59:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:48 INFO - 'download_minidump_stackwalk': True, 04:59:48 INFO - 'download_symbols': 'ondemand', 04:59:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:48 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:48 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:48 INFO - 'installer_path': 'installer.exe', 04:59:48 INFO - 'log_level': 'info', 04:59:48 INFO - 'log_name': 'talos', 04:59:48 INFO - 'log_to_console': True, 04:59:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:48 INFO - 'opt_config_files': (), 04:59:48 INFO - 'pip_index': False, 04:59:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:48 INFO - 'cmd': ('bash', 04:59:48 INFO - '-c', 04:59:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:48 INFO - 'enabled': True, 04:59:48 INFO - 'halt_on_failure': False, 04:59:48 INFO - 'name': 'check_screen_resolution'},), 04:59:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:48 INFO - 'cmd': ('bash', 04:59:48 INFO - '-c', 04:59:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:48 INFO - 'enabled': True, 04:59:48 INFO - 'halt_on_failure': False, 04:59:48 INFO - 'name': 'check_screen_resolution'},), 04:59:48 INFO - 'run_cmd_checks_enabled': True, 04:59:48 INFO - 'sps_profile': False, 04:59:48 INFO - 'sps_profile_interval': 0, 04:59:48 INFO - 'suite': 'dromaeojs-e10s', 04:59:48 INFO - 'system_bits': '32', 04:59:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:48 INFO - 'title': 't-yosemite-r7-0283', 04:59:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:48 INFO - 'use_talos_json': True, 04:59:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:48 INFO - 'work_dir': 'build'} 04:59:48 INFO - [mozharness: 2016-07-26 11:59:48.712429Z] Running clobber step. 04:59:48 INFO - Running pre-action listener: _resource_record_pre_action 04:59:48 INFO - Running main action method: clobber 04:59:48 INFO - rmtree: /builds/slave/test/build 04:59:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:50 INFO - Running post-action listener: _resource_record_post_action 04:59:50 INFO - [mozharness: 2016-07-26 11:59:50.766803Z] Finished clobber step (success) 04:59:50 INFO - [mozharness: 2016-07-26 11:59:50.766966Z] Running read-buildbot-config step. 04:59:50 INFO - Running pre-action listener: _resource_record_pre_action 04:59:50 INFO - Running main action method: read_buildbot_config 04:59:50 INFO - Using buildbot properties: 04:59:50 INFO - { 04:59:50 INFO - "project": "", 04:59:50 INFO - "product": "firefox", 04:59:50 INFO - "script_repo_revision": "production", 04:59:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 04:59:50 INFO - "repository": "", 04:59:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 04:59:50 INFO - "buildid": "20160726030004", 04:59:50 INFO - "pgo_build": "False", 04:59:50 INFO - "basedir": "/builds/slave/test", 04:59:50 INFO - "buildnumber": 9, 04:59:50 INFO - "slavename": "t-yosemite-r7-0283", 04:59:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:59:50 INFO - "platform": "yosemite_r7", 04:59:50 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:59:50 INFO - "branch": "fx-team", 04:59:50 INFO - "repo_path": "integration/fx-team", 04:59:50 INFO - "revision": "864194eff6a935c3001586820d2e5fac08726048", 04:59:50 INFO - "stage_platform": "macosx64", 04:59:50 INFO - "builduid": "13d3519e426e4293aa95d6e8190263d2", 04:59:50 INFO - "slavebuilddir": "test" 04:59:50 INFO - } 04:59:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:59:50 INFO - Running post-action listener: _resource_record_post_action 04:59:50 INFO - [mozharness: 2016-07-26 11:59:50.770343Z] Finished read-buildbot-config step (success) 04:59:50 INFO - [mozharness: 2016-07-26 11:59:50.770477Z] Running download-and-extract step. 04:59:50 INFO - Running pre-action listener: _resource_record_pre_action 04:59:50 INFO - Running main action method: download_and_extract 04:59:50 INFO - mkdir: /builds/slave/test/build/tests 04:59:50 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')]} 04:59:50 INFO - https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:59:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:59:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:59:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:59:51 INFO - Downloaded 1199 bytes. 04:59:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:59:51 INFO - Using the following test package requirements: 04:59:51 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:59:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:59:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'jsshell-mac.zip'], 04:59:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:59:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:59:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:59:51 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:59:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:59:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:59:51 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:51 INFO - https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:52 INFO - Downloaded 18811381 bytes. 04:59:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:59:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:59:56 INFO - Return code: 0 04:59:56 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:59:56 INFO - https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:57 INFO - Downloaded 18811381 bytes. 04:59:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:59:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:00:00 INFO - Return code: 0 05:00: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')]} 05:00:00 INFO - https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:00:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:00:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:00:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:00:01 INFO - Downloaded 14413725 bytes. 05:00:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:00:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:00:01 INFO - Return code: 0 05:00: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')]} 05:00:01 INFO - https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:00:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:00:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:00:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:00:05 INFO - Downloaded 112980203 bytes. 05:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:00:05 INFO - mkdir: /builds/slave/test/properties 05:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:05 INFO - Contents: 05:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:00:07 INFO - Running post-action listener: _resource_record_post_action 05:00:07 INFO - Running post-action listener: set_extra_try_arguments 05:00:07 INFO - [mozharness: 2016-07-26 12:00:07.047689Z] Finished download-and-extract step (success) 05:00:07 INFO - [mozharness: 2016-07-26 12:00:07.047861Z] Running populate-webroot step. 05:00:07 INFO - Running pre-action listener: _resource_record_pre_action 05:00:07 INFO - Running main action method: populate_webroot 05:00:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:07 INFO - '--tppagecycles', 05:00:07 INFO - '7'], 05:00:07 INFO - 'tests': ['tsvgm']}}, 05:00:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:00:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:00:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:00:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['damp', 'tps']}, 05:00:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'tests': ['damp', 'tps']}, 05:00:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['dromaeo_dom']}, 05:00:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['basic_compositor_video']}, 05:00:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:00:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['a11yr', 05:00:07 INFO - 'ts_paint', 05:00:07 INFO - 'tpaint', 05:00:07 INFO - 'sessionrestore', 05:00:07 INFO - 'sessionrestore_no_auto_restore', 05:00:07 INFO - 'tabpaint']}, 05:00:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['tsvgx', 05:00:07 INFO - 'tsvgr_opacity', 05:00:07 INFO - 'tart', 05:00:07 INFO - 'tscrollx', 05:00:07 INFO - 'cart']}, 05:00:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:00:07 INFO - 'tsvgr_opacity', 05:00:07 INFO - 'tart', 05:00:07 INFO - 'tscrollx', 05:00:07 INFO - 'cart']}, 05:00:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'talos_options': ['--disable-e10s'], 05:00:07 INFO - 'tests': ['tp5o']}, 05:00:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'tests': ['tp5o']}, 05:00:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'talos_options': ['--disable-e10s', 05:00:07 INFO - '--xperf_path', 05:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:07 INFO - 'tests': ['tp5n']}, 05:00:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:07 INFO - 'talos_options': ['--xperf_path', 05:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:07 INFO - 'tests': ['tp5n']}}, 05:00:07 INFO - 'talos.zip': {'path': '', 05:00:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:07 INFO - Running post-action listener: _resource_record_post_action 05:00:07 INFO - [mozharness: 2016-07-26 12:00:07.066536Z] Finished populate-webroot step (success) 05:00:07 INFO - [mozharness: 2016-07-26 12:00:07.066664Z] Running create-virtualenv step. 05:00:07 INFO - Running pre-action listener: _resource_record_pre_action 05:00:07 INFO - Running main action method: create_virtualenv 05:00:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:07 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 05:00:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:07 INFO - Using real prefix '/tools/python27' 05:00:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:10 INFO - Installing pip.................done. 05:00:10 INFO - Return code: 0 05:00:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00: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 05:00: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 05:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:10 INFO - 'HOME': '/Users/cltbld', 05:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:10 INFO - 'LOGNAME': 'cltbld', 05:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:10 INFO - 'MOZ_NO_REMOTE': '1', 05:00:10 INFO - 'NO_EM_RESTART': '1', 05:00:10 INFO - 'PAGER': '/bin/cat', 05:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:10 INFO - 'PWD': '/builds/slave/test', 05:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:10 INFO - 'SHELL': '/bin/bash', 05:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:10 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:10 INFO - 'USER': 'cltbld', 05:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:10 INFO - 'XPC_FLAGS': '0x0', 05:00:10 INFO - 'XPC_SERVICE_NAME': '0', 05:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:11 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 05:00:11 INFO - Cleaning up... 05:00:11 INFO - Return code: 0 05:00:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00:11 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')]} 05:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 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')]} 05:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:11 INFO - 'HOME': '/Users/cltbld', 05:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:11 INFO - 'LOGNAME': 'cltbld', 05:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:11 INFO - 'MOZ_NO_REMOTE': '1', 05:00:11 INFO - 'NO_EM_RESTART': '1', 05:00:11 INFO - 'PAGER': '/bin/cat', 05:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:11 INFO - 'PWD': '/builds/slave/test', 05:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:11 INFO - 'SHELL': '/bin/bash', 05:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:11 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:11 INFO - 'USER': 'cltbld', 05:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:11 INFO - 'XPC_FLAGS': '0x0', 05:00:11 INFO - 'XPC_SERVICE_NAME': '0', 05:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:11 INFO - Downloading/unpacking psutil>=3.1.1 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:14 INFO - Installing collected packages: psutil 05:00:14 INFO - Running setup.py install for psutil 05:00:14 INFO - building 'psutil._psutil_osx' extension 05:00: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:00:15 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 05:00:15 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 05:00:15 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 05:00:15 INFO - building 'psutil._psutil_posix' extension 05:00:15 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 05:00:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:00:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:00:15 INFO - ^ 05:00:15 INFO - 1 warning generated. 05:00:15 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 05:00:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:15 INFO - Successfully installed psutil 05:00:15 INFO - Cleaning up... 05:00:15 INFO - Return code: 0 05:00:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:15 INFO - 'HOME': '/Users/cltbld', 05:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:15 INFO - 'LOGNAME': 'cltbld', 05:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:15 INFO - 'MOZ_NO_REMOTE': '1', 05:00:15 INFO - 'NO_EM_RESTART': '1', 05:00:15 INFO - 'PAGER': '/bin/cat', 05:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:15 INFO - 'PWD': '/builds/slave/test', 05:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:15 INFO - 'SHELL': '/bin/bash', 05:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:15 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:15 INFO - 'USER': 'cltbld', 05:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:15 INFO - 'XPC_FLAGS': '0x0', 05:00:15 INFO - 'XPC_SERVICE_NAME': '0', 05:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00:15 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 05:00:15 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 05:00:15 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 05:00:15 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 05:00:15 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 05:00:15 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 05:00:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:18 INFO - Installing collected packages: mozsystemmonitor 05:00:18 INFO - Running setup.py install for mozsystemmonitor 05:00:18 INFO - Successfully installed mozsystemmonitor 05:00:18 INFO - Cleaning up... 05:00:18 INFO - Return code: 0 05:00:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:00:18 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')]} 05:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:18 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')]} 05:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:18 INFO - 'HOME': '/Users/cltbld', 05:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:18 INFO - 'LOGNAME': 'cltbld', 05:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:18 INFO - 'MOZ_NO_REMOTE': '1', 05:00:18 INFO - 'NO_EM_RESTART': '1', 05:00:18 INFO - 'PAGER': '/bin/cat', 05:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:18 INFO - 'PWD': '/builds/slave/test', 05:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:18 INFO - 'SHELL': '/bin/bash', 05:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:18 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:18 INFO - 'USER': 'cltbld', 05:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:18 INFO - 'XPC_FLAGS': '0x0', 05:00:18 INFO - 'XPC_SERVICE_NAME': '0', 05:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:19 INFO - Downloading/unpacking jsonschema==2.5.1 05:00:19 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 05:00:19 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 05:00:19 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 05:00:19 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 05:00:19 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 05:00:19 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 05:00:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:21 INFO - warning: no files found matching '*.txt' 05:00:21 INFO - no previously-included directories found matching 'build' 05:00:21 INFO - no previously-included directories found matching 'dist' 05:00:21 INFO - no previously-included directories found matching '.git*' 05:00:21 INFO - Installing collected packages: jsonschema, functools32 05:00:22 INFO - Running setup.py install for functools32 05:00:22 INFO - warning: no files found matching '*.txt' 05:00:22 INFO - no previously-included directories found matching 'build' 05:00:22 INFO - no previously-included directories found matching 'dist' 05:00:22 INFO - no previously-included directories found matching '.git*' 05:00:22 INFO - Successfully installed jsonschema functools32 05:00:22 INFO - Cleaning up... 05:00:22 INFO - Return code: 0 05:00:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:22 INFO - 'HOME': '/Users/cltbld', 05:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:22 INFO - 'LOGNAME': 'cltbld', 05:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:22 INFO - 'MOZ_NO_REMOTE': '1', 05:00:22 INFO - 'NO_EM_RESTART': '1', 05:00:22 INFO - 'PAGER': '/bin/cat', 05:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:22 INFO - 'PWD': '/builds/slave/test', 05:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:22 INFO - 'SHELL': '/bin/bash', 05:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:22 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:22 INFO - 'USER': 'cltbld', 05:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:22 INFO - 'XPC_FLAGS': '0x0', 05:00:22 INFO - 'XPC_SERVICE_NAME': '0', 05:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:00:22 INFO - Cleaning up... 05:00:22 INFO - Return code: 0 05:00:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:22 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 05:00:22 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 05:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:22 INFO - 'HOME': '/Users/cltbld', 05:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:22 INFO - 'LOGNAME': 'cltbld', 05:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:22 INFO - 'MOZ_NO_REMOTE': '1', 05:00:22 INFO - 'NO_EM_RESTART': '1', 05:00:22 INFO - 'PAGER': '/bin/cat', 05:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:22 INFO - 'PWD': '/builds/slave/test', 05:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:22 INFO - 'SHELL': '/bin/bash', 05:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:22 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:22 INFO - 'USER': 'cltbld', 05:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:22 INFO - 'XPC_FLAGS': '0x0', 05:00:22 INFO - 'XPC_SERVICE_NAME': '0', 05:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:22 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 05:00:22 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 05:00:22 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 05:00:22 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 05:00:22 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 05:00:22 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 05:00:25 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:26 INFO - Downloading docopt-0.6.1.tar.gz 05:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:26 INFO - Running setup.py install for blobuploader 05:00:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:26 INFO - Running setup.py install for requests 05:00:27 INFO - Running setup.py install for docopt 05:00:27 INFO - Successfully installed blobuploader requests docopt 05:00:27 INFO - Cleaning up... 05:00:27 INFO - Return code: 0 05:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:27 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 05:00:27 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 05:00:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:27 INFO - 'HOME': '/Users/cltbld', 05:00:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:27 INFO - 'LOGNAME': 'cltbld', 05:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:27 INFO - 'MOZ_NO_REMOTE': '1', 05:00:27 INFO - 'NO_EM_RESTART': '1', 05:00:27 INFO - 'PAGER': '/bin/cat', 05:00:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:27 INFO - 'PWD': '/builds/slave/test', 05:00:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:27 INFO - 'SHELL': '/bin/bash', 05:00:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:27 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:27 INFO - 'USER': 'cltbld', 05:00:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:27 INFO - 'XPC_FLAGS': '0x0', 05:00:27 INFO - 'XPC_SERVICE_NAME': '0', 05:00:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:27 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-6KRIUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:27 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ghCfEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:27 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-5iPBpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:27 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-T6AMD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-kciTPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-c8Xdk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Xlzofu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-h3gqQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-UxZLnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-sHcSqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-DjugXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ZB0RYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:28 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-p37XFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:29 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Vm_cX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:29 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-1eeAVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:29 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-pqAdGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:29 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-xUz86R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:29 INFO - Running setup.py install for manifestparser 05:00:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:29 INFO - Running setup.py install for mozcrash 05:00:29 INFO - Running setup.py install for mozdebug 05:00:29 INFO - Running setup.py install for mozdevice 05:00:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Running setup.py install for mozfile 05:00:30 INFO - Running setup.py install for mozhttpd 05:00:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Running setup.py install for mozinfo 05:00:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Running setup.py install for mozInstall 05:00:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Running setup.py install for mozleak 05:00:30 INFO - Running setup.py install for mozlog 05:00:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:30 INFO - Running setup.py install for moznetwork 05:00:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozprocess 05:00:31 INFO - Running setup.py install for mozprofile 05:00:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozrunner 05:00:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozscreenshot 05:00:31 INFO - Running setup.py install for moztest 05:00:31 INFO - Running setup.py install for mozversion 05:00:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:31 INFO - Cleaning up... 05:00:32 INFO - Return code: 0 05:00:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:32 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 05:00:32 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 05:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:32 INFO - 'HOME': '/Users/cltbld', 05:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:32 INFO - 'LOGNAME': 'cltbld', 05:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:32 INFO - 'MOZ_NO_REMOTE': '1', 05:00:32 INFO - 'NO_EM_RESTART': '1', 05:00:32 INFO - 'PAGER': '/bin/cat', 05:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:32 INFO - 'PWD': '/builds/slave/test', 05:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:32 INFO - 'SHELL': '/bin/bash', 05:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:32 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:32 INFO - 'USER': 'cltbld', 05:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:32 INFO - 'XPC_FLAGS': '0x0', 05:00:32 INFO - 'XPC_SERVICE_NAME': '0', 05:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:32 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-e43aFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:32 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)) 05:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:32 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-cVPgul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:32 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)) 05:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:32 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-UUFOOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:32 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)) 05:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:32 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-MOHGpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-b6MRFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-gFEiNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-JhTIEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-rvO1Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-l5rM3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-wtyxkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-lmhwF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-c_zhvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:33 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-shBtPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:34 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)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:34 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-8rDIKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:34 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-D67Rj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:34 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)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:34 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ej_OLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:34 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)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:34 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-FGYikR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:37 INFO - Downloading blessings-1.6.tar.gz 05:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:37 INFO - Installing collected packages: blessings 05:00:37 INFO - Running setup.py install for blessings 05:00:37 INFO - Successfully installed blessings 05:00:37 INFO - Cleaning up... 05:00:37 INFO - Return code: 0 05:00:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:37 INFO - Reading from file tmpfile_stdout 05:00:37 INFO - Current package versions: 05:00:37 INFO - blessings == 1.6 05:00:37 INFO - blobuploader == 1.2.4 05:00:37 INFO - docopt == 0.6.1 05:00:37 INFO - functools32 == 3.2.3-2 05:00:37 INFO - jsonschema == 2.5.1 05:00:37 INFO - manifestparser == 1.1 05:00:37 INFO - mozInstall == 1.12 05:00:37 INFO - mozcrash == 0.17 05:00:37 INFO - mozdebug == 0.1 05:00:37 INFO - mozdevice == 0.48 05:00:37 INFO - mozfile == 1.2 05:00:37 INFO - mozhttpd == 0.7 05:00:37 INFO - mozinfo == 0.9 05:00:37 INFO - mozleak == 0.1 05:00:37 INFO - mozlog == 3.3 05:00:37 INFO - moznetwork == 0.27 05:00:37 INFO - mozprocess == 0.23 05:00:37 INFO - mozprofile == 0.28 05:00:37 INFO - mozrunner == 6.12 05:00:37 INFO - mozscreenshot == 0.1 05:00:37 INFO - mozsystemmonitor == 0.3 05:00:37 INFO - moztest == 0.7 05:00:37 INFO - mozversion == 1.4 05:00:37 INFO - psutil == 3.1.1 05:00:37 INFO - requests == 1.2.3 05:00:37 INFO - wsgiref == 0.1.2 05:00:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:37 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')]} 05:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:37 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')]} 05:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:37 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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:37 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 05:00:37 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 05:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:37 INFO - 'HOME': '/Users/cltbld', 05:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:37 INFO - 'LOGNAME': 'cltbld', 05:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:37 INFO - 'MOZ_NO_REMOTE': '1', 05:00:37 INFO - 'NO_EM_RESTART': '1', 05:00:37 INFO - 'PAGER': '/bin/cat', 05:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:37 INFO - 'PWD': '/builds/slave/test', 05:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:37 INFO - 'SHELL': '/bin/bash', 05:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:37 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:37 INFO - 'USER': 'cltbld', 05:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:37 INFO - 'XPC_FLAGS': '0x0', 05:00:37 INFO - 'XPC_SERVICE_NAME': '0', 05:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 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)) 05:00:38 INFO - Cleaning up... 05:00:38 INFO - Return code: 0 05:00:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00:38 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')]} 05:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:38 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')]} 05:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:38 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 0x107cd3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d68618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaaa59e690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, '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.wh8IVRcMNA/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.Narv1CfazI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 05:00:38 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 05:00:38 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 05:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:38 INFO - 'HOME': '/Users/cltbld', 05:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:38 INFO - 'LOGNAME': 'cltbld', 05:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:38 INFO - 'MOZ_NO_REMOTE': '1', 05:00:38 INFO - 'NO_EM_RESTART': '1', 05:00:38 INFO - 'PAGER': '/bin/cat', 05:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:38 INFO - 'PWD': '/builds/slave/test', 05:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:38 INFO - 'SHELL': '/bin/bash', 05:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:00:38 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:38 INFO - 'USER': 'cltbld', 05:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:38 INFO - 'XPC_FLAGS': '0x0', 05:00:38 INFO - 'XPC_SERVICE_NAME': '0', 05:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:38 INFO - Cleaning up... 05:00:38 INFO - Return code: 0 05:00:38 INFO - Running post-action listener: _resource_record_post_action 05:00:38 INFO - Running post-action listener: _start_resource_monitoring 05:00:38 INFO - Starting resource monitoring. 05:00:38 INFO - [mozharness: 2016-07-26 12:00:38.412498Z] Finished create-virtualenv step (success) 05:00:38 INFO - [mozharness: 2016-07-26 12:00:38.413498Z] Running install step. 05:00:38 INFO - Running pre-action listener: _resource_record_pre_action 05:00:38 INFO - Running main action method: install 05:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:38 INFO - Reading from file tmpfile_stdout 05:00:38 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:38 INFO - Reading from file tmpfile_stdout 05:00:38 INFO - Output received: 05:00:38 INFO - Usage: mozinstall [options] installer 05:00:38 INFO - Options: 05:00:38 INFO - -h, --help show this help message and exit 05:00:38 INFO - -d DEST, --destination=DEST 05:00:38 INFO - Directory to install application into. [default: 05:00:38 INFO - "/builds/slave/test"] 05:00:38 INFO - --app=APP Application being installed. [default: firefox] 05:00:38 INFO - mkdir: /builds/slave/test/build/application 05:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:01:13 INFO - Reading from file tmpfile_stdout 05:01:13 INFO - Output received: 05:01:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:01:13 INFO - Running post-action listener: _resource_record_post_action 05:01:13 INFO - [mozharness: 2016-07-26 12:01:13.136663Z] Finished install step (success) 05:01:13 INFO - [mozharness: 2016-07-26 12:01:13.136810Z] Running run-tests step. 05:01:13 INFO - Running pre-action listener: _resource_record_pre_action 05:01:13 INFO - Running main action method: run_tests 05:01:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:13 INFO - 2016-07-26 05:01:13.151 screenresolution[1641:7547] starting screenresolution argv=screenresolution get 05:01:13 INFO - 2016-07-26 05:01:13.154 screenresolution[1641:7547] Display 0: 1600x1200x32@60 05:01:13 INFO - 2016-07-26 05:01:13.160 screenresolution[1642:7549] starting screenresolution argv=screenresolution list 05:01:13 INFO - Available Modes on Display 0 05:01:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:13 INFO - Intel Iris: 05:01:13 INFO - Chipset Model: Intel Iris 05:01:13 INFO - Type: GPU 05:01:13 INFO - Bus: Built-In 05:01:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:13 INFO - Vendor: Intel (0x8086) 05:01:13 INFO - Device ID: 0x0a2e 05:01:13 INFO - Revision ID: 0x0009 05:01:13 INFO - Displays: 05:01:13 INFO - Display: 05:01:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:13 INFO - Display Serial Number: 1600x1200 60 05:01:13 INFO - Main Display: Yes 05:01:13 INFO - Mirror: Off 05:01:13 INFO - Online: Yes 05:01:13 INFO - Rotation: Supported 05:01:13 INFO - Return code: 0 05:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:13 INFO - Python 2.7.3 05:01:13 INFO - Return code: 0 05:01:13 INFO - grabbing minidump binary from tooltool 05:01: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')]} 05:01: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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c37d30>, '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 0x10799cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c2fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01: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 05:01: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 05:01:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:01:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:13 INFO - Return code: 0 05:01:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:01:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:01:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:13 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/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--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 05:01:13 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/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0283 --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 05:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wh8IVRcMNA/Render', 05:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:13 INFO - 'HOME': '/Users/cltbld', 05:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:13 INFO - 'LOGNAME': 'cltbld', 05:01:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:13 INFO - 'MOZ_NO_REMOTE': '1', 05:01:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:13 INFO - 'NO_EM_RESTART': '1', 05:01:13 INFO - 'PAGER': '/bin/cat', 05:01:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:13 INFO - 'PWD': '/builds/slave/test', 05:01:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:13 INFO - 'SHELL': '/bin/bash', 05:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Narv1CfazI/Listeners', 05:01:13 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 05:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:13 INFO - 'USER': 'cltbld', 05:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:13 INFO - 'XPC_FLAGS': '0x0', 05:01:13 INFO - 'XPC_SERVICE_NAME': '0', 05:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:13 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/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--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 05:01:13 INFO - mozversion application_buildid: 20160726030004 05:01:13 INFO - mozversion application_changeset: 864194eff6a935c3001586820d2e5fac08726048 05:01:13 INFO - mozversion application_display_name: Nightly 05:01:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:13 INFO - mozversion application_name: Firefox 05:01:13 INFO - mozversion application_remotingname: firefox 05:01:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:01:13 INFO - mozversion application_vendor: Mozilla 05:01:13 INFO - mozversion application_version: 50.0a1 05:01:13 INFO - mozversion platform_buildid: 20160726030004 05:01:13 INFO - mozversion platform_changeset: 864194eff6a935c3001586820d2e5fac08726048 05:01:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:01:13 INFO - mozversion platform_version: 50.0a1 05:01:13 INFO - using testdate: 1469534473 05:01:13 INFO - actual date: 1469534473 05:01:13 INFO - starting webserver on 'localhost:49271' 05:01:13 INFO - SUITE-START | Running 2 tests 05:01:13 INFO - TEST-START | dromaeo_css 05:01:13 INFO - operating with platform_type : mac_ 05:01:13 INFO - Initialising browser for dromaeo_css test... 05:01:13 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpEnTRyv/profile http://localhost:49271/getInfo.html) 05:01:16 INFO - PROCESS | 1652 | JavaScript error: , line 0: uncaught exception: undefined 05:01:16 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:01:16 INFO - PROCESS | 1652 | colorDepth:24 05:01:16 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:01:16 INFO - PROCESS | 1652 | __metrics 05:01:16 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:01:17 INFO - TEST-INFO | 1652: exit 0 05:01:17 INFO - Browser initialized. 05:01:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:17 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpEnTRyv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:29 INFO - PROCESS | 1655 | RSS: Main: 256700416 05:01:29 INFO - PROCESS | 1655 | 05:02:19 INFO - PROCESS | 1655 | 1469534539274 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] 05:04:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:04:31 INFO - PROCESS | 1655 | RSS: Main: 271994880 05:04:31 INFO - PROCESS | 1655 | 05:07:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:07:34 INFO - PROCESS | 1655 | RSS: Main: 282058752 05:07:34 INFO - PROCESS | 1655 | 05:10:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:10:36 INFO - PROCESS | 1655 | RSS: Main: 289517568 05:10:36 INFO - PROCESS | 1655 | 05:13:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:13:39 INFO - PROCESS | 1655 | RSS: Main: 288456704 05:13:39 INFO - PROCESS | 1655 | 05:16:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:16:42 INFO - PROCESS | 1655 | RSS: Main: 292564992 05:16:42 INFO - PROCESS | 1655 | 05:19:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:19:45 INFO - PROCESS | 1655 | RSS: Main: 292302848 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | __start_tp_report 05:19:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:19:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:19:45 INFO - PROCESS | 1655 | |i|pagename|runs| 05:19:45 INFO - PROCESS | 1655 | |0;dojo.html;2995.3859458810944;3398.643142470042;3401.35374278887;3423.845926933288;3449.5343128677628;5503.807366742165;5651.802186923458;5748.051571490704;5883.058710606304;5886;5655.123455864337;5832.241808564881;5844.503217226541;5891.911621325673;5934.64392136471;5265.341832270966;5288.99509093272;5344.866378340534;5379.193121031857;5436.891262174754;5291.761870715827;5463.754131064112;5480.177973303997;5503.669779813204;5508.20131080993;5202.505761952609;5330.813421530228;5351.839444816662;5364.1149210380245;5394.838154855341;5208.687478751249;5269.104252277122;5306.681599104028;5323.361196656388;5355.518003379676;5188.221766734982;5230.503102205284;5232.895342093137;5248.343957005374;5279.676942145482;5278.656887302313;5413.891722165574;5671.602987790856;5849.824505264849;5948.464638182541;6166.784162554334;6179.752809887599;6229.813105606839;6245.7501699931945;6255.437010669016;5015.398152221756;6269.3730626937395;6285.057241413779;6294.0873573332065;6294.779682711083;5839.211706419664;6006.399360063959;6030.396960304022;6041.3354531002;6043.063325184604;6219.626822390671;6262.2172228471445;6305.936940630627;6373.52198585108;6374.203224596926;6213.65824879636;6238.344973777779;6239.095331176954;6274.2784579773;6285.59143655661;6118.284172697687;6201.410865967726;6206.131141640174;6267.059941008885;6274;6137.836677219288;6190.288116866528;6258.405451482102;6261;6277.089821975788;6216.290520107007;6254.030625253093;6255.812325630239;6257.998720204745;6276.529260305495;5008.223725322581;5052.520010598987;5072.239164125411;5077.949220507748;5104.744762761847;3652.7077833773233;3772.9245415091546;3800.809959502069;4487.730736155841;4624.56638442084;5902.734376953047;5952.940470595326;5996.820095397145;6015.969920150371;6022.277326720734;6154.3845615438095;6263.123162757218;6269.7178626961895;6271.717772700239;6275.309715931244;5923.881522369528;5944.7027648617395;5959.433853783884;6028.216331876828;6058.0610005449225;4688.507706690816;5564.360098588633;5577.414371491016;5581.302337207849;5594.384617692051;6253.718582663882;6467.870642587122;6479.319671434431;6480.092787009822;6496.35036496356;5241;6189.04069869171;6208.410201030895;6338.619682819046;6341.27075386318;6406.711697973696;6443.420092191725;6457.870842583216;6459;6495.447886929664;6139.369480960463;6181.134641150153;6197.96901015492;6256.436920677161;6262.749490020348;6151.384861513813;6180.072989051679;6219.937800621936;6251.156093927261;6264.49884009269;5173.275741396283;6208.782692605737;6245.96877015612;6246.843828904132;6248.781292654735;6125.693715314305;6206.286277078013;6212.043027825182;6236.656983865931;6242.968785156045;6018.849528761641;6065.6663883486835;6069.939300607114;6123.540734444845;6126.172966649446;6165.230572841863;6166.414190651881;6230.252369715663;6232.220972378453;6251.093591429127;5984.850378740566;5999.340072592098;6015.849603759766;6128.203333566607;6155.553444940346 05:19:45 INFO - PROCESS | 1655 | |1;ext.html;494.6067876038549;497.5000124874501;510.92450391924973;542.7910254551997;547.1164070026907;32601.18497037575;42096;42099.94750131248;42101.57898421015;42130.7893460533;43719.78140109295;44110.55889441105;44146.558534414646;44246;44291.67124986248;9491.620335186584;9637.807243855119;9668.274879384038;9668.564914578825;9675.419474831497;9714.271429642806;9797.412155270671;9823;9829.50852457378;9845.950770246138;9768.120869121778;9788.510574471284;9801.411915285105;9811.607535698564;9823.115919567237;9645.228381729445;9682.903170968306;9743.658971935949;9760.121589057018;9779.266555008402;9629.037096290385;9676.467794271312;9722.75693107678;9731;9739.220862330996;41473;41902.79048604768;41907.952301192716;41921.1615767686;42107;2594.701609314915;3417.5557177567007;3488.209850704602;3489.70337521311;3572.214112895159;31892.20269493258;31893.840530797428;31908.680913190805;32051.621145506328;32087.518687219712;2707.2961030132305;2896.4641541314923;3186.1556687477837;3209.5185722141864;3270.1824543864036;447363.7631811853;453548;457677;458069;458869;33071.3385732284;34125.48811767826;34693.47959780605;34864;34871.30257394838;34150;35686.464703028956;36018.8199059003;36257.63742362595;36992.26015479729;14590.19753913545;17235.31058757661;17415.73876391855;17417.04206268642;17433.738493922603;13204.669883252996;13302.866971330357;13307.001974851732;13311.334433278298;13380.063395562218;16680.416185433452;19850.404487865388;20065.69572977752;20265;20436.38690839277;8186.2632363087605;8295.336373090138;8317.8336433271;8335.208155225244;8444.817725518433;859.6518410043942;947.5641205045756;1031.7000579270173;1041.9374837509774;1048.8688913885765;31214;31270;31516.369672606877;31610.683893161233;32065.839670801495;997.6159178716247;1013.2400699475394;1058.6713674338655;1061.7398737309409;1062.1449732965036;15020.849791502163;15714.292856821241;15814.36742530309;15857.841421585867;16043.278052487432;700827;701470;707467;710054.449727748;713945;61521.769564608454;61684.38315616787;62254.37745622576;62307.37692623107;62452.252864827344;1382.2673982789138;1397.8672026157499;1401.6215621782062;1412.9646758830906;1416.199847086393;24040.63939040951;29894.402111957203;30645.846770766002;31256.531152032283;32472.188195295308;54075;54424.45575544194;54879;54906;54936;593517.0648293461;651420;653286;654628.7268563627;656994;21208.469788255417;27067.59398608983;27634.447311053264;27686.86156569204;27688.446231075668;44521;48189.75905120452;50197;51173;51688.74155629198;40355;41417.96455088647;41625.37561936634;42819;43679;41217.1756564873;41817.58182418137;41990.38985485685;42070.94822629459;42665;38390.616093838704;38632.80683596564;39136;39474.60525394709;39966.2006759869;34980;35725.28549428945;36286.45570316395;36438.72464463731;36458.08854778545;22287;24929.376765581004;26362.209133726017;26427.471450571265;26848.865755671097 05:19:45 INFO - PROCESS | 1655 | |2;jquery.html;365.78418732947574;378.29448079332036;388.6308007392977;392.9135590170163;403.46943768943817;19827.801721982774;21333.146674133037;21387.786122138776;21640.458988525275;22625.208117715883;20641.793582064176;20824.791752082474;21647.45881352963;21662.566748665016;21858;5802.941970580293;6012.609180403259;6037.396260373971;6092.51259899207;6104.969475152618;4135.855245066423;5901.20333754944;6091.786787462417;6111;6405.487560995132;6593.604383736991;6730.259671436138;6770.72917083316;6772.153480814898;6773.4242589379965;5635.774569017255;5805.5065319447895;5910.1134829775665;6026.427489388501;6732.629705366203;5695.231143795576;5747.798827041075;5830.2420685310635;5845.240118784531;5858.443447872445;13274.269915154662;13638.454461821515;13657.453701851913;20187.697184542245;24033.49002642625;2236.910523579055;2246.427161073932;2317.2121478697154;2635.920922372332;2658.096247275916;13574.728505429835;16760.413385531447;16763.66472670552;17072.914635426743;18172.091395430307;1697.4059079322262;1940.6991916253003;2005.4785755703476;2013.2047841102828;2116.5766846630736;98339.50830245802;100933;130791;135006;137200.31399843036;9620.182284505896;9895.455749933679;10213.029762172582;10319.742006449898;10414.5834166634;12508;12568.057395695176;12571.245725256515;12581.496740130475;12583.182093163916;8071.556064416513;8293.668253269921;8315.916840831514;8426.157384261525;8435.873461898076;8738.519381434082;8742;8746.240303941735;8888.866667;8969.820603587892;3398.2863598644562;3883.49514563105;3940.0346915006007;4020.276350256982;4050.6632811172244;1426.3224968140257;1605.8153312369227;1793.0765655687333;1945.7665080190184;1989.44295597233;809.5264270401763;842.3724325377633;938.7183844846519;943.4763706143157;960.870282511852;17443;18479.722804157947;21386.679199812017;21811.890940545512;21966.45083872884;827.3505298340774;891.1712107739735;947.6825263536655;991.2664628175098;1007.2747621712356;10913.345199287909;11431.714207144887;11462.197646164856;11540.076793856471;11601.361925094201;505939;514128;514749;613734;621633;117143.41428292805;146260;147085;147753.2612336953;149218;994.1301361308855;995.6564985080136;1017.2624846986024;1035.5288343803552;1037.9532921018572;18484.72272915934;18883.339083132025;18968.336108236144;19326.806731932502;19386;35829.82085089558;45554.54445455503;56036.5990850232;57314;58128;77584.2241577577;79339.60330198312;80725.59637201777;85903;87111.69332459892;7139.750108746169;7330.96334518324;7484.476086673881;7687.461877668509;7706.460547761603;8739.38824282294;12796.232226066466;13357.931365490931;13530.594082177156;13627.727445450837;11202.21584489078;11327.263727857662;11366.602168924048;11599.07207423421;11676.88323116792;10185.694429167137;10990.340579565252;11441.485133169193;11457.140714446283;11550.88449115498;10419.791604167722;11114;11353.829692554784;11569.595064172714;11698.824517632413;6187.659678717714;7550.962245188739;7583.734569290048;7606.277403646644;7719.228077192461;11058.889411105785;11271.661850144508;11512.021478174449;11653.30080195191;11658.88341116578 05:19:45 INFO - PROCESS | 1655 | |3;mootools.html;1586.0325201627008;1605.7751914731937;1881.9529511762212;1949.892755898426;1950.9024548772559;8099.91900080999;8128.91871081289;8667.263282620977;9171.449713017224;9180.954095229523;7551.848963020736;8961.955190224056;8969.058248883872;8975.596098175582;8981.371304008722;2396.808255339573;2415.8429702069398;2420;2420;2437.756224377557;2382.737898831127;2422.6123820188773;2426.5632186206562;2432.975670243293;2438.7073551173794;2363.881805909706;2379.7144342678903;2410.80713542917;2416.395901024744;2430.173740928084;2385.630227314769;2387.85672859629;2404.170561156407;2411.9999999999823;2419.0444774314137;2360.4570948681726;2384.5707772600927;2393.5930891748444;2397.412633904687;2406.2660888429023;8986;9081.001089880174;9126.634934602607;9132.863007054899;9162.175404213653;5394.406615272317;5577.442255774431;5581;5581.190727344553;5593.188987596816;3074.3543855790313;3099.628044634636;3336.948861108743;3595.928081438383;3617.6382361763876;1038.8285932821152;1054.6783231114507;1057.7249915022073;1058.560697310615;1060.862087928572;11980.221285616408;12019.098567607465;12070.818937715854;12106.81839772404;12174.878251217551;4738.1708201064675;4770.813844714673;4841.419029716457;4897.338859253955;4956.281339205867;10004.149647279917;10188.439635819952;10199.7450063749;10274.075333220046;10343.27597068198;4474.239379305526;5268.130758424817;5438;5535.806246781343;5556.388797232301;4512.075024619945;4889.706617602955;5010.123228435009;5040.672356296829;5073.974630126826;3991.6008399160432;4059.6143366380147;4114.917701645951;4146;4172.958270417318;1016.8118898003893;1308.784050631654;1325.6155714842587;1329.056369977327;1332.9800052999212;870.9564521773885;942.8161508505913;958.4919992404037;976.545906153642;988.960441582343;5209.843704688866;5978.611390259706;6032.487238584769;6122.112293717386;6168.19813424252;449.69196100671144;452.2334642780521;454.1688709661312;458.65142491705876;464.5447461487761;3850.5186851643543;4079.2453396121814;4096.672266218696;4192.182524407711;4199.4330765346895;10381.013803688638;10724.195685323632;10768.192385570957;10772.9765672261;10828.776777056954;10573.04842564173;10770;10844.186685998582;10895.346279223113;10896.291741036806;7195.424366050704;8263.297619702393;8299.630560957496;8395.076541580544;8450.323974082059;4689.835855745169;5818.272715910512;5970.641761494324;6079;6139.631622102688;10111.494425278559;10113.5448904798;10166.237532185263;10199.949000254952;10215.23385746087;7385.926140738524;10217;10231.130353919703;10441.530131144269;10447.268691191544;7063.894041589483;7502.962485187539;7685.654145563351;7688.423368247516;7842.529448233125;8556.18716221958;8603.225709686158;8618.612162452579;8683.348748843988;8711.607977640895;7744.2642948919765;7855.2930236276725;7874.881876771968;7971.282584567417;7995.440319177602;7361.190269070291;7419.29516695913;7520.849583008418;7686.923130768844;7687.500312479671;7227.096612923385;7248.927510725036;7359.080114985627;7363.116426028911;7368.078990126234;5001.574866136273;5009.749512524432;5128.923066154085;5137.614678899023;5309.177077552986;5194.883100133489;5208.348956380452;5232.659877108128;5342.278792362981;5369.785208591613 05:19:45 INFO - PROCESS | 1655 | |4;prototype.html;303.95440683897414;319.6308263955131;320.548027281533;323.95788547488837;327.2783512355257;5001.424836143845;5742.598018138727;5767.798127065554;5774.884502309951;5803.883922321551;5543.695096769676;5575.303087114111;5655.26481557398;5674.375818659944;5727.169560413748;3730.1793605406765;3896.688264938798;4060.553339132693;4149.9792501037455;4161.3549899765585;4053.493313335833;4215.430916826235;4220.767857767822;4224.957750422487;4257.446531950842;3505.4741788731767;3828.25349056934;4169.9791501042455;4231.809568569422;4246.384274280227;3991.1618560102415;4110.486189226362;4219.261629214906;4225.598568136023;4251.979524913998;3686.6912246152497;3694;3694.1688120173017;3695.505533764698;3699.223163135745;5406.621536492447;5406.701672170634;5425.321834770653;5440.4287549807095;5494;4179.958200417987;4485.327200919855;4501.48232953208;4802.662149805851;4964.982178653404;5816.476517113438;5826.592138550303;5886.6468011919;5890.793822216245;5895.823125306248;4055.614716601918;4060.92385517838;4086.6322031017053;4095.2219078375015;4124.9587504124875;5397.973010134963;5926.970365148147;6203.658798766066;6243.937560624381;6267.905981410237;3460.9753294323104;3643.890683279506;4143.357779544175;4164.250434921682;4171;3924.7841368724994;4221.704480686322;4223.007593215591;4226.661867050629;4231.238377092153;4694.554017368345;4886.3403440535785;4919.926201106986;4945.554900059012;4945.7774400152075;5258.789648414097;5947.375525569752;5998.430149135826;6037.18498002764;6071.787487437919;1392.5613431769164;1437.964050898715;1438.1946110178333;1454.3818876977243;1455.548779878241;821.6795449774593;887.658251573149;888.2405543260548;895.7402353317595;897.434616191795;5422.885074820823;5489.725513724298;5518.255035570227;5532.917006244909;5609.27079479665;5622.606417550732;6185.494874575714;6189.381061893775;6190.41191086846;6229.96885015572;4085.530164031115;5220.477952204825;5512.751926163332;5550.445875154963;5550.500454958992;2604.882780274892;3241.3517296540786;3250.788698734622;3258.680234505029;3261.2825178460703;5001.399832020181;6356.586821856657;6403.990124641411;6438.00421869222;6483.967580162163;5533.363663178706;5905.102632262772;6243.719032643542;6283.371662833772;6305.621662700252;5630.127330263816;5856.389492889404;5893.3222679391565;6397.136386587844;6415.743370265231;5098.745062746773;5232.502912223333;5501.9174712380145;5985.862686089629;6165.167702360124;4965.726885021359;5410.428188180981;5743.971280143573;5979.760809567742;6037.245344331958;5243.397009343822;5876.1773351729935;6124.6325220486915;6230.563860529901;6352.269489008823;3936;3973.88731155277;3987.8803635890968;3996.8800935971967;4036.354183330653;5611.7194140292;5807.593468457168;5914.792982245601;5936.168936349001;6094.695265236809;5467.781288748405;5482.725863706719;5765.875654247381;5917.704114794329;5944.5838791284195;5227.738613069408;5867.5305975521005;5947.970260148844;5981.760729570942;5997.400259973968;4754.358161648133;5691;5784.438201685605;5792.362840087502;5828.125781132864;4792.7124372537755;4829.830955916526;5631.8592035199445;5950.9702451487465;5997.31030931431;5665.688387138747;5787.565932554991;5804.535637148933;5838.591298609226;5841.036229022164 05:19:45 INFO - PROCESS | 1655 | |5;yui.html;646.6281887914449;730.7953772943574;784.4273680213444;845.3026253340993;846.0735494633382;16011.199440027996;16109.597260068504;16168.434104806305;16289.941153825022;16345.346186152554;14716;14774.335154918028;14808.185549794756;14813.259337033132;14813.407463701438;715.6171448275173;890;893.9731808045752;918.320442872273;923.5105394141083;874.8031692869117;898.8382091223577;913.4975763330175;914.940528865625;919.4713040002025;947.7725345916965;978.5792109392958;992.9801403971916;993.7515621094726;997.2919227348591;930.6510058727977;987.2299606307021;987.7185002274354;988.7379844341256;989.579428742785;692.7921623512926;695.961722105284;715.6314498033516;733.0543598757596;737.7049180327858;15222.847771522254;15250.694986100216;15319.923400383039;15591.220438978005;15665.843341566553;13753.931230343784;13821.516246931309;13885.861141388557;14035.157890526601;14267.357968891425;13138.343082845819;13367.46530138803;13574.321283935862;13747.725045499134;13793.10344827583;4674.555917187861;4744.5492678195515;4745.810167593292;4751.691140075884;4763.3807605011125;106170;115393.4230328843;119404;119997.40001299938;122563.3871830644;10127.189824813997;11724.94137529324;11790.94104529472;11820.940895295469;11871.287722736663;23190.42023949415;23262.418439539146;23399;23436.531269374856;23447.29658110225;8049.27556519916;8120.6345714443;8208.343332533383;8353.33173346131;8423.620937057847;4599.425071866017;4599.655025873006;4617.46899106607;4617.976910115495;4622.399088118523;3253.723433508131;3334.7165490933544;3384.5769278840144;3386.204242003127;3389.983050084783;659.7036822643529;661.3089321658782;670.0819876768858;673.7641825361085;678.1015154919751;516.4396629656865;569.4334137533099;593.7862369546959;618.9508782613427;650.0314531348257;8748.2388426964;9137.497437640994;9263.907360926305;9308;9313.39462934907;607.4077774170149;636.7484843486797;641.7144370755062;643.8132941447021;666.576723780403;4253.63844073251;4456.799444024962;4490.550944905483;4516.774161291923;4568.223402021597;124187.8230959218;139730;147541.31146721204;151349;152374;100299.49850250702;134440.32779836038;135036;136666;137228;100876.49561752145;102548;103347;105181;106287;7279.611833334151;8538;8611;8658.39391242607;8735.475871447734;56838.147427787786;60749;61034.08448873359;62732.05901911384;62779.89691494799;78736;80583.19416805757;84158.15841841503;85771;91843.70390740284;13378.86621133815;13450.663733406744;13778.65553361174;13817.86182138206;14227.14637121776;15111.622209444851;15220.847791522387;15257.923710381378;15503.224838758242;15783.921080394524;13734.313284335944;14060.578182654537;14105;14123;14452.132872027292;13802.792958105436;14065.437382504995;14335.56993290203;14560.927195363955;14692.971491995886;13871.930640347138;13939.09395889264;14491.637709057359;14508.419663213353;14667.193304368362;11277.32336059839;11379.317240965569;11525.884741152817;11679.093705514035;12068.396580171131;10078.337074382938;10197.337173083726;10933.890661093288;11665.35840528779;11765.52937882475 05:19:45 INFO - PROCESS | 1655 | __end_tp_report 05:19:45 INFO - PROCESS | 1655 | __start_cc_report 05:19:45 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,414 05:19:45 INFO - PROCESS | 1655 | __end_cc_report 05:19:45 INFO - PROCESS | 1655 | __startTimestamp1469535585057__endTimestamp 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:19:45 INFO - PROCESS | 1655 | Number of tests: 6 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5798.73 Median:6065.67 stddev:684.04 (11.3%) stddev-sans-first:649.83 05:19:45 INFO - PROCESS | 1655 | Values: 2995.4 3398.6 3401.4 3423.8 3449.5 5503.8 5651.8 5748.1 5883.1 5886.0 5655.1 5832.2 5844.5 5891.9 5934.6 5265.3 5289.0 5344.9 5379.2 5436.9 5291.8 5463.8 5480.2 5503.7 5508.2 5202.5 5330.8 5351.8 5364.1 5394.8 5208.7 5269.1 5306.7 5323.4 5355.5 5188.2 5230.5 5232.9 5248.3 5279.7 5278.7 5413.9 5671.6 5849.8 5948.5 6166.8 6179.8 6229.8 6245.8 6255.4 5015.4 6269.4 6285.1 6294.1 6294.8 5839.2 6006.4 6030.4 6041.3 6043.1 6219.6 6262.2 6305.9 6373.5 6374.2 6213.7 6238.3 6239.1 6274.3 6285.6 6118.3 6201.4 6206.1 6267.1 6274.0 6137.8 6190.3 6258.4 6261.0 6277.1 6216.3 6254.0 6255.8 6258.0 6276.5 5008.2 5052.5 5072.2 5077.9 5104.7 3652.7 3772.9 3800.8 4487.7 4624.6 5902.7 5952.9 5996.8 6016.0 6022.3 6154.4 6263.1 6269.7 6271.7 6275.3 5923.9 5944.7 5959.4 6028.2 6058.1 4688.5 5564.4 5577.4 5581.3 5594.4 6253.7 6467.9 6479.3 6480.1 6496.4 5241.0 6189.0 6208.4 6338.6 6341.3 6406.7 6443.4 6457.9 6459.0 6495.4 6139.4 6181.1 6198.0 6256.4 6262.7 6151.4 6180.1 6219.9 6251.2 6264.5 5173.3 6208.8 6246.0 6246.8 6248.8 6125.7 6206.3 6212.0 6236.7 6243.0 6018.8 6065.7 6069.9 6123.5 6126.2 6165.2 6166.4 6230.3 6232.2 6251.1 5984.9 5999.3 6015.8 6128.2 6155.6 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:72287.66 Median:41445.48 stddev:163802.74 (395.2%) stddev-sans-first:164173.56 05:19:45 INFO - PROCESS | 1655 | Values: 494.6 497.5 510.9 542.8 547.1 32601.2 42096.0 42099.9 42101.6 42130.8 43719.8 44110.6 44146.6 44246.0 44291.7 9491.6 9637.8 9668.3 9668.6 9675.4 9714.3 9797.4 9823.0 9829.5 9846.0 9768.1 9788.5 9801.4 9811.6 9823.1 9645.2 9682.9 9743.7 9760.1 9779.3 9629.0 9676.5 9722.8 9731.0 9739.2 41473.0 41902.8 41908.0 41921.2 42107.0 2594.7 3417.6 3488.2 3489.7 3572.2 31892.2 31893.8 31908.7 32051.6 32087.5 2707.3 2896.5 3186.2 3209.5 3270.2 447363.8 453548.0 457677.0 458069.0 458869.0 33071.3 34125.5 34693.5 34864.0 34871.3 34150.0 35686.5 36018.8 36257.6 36992.3 14590.2 17235.3 17415.7 17417.0 17433.7 13204.7 13302.9 13307.0 13311.3 13380.1 16680.4 19850.4 20065.7 20265.0 20436.4 8186.3 8295.3 8317.8 8335.2 8444.8 859.7 947.6 1031.7 1041.9 1048.9 31214.0 31270.0 31516.4 31610.7 32065.8 997.6 1013.2 1058.7 1061.7 1062.1 15020.8 15714.3 15814.4 15857.8 16043.3 700827.0 701470.0 707467.0 710054.4 713945.0 61521.8 61684.4 62254.4 62307.4 62452.3 1382.3 1397.9 1401.6 1413.0 1416.2 24040.6 29894.4 30645.8 31256.5 32472.2 54075.0 54424.5 54879.0 54906.0 54936.0 593517.1 651420.0 653286.0 654628.7 656994.0 21208.5 27067.6 27634.4 27686.9 27688.4 44521.0 48189.8 50197.0 51173.0 51688.7 40355.0 41418.0 41625.4 42819.0 43679.0 41217.2 41817.6 41990.4 42070.9 42665.0 38390.6 38632.8 39136.0 39474.6 39966.2 34980.0 35725.3 36286.5 36438.7 36458.1 22287.0 24929.4 26362.2 26427.5 26848.9 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34198.24 Median:12471.21 stddev:93911.32 (753.0%) stddev-sans-first:94140.41 05:19:45 INFO - PROCESS | 1655 | Values: 365.8 378.3 388.6 392.9 403.5 19827.8 21333.1 21387.8 21640.5 22625.2 20641.8 20824.8 21647.5 21662.6 21858.0 5802.9 6012.6 6037.4 6092.5 6105.0 4135.9 5901.2 6091.8 6111.0 6405.5 6593.6 6730.3 6770.7 6772.2 6773.4 5635.8 5805.5 5910.1 6026.4 6732.6 5695.2 5747.8 5830.2 5845.2 5858.4 13274.3 13638.5 13657.5 20187.7 24033.5 2236.9 2246.4 2317.2 2635.9 2658.1 13574.7 16760.4 16763.7 17072.9 18172.1 1697.4 1940.7 2005.5 2013.2 2116.6 98339.5 100933.0 130791.0 135006.0 137200.3 9620.2 9895.5 10213.0 10319.7 10414.6 12508.0 12568.1 12571.2 12581.5 12583.2 8071.6 8293.7 8315.9 8426.2 8435.9 8738.5 8742.0 8746.2 8888.9 8969.8 3398.3 3883.5 3940.0 4020.3 4050.7 1426.3 1605.8 1793.1 1945.8 1989.4 809.5 842.4 938.7 943.5 960.9 17443.0 18479.7 21386.7 21811.9 21966.5 827.4 891.2 947.7 991.3 1007.3 10913.3 11431.7 11462.2 11540.1 11601.4 505939.0 514128.0 514749.0 613734.0 621633.0 117143.4 146260.0 147085.0 147753.3 149218.0 994.1 995.7 1017.3 1035.5 1038.0 18484.7 18883.3 18968.3 19326.8 19386.0 35829.8 45554.5 56036.6 57314.0 58128.0 77584.2 79339.6 80725.6 85903.0 87111.7 7139.8 7331.0 7484.5 7687.5 7706.5 8739.4 12796.2 13357.9 13530.6 13627.7 11202.2 11327.3 11366.6 11599.1 11676.9 10185.7 10990.3 11441.5 11457.1 11550.9 10419.8 11114.0 11353.8 11569.6 11698.8 6187.7 7551.0 7583.7 7606.3 7719.2 11058.9 11271.7 11512.0 11653.3 11658.9 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5799.94 Median:2577.19 stddev:3268.18 (126.8%) stddev-sans-first:3262.00 05:19:45 INFO - PROCESS | 1655 | Values: 1586.0 1605.8 1882.0 1949.9 1950.9 8099.9 8128.9 8667.3 9171.4 9181.0 7551.8 8962.0 8969.1 8975.6 8981.4 2396.8 2415.8 2420.0 2420.0 2437.8 2382.7 2422.6 2426.6 2433.0 2438.7 2363.9 2379.7 2410.8 2416.4 2430.2 2385.6 2387.9 2404.2 2412.0 2419.0 2360.5 2384.6 2393.6 2397.4 2406.3 8986.0 9081.0 9126.6 9132.9 9162.2 5394.4 5577.4 5581.0 5581.2 5593.2 3074.4 3099.6 3336.9 3595.9 3617.6 1038.8 1054.7 1057.7 1058.6 1060.9 11980.2 12019.1 12070.8 12106.8 12174.9 4738.2 4770.8 4841.4 4897.3 4956.3 10004.1 10188.4 10199.7 10274.1 10343.3 4474.2 5268.1 5438.0 5535.8 5556.4 4512.1 4889.7 5010.1 5040.7 5074.0 3991.6 4059.6 4114.9 4146.0 4173.0 1016.8 1308.8 1325.6 1329.1 1333.0 871.0 942.8 958.5 976.5 989.0 5209.8 5978.6 6032.5 6122.1 6168.2 449.7 452.2 454.2 458.7 464.5 3850.5 4079.2 4096.7 4192.2 4199.4 10381.0 10724.2 10768.2 10773.0 10828.8 10573.0 10770.0 10844.2 10895.3 10896.3 7195.4 8263.3 8299.6 8395.1 8450.3 4689.8 5818.3 5970.6 6079.0 6139.6 10111.5 10113.5 10166.2 10199.9 10215.2 7385.9 10217.0 10231.1 10441.5 10447.3 7063.9 7503.0 7685.7 7688.4 7842.5 8556.2 8603.2 8618.6 8683.3 8711.6 7744.3 7855.3 7874.9 7971.3 7995.4 7361.2 7419.3 7520.8 7686.9 7687.5 7227.1 7248.9 7359.1 7363.1 7368.1 5001.6 5009.7 5128.9 5137.6 5309.2 5194.9 5208.3 5232.7 5342.3 5369.8 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4784.26 Median:5475.25 stddev:1478.75 (27.0%) stddev-sans-first:1444.15 05:19:45 INFO - PROCESS | 1655 | Values: 304.0 319.6 320.5 324.0 327.3 5001.4 5742.6 5767.8 5774.9 5803.9 5543.7 5575.3 5655.3 5674.4 5727.2 3730.2 3896.7 4060.6 4150.0 4161.4 4053.5 4215.4 4220.8 4225.0 4257.4 3505.5 3828.3 4170.0 4231.8 4246.4 3991.2 4110.5 4219.3 4225.6 4252.0 3686.7 3694.0 3694.2 3695.5 3699.2 5406.6 5406.7 5425.3 5440.4 5494.0 4180.0 4485.3 4501.5 4802.7 4965.0 5816.5 5826.6 5886.6 5890.8 5895.8 4055.6 4060.9 4086.6 4095.2 4125.0 5398.0 5927.0 6203.7 6243.9 6267.9 3461.0 3643.9 4143.4 4164.3 4171.0 3924.8 4221.7 4223.0 4226.7 4231.2 4694.6 4886.3 4919.9 4945.6 4945.8 5258.8 5947.4 5998.4 6037.2 6071.8 1392.6 1438.0 1438.2 1454.4 1455.5 821.7 887.7 888.2 895.7 897.4 5422.9 5489.7 5518.3 5532.9 5609.3 5622.6 6185.5 6189.4 6190.4 6230.0 4085.5 5220.5 5512.8 5550.4 5550.5 2604.9 3241.4 3250.8 3258.7 3261.3 5001.4 6356.6 6404.0 6438.0 6484.0 5533.4 5905.1 6243.7 6283.4 6305.6 5630.1 5856.4 5893.3 6397.1 6415.7 5098.7 5232.5 5501.9 5985.9 6165.2 4965.7 5410.4 5744.0 5979.8 6037.2 5243.4 5876.2 6124.6 6230.6 6352.3 3936.0 3973.9 3987.9 3996.9 4036.4 5611.7 5807.6 5914.8 5936.2 6094.7 5467.8 5482.7 5765.9 5917.7 5944.6 5227.7 5867.5 5948.0 5981.8 5997.4 4754.4 5691.0 5784.4 5792.4 5828.1 4792.7 4829.8 5631.9 5951.0 5997.3 5665.7 5787.6 5804.5 5838.6 5841.0 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24726.71 Median:3294.22 stddev:38806.50 (1178.0%) stddev-sans-first:38873.25 05:19:45 INFO - PROCESS | 1655 | Values: 646.6 730.8 784.4 845.3 846.1 16011.2 16109.6 16168.4 16289.9 16345.3 14716.0 14774.3 14808.2 14813.3 14813.4 715.6 890.0 894.0 918.3 923.5 874.8 898.8 913.5 914.9 919.5 947.8 978.6 993.0 993.8 997.3 930.7 987.2 987.7 988.7 989.6 692.8 696.0 715.6 733.1 737.7 15222.8 15250.7 15319.9 15591.2 15665.8 13753.9 13821.5 13885.9 14035.2 14267.4 13138.3 13367.5 13574.3 13747.7 13793.1 4674.6 4744.5 4745.8 4751.7 4763.4 106170.0 115393.4 119404.0 119997.4 122563.4 10127.2 11724.9 11790.9 11820.9 11871.3 23190.4 23262.4 23399.0 23436.5 23447.3 8049.3 8120.6 8208.3 8353.3 8423.6 4599.4 4599.7 4617.5 4618.0 4622.4 3253.7 3334.7 3384.6 3386.2 3390.0 659.7 661.3 670.1 673.8 678.1 516.4 569.4 593.8 619.0 650.0 8748.2 9137.5 9263.9 9308.0 9313.4 607.4 636.7 641.7 643.8 666.6 4253.6 4456.8 4490.6 4516.8 4568.2 124187.8 139730.0 147541.3 151349.0 152374.0 100299.5 134440.3 135036.0 136666.0 137228.0 100876.5 102548.0 103347.0 105181.0 106287.0 7279.6 8538.0 8611.0 8658.4 8735.5 56838.1 60749.0 61034.1 62732.1 62779.9 78736.0 80583.2 84158.2 85771.0 91843.7 13378.9 13450.7 13778.7 13817.9 14227.1 15111.6 15220.8 15257.9 15503.2 15783.9 13734.3 14060.6 14105.0 14123.0 14452.1 13802.8 14065.4 14335.6 14560.9 14693.0 13871.9 13939.1 14491.6 14508.4 14667.2 11277.3 11379.3 11525.9 11679.1 12068.4 10078.3 10197.3 10933.9 11665.4 11765.5 05:19:45 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:19:45 INFO - PROCESS | 1655 | 05:19:45 INFO - TEST-INFO | 1655: exit 0 05:19:45 INFO - TEST-OK | dromaeo_css | took 1111939ms 05:19:45 INFO - TEST-START | kraken 05:19:45 INFO - operating with platform_type : mac_ 05:19:45 INFO - Initialising browser for kraken test... 05:19:45 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp9F5pUa/profile http://localhost:49271/getInfo.html) 05:19:47 INFO - PROCESS | 1667 | JavaScript error: , line 0: uncaught exception: undefined 05:19:48 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 05:19:48 INFO - PROCESS | 1667 | colorDepth:24 05:19:48 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 05:19:48 INFO - PROCESS | 1667 | __metrics 05:19:48 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:19:49 INFO - TEST-INFO | 1667: exit 0 05:19:49 INFO - Browser initialized. 05:19:49 INFO - Running cycle 1/1 for kraken test... 05:19:49 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp9F5pUa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:01 INFO - PROCESS | 1669 | RSS: Main: 256622592 05:20:01 INFO - PROCESS | 1669 | 05:20:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:05 INFO - PROCESS | 1669 | RSS: Main: 269979648 05:20:05 INFO - PROCESS | 1669 | 05:20:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:20:08 INFO - PROCESS | 1669 | RSS: Main: 278716416 05:20:08 INFO - PROCESS | 1669 | 05:20:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:20:12 INFO - PROCESS | 1669 | RSS: Main: 281837568 05:20:12 INFO - PROCESS | 1669 | 05:20:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:20:15 INFO - PROCESS | 1669 | RSS: Main: 283025408 05:20:15 INFO - PROCESS | 1669 | 05:20:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:17 INFO - PROCESS | 1669 | RSS: Main: 284774400 05:20:17 INFO - PROCESS | 1669 | 05:20:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:21 INFO - PROCESS | 1669 | RSS: Main: 285270016 05:20:21 INFO - PROCESS | 1669 | 05:20:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:25 INFO - PROCESS | 1669 | RSS: Main: 285618176 05:20:25 INFO - PROCESS | 1669 | 05:20:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:20:28 INFO - PROCESS | 1669 | RSS: Main: 285630464 05:20:28 INFO - PROCESS | 1669 | 05:20:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:31 INFO - PROCESS | 1669 | RSS: Main: 285454336 05:20:31 INFO - PROCESS | 1669 | 05:20:32 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:33 INFO - PROCESS | 1669 | RSS: Main: 285163520 05:20:33 INFO - PROCESS | 1669 | 05:20:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:36 INFO - PROCESS | 1669 | RSS: Main: 285151232 05:20:36 INFO - PROCESS | 1669 | 05:20:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:40 INFO - PROCESS | 1669 | RSS: Main: 285286400 05:20:40 INFO - PROCESS | 1669 | 05:20:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:44 INFO - PROCESS | 1669 | RSS: Main: 285581312 05:20:44 INFO - PROCESS | 1669 | 05:20:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:20:46 INFO - PROCESS | 1669 | RSS: Main: 286543872 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | __start_tp_report 05:20:46 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:20:46 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:20:46 INFO - PROCESS | 1669 | |i|pagename|runs| 05:20:46 INFO - PROCESS | 1669 | |0;ai-astar;150;172;136;116;116;117;116;117;117;118 05:20:46 INFO - PROCESS | 1669 | |1;audio-beat-detection;202;141;126;196;148;136;127;121;191;124 05:20:46 INFO - PROCESS | 1669 | |2;audio-dft;207;264;277;200;201;205;200;191;193;195 05:20:46 INFO - PROCESS | 1669 | |3;audio-fft;69;114;105;175;61;59;60;59;130;58 05:20:46 INFO - PROCESS | 1669 | |4;audio-oscillator;71;71;126;72;71;79;112;71;74;112 05:20:46 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;92;128;86;100;85;89;85;88;86 05:20:46 INFO - PROCESS | 1669 | |6;imaging-darkroom;94;100;152;98;95;95;94;107;94;96 05:20:46 INFO - PROCESS | 1669 | |7;imaging-desaturate;133;89;142;103;131;91;113;143;112;92 05:20:46 INFO - PROCESS | 1669 | |8;json-parse-financial;57;58;57;56;57;56;47;55;88;88 05:20:46 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;71;63;76;77;77;74;72;72;72 05:20:46 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;88;102;109;108;107;89;93;89;91;86 05:20:46 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;143;143;141;181;149;145;139;177;169;170 05:20:46 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;182;186;174;195;266;173;188;269;201;185 05:20:46 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;71;70;71;71;72;70;71;74;72;69 05:20:46 INFO - PROCESS | 1669 | __end_tp_report 05:20:46 INFO - PROCESS | 1669 | __start_cc_report 05:20:46 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1089 05:20:46 INFO - PROCESS | 1669 | __end_cc_report 05:20:46 INFO - PROCESS | 1669 | __startTimestamp1469535646038__endTimestamp 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:20:46 INFO - PROCESS | 1669 | Number of tests: 14 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:127.50 Median:117.50 stddev:19.37 (16.5%) stddev-sans-first:18.75 05:20:46 INFO - PROCESS | 1669 | Values: 150.0 172.0 136.0 116.0 116.0 117.0 116.0 117.0 117.0 118.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:144.50 stddev:32.30 (22.4%) stddev-sans-first:28.55 05:20:46 INFO - PROCESS | 1669 | Values: 202.0 141.0 126.0 196.0 148.0 136.0 127.0 121.0 191.0 124.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:213.30 Median:203.00 stddev:30.71 (15.1%) stddev-sans-first:32.48 05:20:46 INFO - PROCESS | 1669 | Values: 207.0 264.0 277.0 200.0 201.0 205.0 200.0 191.0 193.0 195.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:89.00 Median:59.00 stddev:40.47 (68.6%) stddev-sans-first:42.28 05:20:46 INFO - PROCESS | 1669 | Values: 69.0 114.0 105.0 175.0 61.0 59.0 60.0 59.0 130.0 58.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:71.00 stddev:21.71 (30.6%) stddev-sans-first:22.34 05:20:46 INFO - PROCESS | 1669 | Values: 71.0 71.0 126.0 72.0 71.0 79.0 112.0 71.0 74.0 112.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:87.00 stddev:13.16 (15.1%) stddev-sans-first:13.88 05:20:46 INFO - PROCESS | 1669 | Values: 89.0 92.0 128.0 86.0 100.0 85.0 89.0 85.0 88.0 86.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:94.50 stddev:17.85 (18.9%) stddev-sans-first:18.67 05:20:46 INFO - PROCESS | 1669 | Values: 94.0 100.0 152.0 98.0 95.0 95.0 94.0 107.0 94.0 96.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:114.90 Median:142.50 stddev:21.16 (14.9%) stddev-sans-first:21.41 05:20:46 INFO - PROCESS | 1669 | Values: 133.0 89.0 142.0 103.0 131.0 91.0 113.0 143.0 112.0 92.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:57.00 stddev:14.10 (24.7%) stddev-sans-first:14.84 05:20:46 INFO - PROCESS | 1669 | Values: 57.0 58.0 57.0 56.0 57.0 56.0 47.0 55.0 88.0 88.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:73.00 stddev:4.57 (6.3%) stddev-sans-first:4.30 05:20:46 INFO - PROCESS | 1669 | Values: 66.0 71.0 63.0 76.0 77.0 77.0 74.0 72.0 72.0 72.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:88.50 stddev:9.22 (10.4%) stddev-sans-first:9.29 05:20:46 INFO - PROCESS | 1669 | Values: 88.0 102.0 109.0 108.0 107.0 89.0 93.0 89.0 91.0 86.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:159.00 stddev:16.51 (10.4%) stddev-sans-first:16.86 05:20:46 INFO - PROCESS | 1669 | Values: 143.0 143.0 141.0 181.0 149.0 145.0 139.0 177.0 169.0 170.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.90 Median:191.50 stddev:35.59 (18.6%) stddev-sans-first:37.02 05:20:46 INFO - PROCESS | 1669 | Values: 182.0 186.0 174.0 195.0 266.0 173.0 188.0 269.0 201.0 185.0 05:20:46 INFO - PROCESS | 1669 | 05:20:46 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:71.00 stddev:1.37 (1.9%) stddev-sans-first:1.45 05:20:46 INFO - PROCESS | 1669 | Values: 71.0 70.0 71.0 71.0 72.0 70.0 71.0 74.0 72.0 69.0 05:20:46 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:20:46 INFO - PROCESS | 1669 | 05:20:47 INFO - TEST-INFO | 1669: exit 0 05:20:47 INFO - TEST-OK | kraken | took 61417ms 05:20:47 INFO - SUITE-END | took 1173s 05:20:47 INFO - Completed test suite (00:19:33) 05:20:47 INFO - javascript benchmark 05:20:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7467.863877874328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2995.3859458810944, 3398.643142470042, 3401.35374278887, 3423.845926933288, 3449.5343128677628, 5503.807366742165, 5651.802186923458, 5748.051571490704, 5883.058710606304, 5886.0, 5655.123455864337, 5832.241808564881, 5844.503217226541, 5891.911621325673, 5934.64392136471, 5265.341832270966, 5288.99509093272, 5344.866378340534, 5379.193121031857, 5436.891262174754, 5291.761870715827, 5463.754131064112, 5480.177973303997, 5503.669779813204, 5508.20131080993, 5202.505761952609, 5330.813421530228, 5351.839444816662, 5364.1149210380245, 5394.838154855341, 5208.687478751249, 5269.104252277122, 5306.681599104028, 5323.361196656388, 5355.518003379676, 5188.221766734982, 5230.503102205284, 5232.895342093137, 5248.343957005374, 5279.676942145482, 5278.656887302313, 5413.891722165574, 5671.602987790856, 5849.824505264849, 5948.464638182541, 6166.784162554334, 6179.752809887599, 6229.813105606839, 6245.7501699931945, 6255.437010669016, 5015.398152221756, 6269.3730626937395, 6285.057241413779, 6294.0873573332065, 6294.779682711083, 5839.211706419664, 6006.399360063959, 6030.396960304022, 6041.3354531002, 6043.063325184604, 6219.626822390671, 6262.2172228471445, 6305.936940630627, 6373.52198585108, 6374.203224596926, 6213.65824879636, 6238.344973777779, 6239.095331176954, 6274.2784579773, 6285.59143655661, 6118.284172697687, 6201.410865967726, 6206.131141640174, 6267.059941008885, 6274.0, 6137.836677219288, 6190.288116866528, 6258.405451482102, 6261.0, 6277.089821975788, 6216.290520107007, 6254.030625253093, 6255.812325630239, 6257.998720204745, 6276.529260305495, 5008.223725322581, 5052.520010598987, 5072.239164125411, 5077.949220507748, 5104.744762761847, 3652.7077833773233, 3772.9245415091546, 3800.809959502069, 4487.730736155841, 4624.56638442084, 5902.734376953047, 5952.940470595326, 5996.820095397145, 6015.969920150371, 6022.277326720734, 6154.3845615438095, 6263.123162757218, 6269.7178626961895, 6271.717772700239, 6275.309715931244, 5923.881522369528, 5944.7027648617395, 5959.433853783884, 6028.216331876828, 6058.0610005449225, 4688.507706690816, 5564.360098588633, 5577.414371491016, 5581.302337207849, 5594.384617692051, 6253.718582663882, 6467.870642587122, 6479.319671434431, 6480.092787009822, 6496.35036496356, 5241.0, 6189.04069869171, 6208.410201030895, 6338.619682819046, 6341.27075386318, 6406.711697973696, 6443.420092191725, 6457.870842583216, 6459.0, 6495.447886929664, 6139.369480960463, 6181.134641150153, 6197.96901015492, 6256.436920677161, 6262.749490020348, 6151.384861513813, 6180.072989051679, 6219.937800621936, 6251.156093927261, 6264.49884009269, 5173.275741396283, 6208.782692605737, 6245.96877015612, 6246.843828904132, 6248.781292654735, 6125.693715314305, 6206.286277078013, 6212.043027825182, 6236.656983865931, 6242.968785156045, 6018.849528761641, 6065.6663883486835, 6069.939300607114, 6123.540734444845, 6126.172966649446, 6165.230572841863, 6166.414190651881, 6230.252369715663, 6232.220972378453, 6251.093591429127, 5984.850378740566, 5999.340072592098, 6015.849603759766, 6128.203333566607, 6155.553444940346], "value": 5753.07025923098, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.6067876038549, 497.5000124874501, 510.92450391924973, 542.7910254551997, 547.1164070026907, 32601.18497037575, 42096.0, 42099.94750131248, 42101.57898421015, 42130.7893460533, 43719.78140109295, 44110.55889441105, 44146.558534414646, 44246.0, 44291.67124986248, 9491.620335186584, 9637.807243855119, 9668.274879384038, 9668.564914578825, 9675.419474831497, 9714.271429642806, 9797.412155270671, 9823.0, 9829.50852457378, 9845.950770246138, 9768.120869121778, 9788.510574471284, 9801.411915285105, 9811.607535698564, 9823.115919567237, 9645.228381729445, 9682.903170968306, 9743.658971935949, 9760.121589057018, 9779.266555008402, 9629.037096290385, 9676.467794271312, 9722.75693107678, 9731.0, 9739.220862330996, 41473.0, 41902.79048604768, 41907.952301192716, 41921.1615767686, 42107.0, 2594.701609314915, 3417.5557177567007, 3488.209850704602, 3489.70337521311, 3572.214112895159, 31892.20269493258, 31893.840530797428, 31908.680913190805, 32051.621145506328, 32087.518687219712, 2707.2961030132305, 2896.4641541314923, 3186.1556687477837, 3209.5185722141864, 3270.1824543864036, 447363.7631811853, 453548.0, 457677.0, 458069.0, 458869.0, 33071.3385732284, 34125.48811767826, 34693.47959780605, 34864.0, 34871.30257394838, 34150.0, 35686.464703028956, 36018.8199059003, 36257.63742362595, 36992.26015479729, 14590.19753913545, 17235.31058757661, 17415.73876391855, 17417.04206268642, 17433.738493922603, 13204.669883252996, 13302.866971330357, 13307.001974851732, 13311.334433278298, 13380.063395562218, 16680.416185433452, 19850.404487865388, 20065.69572977752, 20265.0, 20436.38690839277, 8186.2632363087605, 8295.336373090138, 8317.8336433271, 8335.208155225244, 8444.817725518433, 859.6518410043942, 947.5641205045756, 1031.7000579270173, 1041.9374837509774, 1048.8688913885765, 31214.0, 31270.0, 31516.369672606877, 31610.683893161233, 32065.839670801495, 997.6159178716247, 1013.2400699475394, 1058.6713674338655, 1061.7398737309409, 1062.1449732965036, 15020.849791502163, 15714.292856821241, 15814.36742530309, 15857.841421585867, 16043.278052487432, 700827.0, 701470.0, 707467.0, 710054.449727748, 713945.0, 61521.769564608454, 61684.38315616787, 62254.37745622576, 62307.37692623107, 62452.252864827344, 1382.2673982789138, 1397.8672026157499, 1401.6215621782062, 1412.9646758830906, 1416.199847086393, 24040.63939040951, 29894.402111957203, 30645.846770766002, 31256.531152032283, 32472.188195295308, 54075.0, 54424.45575544194, 54879.0, 54906.0, 54936.0, 593517.0648293461, 651420.0, 653286.0, 654628.7268563627, 656994.0, 21208.469788255417, 27067.59398608983, 27634.447311053264, 27686.86156569204, 27688.446231075668, 44521.0, 48189.75905120452, 50197.0, 51173.0, 51688.74155629198, 40355.0, 41417.96455088647, 41625.37561936634, 42819.0, 43679.0, 41217.1756564873, 41817.58182418137, 41990.38985485685, 42070.94822629459, 42665.0, 38390.616093838704, 38632.80683596564, 39136.0, 39474.60525394709, 39966.2006759869, 34980.0, 35725.28549428945, 36286.45570316395, 36438.72464463731, 36458.08854778545, 22287.0, 24929.376765581004, 26362.209133726017, 26427.471450571265, 26848.865755671097], "value": 19907.34274720158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.78418732947574, 378.29448079332036, 388.6308007392977, 392.9135590170163, 403.46943768943817, 19827.801721982774, 21333.146674133037, 21387.786122138776, 21640.458988525275, 22625.208117715883, 20641.793582064176, 20824.791752082474, 21647.45881352963, 21662.566748665016, 21858.0, 5802.941970580293, 6012.609180403259, 6037.396260373971, 6092.51259899207, 6104.969475152618, 4135.855245066423, 5901.20333754944, 6091.786787462417, 6111.0, 6405.487560995132, 6593.604383736991, 6730.259671436138, 6770.72917083316, 6772.153480814898, 6773.4242589379965, 5635.774569017255, 5805.5065319447895, 5910.1134829775665, 6026.427489388501, 6732.629705366203, 5695.231143795576, 5747.798827041075, 5830.2420685310635, 5845.240118784531, 5858.443447872445, 13274.269915154662, 13638.454461821515, 13657.453701851913, 20187.697184542245, 24033.49002642625, 2236.910523579055, 2246.427161073932, 2317.2121478697154, 2635.920922372332, 2658.096247275916, 13574.728505429835, 16760.413385531447, 16763.66472670552, 17072.914635426743, 18172.091395430307, 1697.4059079322262, 1940.6991916253003, 2005.4785755703476, 2013.2047841102828, 2116.5766846630736, 98339.50830245802, 100933.0, 130791.0, 135006.0, 137200.31399843036, 9620.182284505896, 9895.455749933679, 10213.029762172582, 10319.742006449898, 10414.5834166634, 12508.0, 12568.057395695176, 12571.245725256515, 12581.496740130475, 12583.182093163916, 8071.556064416513, 8293.668253269921, 8315.916840831514, 8426.157384261525, 8435.873461898076, 8738.519381434082, 8742.0, 8746.240303941735, 8888.866667, 8969.820603587892, 3398.2863598644562, 3883.49514563105, 3940.0346915006007, 4020.276350256982, 4050.6632811172244, 1426.3224968140257, 1605.8153312369227, 1793.0765655687333, 1945.7665080190184, 1989.44295597233, 809.5264270401763, 842.3724325377633, 938.7183844846519, 943.4763706143157, 960.870282511852, 17443.0, 18479.722804157947, 21386.679199812017, 21811.890940545512, 21966.45083872884, 827.3505298340774, 891.1712107739735, 947.6825263536655, 991.2664628175098, 1007.2747621712356, 10913.345199287909, 11431.714207144887, 11462.197646164856, 11540.076793856471, 11601.361925094201, 505939.0, 514128.0, 514749.0, 613734.0, 621633.0, 117143.41428292805, 146260.0, 147085.0, 147753.2612336953, 149218.0, 994.1301361308855, 995.6564985080136, 1017.2624846986024, 1035.5288343803552, 1037.9532921018572, 18484.72272915934, 18883.339083132025, 18968.336108236144, 19326.806731932502, 19386.0, 35829.82085089558, 45554.54445455503, 56036.5990850232, 57314.0, 58128.0, 77584.2241577577, 79339.60330198312, 80725.59637201777, 85903.0, 87111.69332459892, 7139.750108746169, 7330.96334518324, 7484.476086673881, 7687.461877668509, 7706.460547761603, 8739.38824282294, 12796.232226066466, 13357.931365490931, 13530.594082177156, 13627.727445450837, 11202.21584489078, 11327.263727857662, 11366.602168924048, 11599.07207423421, 11676.88323116792, 10185.694429167137, 10990.340579565252, 11441.485133169193, 11457.140714446283, 11550.88449115498, 10419.791604167722, 11114.0, 11353.829692554784, 11569.595064172714, 11698.824517632413, 6187.659678717714, 7550.962245188739, 7583.734569290048, 7606.277403646644, 7719.228077192461, 11058.889411105785, 11271.661850144508, 11512.021478174449, 11653.30080195191, 11658.88341116578], "value": 9552.653291129087, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.0325201627008, 1605.7751914731937, 1881.9529511762212, 1949.892755898426, 1950.9024548772559, 8099.91900080999, 8128.91871081289, 8667.263282620977, 9171.449713017224, 9180.954095229523, 7551.848963020736, 8961.955190224056, 8969.058248883872, 8975.596098175582, 8981.371304008722, 2396.808255339573, 2415.8429702069398, 2420.0, 2420.0, 2437.756224377557, 2382.737898831127, 2422.6123820188773, 2426.5632186206562, 2432.975670243293, 2438.7073551173794, 2363.881805909706, 2379.7144342678903, 2410.80713542917, 2416.395901024744, 2430.173740928084, 2385.630227314769, 2387.85672859629, 2404.170561156407, 2411.9999999999823, 2419.0444774314137, 2360.4570948681726, 2384.5707772600927, 2393.5930891748444, 2397.412633904687, 2406.2660888429023, 8986.0, 9081.001089880174, 9126.634934602607, 9132.863007054899, 9162.175404213653, 5394.406615272317, 5577.442255774431, 5581.0, 5581.190727344553, 5593.188987596816, 3074.3543855790313, 3099.628044634636, 3336.948861108743, 3595.928081438383, 3617.6382361763876, 1038.8285932821152, 1054.6783231114507, 1057.7249915022073, 1058.560697310615, 1060.862087928572, 11980.221285616408, 12019.098567607465, 12070.818937715854, 12106.81839772404, 12174.878251217551, 4738.1708201064675, 4770.813844714673, 4841.419029716457, 4897.338859253955, 4956.281339205867, 10004.149647279917, 10188.439635819952, 10199.7450063749, 10274.075333220046, 10343.27597068198, 4474.239379305526, 5268.130758424817, 5438.0, 5535.806246781343, 5556.388797232301, 4512.075024619945, 4889.706617602955, 5010.123228435009, 5040.672356296829, 5073.974630126826, 3991.6008399160432, 4059.6143366380147, 4114.917701645951, 4146.0, 4172.958270417318, 1016.8118898003893, 1308.784050631654, 1325.6155714842587, 1329.056369977327, 1332.9800052999212, 870.9564521773885, 942.8161508505913, 958.4919992404037, 976.545906153642, 988.960441582343, 5209.843704688866, 5978.611390259706, 6032.487238584769, 6122.112293717386, 6168.19813424252, 449.69196100671144, 452.2334642780521, 454.1688709661312, 458.65142491705876, 464.5447461487761, 3850.5186851643543, 4079.2453396121814, 4096.672266218696, 4192.182524407711, 4199.4330765346895, 10381.013803688638, 10724.195685323632, 10768.192385570957, 10772.9765672261, 10828.776777056954, 10573.04842564173, 10770.0, 10844.186685998582, 10895.346279223113, 10896.291741036806, 7195.424366050704, 8263.297619702393, 8299.630560957496, 8395.076541580544, 8450.323974082059, 4689.835855745169, 5818.272715910512, 5970.641761494324, 6079.0, 6139.631622102688, 10111.494425278559, 10113.5448904798, 10166.237532185263, 10199.949000254952, 10215.23385746087, 7385.926140738524, 10217.0, 10231.130353919703, 10441.530131144269, 10447.268691191544, 7063.894041589483, 7502.962485187539, 7685.654145563351, 7688.423368247516, 7842.529448233125, 8556.18716221958, 8603.225709686158, 8618.612162452579, 8683.348748843988, 8711.607977640895, 7744.2642948919765, 7855.2930236276725, 7874.881876771968, 7971.282584567417, 7995.440319177602, 7361.190269070291, 7419.29516695913, 7520.849583008418, 7686.923130768844, 7687.500312479671, 7227.096612923385, 7248.927510725036, 7359.080114985627, 7363.116426028911, 7368.078990126234, 5001.574866136273, 5009.749512524432, 5128.923066154085, 5137.614678899023, 5309.177077552986, 5194.883100133489, 5208.348956380452, 5232.659877108128, 5342.278792362981, 5369.785208591613], "value": 4587.2856971113715, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.95440683897414, 319.6308263955131, 320.548027281533, 323.95788547488837, 327.2783512355257, 5001.424836143845, 5742.598018138727, 5767.798127065554, 5774.884502309951, 5803.883922321551, 5543.695096769676, 5575.303087114111, 5655.26481557398, 5674.375818659944, 5727.169560413748, 3730.1793605406765, 3896.688264938798, 4060.553339132693, 4149.9792501037455, 4161.3549899765585, 4053.493313335833, 4215.430916826235, 4220.767857767822, 4224.957750422487, 4257.446531950842, 3505.4741788731767, 3828.25349056934, 4169.9791501042455, 4231.809568569422, 4246.384274280227, 3991.1618560102415, 4110.486189226362, 4219.261629214906, 4225.598568136023, 4251.979524913998, 3686.6912246152497, 3694.0, 3694.1688120173017, 3695.505533764698, 3699.223163135745, 5406.621536492447, 5406.701672170634, 5425.321834770653, 5440.4287549807095, 5494.0, 4179.958200417987, 4485.327200919855, 4501.48232953208, 4802.662149805851, 4964.982178653404, 5816.476517113438, 5826.592138550303, 5886.6468011919, 5890.793822216245, 5895.823125306248, 4055.614716601918, 4060.92385517838, 4086.6322031017053, 4095.2219078375015, 4124.9587504124875, 5397.973010134963, 5926.970365148147, 6203.658798766066, 6243.937560624381, 6267.905981410237, 3460.9753294323104, 3643.890683279506, 4143.357779544175, 4164.250434921682, 4171.0, 3924.7841368724994, 4221.704480686322, 4223.007593215591, 4226.661867050629, 4231.238377092153, 4694.554017368345, 4886.3403440535785, 4919.926201106986, 4945.554900059012, 4945.7774400152075, 5258.789648414097, 5947.375525569752, 5998.430149135826, 6037.18498002764, 6071.787487437919, 1392.5613431769164, 1437.964050898715, 1438.1946110178333, 1454.3818876977243, 1455.548779878241, 821.6795449774593, 887.658251573149, 888.2405543260548, 895.7402353317595, 897.434616191795, 5422.885074820823, 5489.725513724298, 5518.255035570227, 5532.917006244909, 5609.27079479665, 5622.606417550732, 6185.494874575714, 6189.381061893775, 6190.41191086846, 6229.96885015572, 4085.530164031115, 5220.477952204825, 5512.751926163332, 5550.445875154963, 5550.500454958992, 2604.882780274892, 3241.3517296540786, 3250.788698734622, 3258.680234505029, 3261.2825178460703, 5001.399832020181, 6356.586821856657, 6403.990124641411, 6438.00421869222, 6483.967580162163, 5533.363663178706, 5905.102632262772, 6243.719032643542, 6283.371662833772, 6305.621662700252, 5630.127330263816, 5856.389492889404, 5893.3222679391565, 6397.136386587844, 6415.743370265231, 5098.745062746773, 5232.502912223333, 5501.9174712380145, 5985.862686089629, 6165.167702360124, 4965.726885021359, 5410.428188180981, 5743.971280143573, 5979.760809567742, 6037.245344331958, 5243.397009343822, 5876.1773351729935, 6124.6325220486915, 6230.563860529901, 6352.269489008823, 3936.0, 3973.88731155277, 3987.8803635890968, 3996.8800935971967, 4036.354183330653, 5611.7194140292, 5807.593468457168, 5914.792982245601, 5936.168936349001, 6094.695265236809, 5467.781288748405, 5482.725863706719, 5765.875654247381, 5917.704114794329, 5944.5838791284195, 5227.738613069408, 5867.5305975521005, 5947.970260148844, 5981.760729570942, 5997.400259973968, 4754.358161648133, 5691.0, 5784.438201685605, 5792.362840087502, 5828.125781132864, 4792.7124372537755, 4829.830955916526, 5631.8592035199445, 5950.9702451487465, 5997.31030931431, 5665.688387138747, 5787.565932554991, 5804.535637148933, 5838.591298609226, 5841.036229022164], "value": 4308.868562188248, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.6281887914449, 730.7953772943574, 784.4273680213444, 845.3026253340993, 846.0735494633382, 16011.199440027996, 16109.597260068504, 16168.434104806305, 16289.941153825022, 16345.346186152554, 14716.0, 14774.335154918028, 14808.185549794756, 14813.259337033132, 14813.407463701438, 715.6171448275173, 890.0, 893.9731808045752, 918.320442872273, 923.5105394141083, 874.8031692869117, 898.8382091223577, 913.4975763330175, 914.940528865625, 919.4713040002025, 947.7725345916965, 978.5792109392958, 992.9801403971916, 993.7515621094726, 997.2919227348591, 930.6510058727977, 987.2299606307021, 987.7185002274354, 988.7379844341256, 989.579428742785, 692.7921623512926, 695.961722105284, 715.6314498033516, 733.0543598757596, 737.7049180327858, 15222.847771522254, 15250.694986100216, 15319.923400383039, 15591.220438978005, 15665.843341566553, 13753.931230343784, 13821.516246931309, 13885.861141388557, 14035.157890526601, 14267.357968891425, 13138.343082845819, 13367.46530138803, 13574.321283935862, 13747.725045499134, 13793.10344827583, 4674.555917187861, 4744.5492678195515, 4745.810167593292, 4751.691140075884, 4763.3807605011125, 106170.0, 115393.4230328843, 119404.0, 119997.40001299938, 122563.3871830644, 10127.189824813997, 11724.94137529324, 11790.94104529472, 11820.940895295469, 11871.287722736663, 23190.42023949415, 23262.418439539146, 23399.0, 23436.531269374856, 23447.29658110225, 8049.27556519916, 8120.6345714443, 8208.343332533383, 8353.33173346131, 8423.620937057847, 4599.425071866017, 4599.655025873006, 4617.46899106607, 4617.976910115495, 4622.399088118523, 3253.723433508131, 3334.7165490933544, 3384.5769278840144, 3386.204242003127, 3389.983050084783, 659.7036822643529, 661.3089321658782, 670.0819876768858, 673.7641825361085, 678.1015154919751, 516.4396629656865, 569.4334137533099, 593.7862369546959, 618.9508782613427, 650.0314531348257, 8748.2388426964, 9137.497437640994, 9263.907360926305, 9308.0, 9313.39462934907, 607.4077774170149, 636.7484843486797, 641.7144370755062, 643.8132941447021, 666.576723780403, 4253.63844073251, 4456.799444024962, 4490.550944905483, 4516.774161291923, 4568.223402021597, 124187.8230959218, 139730.0, 147541.31146721204, 151349.0, 152374.0, 100299.49850250702, 134440.32779836038, 135036.0, 136666.0, 137228.0, 100876.49561752145, 102548.0, 103347.0, 105181.0, 106287.0, 7279.611833334151, 8538.0, 8611.0, 8658.39391242607, 8735.475871447734, 56838.147427787786, 60749.0, 61034.08448873359, 62732.05901911384, 62779.89691494799, 78736.0, 80583.19416805757, 84158.15841841503, 85771.0, 91843.70390740284, 13378.86621133815, 13450.663733406744, 13778.65553361174, 13817.86182138206, 14227.14637121776, 15111.622209444851, 15220.847791522387, 15257.923710381378, 15503.224838758242, 15783.921080394524, 13734.313284335944, 14060.578182654537, 14105.0, 14123.0, 14452.132872027292, 13802.792958105436, 14065.437382504995, 14335.56993290203, 14560.927195363955, 14692.971491995886, 13871.930640347138, 13939.09395889264, 14491.637709057359, 14508.419663213353, 14667.193304368362, 11277.32336059839, 11379.317240965569, 11525.884741152817, 11679.093705514035, 12068.396580171131, 10078.337074382938, 10197.337173083726, 10933.890661093288, 11665.35840528779, 11765.52937882475], "value": 8020.08359395629, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1635.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 172.0, 136.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 118.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 141.0, 126.0, 196.0, 148.0, 136.0, 127.0, 121.0, 191.0, 124.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 264.0, 277.0, 200.0, 201.0, 205.0, 200.0, 191.0, 193.0, 195.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 114.0, 105.0, 175.0, 61.0, 59.0, 60.0, 59.0, 130.0, 58.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 126.0, 72.0, 71.0, 79.0, 112.0, 71.0, 74.0, 112.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 128.0, 86.0, 100.0, 85.0, 89.0, 85.0, 88.0, 86.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 152.0, 98.0, 95.0, 95.0, 94.0, 107.0, 94.0, 96.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 89.0, 142.0, 103.0, 131.0, 91.0, 113.0, 143.0, 112.0, 92.0], "value": 114.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 56.0, 57.0, 56.0, 47.0, 55.0, 88.0, 88.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 63.0, 76.0, 77.0, 77.0, 74.0, 72.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 102.0, 109.0, 108.0, 107.0, 89.0, 93.0, 89.0, 91.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 143.0, 141.0, 181.0, 149.0, 145.0, 139.0, 177.0, 169.0, 170.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 174.0, 195.0, 266.0, 173.0, 188.0, 269.0, 201.0, 185.0], "value": 201.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 71.0, 72.0, 70.0, 71.0, 74.0, 72.0, 69.0], "value": 71.1, "unit": "score"}]}]} 05:20:47 INFO - Return code: 0 05:20:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:20:47 ERROR - # TBPL SUCCESS # 05:20:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:47 INFO - 2016-07-26 05:20:47.355 screenresolution[1674:10634] starting screenresolution argv=screenresolution get 05:20:47 INFO - 2016-07-26 05:20:47.359 screenresolution[1674:10634] Display 0: 1600x1200x32@60 05:20:47 INFO - 2016-07-26 05:20:47.365 screenresolution[1675:10637] starting screenresolution argv=screenresolution list 05:20:47 INFO - Available Modes on Display 0 05:20:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:47 INFO - Intel Iris: 05:20:47 INFO - Chipset Model: Intel Iris 05:20:47 INFO - Type: GPU 05:20:47 INFO - Bus: Built-In 05:20:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:47 INFO - Vendor: Intel (0x8086) 05:20:47 INFO - Device ID: 0x0a2e 05:20:47 INFO - Revision ID: 0x0009 05:20:47 INFO - Displays: 05:20:47 INFO - Display: 05:20:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:47 INFO - Display Serial Number: 1600x1200 60 05:20:47 INFO - Main Display: Yes 05:20:47 INFO - Mirror: Off 05:20:47 INFO - Online: Yes 05:20:47 INFO - Rotation: Supported 05:20:47 INFO - Return code: 0 05:20:47 INFO - Running post-action listener: _resource_record_post_action 05:20:47 INFO - [mozharness: 2016-07-26 12:20:47.606296Z] Finished run-tests step (success) 05:20:47 INFO - Running post-run listener: _resource_record_post_run 05:20:47 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 37118976; Write bytes: 706633216; Read time: 239; Write time: 2029 05:20:47 INFO - TinderboxPrint: CPU usage
54.2% 05:20:47 INFO - TinderboxPrint: I/O read bytes / time
37,118,976 / 239 05:20:47 INFO - TinderboxPrint: I/O write bytes / time
706,633,216 / 2,029 05:20:47 INFO - TinderboxPrint: CPU idle
2,214.8 (45.8%) 05:20:47 INFO - TinderboxPrint: CPU system
971.2 (20.1%) 05:20:47 INFO - TinderboxPrint: CPU user
1,647.7 (34.1%) 05:20:47 INFO - TinderboxPrint: Swap in / out
445,681,664 / 0 05:20:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393675264; Write bytes: 387274752; Read time: 29393; Write time: 852 05:20:47 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 36160512; Write bytes: 296281600; Read time: 226; Write time: 1122 05:20:48 INFO - Running post-run listener: _upload_blobber_files 05:20:48 INFO - Blob upload gear active. 05:20:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:48 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 05:20:48 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'] 05:20:48 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 05:20:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:20:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:49 INFO - (blobuploader) - INFO - Done attempting. 05:20:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:20:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:20:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:50 INFO - (blobuploader) - INFO - Done attempting. 05:20:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:04 INFO - (blobuploader) - INFO - Done attempting. 05:21:04 INFO - (blobuploader) - INFO - Iteration through files over. 05:21:04 INFO - Return code: 0 05:21:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:21:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:21:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e2c566bc04e87fc3e578e8571ecf8753728f8687820e660ee4796f4bec145f898021cc84b1042c56b2edb2238dc0df55278f90a6a125ae480eb489669ea4967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef263cf00879bd1f21892491eafce3602264467259da382c064a89e21ac3dc2c7d05a5ca39155b2d0303d6a33251d9561b619899dacc1f6677b5cb997714f4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19793a881d9d8ecf121dab5eb44c509aaf3512cb9718f1e5b39a770c9de5443e61d1ba54e0dc75be5ded9ccaf12ab615ded3104d3a909bdf874e05b9ff960d94"} 05:21:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:21:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:21:04 INFO - Contents: 05:21:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e2c566bc04e87fc3e578e8571ecf8753728f8687820e660ee4796f4bec145f898021cc84b1042c56b2edb2238dc0df55278f90a6a125ae480eb489669ea4967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef263cf00879bd1f21892491eafce3602264467259da382c064a89e21ac3dc2c7d05a5ca39155b2d0303d6a33251d9561b619899dacc1f6677b5cb997714f4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19793a881d9d8ecf121dab5eb44c509aaf3512cb9718f1e5b39a770c9de5443e61d1ba54e0dc75be5ded9ccaf12ab615ded3104d3a909bdf874e05b9ff960d94"} 05:21:04 INFO - Running post-run listener: copy_logs_to_upload_dir 05:21:04 INFO - Copying logs to upload dir... 05:21:04 INFO - mkdir: /builds/slave/test/build/upload/logs 05:21:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.890235 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-26 05:21:04.448660) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 05:21:04.451914) ========= 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.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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/3e2c566bc04e87fc3e578e8571ecf8753728f8687820e660ee4796f4bec145f898021cc84b1042c56b2edb2238dc0df55278f90a6a125ae480eb489669ea4967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef263cf00879bd1f21892491eafce3602264467259da382c064a89e21ac3dc2c7d05a5ca39155b2d0303d6a33251d9561b619899dacc1f6677b5cb997714f4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19793a881d9d8ecf121dab5eb44c509aaf3512cb9718f1e5b39a770c9de5443e61d1ba54e0dc75be5ded9ccaf12ab615ded3104d3a909bdf874e05b9ff960d94"} build_url:https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/BYMRxfIqRQC8J_CiZrdmog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e2c566bc04e87fc3e578e8571ecf8753728f8687820e660ee4796f4bec145f898021cc84b1042c56b2edb2238dc0df55278f90a6a125ae480eb489669ea4967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef263cf00879bd1f21892491eafce3602264467259da382c064a89e21ac3dc2c7d05a5ca39155b2d0303d6a33251d9561b619899dacc1f6677b5cb997714f4ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19793a881d9d8ecf121dab5eb44c509aaf3512cb9718f1e5b39a770c9de5443e61d1ba54e0dc75be5ded9ccaf12ab615ded3104d3a909bdf874e05b9ff960d94"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 05:21:04.484402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 05:21:04.484731) ========= 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.wh8IVRcMNA/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.Narv1CfazI/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 05:21:04.533722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 05:21:04.534060) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 05:21:04.534398) ========= ========= Total master_lag: 0.17 =========