builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0061 starttime: 1464239721.21 results: success (0) buildid: 20160525201649 builduid: f5ed8adc79ea4350bad0dfc044fcbc90 revision: 8dfd7c9d86397ed898d84933a3c39834f1db886f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.205750) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.206300) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.206592) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.238432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.238796) ========= 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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-25 22:15:21-- 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% 136M=0s 2016-05-25 22:15:21 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084047 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.348048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.348372) ========= 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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.029250 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:21.397055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-25 22:15:21.397417) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8dfd7c9d86397ed898d84933a3c39834f1db886f --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 8dfd7c9d86397ed898d84933a3c39834f1db886f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-25 22:15:21,472 truncating revision to first 12 chars 2016-05-25 22:15:21,472 Setting DEBUG logging. 2016-05-25 22:15:21,472 attempt 1/10 2016-05-25 22:15:21,472 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8dfd7c9d8639?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 22:15:22,518 unpacking tar archive at: fx-team-8dfd7c9d8639/testing/mozharness/ program finished with exit code 0 elapsedTime=1.319372 ========= master_lag: 2.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-25 22:15:25.032382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:25.032885) ========= script_repo_revision: 8dfd7c9d86397ed898d84933a3c39834f1db886f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:25.033335) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:25.033602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 22:15:25.085940) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-25 22:15:25.086368) ========= /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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:15:25 INFO - MultiFileLogger online at 20160525 22:15:25 in /builds/slave/test 22:15:25 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 22:15:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:15:25 INFO - {'append_to_log': False, 22:15:25 INFO - 'base_work_dir': '/builds/slave/test', 22:15:25 INFO - 'blob_upload_branch': 'Fx-Team', 22:15:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:15:25 INFO - 'branch': 'Fx-Team', 22:15:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:15:25 INFO - 'config_files': ('talos/mac_config.py',), 22:15:25 INFO - 'default_actions': ('clobber', 22:15:25 INFO - 'read-buildbot-config', 22:15:25 INFO - 'download-and-extract', 22:15:25 INFO - 'populate-webroot', 22:15:25 INFO - 'create-virtualenv', 22:15:25 INFO - 'install', 22:15:25 INFO - 'run-tests'), 22:15:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:15:25 INFO - 'download_minidump_stackwalk': True, 22:15:25 INFO - 'download_symbols': 'ondemand', 22:15:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:15:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:15:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:15:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:15:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:15:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:15:25 INFO - 'installer_path': 'installer.exe', 22:15:25 INFO - 'log_level': 'info', 22:15:25 INFO - 'log_name': 'talos', 22:15:25 INFO - 'log_to_console': True, 22:15:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:15:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:15:25 INFO - 'opt_config_files': (), 22:15:25 INFO - 'pip_index': False, 22:15:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:15:25 INFO - 'cmd': ('bash', 22:15:25 INFO - '-c', 22:15:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:15:25 INFO - 'enabled': True, 22:15:25 INFO - 'halt_on_failure': False, 22:15:25 INFO - 'name': 'check_screen_resolution'},), 22:15:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:15:25 INFO - 'cmd': ('bash', 22:15:25 INFO - '-c', 22:15:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:15:25 INFO - 'enabled': True, 22:15:25 INFO - 'halt_on_failure': False, 22:15:25 INFO - 'name': 'check_screen_resolution'},), 22:15:25 INFO - 'run_cmd_checks_enabled': True, 22:15:25 INFO - 'sps_profile': False, 22:15:25 INFO - 'sps_profile_interval': 0, 22:15:25 INFO - 'suite': 'dromaeojs-e10s', 22:15:25 INFO - 'system_bits': '32', 22:15:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:15:25 INFO - 'title': 't-yosemite-r7-0061', 22:15:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:15:25 INFO - 'use_talos_json': True, 22:15:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:15:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:15:25 INFO - 'work_dir': 'build'} 22:15:25 INFO - ##### 22:15:25 INFO - ##### Running clobber step. 22:15:25 INFO - ##### 22:15:25 INFO - Running pre-action listener: _resource_record_pre_action 22:15:25 INFO - Running main action method: clobber 22:15:25 INFO - rmtree: /builds/slave/test/build 22:15:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:15:27 INFO - Running post-action listener: _resource_record_post_action 22:15:27 INFO - ##### 22:15:27 INFO - ##### Finished clobber step (success) 22:15:27 INFO - ##### 22:15:27 INFO - ##### 22:15:27 INFO - ##### Running read-buildbot-config step. 22:15:27 INFO - ##### 22:15:27 INFO - Running pre-action listener: _resource_record_pre_action 22:15:27 INFO - Running main action method: read_buildbot_config 22:15:27 INFO - Using buildbot properties: 22:15:27 INFO - { 22:15:27 INFO - "project": "", 22:15:27 INFO - "product": "firefox", 22:15:27 INFO - "script_repo_revision": "production", 22:15:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:15:27 INFO - "repository": "", 22:15:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:15:27 INFO - "buildid": "20160525201649", 22:15:27 INFO - "pgo_build": "False", 22:15:27 INFO - "basedir": "/builds/slave/test", 22:15:27 INFO - "buildnumber": 5, 22:15:27 INFO - "slavename": "t-yosemite-r7-0061", 22:15:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:15:27 INFO - "platform": "yosemite_r7", 22:15:27 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:15:27 INFO - "branch": "fx-team", 22:15:27 INFO - "repo_path": "integration/fx-team", 22:15:27 INFO - "revision": "8dfd7c9d86397ed898d84933a3c39834f1db886f", 22:15:27 INFO - "stage_platform": "macosx64", 22:15:27 INFO - "builduid": "f5ed8adc79ea4350bad0dfc044fcbc90", 22:15:27 INFO - "slavebuilddir": "test" 22:15:27 INFO - } 22:15:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:15:27 INFO - Running post-action listener: _resource_record_post_action 22:15:27 INFO - ##### 22:15:27 INFO - ##### Finished read-buildbot-config step (success) 22:15:27 INFO - ##### 22:15:27 INFO - ##### 22:15:27 INFO - ##### Running download-and-extract step. 22:15:27 INFO - ##### 22:15:27 INFO - Running pre-action listener: _resource_record_pre_action 22:15:27 INFO - Running main action method: download_and_extract 22:15:27 INFO - mkdir: /builds/slave/test/build/tests 22:15: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')]} 22:15:27 INFO - https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:15:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:15:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:15:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:15:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 22:15:28 INFO - Downloaded 1199 bytes. 22:15:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:15:28 INFO - Using the following test package requirements: 22:15:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:15:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:15:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:15:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'jsshell-mac.zip'], 22:15:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:15:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:15:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:15:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:15:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:15:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:15:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:15:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:15:28 INFO - https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:15:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:15:29 INFO - Downloaded 18622344 bytes. 22:15:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:15:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:15:33 INFO - Return code: 0 22:15:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:15:33 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')]} 22:15:33 INFO - https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:15:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:15:34 INFO - Downloaded 18622344 bytes. 22:15:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:15:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:15:37 INFO - Return code: 0 22:15: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')]} 22:15:37 INFO - https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:15:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:15:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:15:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:15:38 INFO - Downloaded 10834034 bytes. 22:15:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:15:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:15:38 INFO - Return code: 0 22:15: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')]} 22:15:38 INFO - https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:15:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:15:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:15:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 22:15:41 INFO - Downloaded 110499153 bytes. 22:15:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:15:41 INFO - mkdir: /builds/slave/test/properties 22:15:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:15:41 INFO - Writing to file /builds/slave/test/properties/build_url 22:15:41 INFO - Contents: 22:15:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:15:43 INFO - Running post-action listener: _resource_record_post_action 22:15:43 INFO - Running post-action listener: set_extra_try_arguments 22:15:43 INFO - ##### 22:15:43 INFO - ##### Finished download-and-extract step (success) 22:15:43 INFO - ##### 22:15:43 INFO - ##### 22:15:43 INFO - ##### Running populate-webroot step. 22:15:43 INFO - ##### 22:15:43 INFO - Running pre-action listener: _resource_record_pre_action 22:15:43 INFO - Running main action method: populate_webroot 22:15:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:15:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:15:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:15:43 INFO - '--tppagecycles', 22:15:43 INFO - '7'], 22:15:43 INFO - 'tests': ['tsvgm']}}, 22:15:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:15:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:15:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:15:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:15:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['damp', 'tps']}, 22:15:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'tests': ['damp', 'tps']}, 22:15:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['dromaeo_dom']}, 22:15:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:15:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'other-e10s': {'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['a11yr', 22:15:43 INFO - 'ts_paint', 22:15:43 INFO - 'tpaint', 22:15:43 INFO - 'sessionrestore', 22:15:43 INFO - 'sessionrestore_no_auto_restore', 22:15:43 INFO - 'tabpaint']}, 22:15:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['tsvgx', 22:15:43 INFO - 'tsvgr_opacity', 22:15:43 INFO - 'tart', 22:15:43 INFO - 'tscrollx', 22:15:43 INFO - 'cart']}, 22:15:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:15:43 INFO - 'tsvgr_opacity', 22:15:43 INFO - 'tart', 22:15:43 INFO - 'tscrollx', 22:15:43 INFO - 'cart']}, 22:15:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'talos_options': ['--disable-e10s'], 22:15:43 INFO - 'tests': ['tp5o']}, 22:15:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'tests': ['tp5o']}, 22:15:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'talos_options': ['--disable-e10s', 22:15:43 INFO - '--xperf_path', 22:15:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:43 INFO - 'tests': ['tp5n']}, 22:15:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:43 INFO - 'talos_options': ['--xperf_path', 22:15:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:43 INFO - 'tests': ['tp5n']}}, 22:15:43 INFO - 'talos.zip': {'path': '', 22:15:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:15:43 INFO - Running post-action listener: _resource_record_post_action 22:15:43 INFO - ##### 22:15:43 INFO - ##### Finished populate-webroot step (success) 22:15:43 INFO - ##### 22:15:43 INFO - ##### 22:15:43 INFO - ##### Running create-virtualenv step. 22:15:43 INFO - ##### 22:15:43 INFO - Running pre-action listener: _resource_record_pre_action 22:15:43 INFO - Running main action method: create_virtualenv 22:15:43 INFO - Creating virtualenv /builds/slave/test/build/venv 22:15:43 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 22:15:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:15:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:15:43 INFO - Using real prefix '/tools/python27' 22:15:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:15:43 INFO - Installing distribute.............................................................................................................................................................................................done. 22:15:46 INFO - Installing pip.................done. 22:15:46 INFO - Return code: 0 22:15:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:46 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:15:46 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 22:15:46 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 22:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:15:46 INFO - 'HOME': '/Users/cltbld', 22:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:15:46 INFO - 'LOGNAME': 'cltbld', 22:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:46 INFO - 'MOZ_NO_REMOTE': '1', 22:15:46 INFO - 'NO_EM_RESTART': '1', 22:15:46 INFO - 'PAGER': '/bin/cat', 22:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:46 INFO - 'PWD': '/builds/slave/test', 22:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:15:46 INFO - 'SHELL': '/bin/bash', 22:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:15:46 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:15:46 INFO - 'USER': 'cltbld', 22:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:46 INFO - 'XPC_FLAGS': '0x0', 22:15:46 INFO - 'XPC_SERVICE_NAME': '0', 22:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:47 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 22:15:47 INFO - Cleaning up... 22:15:47 INFO - Return code: 0 22:15:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:47 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:15:47 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 22:15:47 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 22:15:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:15:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:15:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:15:47 INFO - 'HOME': '/Users/cltbld', 22:15:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:15:47 INFO - 'LOGNAME': 'cltbld', 22:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:47 INFO - 'MOZ_NO_REMOTE': '1', 22:15:47 INFO - 'NO_EM_RESTART': '1', 22:15:47 INFO - 'PAGER': '/bin/cat', 22:15:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:47 INFO - 'PWD': '/builds/slave/test', 22:15:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:15:47 INFO - 'SHELL': '/bin/bash', 22:15:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:15:47 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:15:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:15:47 INFO - 'USER': 'cltbld', 22:15:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:15:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:47 INFO - 'XPC_FLAGS': '0x0', 22:15:47 INFO - 'XPC_SERVICE_NAME': '0', 22:15:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:47 INFO - Downloading/unpacking psutil>=3.1.1 22:15:47 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 22:15:47 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 22:15:47 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 22:15:47 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 22:15:47 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 22:15:47 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 22:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:15:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:50 INFO - Installing collected packages: psutil 22:15:50 INFO - Running setup.py install for psutil 22:15:50 INFO - building 'psutil._psutil_osx' extension 22:15:50 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 22:15:50 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 22:15:50 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 22:15:51 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 22:15:51 INFO - building 'psutil._psutil_posix' extension 22:15:51 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 22:15:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:15:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:15:51 INFO - ^ 22:15:51 INFO - 1 warning generated. 22:15:51 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 22:15:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:51 INFO - Successfully installed psutil 22:15:51 INFO - Cleaning up... 22:15:51 INFO - Return code: 0 22:15:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:15: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')]} 22:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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')]} 22:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:15:51 INFO - 'HOME': '/Users/cltbld', 22:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:15:51 INFO - 'LOGNAME': 'cltbld', 22:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:51 INFO - 'MOZ_NO_REMOTE': '1', 22:15:51 INFO - 'NO_EM_RESTART': '1', 22:15:51 INFO - 'PAGER': '/bin/cat', 22:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:51 INFO - 'PWD': '/builds/slave/test', 22:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:15:51 INFO - 'SHELL': '/bin/bash', 22:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:15:51 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:15:51 INFO - 'USER': 'cltbld', 22:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:51 INFO - 'XPC_FLAGS': '0x0', 22:15:51 INFO - 'XPC_SERVICE_NAME': '0', 22:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:15:54 INFO - Installing collected packages: mozsystemmonitor 22:15:54 INFO - Running setup.py install for mozsystemmonitor 22:15:54 INFO - Successfully installed mozsystemmonitor 22:15:54 INFO - Cleaning up... 22:15:54 INFO - Return code: 0 22:15:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:15:54 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')]} 22:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:54 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')]} 22:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:54 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:15:54 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 22:15:54 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 22:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:15:54 INFO - 'HOME': '/Users/cltbld', 22:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:15:54 INFO - 'LOGNAME': 'cltbld', 22:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:54 INFO - 'MOZ_NO_REMOTE': '1', 22:15:54 INFO - 'NO_EM_RESTART': '1', 22:15:54 INFO - 'PAGER': '/bin/cat', 22:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:54 INFO - 'PWD': '/builds/slave/test', 22:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:15:54 INFO - 'SHELL': '/bin/bash', 22:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:15:54 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:15:54 INFO - 'USER': 'cltbld', 22:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:54 INFO - 'XPC_FLAGS': '0x0', 22:15:54 INFO - 'XPC_SERVICE_NAME': '0', 22:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:54 INFO - Downloading/unpacking blobuploader==1.2.4 22:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:57 INFO - Downloading blobuploader-1.2.4.tar.gz 22:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:15:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:15:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:58 INFO - Downloading docopt-0.6.1.tar.gz 22:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:15:58 INFO - Installing collected packages: blobuploader, requests, docopt 22:15:58 INFO - Running setup.py install for blobuploader 22:15:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:15:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:15:58 INFO - Running setup.py install for requests 22:15:59 INFO - Running setup.py install for docopt 22:15:59 INFO - Successfully installed blobuploader requests docopt 22:15:59 INFO - Cleaning up... 22:15:59 INFO - Return code: 0 22:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:15:59 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')]} 22:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:00 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:16:00 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 22:16:00 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 22:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:00 INFO - 'HOME': '/Users/cltbld', 22:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:00 INFO - 'LOGNAME': 'cltbld', 22:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:00 INFO - 'MOZ_NO_REMOTE': '1', 22:16:00 INFO - 'NO_EM_RESTART': '1', 22:16:00 INFO - 'PAGER': '/bin/cat', 22:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:00 INFO - 'PWD': '/builds/slave/test', 22:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:00 INFO - 'SHELL': '/bin/bash', 22:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:00 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:00 INFO - 'USER': 'cltbld', 22:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:00 INFO - 'XPC_FLAGS': '0x0', 22:16:00 INFO - 'XPC_SERVICE_NAME': '0', 22:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-duHuJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-N2AHiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-LZSJgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-h4zI6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ndh2Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9iy9pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:00 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ieBML6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-MVgwLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-OaK8AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zPNzET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-MVhmmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9I5oou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-XKUT9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-K0OHA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-WmiHpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9Kag2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:01 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-EZCx64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:16:02 INFO - Running setup.py install for manifestparser 22:16:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:16:02 INFO - Running setup.py install for mozcrash 22:16:02 INFO - Running setup.py install for mozdebug 22:16:02 INFO - Running setup.py install for mozdevice 22:16:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:16:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:16:02 INFO - Running setup.py install for mozfile 22:16:02 INFO - Running setup.py install for mozhttpd 22:16:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:16:02 INFO - Running setup.py install for mozinfo 22:16:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozInstall 22:16:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozleak 22:16:03 INFO - Running setup.py install for mozlog 22:16:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for moznetwork 22:16:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozprocess 22:16:03 INFO - Running setup.py install for mozprofile 22:16:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for mozrunner 22:16:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for mozscreenshot 22:16:04 INFO - Running setup.py install for moztest 22:16:04 INFO - Running setup.py install for mozversion 22:16:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:16:04 INFO - Cleaning up... 22:16:04 INFO - Return code: 0 22:16:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:04 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:16:04 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 22:16:04 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 22:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:04 INFO - 'HOME': '/Users/cltbld', 22:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:04 INFO - 'LOGNAME': 'cltbld', 22:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:04 INFO - 'MOZ_NO_REMOTE': '1', 22:16:04 INFO - 'NO_EM_RESTART': '1', 22:16:04 INFO - 'PAGER': '/bin/cat', 22:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:04 INFO - 'PWD': '/builds/slave/test', 22:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:04 INFO - 'SHELL': '/bin/bash', 22:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:04 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:04 INFO - 'USER': 'cltbld', 22:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:04 INFO - 'XPC_FLAGS': '0x0', 22:16:04 INFO - 'XPC_SERVICE_NAME': '0', 22:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:04 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-6AnEG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-IkVHvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-5X3hKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-BQ_3M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7AZ6TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-EtslQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-pnur4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-VIu9IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-3ChSFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-l5I92g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:05 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-boQ327-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-bAZYu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-0pLMOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-jim1bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-HBYj6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-pv5sYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:06 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-GhxE_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:09 INFO - Downloading blessings-1.6.tar.gz 22:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:16:09 INFO - Installing collected packages: blessings 22:16:09 INFO - Running setup.py install for blessings 22:16:09 INFO - Successfully installed blessings 22:16:09 INFO - Cleaning up... 22:16:09 INFO - Return code: 0 22:16:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:09 INFO - Reading from file tmpfile_stdout 22:16:09 INFO - Current package versions: 22:16:09 INFO - blessings == 1.6 22:16:09 INFO - blobuploader == 1.2.4 22:16:09 INFO - docopt == 0.6.1 22:16:09 INFO - manifestparser == 1.1 22:16:09 INFO - mozInstall == 1.12 22:16:09 INFO - mozcrash == 0.17 22:16:09 INFO - mozdebug == 0.1 22:16:09 INFO - mozdevice == 0.48 22:16:09 INFO - mozfile == 1.2 22:16:09 INFO - mozhttpd == 0.7 22:16:09 INFO - mozinfo == 0.9 22:16:09 INFO - mozleak == 0.1 22:16:09 INFO - mozlog == 3.2 22:16:09 INFO - moznetwork == 0.27 22:16:09 INFO - mozprocess == 0.22 22:16:09 INFO - mozprofile == 0.28 22:16:09 INFO - mozrunner == 6.11 22:16:09 INFO - mozscreenshot == 0.1 22:16:09 INFO - mozsystemmonitor == 0.3 22:16:09 INFO - moztest == 0.7 22:16:09 INFO - mozversion == 1.4 22:16:09 INFO - psutil == 3.1.1 22:16:09 INFO - requests == 1.2.3 22:16:09 INFO - wsgiref == 0.1.2 22:16:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:09 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 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:16:09 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 22:16:09 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 22:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:09 INFO - 'HOME': '/Users/cltbld', 22:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:09 INFO - 'LOGNAME': 'cltbld', 22:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:09 INFO - 'MOZ_NO_REMOTE': '1', 22:16:09 INFO - 'NO_EM_RESTART': '1', 22:16:09 INFO - 'PAGER': '/bin/cat', 22:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:09 INFO - 'PWD': '/builds/slave/test', 22:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:09 INFO - 'SHELL': '/bin/bash', 22:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:09 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:09 INFO - 'USER': 'cltbld', 22:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:09 INFO - 'XPC_FLAGS': '0x0', 22:16:09 INFO - 'XPC_SERVICE_NAME': '0', 22:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 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)) 22:16:10 INFO - Cleaning up... 22:16:10 INFO - Return code: 0 22:16:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:16: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', 'jsonschema'] in /builds/slave/test/build 22:16: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 jsonschema 22:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:10 INFO - 'HOME': '/Users/cltbld', 22:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:10 INFO - 'LOGNAME': 'cltbld', 22:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:10 INFO - 'MOZ_NO_REMOTE': '1', 22:16:10 INFO - 'NO_EM_RESTART': '1', 22:16:10 INFO - 'PAGER': '/bin/cat', 22:16:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:10 INFO - 'PWD': '/builds/slave/test', 22:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:10 INFO - 'SHELL': '/bin/bash', 22:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:10 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:10 INFO - 'USER': 'cltbld', 22:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:10 INFO - 'XPC_FLAGS': '0x0', 22:16:10 INFO - 'XPC_SERVICE_NAME': '0', 22:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:10 INFO - Downloading/unpacking jsonschema 22:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:16:12 INFO - Downloading/unpacking functools32 (from jsonschema) 22:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:16:13 INFO - warning: no files found matching '*.txt' 22:16:13 INFO - no previously-included directories found matching 'build' 22:16:13 INFO - no previously-included directories found matching 'dist' 22:16:13 INFO - no previously-included directories found matching '.git*' 22:16:13 INFO - Installing collected packages: jsonschema, functools32 22:16:13 INFO - Running setup.py install for functools32 22:16:13 INFO - warning: no files found matching '*.txt' 22:16:13 INFO - no previously-included directories found matching 'build' 22:16:13 INFO - no previously-included directories found matching 'dist' 22:16:13 INFO - no previously-included directories found matching '.git*' 22:16:13 INFO - Successfully installed jsonschema functools32 22:16:13 INFO - Cleaning up... 22:16:13 INFO - Return code: 0 22:16:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa60b4a9610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, '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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 22:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:13 INFO - 'HOME': '/Users/cltbld', 22:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:13 INFO - 'LOGNAME': 'cltbld', 22:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:13 INFO - 'MOZ_NO_REMOTE': '1', 22:16:13 INFO - 'NO_EM_RESTART': '1', 22:16:13 INFO - 'PAGER': '/bin/cat', 22:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:13 INFO - 'PWD': '/builds/slave/test', 22:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:13 INFO - 'SHELL': '/bin/bash', 22:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:13 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:13 INFO - 'USER': 'cltbld', 22:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:13 INFO - 'XPC_FLAGS': '0x0', 22:16:13 INFO - 'XPC_SERVICE_NAME': '0', 22:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:13 INFO - Downloading/unpacking flake8 22:16:13 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 22:16:13 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 22:16:13 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 22:16:13 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 22:16:13 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 22:16:13 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 22:16:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:16:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:16:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:16 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 22:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:16:17 INFO - no previously-included directories found matching 'docs/_build' 22:16:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:16:17 INFO - Running setup.py install for pep8 22:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:16:17 INFO - no previously-included directories found matching 'docs/_build' 22:16:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:16:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:16:17 INFO - Cleaning up... 22:16:17 INFO - Return code: 0 22:16:17 INFO - Running post-action listener: _resource_record_post_action 22:16:17 INFO - Running post-action listener: _start_resource_monitoring 22:16:17 INFO - Starting resource monitoring. 22:16:17 INFO - ##### 22:16:17 INFO - ##### Finished create-virtualenv step (success) 22:16:17 INFO - ##### 22:16:17 INFO - ##### 22:16:17 INFO - ##### Running install step. 22:16:17 INFO - ##### 22:16:17 INFO - Running pre-action listener: _resource_record_pre_action 22:16:17 INFO - Running main action method: install 22:16:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:18 INFO - Reading from file tmpfile_stdout 22:16:18 INFO - Detecting whether we're running mozinstall >=1.0... 22:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:16:18 INFO - Reading from file tmpfile_stdout 22:16:18 INFO - Output received: 22:16:18 INFO - Usage: mozinstall [options] installer 22:16:18 INFO - Options: 22:16:18 INFO - -h, --help show this help message and exit 22:16:18 INFO - -d DEST, --destination=DEST 22:16:18 INFO - Directory to install application into. [default: 22:16:18 INFO - "/builds/slave/test"] 22:16:18 INFO - --app=APP Application being installed. [default: firefox] 22:16:18 INFO - mkdir: /builds/slave/test/build/application 22:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:16:52 INFO - Reading from file tmpfile_stdout 22:16:52 INFO - Output received: 22:16:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:16:52 INFO - Running post-action listener: _resource_record_post_action 22:16:52 INFO - ##### 22:16:52 INFO - ##### Finished install step (success) 22:16:52 INFO - ##### 22:16:52 INFO - ##### 22:16:52 INFO - ##### Running run-tests step. 22:16:52 INFO - ##### 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: run_tests 22:16:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:16:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:16:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:16:52 INFO - 2016-05-25 22:16:52.052 screenresolution[1641:7573] starting screenresolution argv=screenresolution get 22:16:52 INFO - 2016-05-25 22:16:52.054 screenresolution[1641:7573] Display 0: 1600x1200x32@60 22:16:52 INFO - 2016-05-25 22:16:52.060 screenresolution[1642:7575] starting screenresolution argv=screenresolution list 22:16:52 INFO - Available Modes on Display 0 22:16:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:16:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:16:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:16:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:16:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:16:52 INFO - Intel Iris: 22:16:52 INFO - Chipset Model: Intel Iris 22:16:52 INFO - Type: GPU 22:16:52 INFO - Bus: Built-In 22:16:52 INFO - VRAM (Dynamic, Max): 1536 MB 22:16:52 INFO - Vendor: Intel (0x8086) 22:16:52 INFO - Device ID: 0x0a2e 22:16:52 INFO - Revision ID: 0x0009 22:16:52 INFO - Displays: 22:16:52 INFO - Display: 22:16:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:16:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:16:52 INFO - Display Serial Number: 1600x1200 60 22:16:52 INFO - Main Display: Yes 22:16:52 INFO - Mirror: Off 22:16:52 INFO - Online: Yes 22:16:52 INFO - Rotation: Supported 22:16:52 INFO - Return code: 0 22:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:16:52 INFO - Python 2.7.3 22:16:52 INFO - Return code: 0 22:16:52 INFO - grabbing minidump binary from tooltool 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edded30>, '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 0x10ec02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edd5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:16:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:16:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:16:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:16:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:16:52 INFO - Return code: 0 22:16:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:16:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:16:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:16:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:16:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:16:53 INFO - Return code: 0 22:16:53 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/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 22:16:53 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/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --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 22:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8MOUmYcO7/Render', 22:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:53 INFO - 'HOME': '/Users/cltbld', 22:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:53 INFO - 'LOGNAME': 'cltbld', 22:16:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:16:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:53 INFO - 'MOZ_NO_REMOTE': '1', 22:16:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:16:53 INFO - 'NO_EM_RESTART': '1', 22:16:53 INFO - 'PAGER': '/bin/cat', 22:16:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:53 INFO - 'PWD': '/builds/slave/test', 22:16:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:53 INFO - 'SHELL': '/bin/bash', 22:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8QnCOezQmJ/Listeners', 22:16:53 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 22:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:53 INFO - 'USER': 'cltbld', 22:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:53 INFO - 'XPC_FLAGS': '0x0', 22:16:53 INFO - 'XPC_SERVICE_NAME': '0', 22:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:53 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/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 22:16:53 INFO - mozversion application_buildid: 20160525201649 22:16:53 INFO - mozversion application_changeset: 8dfd7c9d86397ed898d84933a3c39834f1db886f 22:16:53 INFO - mozversion application_display_name: Nightly 22:16:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:16:53 INFO - mozversion application_name: Firefox 22:16:53 INFO - mozversion application_remotingname: firefox 22:16:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:16:53 INFO - mozversion application_vendor: Mozilla 22:16:53 INFO - mozversion application_version: 49.0a1 22:16:53 INFO - mozversion platform_buildid: 20160525201649 22:16:53 INFO - mozversion platform_changeset: 8dfd7c9d86397ed898d84933a3c39834f1db886f 22:16:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:16:53 INFO - mozversion platform_version: 49.0a1 22:16:53 INFO - using testdate: 1464239813 22:16:53 INFO - actual date: 1464239813 22:16:53 INFO - starting webserver on 'localhost:49275' 22:16:53 INFO - SUITE-START | Running 2 tests 22:16:53 INFO - TEST-START | dromaeo_css 22:16:53 INFO - operating with platform_type : mac_ 22:16:53 INFO - Initialising browser for dromaeo_css test... 22:16:53 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp2PcGbf/profile http://localhost:49275/getInfo.html) 22:16:57 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 22:16:57 INFO - PROCESS | 1660 | colorDepth:24 22:16:57 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 22:16:57 INFO - PROCESS | 1660 | __metrics 22:16:57 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:16:57 INFO - TEST-INFO | 1660: exit 0 22:16:57 INFO - Browser initialized. 22:16:57 INFO - Running cycle 1/1 for dromaeo_css test... 22:16:57 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp2PcGbf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:17:09 INFO - PROCESS | 1663 | RSS: Main: 252305408 22:17:09 INFO - PROCESS | 1663 | 22:17:59 INFO - PROCESS | 1663 | 1464239879581 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] 22:20:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:20:10 INFO - PROCESS | 1663 | RSS: Main: 264007680 22:20:10 INFO - PROCESS | 1663 | 22:23:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 22:23:13 INFO - PROCESS | 1663 | RSS: Main: 275632128 22:23:13 INFO - PROCESS | 1663 | 22:26:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 22:26:16 INFO - PROCESS | 1663 | RSS: Main: 277790720 22:26:16 INFO - PROCESS | 1663 | 22:29:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:29:18 INFO - PROCESS | 1663 | RSS: Main: 279396352 22:29:18 INFO - PROCESS | 1663 | 22:32:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:32:22 INFO - PROCESS | 1663 | RSS: Main: 282312704 22:32:22 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:35:24 INFO - PROCESS | 1663 | RSS: Main: 283471872 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | __start_tp_report 22:35:24 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 22:35:24 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 22:35:24 INFO - PROCESS | 1663 | |i|pagename|runs| 22:35:24 INFO - PROCESS | 1663 | |0;dojo.html;3442.3459542686887;3460.15226269564;3472.8958131256054;3478.1652403423172;3489.057954352325;5584.1902924075985;5652.321721393433;5688.687122208277;5782.421757824215;5822.679752613604;4725.787339569719;4829.565339119478;5460.153676180188;5587.413321601224;5721.399253078419;5140.331756871601;5173.663711858736;5288.338957630296;5328.6802791832615;5351.384590772072;206.522717498925;5137;5227.18978558324;5253.605979551549;5305.840824775243;4204.537500874917;5183.144781111112;5201.29782479368;5217.973910130463;5223.791048358061;5226.216067589854;5232.502912223333;5242.816501422432;5252.343457067866;5269.051570717269;4230.534641189467;5096.949030509684;5136.974315128437;5174.870628234286;5215.191645294985;5498.615096943216;5616.859578510529;5623.212750214973;5654.717264136777;5675.659460432346;6107.992181290082;6201.224846894138;6241.968790156066;6259.687015649245;6295.338989406138;6146.907796383058;6213.813585592439;6216.751329946796;6270.153529273535;6282.2461304643275;5811.418858114197;5812.099124635688;5820.068788993783;5825.475707186373;5865.296164460292;6112.786052488231;6188.938110618837;6288.213973253343;6288.52836037299;6294.433500984842;4962.255661650781;6160.630362178284;6162.630242185483;6168.0439531872635;6176.382361763787;6212.347703491158;6222.0355844844125;6234.064890266496;6249.96875015622;6252.343503932113;6082.878342433215;6144.126041557368;6151.6001459904965;6214.223222097238;6214.937850621526;6125.1731016313115;6125.632462052291;6158.7228574714245;6173.536984726164;6186.907196392058;4805.711657300573;4881.219004959189;4884.975575122102;4893.877653058631;4928.7289199093675;4339.654707040764;4362.323839804835;4394.626456751212;4408.9118217636105;4425;4373.803178856959;4776.904461910812;5659.434056594308;5734.856628584319;5853.268341457318;5194.896102077937;6251.624902505864;6261.7182226799905;6265.71804268809;6303.905441418382;5685.77256909719;5751.907137643834;5762.9135562966585;5854.268216472941;5874.794382196688;5423.430539793344;5432.456754324536;5438.7280635968045;5445.863853403696;5453.509184173444;6471.320511346335;6472.967635161794;6481.805545833632;6486.027095935648;6527.8694426111215;6084.361142080018;6111.95640283616;6133.610107582569;6171.84570385744;6207.099970504342;6430.582012169194;6458.063580780667;6464.191976003;6471.158749362553;6494.6103233806125;6206.937930620636;6243.250809902841;6253.530985176221;6254.843628909313;6268.153799237227;6119.502553941335;6146.078088286793;6179.721912514037;6209.968950155401;6264.404881536247;5552.7778888843995;6115.510759139347;6118.877622447437;6198.318184999556;6212.223472065992;5929.822105336847;6107.786227482195;6139.938600613937;6179.969100154471;6183.96908015475;4447.555244475527;5944.940550594439;5956.285245770535;5970.402959703995;5981.342052374148;5978.671173085522;6175.351588083367;6177.413145751326;6178.845528861814;6204.069389591416;5785.681787501728;6000.8799824004145;6044.758209671564;6049.667268300285;6079.240094988127 22:35:24 INFO - PROCESS | 1663 | |1;ext.html;481.85785193367974;493.61744647897876;499.57536094319835;511.88482591416926;576.7491141353509;38949.415758763644;41770.16459670805;41878.79060604697;41889.79055104728;41965;35317.470237946385;43884;43953.78023109888;43987.78006109973;43988.78005609968;9615.567299471522;9824.607015719363;9840.409575425461;9843;9926.851097233548;9741.659041933533;9792.363496372749;9822.557984890696;9847.950760246189;9862.50687465624;9750.512474376254;9811.50942452878;9827.65603203888;9835.606575736962;9969.700908972742;8610.009848867398;9488.478133702643;9775.413475191512;9809.068138526864;9849.704508864745;9661.758456038586;9679.370840895319;9769.51152442375;9794.853077203848;9803.950980245054;40193.19613607712;40513;40603.59396406057;40668.59331406678;40749.77750667455;2715.334742987961;3012.8041677290908;3057.6942305769467;3226.7418606511424;3268;19647.508812279666;23047.654285185734;28535.715892784876;28786.70459829292;29742;2296.219285442941;2430;2607.778338841201;2681.8659067046765;2777.5139350613563;418867;460736;464149;468336;472490;31611.89358372492;33854.8307258467;34069.659303406646;34763.30473390569;35474.822625887224;32079.51880721791;35989;36086.639133608856;36357;36387.81806091005;11891.940540297242;12064.672885982534;13004.154729942524;13023.47906083765;13027.869721302666;12368.1960672556;12795.61613151607;12827.551035713892;12830.294333811544;12881.677958051123;7162.283771622796;7399.186089530147;7442.06974128234;7486.588237646982;7524.699012039458;2711.620373147761;3266.3304022675293;3268.346330733863;3296.5549650796834;3342.5487559179687;928.8188803183449;962.4898803634085;963.325672029582;985.2905907746411;1039.7660526381655;26042.479150416886;28591.428171436455;29492.410151796845;29524.704752952195;29759.702402975694;1037.061459379263;1060.565168281001;1061.2147011211648;1106.773111512138;1161.4134861894859;15225.923870380577;15294.847051529343;15364.308606112752;15581.454649087455;16065.598360041093;693138;719310;723773;727033;1041903;62467;62493.37506624967;62617.43456413535;63254.41863953438;63296;1411.470698488067;1420.4673247532176;1422.7439060969125;1438.5900018494679;1441.128117488925;30136.698633013388;30161.547576785928;30847.383052339275;31003.68996310008;31431;58743.82512349814;59355;59467.702661486415;59540.70229648824;59545.702271488364;654229;677476;678299;679157;680484;41719.7914010428;43344.56655433405;43492.13015739731;43933;44204.77897610491;44976;46645.30032049454;46701.53298467108;47689.76155119202;49145.50854491409;37761.81119094387;37918.24163516659;37954.81022594869;38293;38449.92400607889;37571.436428453046;37809.810950945066;38051;38449;38802.22395552017;30086.849565752033;32389.19027024343;37335.81332093322;38320;38354.61645383511;31749;33670;34020.65979340274;34127;34312;24873.51264873498;25149.497010060062;25206.74793252044;25418.872905635355;26768.929242830847 22:35:24 INFO - PROCESS | 1663 | |2;jquery.html;320.96950789674975;357.45309676195416;379.1261143065235;397.8388752555216;407.05766151359603;19892.502687432803;20285.290014849485;21196.576068478622;21213.78786212141;21283.893580532076;16864.83135168648;20057.398278051645;20671.896640516814;20702.89648551755;21209;5246.475352464761;5402.108652072403;5418.241446197536;5554.91667624986;5957.106434034886;5326.866828329284;5492.917606235909;5873.500752436027;5927.792527261547;5941.494972927285;6638.966805165992;6676.19885613727;6695.832604184886;6709.496787740914;6712;6048.1834952281315;6079.361667024943;6096.51227901769;6575.704093315789;6658.3674550917585;5794.333651630074;5842.591018628697;5867.031939729939;5873.676947767914;5888.175655408247;13482.730345393134;16142.75785863213;19278.421647350602;25797.74202257972;26272.605910911352;1719.6646653902494;1937.2928880958489;2004.6924653262358;2026.9763769296558;2083.125087463269;9883;10202.132818710419;10274.383536987805;10286.639967601099;10333.276670633059;1740.3299729604066;1773.9379121730678;1776.4581802550217;1778.36867911892;1798.6614612381452;76454.47091058148;110415.44792275988;121820;122441;123193.38403308016;11290.379029153475;11658.41707914601;11675.416229188506;11686.76626467466;12012.339321337235;14959.92520037393;14961.551153465414;14963.476278330423;14975.775363369557;14976.70046599062;7188.6046267455795;8769.736907892773;8807.32660794447;8834.337424693174;8849;7200.739870522742;8601.483910965362;8763.123687631185;8856.114388561093;8875.778105547284;4483.125790470827;4636.814527418933;4663.930041049387;4669.322948172496;4768.499307572655;1840.0431775476945;1933.661609218381;1999.9300723750848;2015.4256037029377;2024.2409096588779;849.1254008371387;857.4683696108452;914.2548822709471;916.9312301577409;933.3186773655134;16794.32822687103;18374.724379134324;20127.798722012885;20317;20461.079251433723;887.9023307436177;897.2059727141527;907.7775944893543;940.3699521320732;947.4268067818871;9527.475988820543;10464.21518386124;10694;10746.462676866126;10748.247622666495;572053;581491;586931;598289;603200;65627;126397;132102;132113;139805.90291145438;947.5404428852002;949.9192568631605;976.9071938165721;991.2268430624125;992.5880759484733;17889.552761180556;18118.365857194935;18120.818791812442;18163.18265678074;18518.629627407645;51163.48836511736;51470.48529514657;51844.740776295876;52403.73798131137;52642.94714105773;53538;64180.35819641931;68534.62930741257;76317;78372.8244076328;5704.486596206361;7632.923670763222;7988.680452782056;8099;8142.47073940192;11272.492737826653;12548.82763719191;12762.808557871454;12828.935855320664;12934.288614126313;10820.837687434538;10832.620858269922;10863.185261105291;10863.511141998471;10940.726481838017;9464.574094165642;10476.952304769462;10884;10981.39602321848;11298.661040168809;11053.06048985813;11228.719282018015;11279.718007049889;11510.53957841677;11595.5361785531;5980.312264089685;7539.095308563007;8016.238457346542;8039.337184432898;8156.551389673625;9803.509824508888;10989.670309890716;11211.271267367594;11274.549018039186;11353.091752659933 22:35:24 INFO - PROCESS | 1663 | |3;mootools.html;1560.118533028839;1623.212741820217;1838.4852241372412;1914.5979344337688;1915.4636701723523;8017.919820801791;8594.097619749922;8644.17880301372;9090.272778177754;9103.499307538083;7391.8152046198875;8727.345449091328;8874.20132188103;8878.112188781119;8906.732798016053;2296.4258935266225;2310.8844557772127;2321.0947730385165;2334.3580515358326;2336.4626135988733;2330.592146374386;2355.646653002046;2415.4444477770126;2415.6738840256517;2424.830261881669;2159.8056174944254;2294.8967296471615;2372.1578839511985;2373.1219448803968;2377.1679912030822;1992.7110568967491;2067.224790703486;2374.6200607902824;2388.948862500503;2402.6396040593963;2002.7196192533056;2369.8104151667826;2378.6432035194684;2382.5354055959097;2385.1651921827397;8858.822823543493;8895.866562001574;8921.464712117293;8922.68770593026;8941.46351218929;5316.494932981361;5441.102218134003;5463.726813659301;5464.644798088111;5482.17767334899;2505.5615267328144;2649.9337516562045;3204.535342375366;3290.7696461247724;3321.8173000484962;908.3777612335505;926.4163576946548;994.7612572982432;1001.9649312274036;1019.3883669798062;9893.30746847721;11724;11822.527098916033;11834.869723449776;11839.28964262147;4772.229968095813;4782.019685964369;4890.339804126468;4906.6320025997475;4928.753562321869;9975.850362244571;9994.800103997879;10004.849927251096;10007.749806254902;10014.699559013241;5372.355317361941;5398.163284690879;5420.512153906167;5452.073147564924;5459.208414779835;4208.758416267208;4241.384999175102;4838.758062096881;4975.129352363322;4981.053599816096;4030.972102113942;4056.472658554369;4099.016236103374;4162.62536371728;4203.5376108628025;1132.3828513460019;1285.9807102893465;1301.1087405127519;1330.2683524061727;1343.3887581150552;745.7725393754957;765.5789315876245;772.1082150116624;822.8313195794848;894.5124906925611;5819.970900145557;5862.794802181903;5944.405559444021;6058.787942421994;6135.171751813448;939.5818860606964;984.0012387426767;986.7483791633231;989.7426669066094;1005.1556692378438;3132.9373412531622;3762.795905310329;3881.4177873319227;3939.5469521005452;3969.6824254059607;9926.503674816231;10204.642837500653;10302.33034852732;10391.792164156675;10419;8833.514156721314;10164.4917754112;10223.028812262823;10345.172386209235;10347.4308913009;7820.648070836826;8180.754577362689;8191.1808819118805;8312.418130730912;8366.665333386596;5768.971155144198;5876.324222714443;5930.733117009659;5945.286565612154;6000.909986350295;9869.605215791287;10016;10056.64801731936;10099.596016159272;10104.343217690826;9875;10117.747056323356;10250.38497690141;10282.280240383101;10338.586456541654;6975.232724400211;7420.812669972778;7533.8116547086765;7639.236076392316;7712.8457430852195;8432.283255923316;8456.577171141294;8475.152484751723;8516.977962644522;8553.44402613853;6196.318404975539;7339.926600733925;7400;7548.320651141424;7548.584827834302;7306;7471.103467583925;7673.769786906401;7686.961565192362;7706.7687969361;6799.354061364163;6867.07294515234;6883.690233939385;6923.5499692519825;6958.478114141561;4905.852824415273;4984.675996060245;4995.850124496271;4996.450390457119;5183.248428977852;4403.471583410012;4993.825216117419;5159.251908473176;5181.119209734279;5312.096943519534 22:35:24 INFO - PROCESS | 1663 | |4;prototype.html;298.2365145228217;307.17982985428904;311.9438501069808;315.8657570532523;318.5842475569381;5228.5555727763185;5307.65500242484;5453.809116680917;5458.0351073613465;5556.277294212877;5178.896422071556;5505.311836020497;5513.283273174492;5515.61390702651;5543.253875029363;4067.450894129299;4140.275451795938;4167.229062623409;4183.351580505019;4189.958100418988;3822.617738226183;3956.4263181838614;4131.132462182945;4165.875403544199;4196.880575309465;3540.8583656653705;4103.322951712964;4151.564085770996;4166.145940082276;4180.749155050707;4165.83336666533;4174.18603372344;4186.581341865789;4203.873883783492;4211.747295162269;2996.5954596129463;3470.6355832637714;3568.429051351797;3593.3531964246426;3597.80212088335;4392.626626736731;4945.727984960826;5286.709230992294;5303.893922121536;5325.828317770084;4042.090529630839;4396.384506169139;4435.737773735386;4763.976180119077;4790.832320868788;5781.277340332458;5808.332041815203;5827.562932780059;5843.649381037151;5845.901672113787;3287.474004159347;3315.966840331614;3899.473571067898;3933.2133573285173;3950.2889479893606;5154.639175257734;5826.76692932278;6155.415235552616;6180.415359091761;6238.189035425366;3955.149642826806;3955.248502784462;3958.5843486434087;3962.049108214008;4007.6593489553134;3344.8125915300207;3418.7265018798435;3551.3430015447225;3862.356746396194;4038.212548553004;3923.9607603923596;4231.830726770956;4451.888702782457;4714.292856071548;4720.740359280204;5456.61803673747;5516.889662206813;5910.202122713465;5923.318818335946;5936.8812623748145;1281.4810001949224;1286.6268782053287;1328.8538260791306;1371.8971077169053;1379.3379177994418;735.797655644702;812.9187081291824;829.9336053115736;832.966681332752;842.3429724814654;3817.5609804872797;3976.5029371328583;4034.2536630722825;4581.885452863705;4598.931016034762;4414.713043652152;4417.690761646718;4571.679982401267;5012.448630650625;5071.435707862953;4502.739233014761;4525.434320709911;4546.613537849254;4548.407407777601;4583.6539326291995;2487.8507289562685;2613.4250464898073;2634.315078079713;2672.839629622229;2683.8926442942065;4798.640101992364;4931.260310953386;5051.2423136529815;5200.713960732196;5319.893602128013;4598.8620341389815;4629.990506171016;4806.110869489155;4877.463479017305;4988.251762235694;5586;5638.323401191883;5848.824535263949;6148.077788331786;6293.590916590407;6032.849178770566;6036.396360363929;6070.48400885912;6085.72199838039;6105.572609917263;5275.287836142072;5316.441773613715;5636.943630563642;5946.28644562638;5958.97394885741;5540.922154192178;6294.464970477561;6296.411045703905;6333.8733225336155;6402.079376187218;3380;3525.4359302512503;3911.589283125231;3964;3964.9096498462;4782.880427989328;5422.539084177889;6032.879342413039;6043.00290452088;6068.423499767515;5700.942990570041;5856.736446859987;5859.824205273849;5921;5935.904226083988;5413.079776437936;5826.70866456684;5851.624868156065;5877.647341159544;5895.67573783446;5520;5585.441455854382;5704.176432665857;5743.62666426681;5765.221695071279;5802.6808525531505;5806.564507662027;5809.680467574324;5838.474537291664;5839.737211825393;4378.693491455695;5150.201718733602;5502.119660854244;5708.372079071381;5733.971330143323 22:35:24 INFO - PROCESS | 1663 | |5;yui.html;617.78135911899;694.1184695436792;710.3358359933462;748.1994266135234;754.3173428047616;11927.64217073487;12688.984881209504;14587.927060364696;14711.190884501348;15229.923850380761;14175.724184823364;14364.569062928103;14371.922105842077;14373.281335933214;14386.712265754679;724.8767709489399;895.9596818143167;899.8470260055806;922.3267015078997;924.7179610218881;868.7914900423884;884.7345796261128;899.1727610598233;903.5121034641286;910.0671811393308;942.4109931292942;942.839717248066;943.6791490893129;944.0983860413289;947.0009140356921;910.1762904571374;912.2610685344858;934.5514153206431;936.541094863512;940.1726480697012;692.0553444548206;696.2202333386589;699.6291965258421;700.8002719224996;703.4829400390709;14677.77983330251;14877.553673389815;14899.553013409615;14910.956233063689;14970.550883473514;12458.81311780324;12574.11981161319;12579.182353147016;12852.807207891889;12935.87064129356;7636;9911.454869982148;9965.451900145488;10148.289619726622;10246.897531024744;3043.223977885625;3396.320735852815;3461.930761384783;3481.669241422061;3494;85713.57143214245;86688;86718.56640716756;86924.56537717271;87335.68996465058;9362.204212641866;9400.95299523498;9441.858372124423;9710.417374957384;9714.951425242829;21518.569628607653;21695.02372393246;21950.341489755334;21957.341279761633;22026.559468810534;7260.128784545888;7329.267073292628;7356.595387253752;7420.740274090489;7492.587907665022;3926.450296958428;4015.5382131055303;4032.9395059074136;4038.394240863835;4038.5355684096116;3002.504586743207;3451.240727040047;3558.5551806024246;3561.6438356164176;3611.855525778992;517.4489169034965;626.996865015672;632.8259728574678;633.1673848888696;636.7357546618101;485.42719590882393;547.7179252684871;563.3633993587302;600.1148306248249;607.8237311179797;7564.281393267631;8033.879491807627;8074.3944204183745;8121.066077401175;8177.7291816803045;480.0998128509045;495.13598770146325;552.3592632546228;570.2273419516579;571.6027360984076;3722.3671975764187;3729.9627003729615;3730.347189241872;3770.6606405423095;3949.624785645359;94580;94761.57857632141;95989;97061.51469242609;97993;84543.5772821132;84728.72906906401;84890.57554712187;85491;88597.11402885888;72775.63612181906;73179.53640927258;73570;74002.62998684972;74510.62744686242;6818.147731533559;7047.577145371294;7433;7501.399888009056;7606.239376062349;33597.80820822018;35594.64405355913;40243.597564023985;47343.52656473391;47554.52445475639;40666.79666601648;50110.749446252536;56358.7182064087;58680.826383472944;59875.70062149662;10919.454027298762;10949.397783122005;11018.50416731233;11019.173561983043;11040.668779936615;11700.180987330805;11700.2979821211;11809.055275578105;11815.822762658396;11824.70438239051;11234.55061797519;11250.268732532359;11392.715182120182;11658.825117623073;11677.474513647076;11301.547938082385;11391.54433822671;11399.772004559696;11734.530618775494;11789.174757766874;10976.039551864627;11003.669889903316;11039.503222354853;11173.05029072538;11355.829662555234;9152.130547597968;9253.537323133682;9361.391509551857;9412;9604.567794449127;8238.134995825352;8336.958315208385;8426.536540490331;8535.829283414421;9459.716208513755 22:35:24 INFO - PROCESS | 1663 | __end_tp_report 22:35:24 INFO - PROCESS | 1663 | __start_cc_report 22:35:24 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,418 22:35:24 INFO - PROCESS | 1663 | __end_cc_report 22:35:24 INFO - PROCESS | 1663 | __startTimestamp1464240924745__endTimestamp 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | ------- Summary: start ------- 22:35:24 INFO - PROCESS | 1663 | Number of tests: 6 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5667.65 Median:5956.29 stddev:807.58 (13.6%) stddev-sans-first:790.96 22:35:24 INFO - PROCESS | 1663 | Values: 3442.3 3460.2 3472.9 3478.2 3489.1 5584.2 5652.3 5688.7 5782.4 5822.7 4725.8 4829.6 5460.2 5587.4 5721.4 5140.3 5173.7 5288.3 5328.7 5351.4 206.5 5137.0 5227.2 5253.6 5305.8 4204.5 5183.1 5201.3 5218.0 5223.8 5226.2 5232.5 5242.8 5252.3 5269.1 4230.5 5096.9 5137.0 5174.9 5215.2 5498.6 5616.9 5623.2 5654.7 5675.7 6108.0 6201.2 6242.0 6259.7 6295.3 6146.9 6213.8 6216.8 6270.2 6282.2 5811.4 5812.1 5820.1 5825.5 5865.3 6112.8 6188.9 6288.2 6288.5 6294.4 4962.3 6160.6 6162.6 6168.0 6176.4 6212.3 6222.0 6234.1 6250.0 6252.3 6082.9 6144.1 6151.6 6214.2 6214.9 6125.2 6125.6 6158.7 6173.5 6186.9 4805.7 4881.2 4885.0 4893.9 4928.7 4339.7 4362.3 4394.6 4408.9 4425.0 4373.8 4776.9 5659.4 5734.9 5853.3 5194.9 6251.6 6261.7 6265.7 6303.9 5685.8 5751.9 5762.9 5854.3 5874.8 5423.4 5432.5 5438.7 5445.9 5453.5 6471.3 6473.0 6481.8 6486.0 6527.9 6084.4 6112.0 6133.6 6171.8 6207.1 6430.6 6458.1 6464.2 6471.2 6494.6 6206.9 6243.3 6253.5 6254.8 6268.2 6119.5 6146.1 6179.7 6210.0 6264.4 5552.8 6115.5 6118.9 6198.3 6212.2 5929.8 6107.8 6139.9 6180.0 6184.0 4447.6 5944.9 5956.3 5970.4 5981.3 5978.7 6175.4 6177.4 6178.8 6204.1 5785.7 6000.9 6044.8 6049.7 6079.2 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:74540.98 Median:38449.46 stddev:176812.21 (459.9%) stddev-sans-first:177220.78 22:35:24 INFO - PROCESS | 1663 | Values: 481.9 493.6 499.6 511.9 576.7 38949.4 41770.2 41878.8 41889.8 41965.0 35317.5 43884.0 43953.8 43987.8 43988.8 9615.6 9824.6 9840.4 9843.0 9926.9 9741.7 9792.4 9822.6 9848.0 9862.5 9750.5 9811.5 9827.7 9835.6 9969.7 8610.0 9488.5 9775.4 9809.1 9849.7 9661.8 9679.4 9769.5 9794.9 9804.0 40193.2 40513.0 40603.6 40668.6 40749.8 2715.3 3012.8 3057.7 3226.7 3268.0 19647.5 23047.7 28535.7 28786.7 29742.0 2296.2 2430.0 2607.8 2681.9 2777.5 418867.0 460736.0 464149.0 468336.0 472490.0 31611.9 33854.8 34069.7 34763.3 35474.8 32079.5 35989.0 36086.6 36357.0 36387.8 11891.9 12064.7 13004.2 13023.5 13027.9 12368.2 12795.6 12827.6 12830.3 12881.7 7162.3 7399.2 7442.1 7486.6 7524.7 2711.6 3266.3 3268.3 3296.6 3342.5 928.8 962.5 963.3 985.3 1039.8 26042.5 28591.4 29492.4 29524.7 29759.7 1037.1 1060.6 1061.2 1106.8 1161.4 15225.9 15294.8 15364.3 15581.5 16065.6 693138.0 719310.0 723773.0 727033.0 1041903.0 62467.0 62493.4 62617.4 63254.4 63296.0 1411.5 1420.5 1422.7 1438.6 1441.1 30136.7 30161.5 30847.4 31003.7 31431.0 58743.8 59355.0 59467.7 59540.7 59545.7 654229.0 677476.0 678299.0 679157.0 680484.0 41719.8 43344.6 43492.1 43933.0 44204.8 44976.0 46645.3 46701.5 47689.8 49145.5 37761.8 37918.2 37954.8 38293.0 38449.9 37571.4 37809.8 38051.0 38449.0 38802.2 30086.8 32389.2 37335.8 38320.0 38354.6 31749.0 33670.0 34020.7 34127.0 34312.0 24873.5 25149.5 25206.7 25418.9 26768.9 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33773.46 Median:368.29 stddev:98010.95 (26612.5%) stddev-sans-first:98253.71 22:35:24 INFO - PROCESS | 1663 | Values: 321.0 357.5 379.1 397.8 407.1 19892.5 20285.3 21196.6 21213.8 21283.9 16864.8 20057.4 20671.9 20702.9 21209.0 5246.5 5402.1 5418.2 5554.9 5957.1 5326.9 5492.9 5873.5 5927.8 5941.5 6639.0 6676.2 6695.8 6709.5 6712.0 6048.2 6079.4 6096.5 6575.7 6658.4 5794.3 5842.6 5867.0 5873.7 5888.2 13482.7 16142.8 19278.4 25797.7 26272.6 1719.7 1937.3 2004.7 2027.0 2083.1 9883.0 10202.1 10274.4 10286.6 10333.3 1740.3 1773.9 1776.5 1778.4 1798.7 76454.5 110415.4 121820.0 122441.0 123193.4 11290.4 11658.4 11675.4 11686.8 12012.3 14959.9 14961.6 14963.5 14975.8 14976.7 7188.6 8769.7 8807.3 8834.3 8849.0 7200.7 8601.5 8763.1 8856.1 8875.8 4483.1 4636.8 4663.9 4669.3 4768.5 1840.0 1933.7 1999.9 2015.4 2024.2 849.1 857.5 914.3 916.9 933.3 16794.3 18374.7 20127.8 20317.0 20461.1 887.9 897.2 907.8 940.4 947.4 9527.5 10464.2 10694.0 10746.5 10748.2 572053.0 581491.0 586931.0 598289.0 603200.0 65627.0 126397.0 132102.0 132113.0 139805.9 947.5 949.9 976.9 991.2 992.6 17889.6 18118.4 18120.8 18163.2 18518.6 51163.5 51470.5 51844.7 52403.7 52642.9 53538.0 64180.4 68534.6 76317.0 78372.8 5704.5 7632.9 7988.7 8099.0 8142.5 11272.5 12548.8 12762.8 12828.9 12934.3 10820.8 10832.6 10863.2 10863.5 10940.7 9464.6 10477.0 10884.0 10981.4 11298.7 11053.1 11228.7 11279.7 11510.5 11595.5 5980.3 7539.1 8016.2 8039.3 8156.6 9803.5 10989.7 11211.3 11274.5 11353.1 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5673.77 Median:4996.15 stddev:3174.04 (63.5%) stddev-sans-first:3167.89 22:35:24 INFO - PROCESS | 1663 | Values: 1560.1 1623.2 1838.5 1914.6 1915.5 8017.9 8594.1 8644.2 9090.3 9103.5 7391.8 8727.3 8874.2 8878.1 8906.7 2296.4 2310.9 2321.1 2334.4 2336.5 2330.6 2355.6 2415.4 2415.7 2424.8 2159.8 2294.9 2372.2 2373.1 2377.2 1992.7 2067.2 2374.6 2388.9 2402.6 2002.7 2369.8 2378.6 2382.5 2385.2 8858.8 8895.9 8921.5 8922.7 8941.5 5316.5 5441.1 5463.7 5464.6 5482.2 2505.6 2649.9 3204.5 3290.8 3321.8 908.4 926.4 994.8 1002.0 1019.4 9893.3 11724.0 11822.5 11834.9 11839.3 4772.2 4782.0 4890.3 4906.6 4928.8 9975.9 9994.8 10004.8 10007.7 10014.7 5372.4 5398.2 5420.5 5452.1 5459.2 4208.8 4241.4 4838.8 4975.1 4981.1 4031.0 4056.5 4099.0 4162.6 4203.5 1132.4 1286.0 1301.1 1330.3 1343.4 745.8 765.6 772.1 822.8 894.5 5820.0 5862.8 5944.4 6058.8 6135.2 939.6 984.0 986.7 989.7 1005.2 3132.9 3762.8 3881.4 3939.5 3969.7 9926.5 10204.6 10302.3 10391.8 10419.0 8833.5 10164.5 10223.0 10345.2 10347.4 7820.6 8180.8 8191.2 8312.4 8366.7 5769.0 5876.3 5930.7 5945.3 6000.9 9869.6 10016.0 10056.6 10099.6 10104.3 9875.0 10117.7 10250.4 10282.3 10338.6 6975.2 7420.8 7533.8 7639.2 7712.8 8432.3 8456.6 8475.2 8517.0 8553.4 6196.3 7339.9 7400.0 7548.3 7548.6 7306.0 7471.1 7673.8 7687.0 7706.8 6799.4 6867.1 6883.7 6923.5 6958.5 4905.9 4984.7 4995.9 4996.5 5183.2 4403.5 4993.8 5159.3 5181.1 5312.1 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4538.28 Median:4904.36 stddev:1452.99 (29.6%) stddev-sans-first:1421.79 22:35:24 INFO - PROCESS | 1663 | Values: 298.2 307.2 311.9 315.9 318.6 5228.6 5307.7 5453.8 5458.0 5556.3 5178.9 5505.3 5513.3 5515.6 5543.3 4067.5 4140.3 4167.2 4183.4 4190.0 3822.6 3956.4 4131.1 4165.9 4196.9 3540.9 4103.3 4151.6 4166.1 4180.7 4165.8 4174.2 4186.6 4203.9 4211.7 2996.6 3470.6 3568.4 3593.4 3597.8 4392.6 4945.7 5286.7 5303.9 5325.8 4042.1 4396.4 4435.7 4764.0 4790.8 5781.3 5808.3 5827.6 5843.6 5845.9 3287.5 3316.0 3899.5 3933.2 3950.3 5154.6 5826.8 6155.4 6180.4 6238.2 3955.1 3955.2 3958.6 3962.0 4007.7 3344.8 3418.7 3551.3 3862.4 4038.2 3924.0 4231.8 4451.9 4714.3 4720.7 5456.6 5516.9 5910.2 5923.3 5936.9 1281.5 1286.6 1328.9 1371.9 1379.3 735.8 812.9 829.9 833.0 842.3 3817.6 3976.5 4034.3 4581.9 4598.9 4414.7 4417.7 4571.7 5012.4 5071.4 4502.7 4525.4 4546.6 4548.4 4583.7 2487.9 2613.4 2634.3 2672.8 2683.9 4798.6 4931.3 5051.2 5200.7 5319.9 4598.9 4630.0 4806.1 4877.5 4988.3 5586.0 5638.3 5848.8 6148.1 6293.6 6032.8 6036.4 6070.5 6085.7 6105.6 5275.3 5316.4 5636.9 5946.3 5959.0 5540.9 6294.5 6296.4 6333.9 6402.1 3380.0 3525.4 3911.6 3964.0 3964.9 4782.9 5422.5 6032.9 6043.0 6068.4 5700.9 5856.7 5859.8 5921.0 5935.9 5413.1 5826.7 5851.6 5877.6 5895.7 5520.0 5585.4 5704.2 5743.6 5765.2 5802.7 5806.6 5809.7 5838.5 5839.7 4378.7 5150.2 5502.1 5708.4 5734.0 22:35:24 INFO - PROCESS | 1663 | 22:35:24 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18095.89 Median:30237.91 stddev:26304.08 (87.0%) stddev-sans-first:26345.13 22:35:24 INFO - PROCESS | 1663 | Values: 617.8 694.1 710.3 748.2 754.3 11927.6 12689.0 14587.9 14711.2 15229.9 14175.7 14364.6 14371.9 14373.3 14386.7 724.9 896.0 899.8 922.3 924.7 868.8 884.7 899.2 903.5 910.1 942.4 942.8 943.7 944.1 947.0 910.2 912.3 934.6 936.5 940.2 692.1 696.2 699.6 700.8 703.5 14677.8 14877.6 14899.6 14911.0 14970.6 12458.8 12574.1 12579.2 12852.8 12935.9 7636.0 9911.5 9965.5 10148.3 10246.9 3043.2 3396.3 3461.9 3481.7 3494.0 85713.6 86688.0 86718.6 86924.6 87335.7 9362.2 9401.0 9441.9 9710.4 9715.0 21518.6 21695.0 21950.3 21957.3 22026.6 7260.1 7329.3 7356.6 7420.7 7492.6 3926.5 4015.5 4032.9 4038.4 4038.5 3002.5 3451.2 3558.6 3561.6 3611.9 517.4 627.0 632.8 633.2 636.7 485.4 547.7 563.4 600.1 607.8 7564.3 8033.9 8074.4 8121.1 8177.7 480.1 495.1 552.4 570.2 571.6 3722.4 3730.0 3730.3 3770.7 3949.6 94580.0 94761.6 95989.0 97061.5 97993.0 84543.6 84728.7 84890.6 85491.0 88597.1 72775.6 73179.5 73570.0 74002.6 74510.6 6818.1 7047.6 7433.0 7501.4 7606.2 33597.8 35594.6 40243.6 47343.5 47554.5 40666.8 50110.7 56358.7 58680.8 59875.7 10919.5 10949.4 11018.5 11019.2 11040.7 11700.2 11700.3 11809.1 11815.8 11824.7 11234.6 11250.3 11392.7 11658.8 11677.5 11301.5 11391.5 11399.8 11734.5 11789.2 10976.0 11003.7 11039.5 11173.1 11355.8 9152.1 9253.5 9361.4 9412.0 9604.6 8238.1 8337.0 8426.5 8535.8 9459.7 22:35:24 INFO - PROCESS | 1663 | -------- Summary: end -------- 22:35:24 INFO - PROCESS | 1663 | 22:35:25 INFO - TEST-INFO | 1663: exit 0 22:35:25 INFO - TEST-OK | dromaeo_css | took 1111409ms 22:35:25 INFO - TEST-START | kraken 22:35:25 INFO - operating with platform_type : mac_ 22:35:25 INFO - Initialising browser for kraken test... 22:35:25 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpbhifPQ/profile http://localhost:49275/getInfo.html) 22:35:28 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 22:35:28 INFO - PROCESS | 1672 | colorDepth:24 22:35:28 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 22:35:28 INFO - PROCESS | 1672 | __metrics 22:35:28 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:35:28 INFO - TEST-INFO | 1672: exit 0 22:35:28 INFO - Browser initialized. 22:35:28 INFO - Running cycle 1/1 for kraken test... 22:35:28 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpbhifPQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:35:41 INFO - PROCESS | 1674 | RSS: Main: 252329984 22:35:41 INFO - PROCESS | 1674 | 22:35:43 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 22:35:44 INFO - PROCESS | 1674 | RSS: Main: 264183808 22:35:44 INFO - PROCESS | 1674 | 22:35:47 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 22:35:48 INFO - PROCESS | 1674 | RSS: Main: 268935168 22:35:48 INFO - PROCESS | 1674 | 22:35:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 22:35:52 INFO - PROCESS | 1674 | RSS: Main: 269299712 22:35:52 INFO - PROCESS | 1674 | 22:35:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 22:35:54 INFO - PROCESS | 1674 | RSS: Main: 269967360 22:35:54 INFO - PROCESS | 1674 | 22:35:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:35:57 INFO - PROCESS | 1674 | RSS: Main: 270622720 22:35:57 INFO - PROCESS | 1674 | 22:36:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 22:36:00 INFO - PROCESS | 1674 | RSS: Main: 271130624 22:36:00 INFO - PROCESS | 1674 | 22:36:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 22:36:04 INFO - PROCESS | 1674 | RSS: Main: 271437824 22:36:04 INFO - PROCESS | 1674 | 22:36:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 22:36:08 INFO - PROCESS | 1674 | RSS: Main: 271347712 22:36:08 INFO - PROCESS | 1674 | 22:36:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:36:10 INFO - PROCESS | 1674 | RSS: Main: 270884864 22:36:10 INFO - PROCESS | 1674 | 22:36:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:36:12 INFO - PROCESS | 1674 | RSS: Main: 271187968 22:36:12 INFO - PROCESS | 1674 | 22:36:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:36:15 INFO - PROCESS | 1674 | RSS: Main: 271175680 22:36:15 INFO - PROCESS | 1674 | 22:36:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:36:19 INFO - PROCESS | 1674 | RSS: Main: 270901248 22:36:19 INFO - PROCESS | 1674 | 22:36:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:36:22 INFO - PROCESS | 1674 | RSS: Main: 271343616 22:36:22 INFO - PROCESS | 1674 | 22:36:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 22:36:25 INFO - PROCESS | 1674 | RSS: Main: 272838656 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | __start_tp_report 22:36:25 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 22:36:25 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 22:36:25 INFO - PROCESS | 1674 | |i|pagename|runs| 22:36:25 INFO - PROCESS | 1674 | |0;ai-astar;114;111;158;173;173;116;118;121;166;116 22:36:25 INFO - PROCESS | 1674 | |1;audio-beat-detection;152;122;145;169;144;170;165;126;170;119 22:36:25 INFO - PROCESS | 1674 | |2;audio-dft;194;205;234;203;196;199;197;200;198;194 22:36:25 INFO - PROCESS | 1674 | |3;audio-fft;71;82;75;146;75;82;82;71;128;58 22:36:25 INFO - PROCESS | 1674 | |4;audio-oscillator;71;88;128;71;72;80;116;60;68;124 22:36:25 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;87;89;85;92;86;89;86;90;85 22:36:25 INFO - PROCESS | 1674 | |6;imaging-darkroom;96;94;105;96;131;107;96;95;105;126 22:36:25 INFO - PROCESS | 1674 | |7;imaging-desaturate;148;147;93;93;93;92;93;91;79;147 22:36:25 INFO - PROCESS | 1674 | |8;json-parse-financial;60;60;61;59;58;69;83;100;94;59 22:36:25 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;69;64;67;71;76;77;72;72;71;73 22:36:25 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;87;85;85;90;90;89;86;87;89;94 22:36:25 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;159;130;151;124;159;157;153;160;260;167 22:36:25 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;196;232;191;167;172;171;165;172;165;169 22:36:25 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;72;69;86;88;91;89;91;64;64;70 22:36:25 INFO - PROCESS | 1674 | __end_tp_report 22:36:25 INFO - PROCESS | 1674 | __start_cc_report 22:36:25 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1098 22:36:25 INFO - PROCESS | 1674 | __end_cc_report 22:36:25 INFO - PROCESS | 1674 | __startTimestamp1464240984997__endTimestamp 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | ------- Summary: start ------- 22:36:25 INFO - PROCESS | 1674 | Number of tests: 14 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:136.60 Median:139.50 stddev:27.03 (19.4%) stddev-sans-first:27.41 22:36:25 INFO - PROCESS | 1674 | Values: 114.0 111.0 158.0 173.0 173.0 116.0 118.0 121.0 166.0 116.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:158.50 stddev:20.38 (12.9%) stddev-sans-first:21.57 22:36:25 INFO - PROCESS | 1674 | Values: 152.0 122.0 145.0 169.0 144.0 170.0 165.0 126.0 170.0 119.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:202.00 Median:199.50 stddev:11.79 (5.9%) stddev-sans-first:12.15 22:36:25 INFO - PROCESS | 1674 | Values: 194.0 205.0 234.0 203.0 196.0 199.0 197.0 200.0 198.0 194.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:87.00 Median:75.00 stddev:27.64 (36.9%) stddev-sans-first:28.71 22:36:25 INFO - PROCESS | 1674 | Values: 71.0 82.0 75.0 146.0 75.0 82.0 82.0 71.0 128.0 58.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:71.00 stddev:25.30 (35.6%) stddev-sans-first:26.10 22:36:25 INFO - PROCESS | 1674 | Values: 71.0 88.0 128.0 71.0 72.0 80.0 116.0 60.0 68.0 124.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:2.42 (2.7%) stddev-sans-first:2.45 22:36:25 INFO - PROCESS | 1674 | Values: 90.0 87.0 89.0 85.0 92.0 86.0 89.0 86.0 90.0 85.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:94.50 stddev:13.27 (14.0%) stddev-sans-first:13.66 22:36:25 INFO - PROCESS | 1674 | Values: 96.0 94.0 105.0 96.0 131.0 107.0 96.0 95.0 105.0 126.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:107.60 Median:92.50 stddev:27.74 (30.0%) stddev-sans-first:25.28 22:36:25 INFO - PROCESS | 1674 | Values: 148.0 147.0 93.0 93.0 93.0 92.0 93.0 91.0 79.0 147.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:70.30 Median:60.50 stddev:16.03 (26.5%) stddev-sans-first:16.56 22:36:25 INFO - PROCESS | 1674 | Values: 60.0 60.0 61.0 59.0 58.0 69.0 83.0 100.0 94.0 59.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:3.88 (5.4%) stddev-sans-first:4.03 22:36:25 INFO - PROCESS | 1674 | Values: 69.0 64.0 67.0 71.0 76.0 77.0 72.0 72.0 71.0 73.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:89.00 stddev:2.78 (3.1%) stddev-sans-first:2.92 22:36:25 INFO - PROCESS | 1674 | Values: 87.0 85.0 85.0 90.0 90.0 89.0 86.0 87.0 89.0 94.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:162.00 Median:159.00 stddev:37.04 (23.3%) stddev-sans-first:39.27 22:36:25 INFO - PROCESS | 1674 | Values: 159.0 130.0 151.0 124.0 159.0 157.0 153.0 160.0 260.0 167.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:172.00 stddev:21.16 (12.3%) stddev-sans-first:21.64 22:36:25 INFO - PROCESS | 1674 | Values: 196.0 232.0 191.0 167.0 172.0 171.0 165.0 172.0 165.0 169.0 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:87.00 stddev:11.52 (13.2%) stddev-sans-first:11.98 22:36:25 INFO - PROCESS | 1674 | Values: 72.0 69.0 86.0 88.0 91.0 89.0 91.0 64.0 64.0 70.0 22:36:25 INFO - PROCESS | 1674 | -------- Summary: end -------- 22:36:25 INFO - PROCESS | 1674 | 22:36:25 INFO - TEST-INFO | 1674: exit 0 22:36:25 INFO - TEST-OK | kraken | took 60608ms 22:36:25 INFO - SUITE-END | took 1172s 22:36:26 INFO - Completed test suite (00:19:32) 22:36:26 INFO - javascript benchmark 22:36:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7033.595523838324, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3442.3459542686887, 3460.15226269564, 3472.8958131256054, 3478.1652403423172, 3489.057954352325, 5584.1902924075985, 5652.321721393433, 5688.687122208277, 5782.421757824215, 5822.679752613604, 4725.787339569719, 4829.565339119478, 5460.153676180188, 5587.413321601224, 5721.399253078419, 5140.331756871601, 5173.663711858736, 5288.338957630296, 5328.6802791832615, 5351.384590772072, 206.522717498925, 5137.0, 5227.18978558324, 5253.605979551549, 5305.840824775243, 4204.537500874917, 5183.144781111112, 5201.29782479368, 5217.973910130463, 5223.791048358061, 5226.216067589854, 5232.502912223333, 5242.816501422432, 5252.343457067866, 5269.051570717269, 4230.534641189467, 5096.949030509684, 5136.974315128437, 5174.870628234286, 5215.191645294985, 5498.615096943216, 5616.859578510529, 5623.212750214973, 5654.717264136777, 5675.659460432346, 6107.992181290082, 6201.224846894138, 6241.968790156066, 6259.687015649245, 6295.338989406138, 6146.907796383058, 6213.813585592439, 6216.751329946796, 6270.153529273535, 6282.2461304643275, 5811.418858114197, 5812.099124635688, 5820.068788993783, 5825.475707186373, 5865.296164460292, 6112.786052488231, 6188.938110618837, 6288.213973253343, 6288.52836037299, 6294.433500984842, 4962.255661650781, 6160.630362178284, 6162.630242185483, 6168.0439531872635, 6176.382361763787, 6212.347703491158, 6222.0355844844125, 6234.064890266496, 6249.96875015622, 6252.343503932113, 6082.878342433215, 6144.126041557368, 6151.6001459904965, 6214.223222097238, 6214.937850621526, 6125.1731016313115, 6125.632462052291, 6158.7228574714245, 6173.536984726164, 6186.907196392058, 4805.711657300573, 4881.219004959189, 4884.975575122102, 4893.877653058631, 4928.7289199093675, 4339.654707040764, 4362.323839804835, 4394.626456751212, 4408.9118217636105, 4425.0, 4373.803178856959, 4776.904461910812, 5659.434056594308, 5734.856628584319, 5853.268341457318, 5194.896102077937, 6251.624902505864, 6261.7182226799905, 6265.71804268809, 6303.905441418382, 5685.77256909719, 5751.907137643834, 5762.9135562966585, 5854.268216472941, 5874.794382196688, 5423.430539793344, 5432.456754324536, 5438.7280635968045, 5445.863853403696, 5453.509184173444, 6471.320511346335, 6472.967635161794, 6481.805545833632, 6486.027095935648, 6527.8694426111215, 6084.361142080018, 6111.95640283616, 6133.610107582569, 6171.84570385744, 6207.099970504342, 6430.582012169194, 6458.063580780667, 6464.191976003, 6471.158749362553, 6494.6103233806125, 6206.937930620636, 6243.250809902841, 6253.530985176221, 6254.843628909313, 6268.153799237227, 6119.502553941335, 6146.078088286793, 6179.721912514037, 6209.968950155401, 6264.404881536247, 5552.7778888843995, 6115.510759139347, 6118.877622447437, 6198.318184999556, 6212.223472065992, 5929.822105336847, 6107.786227482195, 6139.938600613937, 6179.969100154471, 6183.96908015475, 4447.555244475527, 5944.940550594439, 5956.285245770535, 5970.402959703995, 5981.342052374148, 5978.671173085522, 6175.351588083367, 6177.413145751326, 6178.845528861814, 6204.069389591416, 5785.681787501728, 6000.8799824004145, 6044.758209671564, 6049.667268300285, 6079.240094988127], "value": 5619.719686976128, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.85785193367974, 493.61744647897876, 499.57536094319835, 511.88482591416926, 576.7491141353509, 38949.415758763644, 41770.16459670805, 41878.79060604697, 41889.79055104728, 41965.0, 35317.470237946385, 43884.0, 43953.78023109888, 43987.78006109973, 43988.78005609968, 9615.567299471522, 9824.607015719363, 9840.409575425461, 9843.0, 9926.851097233548, 9741.659041933533, 9792.363496372749, 9822.557984890696, 9847.950760246189, 9862.50687465624, 9750.512474376254, 9811.50942452878, 9827.65603203888, 9835.606575736962, 9969.700908972742, 8610.009848867398, 9488.478133702643, 9775.413475191512, 9809.068138526864, 9849.704508864745, 9661.758456038586, 9679.370840895319, 9769.51152442375, 9794.853077203848, 9803.950980245054, 40193.19613607712, 40513.0, 40603.59396406057, 40668.59331406678, 40749.77750667455, 2715.334742987961, 3012.8041677290908, 3057.6942305769467, 3226.7418606511424, 3268.0, 19647.508812279666, 23047.654285185734, 28535.715892784876, 28786.70459829292, 29742.0, 2296.219285442941, 2430.0, 2607.778338841201, 2681.8659067046765, 2777.5139350613563, 418867.0, 460736.0, 464149.0, 468336.0, 472490.0, 31611.89358372492, 33854.8307258467, 34069.659303406646, 34763.30473390569, 35474.822625887224, 32079.51880721791, 35989.0, 36086.639133608856, 36357.0, 36387.81806091005, 11891.940540297242, 12064.672885982534, 13004.154729942524, 13023.47906083765, 13027.869721302666, 12368.1960672556, 12795.61613151607, 12827.551035713892, 12830.294333811544, 12881.677958051123, 7162.283771622796, 7399.186089530147, 7442.06974128234, 7486.588237646982, 7524.699012039458, 2711.620373147761, 3266.3304022675293, 3268.346330733863, 3296.5549650796834, 3342.5487559179687, 928.8188803183449, 962.4898803634085, 963.325672029582, 985.2905907746411, 1039.7660526381655, 26042.479150416886, 28591.428171436455, 29492.410151796845, 29524.704752952195, 29759.702402975694, 1037.061459379263, 1060.565168281001, 1061.2147011211648, 1106.773111512138, 1161.4134861894859, 15225.923870380577, 15294.847051529343, 15364.308606112752, 15581.454649087455, 16065.598360041093, 693138.0, 719310.0, 723773.0, 727033.0, 1041903.0, 62467.0, 62493.37506624967, 62617.43456413535, 63254.41863953438, 63296.0, 1411.470698488067, 1420.4673247532176, 1422.7439060969125, 1438.5900018494679, 1441.128117488925, 30136.698633013388, 30161.547576785928, 30847.383052339275, 31003.68996310008, 31431.0, 58743.82512349814, 59355.0, 59467.702661486415, 59540.70229648824, 59545.702271488364, 654229.0, 677476.0, 678299.0, 679157.0, 680484.0, 41719.7914010428, 43344.56655433405, 43492.13015739731, 43933.0, 44204.77897610491, 44976.0, 46645.30032049454, 46701.53298467108, 47689.76155119202, 49145.50854491409, 37761.81119094387, 37918.24163516659, 37954.81022594869, 38293.0, 38449.92400607889, 37571.436428453046, 37809.810950945066, 38051.0, 38449.0, 38802.22395552017, 30086.849565752033, 32389.19027024343, 37335.81332093322, 38320.0, 38354.61645383511, 31749.0, 33670.0, 34020.65979340274, 34127.0, 34312.0, 24873.51264873498, 25149.497010060062, 25206.74793252044, 25418.872905635355, 26768.929242830847], "value": 18582.048314493757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.96950789674975, 357.45309676195416, 379.1261143065235, 397.8388752555216, 407.05766151359603, 19892.502687432803, 20285.290014849485, 21196.576068478622, 21213.78786212141, 21283.893580532076, 16864.83135168648, 20057.398278051645, 20671.896640516814, 20702.89648551755, 21209.0, 5246.475352464761, 5402.108652072403, 5418.241446197536, 5554.91667624986, 5957.106434034886, 5326.866828329284, 5492.917606235909, 5873.500752436027, 5927.792527261547, 5941.494972927285, 6638.966805165992, 6676.19885613727, 6695.832604184886, 6709.496787740914, 6712.0, 6048.1834952281315, 6079.361667024943, 6096.51227901769, 6575.704093315789, 6658.3674550917585, 5794.333651630074, 5842.591018628697, 5867.031939729939, 5873.676947767914, 5888.175655408247, 13482.730345393134, 16142.75785863213, 19278.421647350602, 25797.74202257972, 26272.605910911352, 1719.6646653902494, 1937.2928880958489, 2004.6924653262358, 2026.9763769296558, 2083.125087463269, 9883.0, 10202.132818710419, 10274.383536987805, 10286.639967601099, 10333.276670633059, 1740.3299729604066, 1773.9379121730678, 1776.4581802550217, 1778.36867911892, 1798.6614612381452, 76454.47091058148, 110415.44792275988, 121820.0, 122441.0, 123193.38403308016, 11290.379029153475, 11658.41707914601, 11675.416229188506, 11686.76626467466, 12012.339321337235, 14959.92520037393, 14961.551153465414, 14963.476278330423, 14975.775363369557, 14976.70046599062, 7188.6046267455795, 8769.736907892773, 8807.32660794447, 8834.337424693174, 8849.0, 7200.739870522742, 8601.483910965362, 8763.123687631185, 8856.114388561093, 8875.778105547284, 4483.125790470827, 4636.814527418933, 4663.930041049387, 4669.322948172496, 4768.499307572655, 1840.0431775476945, 1933.661609218381, 1999.9300723750848, 2015.4256037029377, 2024.2409096588779, 849.1254008371387, 857.4683696108452, 914.2548822709471, 916.9312301577409, 933.3186773655134, 16794.32822687103, 18374.724379134324, 20127.798722012885, 20317.0, 20461.079251433723, 887.9023307436177, 897.2059727141527, 907.7775944893543, 940.3699521320732, 947.4268067818871, 9527.475988820543, 10464.21518386124, 10694.0, 10746.462676866126, 10748.247622666495, 572053.0, 581491.0, 586931.0, 598289.0, 603200.0, 65627.0, 126397.0, 132102.0, 132113.0, 139805.90291145438, 947.5404428852002, 949.9192568631605, 976.9071938165721, 991.2268430624125, 992.5880759484733, 17889.552761180556, 18118.365857194935, 18120.818791812442, 18163.18265678074, 18518.629627407645, 51163.48836511736, 51470.48529514657, 51844.740776295876, 52403.73798131137, 52642.94714105773, 53538.0, 64180.35819641931, 68534.62930741257, 76317.0, 78372.8244076328, 5704.486596206361, 7632.923670763222, 7988.680452782056, 8099.0, 8142.47073940192, 11272.492737826653, 12548.82763719191, 12762.808557871454, 12828.935855320664, 12934.288614126313, 10820.837687434538, 10832.620858269922, 10863.185261105291, 10863.511141998471, 10940.726481838017, 9464.574094165642, 10476.952304769462, 10884.0, 10981.39602321848, 11298.661040168809, 11053.06048985813, 11228.719282018015, 11279.718007049889, 11510.53957841677, 11595.5361785531, 5980.312264089685, 7539.095308563007, 8016.238457346542, 8039.337184432898, 8156.551389673625, 9803.509824508888, 10989.670309890716, 11211.271267367594, 11274.549018039186, 11353.091752659933], "value": 9319.3964451294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.118533028839, 1623.212741820217, 1838.4852241372412, 1914.5979344337688, 1915.4636701723523, 8017.919820801791, 8594.097619749922, 8644.17880301372, 9090.272778177754, 9103.499307538083, 7391.8152046198875, 8727.345449091328, 8874.20132188103, 8878.112188781119, 8906.732798016053, 2296.4258935266225, 2310.8844557772127, 2321.0947730385165, 2334.3580515358326, 2336.4626135988733, 2330.592146374386, 2355.646653002046, 2415.4444477770126, 2415.6738840256517, 2424.830261881669, 2159.8056174944254, 2294.8967296471615, 2372.1578839511985, 2373.1219448803968, 2377.1679912030822, 1992.7110568967491, 2067.224790703486, 2374.6200607902824, 2388.948862500503, 2402.6396040593963, 2002.7196192533056, 2369.8104151667826, 2378.6432035194684, 2382.5354055959097, 2385.1651921827397, 8858.822823543493, 8895.866562001574, 8921.464712117293, 8922.68770593026, 8941.46351218929, 5316.494932981361, 5441.102218134003, 5463.726813659301, 5464.644798088111, 5482.17767334899, 2505.5615267328144, 2649.9337516562045, 3204.535342375366, 3290.7696461247724, 3321.8173000484962, 908.3777612335505, 926.4163576946548, 994.7612572982432, 1001.9649312274036, 1019.3883669798062, 9893.30746847721, 11724.0, 11822.527098916033, 11834.869723449776, 11839.28964262147, 4772.229968095813, 4782.019685964369, 4890.339804126468, 4906.6320025997475, 4928.753562321869, 9975.850362244571, 9994.800103997879, 10004.849927251096, 10007.749806254902, 10014.699559013241, 5372.355317361941, 5398.163284690879, 5420.512153906167, 5452.073147564924, 5459.208414779835, 4208.758416267208, 4241.384999175102, 4838.758062096881, 4975.129352363322, 4981.053599816096, 4030.972102113942, 4056.472658554369, 4099.016236103374, 4162.62536371728, 4203.5376108628025, 1132.3828513460019, 1285.9807102893465, 1301.1087405127519, 1330.2683524061727, 1343.3887581150552, 745.7725393754957, 765.5789315876245, 772.1082150116624, 822.8313195794848, 894.5124906925611, 5819.970900145557, 5862.794802181903, 5944.405559444021, 6058.787942421994, 6135.171751813448, 939.5818860606964, 984.0012387426767, 986.7483791633231, 989.7426669066094, 1005.1556692378438, 3132.9373412531622, 3762.795905310329, 3881.4177873319227, 3939.5469521005452, 3969.6824254059607, 9926.503674816231, 10204.642837500653, 10302.33034852732, 10391.792164156675, 10419.0, 8833.514156721314, 10164.4917754112, 10223.028812262823, 10345.172386209235, 10347.4308913009, 7820.648070836826, 8180.754577362689, 8191.1808819118805, 8312.418130730912, 8366.665333386596, 5768.971155144198, 5876.324222714443, 5930.733117009659, 5945.286565612154, 6000.909986350295, 9869.605215791287, 10016.0, 10056.64801731936, 10099.596016159272, 10104.343217690826, 9875.0, 10117.747056323356, 10250.38497690141, 10282.280240383101, 10338.586456541654, 6975.232724400211, 7420.812669972778, 7533.8116547086765, 7639.236076392316, 7712.8457430852195, 8432.283255923316, 8456.577171141294, 8475.152484751723, 8516.977962644522, 8553.44402613853, 6196.318404975539, 7339.926600733925, 7400.0, 7548.320651141424, 7548.584827834302, 7306.0, 7471.103467583925, 7673.769786906401, 7686.961565192362, 7706.7687969361, 6799.354061364163, 6867.07294515234, 6883.690233939385, 6923.5499692519825, 6958.478114141561, 4905.852824415273, 4984.675996060245, 4995.850124496271, 4996.450390457119, 5183.248428977852, 4403.471583410012, 4993.825216117419, 5159.251908473176, 5181.119209734279, 5312.096943519534], "value": 4546.938811965359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.2365145228217, 307.17982985428904, 311.9438501069808, 315.8657570532523, 318.5842475569381, 5228.5555727763185, 5307.65500242484, 5453.809116680917, 5458.0351073613465, 5556.277294212877, 5178.896422071556, 5505.311836020497, 5513.283273174492, 5515.61390702651, 5543.253875029363, 4067.450894129299, 4140.275451795938, 4167.229062623409, 4183.351580505019, 4189.958100418988, 3822.617738226183, 3956.4263181838614, 4131.132462182945, 4165.875403544199, 4196.880575309465, 3540.8583656653705, 4103.322951712964, 4151.564085770996, 4166.145940082276, 4180.749155050707, 4165.83336666533, 4174.18603372344, 4186.581341865789, 4203.873883783492, 4211.747295162269, 2996.5954596129463, 3470.6355832637714, 3568.429051351797, 3593.3531964246426, 3597.80212088335, 4392.626626736731, 4945.727984960826, 5286.709230992294, 5303.893922121536, 5325.828317770084, 4042.090529630839, 4396.384506169139, 4435.737773735386, 4763.976180119077, 4790.832320868788, 5781.277340332458, 5808.332041815203, 5827.562932780059, 5843.649381037151, 5845.901672113787, 3287.474004159347, 3315.966840331614, 3899.473571067898, 3933.2133573285173, 3950.2889479893606, 5154.639175257734, 5826.76692932278, 6155.415235552616, 6180.415359091761, 6238.189035425366, 3955.149642826806, 3955.248502784462, 3958.5843486434087, 3962.049108214008, 4007.6593489553134, 3344.8125915300207, 3418.7265018798435, 3551.3430015447225, 3862.356746396194, 4038.212548553004, 3923.9607603923596, 4231.830726770956, 4451.888702782457, 4714.292856071548, 4720.740359280204, 5456.61803673747, 5516.889662206813, 5910.202122713465, 5923.318818335946, 5936.8812623748145, 1281.4810001949224, 1286.6268782053287, 1328.8538260791306, 1371.8971077169053, 1379.3379177994418, 735.797655644702, 812.9187081291824, 829.9336053115736, 832.966681332752, 842.3429724814654, 3817.5609804872797, 3976.5029371328583, 4034.2536630722825, 4581.885452863705, 4598.931016034762, 4414.713043652152, 4417.690761646718, 4571.679982401267, 5012.448630650625, 5071.435707862953, 4502.739233014761, 4525.434320709911, 4546.613537849254, 4548.407407777601, 4583.6539326291995, 2487.8507289562685, 2613.4250464898073, 2634.315078079713, 2672.839629622229, 2683.8926442942065, 4798.640101992364, 4931.260310953386, 5051.2423136529815, 5200.713960732196, 5319.893602128013, 4598.8620341389815, 4629.990506171016, 4806.110869489155, 4877.463479017305, 4988.251762235694, 5586.0, 5638.323401191883, 5848.824535263949, 6148.077788331786, 6293.590916590407, 6032.849178770566, 6036.396360363929, 6070.48400885912, 6085.72199838039, 6105.572609917263, 5275.287836142072, 5316.441773613715, 5636.943630563642, 5946.28644562638, 5958.97394885741, 5540.922154192178, 6294.464970477561, 6296.411045703905, 6333.8733225336155, 6402.079376187218, 3380.0, 3525.4359302512503, 3911.589283125231, 3964.0, 3964.9096498462, 4782.880427989328, 5422.539084177889, 6032.879342413039, 6043.00290452088, 6068.423499767515, 5700.942990570041, 5856.736446859987, 5859.824205273849, 5921.0, 5935.904226083988, 5413.079776437936, 5826.70866456684, 5851.624868156065, 5877.647341159544, 5895.67573783446, 5520.0, 5585.441455854382, 5704.176432665857, 5743.62666426681, 5765.221695071279, 5802.6808525531505, 5806.564507662027, 5809.680467574324, 5838.474537291664, 5839.737211825393, 4378.693491455695, 5150.201718733602, 5502.119660854244, 5708.372079071381, 5733.971330143323], "value": 4072.204517432355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.78135911899, 694.1184695436792, 710.3358359933462, 748.1994266135234, 754.3173428047616, 11927.64217073487, 12688.984881209504, 14587.927060364696, 14711.190884501348, 15229.923850380761, 14175.724184823364, 14364.569062928103, 14371.922105842077, 14373.281335933214, 14386.712265754679, 724.8767709489399, 895.9596818143167, 899.8470260055806, 922.3267015078997, 924.7179610218881, 868.7914900423884, 884.7345796261128, 899.1727610598233, 903.5121034641286, 910.0671811393308, 942.4109931292942, 942.839717248066, 943.6791490893129, 944.0983860413289, 947.0009140356921, 910.1762904571374, 912.2610685344858, 934.5514153206431, 936.541094863512, 940.1726480697012, 692.0553444548206, 696.2202333386589, 699.6291965258421, 700.8002719224996, 703.4829400390709, 14677.77983330251, 14877.553673389815, 14899.553013409615, 14910.956233063689, 14970.550883473514, 12458.81311780324, 12574.11981161319, 12579.182353147016, 12852.807207891889, 12935.87064129356, 7636.0, 9911.454869982148, 9965.451900145488, 10148.289619726622, 10246.897531024744, 3043.223977885625, 3396.320735852815, 3461.930761384783, 3481.669241422061, 3494.0, 85713.57143214245, 86688.0, 86718.56640716756, 86924.56537717271, 87335.68996465058, 9362.204212641866, 9400.95299523498, 9441.858372124423, 9710.417374957384, 9714.951425242829, 21518.569628607653, 21695.02372393246, 21950.341489755334, 21957.341279761633, 22026.559468810534, 7260.128784545888, 7329.267073292628, 7356.595387253752, 7420.740274090489, 7492.587907665022, 3926.450296958428, 4015.5382131055303, 4032.9395059074136, 4038.394240863835, 4038.5355684096116, 3002.504586743207, 3451.240727040047, 3558.5551806024246, 3561.6438356164176, 3611.855525778992, 517.4489169034965, 626.996865015672, 632.8259728574678, 633.1673848888696, 636.7357546618101, 485.42719590882393, 547.7179252684871, 563.3633993587302, 600.1148306248249, 607.8237311179797, 7564.281393267631, 8033.879491807627, 8074.3944204183745, 8121.066077401175, 8177.7291816803045, 480.0998128509045, 495.13598770146325, 552.3592632546228, 570.2273419516579, 571.6027360984076, 3722.3671975764187, 3729.9627003729615, 3730.347189241872, 3770.6606405423095, 3949.624785645359, 94580.0, 94761.57857632141, 95989.0, 97061.51469242609, 97993.0, 84543.5772821132, 84728.72906906401, 84890.57554712187, 85491.0, 88597.11402885888, 72775.63612181906, 73179.53640927258, 73570.0, 74002.62998684972, 74510.62744686242, 6818.147731533559, 7047.577145371294, 7433.0, 7501.399888009056, 7606.239376062349, 33597.80820822018, 35594.64405355913, 40243.597564023985, 47343.52656473391, 47554.52445475639, 40666.79666601648, 50110.749446252536, 56358.7182064087, 58680.826383472944, 59875.70062149662, 10919.454027298762, 10949.397783122005, 11018.50416731233, 11019.173561983043, 11040.668779936615, 11700.180987330805, 11700.2979821211, 11809.055275578105, 11815.822762658396, 11824.70438239051, 11234.55061797519, 11250.268732532359, 11392.715182120182, 11658.825117623073, 11677.474513647076, 11301.547938082385, 11391.54433822671, 11399.772004559696, 11734.530618775494, 11789.174757766874, 10976.039551864627, 11003.669889903316, 11039.503222354853, 11173.05029072538, 11355.829662555234, 9152.130547597968, 9253.537323133682, 9361.391509551857, 9412.0, 9604.567794449127, 8238.134995825352, 8336.958315208385, 8426.536540490331, 8535.829283414421, 9459.716208513755], "value": 6718.557735517514, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 158.0, 173.0, 173.0, 116.0, 118.0, 121.0, 166.0, 116.0], "value": 136.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 145.0, 169.0, 144.0, 170.0, 165.0, 126.0, 170.0, 119.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 205.0, 234.0, 203.0, 196.0, 199.0, 197.0, 200.0, 198.0, 194.0], "value": 202.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 75.0, 146.0, 75.0, 82.0, 82.0, 71.0, 128.0, 58.0], "value": 87.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 88.0, 128.0, 71.0, 72.0, 80.0, 116.0, 60.0, 68.0, 124.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 85.0, 92.0, 86.0, 89.0, 86.0, 90.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 105.0, 96.0, 131.0, 107.0, 96.0, 95.0, 105.0, 126.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 93.0, 93.0, 93.0, 92.0, 93.0, 91.0, 79.0, 147.0], "value": 107.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 59.0, 58.0, 69.0, 83.0, 100.0, 94.0, 59.0], "value": 70.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 67.0, 71.0, 76.0, 77.0, 72.0, 72.0, 71.0, 73.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 90.0, 90.0, 89.0, 86.0, 87.0, 89.0, 94.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 130.0, 151.0, 124.0, 159.0, 157.0, 153.0, 160.0, 260.0, 167.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 232.0, 191.0, 167.0, 172.0, 171.0, 165.0, 172.0, 165.0, 169.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 86.0, 88.0, 91.0, 89.0, 91.0, 64.0, 64.0, 70.0], "value": 78.4, "unit": "score"}]}]} 22:36:26 INFO - Return code: 0 22:36:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:36:26 ERROR - # TBPL SUCCESS # 22:36:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:36:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:36:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:36:26 INFO - 2016-05-25 22:36:26.299 screenresolution[1679:10356] starting screenresolution argv=screenresolution get 22:36:26 INFO - 2016-05-25 22:36:26.301 screenresolution[1679:10356] Display 0: 1600x1200x32@60 22:36:26 INFO - 2016-05-25 22:36:26.308 screenresolution[1680:10359] starting screenresolution argv=screenresolution list 22:36:26 INFO - Available Modes on Display 0 22:36:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:36:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:36:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:36:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:36:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:36:26 INFO - Intel Iris: 22:36:26 INFO - Chipset Model: Intel Iris 22:36:26 INFO - Type: GPU 22:36:26 INFO - Bus: Built-In 22:36:26 INFO - VRAM (Dynamic, Max): 1536 MB 22:36:26 INFO - Vendor: Intel (0x8086) 22:36:26 INFO - Device ID: 0x0a2e 22:36:26 INFO - Revision ID: 0x0009 22:36:26 INFO - Displays: 22:36:26 INFO - Display: 22:36:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:36:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:36:26 INFO - Display Serial Number: 1600x1200 60 22:36:26 INFO - Main Display: Yes 22:36:26 INFO - Mirror: Off 22:36:26 INFO - Online: Yes 22:36:26 INFO - Rotation: Supported 22:36:26 INFO - Return code: 0 22:36:26 INFO - Running post-action listener: _resource_record_post_action 22:36:26 INFO - ##### 22:36:26 INFO - ##### Finished run-tests step (success) 22:36:26 INFO - ##### 22:36:26 INFO - Running post-run listener: _resource_record_post_run 22:36:26 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 36709888; Write bytes: 700647936; Read time: 522; Write time: 1974 22:36:26 INFO - TinderboxPrint: CPU usage
54.5% 22:36:26 INFO - TinderboxPrint: I/O read bytes / time
36,709,888 / 522 22:36:26 INFO - TinderboxPrint: I/O write bytes / time
700,647,936 / 1,974 22:36:26 INFO - TinderboxPrint: CPU idle
2,197.8 (45.5%) 22:36:26 INFO - TinderboxPrint: CPU system
974.8 (20.2%) 22:36:26 INFO - TinderboxPrint: CPU user
1,661.0 (34.4%) 22:36:26 INFO - TinderboxPrint: Swap in / out
433,577,984 / 0 22:36:26 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400681472; Write bytes: 389293568; Read time: 29559; Write time: 657 22:36:26 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29642240; Write bytes: 296168448; Read time: 433; Write time: 1195 22:36:27 INFO - Running post-run listener: _upload_blobber_files 22:36:27 INFO - Blob upload gear active. 22:36:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:36:27 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 22:36:27 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'] 22:36:27 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 22:36:27 INFO - (blobuploader) - INFO - Open directory for files ... 22:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:28 INFO - (blobuploader) - INFO - Done attempting. 22:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:29 INFO - (blobuploader) - INFO - Done attempting. 22:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:32 INFO - (blobuploader) - INFO - Done attempting. 22:36:32 INFO - (blobuploader) - INFO - Iteration through files over. 22:36:32 INFO - Return code: 0 22:36:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:36:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:36:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3c5345f5841c45b045c3077696a4953fe72b9d9e7b9f94f2e10d6a646e613d7b34d5845d8dc77d4e8d786e9623a526f6af737b5e7f5042e0a05880a41e8c81b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5603cbf21fa94416999cbcfc23098f9df6570a154fbada6a2168852e209f2f02d9966d8bf57da786168139a4a76b2c4e38b8490a7ce0b7c9853df6210a44d45a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b670f752d09c4e38fcb04ecd74edd9a4ac4112a7c9d3954216363b262521b7c9c7ce645047347201e4e0a89b0bbbd63a0fdeab5edb4d408aee55996899833754"} 22:36:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:36:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:36:32 INFO - Contents: 22:36:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3c5345f5841c45b045c3077696a4953fe72b9d9e7b9f94f2e10d6a646e613d7b34d5845d8dc77d4e8d786e9623a526f6af737b5e7f5042e0a05880a41e8c81b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5603cbf21fa94416999cbcfc23098f9df6570a154fbada6a2168852e209f2f02d9966d8bf57da786168139a4a76b2c4e38b8490a7ce0b7c9853df6210a44d45a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b670f752d09c4e38fcb04ecd74edd9a4ac4112a7c9d3954216363b262521b7c9c7ce645047347201e4e0a89b0bbbd63a0fdeab5edb4d408aee55996899833754"} 22:36:32 INFO - Running post-run listener: copy_logs_to_upload_dir 22:36:32 INFO - Copying logs to upload dir... 22:36:32 INFO - mkdir: /builds/slave/test/build/upload/logs 22:36:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.425623 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-25 22:36:32.550430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 22:36:32.553678) ========= 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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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/e3c5345f5841c45b045c3077696a4953fe72b9d9e7b9f94f2e10d6a646e613d7b34d5845d8dc77d4e8d786e9623a526f6af737b5e7f5042e0a05880a41e8c81b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5603cbf21fa94416999cbcfc23098f9df6570a154fbada6a2168852e209f2f02d9966d8bf57da786168139a4a76b2c4e38b8490a7ce0b7c9853df6210a44d45a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b670f752d09c4e38fcb04ecd74edd9a4ac4112a7c9d3954216363b262521b7c9c7ce645047347201e4e0a89b0bbbd63a0fdeab5edb4d408aee55996899833754"} build_url:https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011002 build_url: 'https://queue.taskcluster.net/v1/task/Fm11m3nUTfOfGifkfO34Hw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3c5345f5841c45b045c3077696a4953fe72b9d9e7b9f94f2e10d6a646e613d7b34d5845d8dc77d4e8d786e9623a526f6af737b5e7f5042e0a05880a41e8c81b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5603cbf21fa94416999cbcfc23098f9df6570a154fbada6a2168852e209f2f02d9966d8bf57da786168139a4a76b2c4e38b8490a7ce0b7c9853df6210a44d45a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b670f752d09c4e38fcb04ecd74edd9a4ac4112a7c9d3954216363b262521b7c9c7ce645047347201e4e0a89b0bbbd63a0fdeab5edb4d408aee55996899833754"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 22:36:32.583243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:36:32.583568) ========= 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.p8MOUmYcO7/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.8QnCOezQmJ/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.005078 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:36:32.631908) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 22:36:32.632207) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 22:36:32.632533) ========= ========= Total master_lag: 2.46 =========