builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0322 starttime: 1464376190.61 results: success (0) buildid: 20160527091541 builduid: ea10b0c054ac40e1be19952977d41872 revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.608777) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.609878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.610567) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.636522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.637301) ========= 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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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-27 12:09:50-- 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% 19.6M=0.001s 2016-05-27 12:09:50 (19.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136185 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.799399) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.800200) ========= 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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.025257 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:50.853772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 12:09:50.854202) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eec76f75c79a0a597131357ec1eed49683183c30 --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 eec76f75c79a0a597131357ec1eed49683183c30 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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-27 12:09:50,929 truncating revision to first 12 chars 2016-05-27 12:09:50,929 Setting DEBUG logging. 2016-05-27 12:09:50,929 attempt 1/10 2016-05-27 12:09:50,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eec76f75c79a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 12:09:51,886 unpacking tar archive at: fx-team-eec76f75c79a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.452590 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 12:09:52.327353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:52.327764) ========= script_repo_revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:52.328280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:52.328595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 12:09:52.345404) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-05-27 12:09:52.345729) ========= /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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:09:52 INFO - MultiFileLogger online at 20160527 12:09:52 in /builds/slave/test 12:09:52 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 12:09:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:09:52 INFO - {'append_to_log': False, 12:09:52 INFO - 'base_work_dir': '/builds/slave/test', 12:09:52 INFO - 'blob_upload_branch': 'Fx-Team', 12:09:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:09:52 INFO - 'branch': 'Fx-Team', 12:09:52 INFO - 'buildbot_json_path': 'buildprops.json', 12:09:52 INFO - 'config_files': ('talos/mac_config.py',), 12:09:52 INFO - 'default_actions': ('clobber', 12:09:52 INFO - 'read-buildbot-config', 12:09:52 INFO - 'download-and-extract', 12:09:52 INFO - 'populate-webroot', 12:09:52 INFO - 'create-virtualenv', 12:09:52 INFO - 'install', 12:09:52 INFO - 'run-tests'), 12:09:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:09:52 INFO - 'download_minidump_stackwalk': True, 12:09:52 INFO - 'download_symbols': 'ondemand', 12:09:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:09:52 INFO - 'tooltool.py': '/tools/tooltool.py', 12:09:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:09:52 INFO - '/tools/misc-python/virtualenv.py')}, 12:09:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:09:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:09:52 INFO - 'installer_path': 'installer.exe', 12:09:52 INFO - 'log_level': 'info', 12:09:52 INFO - 'log_name': 'talos', 12:09:52 INFO - 'log_to_console': True, 12:09:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:09:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:09:52 INFO - 'opt_config_files': (), 12:09:52 INFO - 'pip_index': False, 12:09:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:52 INFO - 'cmd': ('bash', 12:09:52 INFO - '-c', 12:09:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:52 INFO - 'enabled': True, 12:09:52 INFO - 'halt_on_failure': False, 12:09:52 INFO - 'name': 'check_screen_resolution'},), 12:09:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:52 INFO - 'cmd': ('bash', 12:09:52 INFO - '-c', 12:09:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:52 INFO - 'enabled': True, 12:09:52 INFO - 'halt_on_failure': False, 12:09:52 INFO - 'name': 'check_screen_resolution'},), 12:09:52 INFO - 'run_cmd_checks_enabled': True, 12:09:52 INFO - 'sps_profile': False, 12:09:52 INFO - 'sps_profile_interval': 0, 12:09:52 INFO - 'suite': 'dromaeojs-e10s', 12:09:52 INFO - 'system_bits': '32', 12:09:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:09:52 INFO - 'title': 't-yosemite-r7-0322', 12:09:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:09:52 INFO - 'use_talos_json': True, 12:09:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:09:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:09:52 INFO - 'work_dir': 'build'} 12:09:52 INFO - ##### 12:09:52 INFO - ##### Running clobber step. 12:09:52 INFO - ##### 12:09:52 INFO - Running pre-action listener: _resource_record_pre_action 12:09:52 INFO - Running main action method: clobber 12:09:52 INFO - rmtree: /builds/slave/test/build 12:09:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:09:54 INFO - Running post-action listener: _resource_record_post_action 12:09:54 INFO - ##### 12:09:54 INFO - ##### Finished clobber step (success) 12:09:54 INFO - ##### 12:09:54 INFO - ##### 12:09:54 INFO - ##### Running read-buildbot-config step. 12:09:54 INFO - ##### 12:09:54 INFO - Running pre-action listener: _resource_record_pre_action 12:09:54 INFO - Running main action method: read_buildbot_config 12:09:54 INFO - Using buildbot properties: 12:09:54 INFO - { 12:09:54 INFO - "project": "", 12:09:54 INFO - "product": "firefox", 12:09:54 INFO - "script_repo_revision": "production", 12:09:54 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 12:09:54 INFO - "repository": "", 12:09:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 12:09:54 INFO - "buildid": "20160527091541", 12:09:54 INFO - "pgo_build": "False", 12:09:54 INFO - "basedir": "/builds/slave/test", 12:09:54 INFO - "buildnumber": 18, 12:09:54 INFO - "slavename": "t-yosemite-r7-0322", 12:09:54 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:09:54 INFO - "platform": "yosemite_r7", 12:09:54 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 12:09:54 INFO - "branch": "fx-team", 12:09:54 INFO - "repo_path": "integration/fx-team", 12:09:54 INFO - "revision": "eec76f75c79a0a597131357ec1eed49683183c30", 12:09:54 INFO - "stage_platform": "macosx64", 12:09:54 INFO - "builduid": "ea10b0c054ac40e1be19952977d41872", 12:09:54 INFO - "slavebuilddir": "test" 12:09:54 INFO - } 12:09:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:09:54 INFO - Running post-action listener: _resource_record_post_action 12:09:54 INFO - ##### 12:09:54 INFO - ##### Finished read-buildbot-config step (success) 12:09:54 INFO - ##### 12:09:54 INFO - ##### 12:09:54 INFO - ##### Running download-and-extract step. 12:09:54 INFO - ##### 12:09:54 INFO - Running pre-action listener: _resource_record_pre_action 12:09:54 INFO - Running main action method: download_and_extract 12:09:54 INFO - mkdir: /builds/slave/test/build/tests 12:09: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')]} 12:09:54 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:09:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:09:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:09:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:09:56 INFO - Downloaded 1199 bytes. 12:09:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:09:56 INFO - Using the following test package requirements: 12:09:56 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:09:56 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:09:56 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:09:56 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'jsshell-mac.zip'], 12:09:56 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:09:56 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:09:56 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:09:56 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:09:56 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:09:56 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:09:56 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:09:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:56 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:09:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:09:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:09:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:09:58 INFO - Downloaded 18684825 bytes. 12:09:58 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'] 12:09:58 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 12:10:01 INFO - Return code: 0 12:10:01 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 12:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:01 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:10:04 INFO - Downloaded 18684825 bytes. 12:10:04 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'] 12:10:04 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 12:10:07 INFO - Return code: 0 12:10:07 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')]} 12:10:07 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:10:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:10:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:10:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:10:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:10:08 INFO - Downloaded 10834034 bytes. 12:10:08 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'] 12:10:08 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 12:10:09 INFO - Return code: 0 12:10: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')]} 12:10:09 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:10:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:10:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:10:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:10:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 12:11:08 INFO - Downloaded 110486912 bytes. 12:11:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:08 INFO - mkdir: /builds/slave/test/properties 12:11:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:08 INFO - Contents: 12:11:08 INFO - build_url:https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:11 INFO - Running post-action listener: _resource_record_post_action 12:11:11 INFO - Running post-action listener: set_extra_try_arguments 12:11:11 INFO - ##### 12:11:11 INFO - ##### Finished download-and-extract step (success) 12:11:11 INFO - ##### 12:11:11 INFO - ##### 12:11:11 INFO - ##### Running populate-webroot step. 12:11:11 INFO - ##### 12:11:11 INFO - Running pre-action listener: _resource_record_pre_action 12:11:11 INFO - Running main action method: populate_webroot 12:11:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:11 INFO - '--tppagecycles', 12:11:11 INFO - '7'], 12:11:11 INFO - 'tests': ['tsvgm']}}, 12:11:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['damp', 'tps']}, 12:11:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'tests': ['damp', 'tps']}, 12:11:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['dromaeo_dom']}, 12:11:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['a11yr', 12:11:11 INFO - 'ts_paint', 12:11:11 INFO - 'tpaint', 12:11:11 INFO - 'sessionrestore', 12:11:11 INFO - 'sessionrestore_no_auto_restore', 12:11:11 INFO - 'tabpaint']}, 12:11:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['tsvgx', 12:11:11 INFO - 'tsvgr_opacity', 12:11:11 INFO - 'tart', 12:11:11 INFO - 'tscrollx', 12:11:11 INFO - 'cart']}, 12:11:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:11 INFO - 'tsvgr_opacity', 12:11:11 INFO - 'tart', 12:11:11 INFO - 'tscrollx', 12:11:11 INFO - 'cart']}, 12:11:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'talos_options': ['--disable-e10s'], 12:11:11 INFO - 'tests': ['tp5o']}, 12:11:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'tests': ['tp5o']}, 12:11:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'talos_options': ['--disable-e10s', 12:11:11 INFO - '--xperf_path', 12:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:11 INFO - 'tests': ['tp5n']}, 12:11:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:11 INFO - 'talos_options': ['--xperf_path', 12:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:11 INFO - 'tests': ['tp5n']}}, 12:11:11 INFO - 'talos.zip': {'path': '', 12:11:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:11 INFO - Running post-action listener: _resource_record_post_action 12:11:11 INFO - ##### 12:11:11 INFO - ##### Finished populate-webroot step (success) 12:11:11 INFO - ##### 12:11:11 INFO - ##### 12:11:11 INFO - ##### Running create-virtualenv step. 12:11:11 INFO - ##### 12:11:11 INFO - Running pre-action listener: _resource_record_pre_action 12:11:11 INFO - Running main action method: create_virtualenv 12:11:11 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:11 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 12:11:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:11 INFO - Using real prefix '/tools/python27' 12:11:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:12 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:15 INFO - Installing pip.................done. 12:11:15 INFO - Return code: 0 12:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:15 INFO - 'HOME': '/Users/cltbld', 12:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:15 INFO - 'LOGNAME': 'cltbld', 12:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:15 INFO - 'MOZ_NO_REMOTE': '1', 12:11:15 INFO - 'NO_EM_RESTART': '1', 12:11:15 INFO - 'PAGER': '/bin/cat', 12:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:15 INFO - 'PWD': '/builds/slave/test', 12:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:15 INFO - 'SHELL': '/bin/bash', 12:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:15 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:15 INFO - 'USER': 'cltbld', 12:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:15 INFO - 'XPC_FLAGS': '0x0', 12:11:15 INFO - 'XPC_SERVICE_NAME': '0', 12:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:15 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 12:11:15 INFO - Cleaning up... 12:11:15 INFO - Return code: 0 12:11:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:15 INFO - 'HOME': '/Users/cltbld', 12:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:15 INFO - 'LOGNAME': 'cltbld', 12:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:15 INFO - 'MOZ_NO_REMOTE': '1', 12:11:15 INFO - 'NO_EM_RESTART': '1', 12:11:15 INFO - 'PAGER': '/bin/cat', 12:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:15 INFO - 'PWD': '/builds/slave/test', 12:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:15 INFO - 'SHELL': '/bin/bash', 12:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:15 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:15 INFO - 'USER': 'cltbld', 12:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:15 INFO - 'XPC_FLAGS': '0x0', 12:11:15 INFO - 'XPC_SERVICE_NAME': '0', 12:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:15 INFO - Downloading/unpacking psutil>=3.1.1 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:19 INFO - Installing collected packages: psutil 12:11:19 INFO - Running setup.py install for psutil 12:11:19 INFO - building 'psutil._psutil_osx' extension 12:11:19 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 12:11:19 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 12:11:19 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 12:11:19 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 12:11:19 INFO - building 'psutil._psutil_posix' extension 12:11:19 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 12:11:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:19 INFO - ^ 12:11:20 INFO - 1 warning generated. 12:11:20 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 12:11:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:20 INFO - Successfully installed psutil 12:11:20 INFO - Cleaning up... 12:11:20 INFO - Return code: 0 12:11:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:11:20 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')]} 12:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:20 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')]} 12:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:20 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 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:20 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 12:11:20 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 12:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:20 INFO - 'HOME': '/Users/cltbld', 12:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:20 INFO - 'LOGNAME': 'cltbld', 12:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:20 INFO - 'MOZ_NO_REMOTE': '1', 12:11:20 INFO - 'NO_EM_RESTART': '1', 12:11:20 INFO - 'PAGER': '/bin/cat', 12:11:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:20 INFO - 'PWD': '/builds/slave/test', 12:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:20 INFO - 'SHELL': '/bin/bash', 12:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:20 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:20 INFO - 'USER': 'cltbld', 12:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:20 INFO - 'XPC_FLAGS': '0x0', 12:11:20 INFO - 'XPC_SERVICE_NAME': '0', 12:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:11:23 INFO - Installing collected packages: mozsystemmonitor 12:11:23 INFO - Running setup.py install for mozsystemmonitor 12:11:23 INFO - Successfully installed mozsystemmonitor 12:11:23 INFO - Cleaning up... 12:11:23 INFO - Return code: 0 12:11:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:23 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 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:23 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 12:11:23 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 12:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:23 INFO - 'HOME': '/Users/cltbld', 12:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:23 INFO - 'LOGNAME': 'cltbld', 12:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:23 INFO - 'MOZ_NO_REMOTE': '1', 12:11:23 INFO - 'NO_EM_RESTART': '1', 12:11:23 INFO - 'PAGER': '/bin/cat', 12:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:23 INFO - 'PWD': '/builds/slave/test', 12:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:23 INFO - 'SHELL': '/bin/bash', 12:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:23 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:23 INFO - 'USER': 'cltbld', 12:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:23 INFO - 'XPC_FLAGS': '0x0', 12:11:23 INFO - 'XPC_SERVICE_NAME': '0', 12:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:23 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:25 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:27 INFO - Downloading docopt-0.6.1.tar.gz 12:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:27 INFO - Running setup.py install for blobuploader 12:11:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:27 INFO - Running setup.py install for requests 12:11:27 INFO - Running setup.py install for docopt 12:11:28 INFO - Successfully installed blobuploader requests docopt 12:11:28 INFO - Cleaning up... 12:11:28 INFO - Return code: 0 12:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 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 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:28 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 12:11:28 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 12:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:28 INFO - 'HOME': '/Users/cltbld', 12:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:28 INFO - 'LOGNAME': 'cltbld', 12:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:28 INFO - 'MOZ_NO_REMOTE': '1', 12:11:28 INFO - 'NO_EM_RESTART': '1', 12:11:28 INFO - 'PAGER': '/bin/cat', 12:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:28 INFO - 'PWD': '/builds/slave/test', 12:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:28 INFO - 'SHELL': '/bin/bash', 12:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:28 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:28 INFO - 'USER': 'cltbld', 12:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:28 INFO - 'XPC_FLAGS': '0x0', 12:11:28 INFO - 'XPC_SERVICE_NAME': '0', 12:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-S5hHUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-doMcUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-WmtwOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-iWOiUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Gt2e5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-WAUEeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rfeDsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-VUYqfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-O5AndL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ThmbOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-AfHLbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-zz6fsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-I1moGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-RCOIVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-58L1gD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:29 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-wSMpNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:30 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-m5x08a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:30 INFO - Running setup.py install for manifestparser 12:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozcrash 12:11:30 INFO - Running setup.py install for mozdebug 12:11:30 INFO - Running setup.py install for mozdevice 12:11:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozfile 12:11:30 INFO - Running setup.py install for mozhttpd 12:11:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozinfo 12:11:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozInstall 12:11:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozleak 12:11:31 INFO - Running setup.py install for mozlog 12:11:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for moznetwork 12:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozprocess 12:11:31 INFO - Running setup.py install for mozprofile 12:11:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Running setup.py install for mozrunner 12:11:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Running setup.py install for mozscreenshot 12:11:32 INFO - Running setup.py install for moztest 12:11:32 INFO - Running setup.py install for mozversion 12:11:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:32 INFO - Cleaning up... 12:11:32 INFO - Return code: 0 12:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:32 INFO - 'HOME': '/Users/cltbld', 12:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:32 INFO - 'LOGNAME': 'cltbld', 12:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:32 INFO - 'MOZ_NO_REMOTE': '1', 12:11:32 INFO - 'NO_EM_RESTART': '1', 12:11:32 INFO - 'PAGER': '/bin/cat', 12:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:32 INFO - 'PWD': '/builds/slave/test', 12:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:32 INFO - 'SHELL': '/bin/bash', 12:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:32 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:32 INFO - 'USER': 'cltbld', 12:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:32 INFO - 'XPC_FLAGS': '0x0', 12:11:32 INFO - 'XPC_SERVICE_NAME': '0', 12:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-oQiA9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:33 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)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-czkjsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:33 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)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OHTy7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:33 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)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-_HSBiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OLkSHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-RHrh36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-O6sTjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-wLSBu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-QX7csK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:33 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-XJiAVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-fQ_TwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ppdH2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-mvVNzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ajz9Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OXcNr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-nTM0ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:34 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-yv75V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:37 INFO - Downloading blessings-1.6.tar.gz 12:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:37 INFO - Installing collected packages: blessings 12:11:37 INFO - Running setup.py install for blessings 12:11:37 INFO - Successfully installed blessings 12:11:37 INFO - Cleaning up... 12:11:37 INFO - Return code: 0 12:11:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:37 INFO - Reading from file tmpfile_stdout 12:11:37 INFO - Current package versions: 12:11:37 INFO - blessings == 1.6 12:11:37 INFO - blobuploader == 1.2.4 12:11:37 INFO - docopt == 0.6.1 12:11:37 INFO - manifestparser == 1.1 12:11:37 INFO - mozInstall == 1.12 12:11:37 INFO - mozcrash == 0.17 12:11:37 INFO - mozdebug == 0.1 12:11:37 INFO - mozdevice == 0.48 12:11:37 INFO - mozfile == 1.2 12:11:37 INFO - mozhttpd == 0.7 12:11:37 INFO - mozinfo == 0.9 12:11:37 INFO - mozleak == 0.1 12:11:37 INFO - mozlog == 3.2 12:11:37 INFO - moznetwork == 0.27 12:11:37 INFO - mozprocess == 0.22 12:11:37 INFO - mozprofile == 0.28 12:11:37 INFO - mozrunner == 6.11 12:11:37 INFO - mozscreenshot == 0.1 12:11:37 INFO - mozsystemmonitor == 0.3 12:11:37 INFO - moztest == 0.7 12:11:37 INFO - mozversion == 1.4 12:11:37 INFO - psutil == 3.1.1 12:11:37 INFO - requests == 1.2.3 12:11:37 INFO - wsgiref == 0.1.2 12:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:37 INFO - 'HOME': '/Users/cltbld', 12:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:37 INFO - 'LOGNAME': 'cltbld', 12:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:37 INFO - 'MOZ_NO_REMOTE': '1', 12:11:37 INFO - 'NO_EM_RESTART': '1', 12:11:37 INFO - 'PAGER': '/bin/cat', 12:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:37 INFO - 'PWD': '/builds/slave/test', 12:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:37 INFO - 'SHELL': '/bin/bash', 12:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:37 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:37 INFO - 'USER': 'cltbld', 12:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:37 INFO - 'XPC_FLAGS': '0x0', 12:11:37 INFO - 'XPC_SERVICE_NAME': '0', 12:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:11:38 INFO - Cleaning up... 12:11:38 INFO - Return code: 0 12:11:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:38 INFO - 'HOME': '/Users/cltbld', 12:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:38 INFO - 'LOGNAME': 'cltbld', 12:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:38 INFO - 'MOZ_NO_REMOTE': '1', 12:11:38 INFO - 'NO_EM_RESTART': '1', 12:11:38 INFO - 'PAGER': '/bin/cat', 12:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:38 INFO - 'PWD': '/builds/slave/test', 12:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:38 INFO - 'SHELL': '/bin/bash', 12:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:38 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:38 INFO - 'USER': 'cltbld', 12:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:38 INFO - 'XPC_FLAGS': '0x0', 12:11:38 INFO - 'XPC_SERVICE_NAME': '0', 12:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:38 INFO - Downloading/unpacking jsonschema 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:11:40 INFO - Downloading/unpacking functools32 (from jsonschema) 12:11:40 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 12:11:40 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 12:11:40 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 12:11:40 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 12:11:40 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 12:11:40 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 12:11:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:11:41 INFO - warning: no files found matching '*.txt' 12:11:41 INFO - no previously-included directories found matching 'build' 12:11:41 INFO - no previously-included directories found matching 'dist' 12:11:41 INFO - no previously-included directories found matching '.git*' 12:11:41 INFO - Installing collected packages: jsonschema, functools32 12:11:41 INFO - Running setup.py install for functools32 12:11:41 INFO - warning: no files found matching '*.txt' 12:11:41 INFO - no previously-included directories found matching 'build' 12:11:41 INFO - no previously-included directories found matching 'dist' 12:11:41 INFO - no previously-included directories found matching '.git*' 12:11:41 INFO - Successfully installed jsonschema functools32 12:11:41 INFO - Cleaning up... 12:11:41 INFO - Return code: 0 12:11:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:11:41 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')]} 12:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:41 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')]} 12:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:41 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 0x10cb1ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf415a5ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, '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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 12:11:41 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 12:11:41 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 12:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:41 INFO - 'HOME': '/Users/cltbld', 12:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:41 INFO - 'LOGNAME': 'cltbld', 12:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:41 INFO - 'MOZ_NO_REMOTE': '1', 12:11:41 INFO - 'NO_EM_RESTART': '1', 12:11:41 INFO - 'PAGER': '/bin/cat', 12:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:41 INFO - 'PWD': '/builds/slave/test', 12:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:41 INFO - 'SHELL': '/bin/bash', 12:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:11:41 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:41 INFO - 'USER': 'cltbld', 12:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:41 INFO - 'XPC_FLAGS': '0x0', 12:11:41 INFO - 'XPC_SERVICE_NAME': '0', 12:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:41 INFO - Downloading/unpacking flake8 12:11:41 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 12:11:41 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 12:11:41 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 12:11:41 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 12:11:41 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 12:11:41 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 12:11:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:11:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:11:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:44 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 12:11:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:11:45 INFO - no previously-included directories found matching 'docs/_build' 12:11:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:11:45 INFO - Running setup.py install for pep8 12:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:11:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:11:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:11:45 INFO - no previously-included directories found matching 'docs/_build' 12:11:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:11:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:11:45 INFO - Cleaning up... 12:11:45 INFO - Return code: 0 12:11:45 INFO - Running post-action listener: _resource_record_post_action 12:11:45 INFO - Running post-action listener: _start_resource_monitoring 12:11:45 INFO - Starting resource monitoring. 12:11:45 INFO - ##### 12:11:45 INFO - ##### Finished create-virtualenv step (success) 12:11:45 INFO - ##### 12:11:45 INFO - ##### 12:11:45 INFO - ##### Running install step. 12:11:45 INFO - ##### 12:11:45 INFO - Running pre-action listener: _resource_record_pre_action 12:11:45 INFO - Running main action method: install 12:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:46 INFO - Reading from file tmpfile_stdout 12:11:46 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:46 INFO - Reading from file tmpfile_stdout 12:11:46 INFO - Output received: 12:11:46 INFO - Usage: mozinstall [options] installer 12:11:46 INFO - Options: 12:11:46 INFO - -h, --help show this help message and exit 12:11:46 INFO - -d DEST, --destination=DEST 12:11:46 INFO - Directory to install application into. [default: 12:11:46 INFO - "/builds/slave/test"] 12:11:46 INFO - --app=APP Application being installed. [default: firefox] 12:11:46 INFO - mkdir: /builds/slave/test/build/application 12:11:46 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'] 12:11:46 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 12:12:20 INFO - Reading from file tmpfile_stdout 12:12:20 INFO - Output received: 12:12:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:20 INFO - Running post-action listener: _resource_record_post_action 12:12:20 INFO - ##### 12:12:20 INFO - ##### Finished install step (success) 12:12:20 INFO - ##### 12:12:20 INFO - ##### 12:12:20 INFO - ##### Running run-tests step. 12:12:20 INFO - ##### 12:12:20 INFO - Running pre-action listener: _resource_record_pre_action 12:12:20 INFO - Running main action method: run_tests 12:12:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:20 INFO - 2016-05-27 12:12:20.032 screenresolution[1647:7677] starting screenresolution argv=screenresolution get 12:12:20 INFO - 2016-05-27 12:12:20.034 screenresolution[1647:7677] Display 0: 1600x1200x32@60 12:12:20 INFO - 2016-05-27 12:12:20.040 screenresolution[1648:7679] starting screenresolution argv=screenresolution list 12:12:20 INFO - Available Modes on Display 0 12:12:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:20 INFO - Intel Iris: 12:12:20 INFO - Chipset Model: Intel Iris 12:12:20 INFO - Type: GPU 12:12:20 INFO - Bus: Built-In 12:12:20 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:20 INFO - Vendor: Intel (0x8086) 12:12:20 INFO - Device ID: 0x0a2e 12:12:20 INFO - Revision ID: 0x0009 12:12:20 INFO - Displays: 12:12:20 INFO - Display: 12:12:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:20 INFO - Display Serial Number: 1600x1200 60 12:12:20 INFO - Main Display: Yes 12:12:20 INFO - Mirror: Off 12:12:20 INFO - Online: Yes 12:12:20 INFO - Rotation: Supported 12:12:20 INFO - Return code: 0 12:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:20 INFO - Python 2.7.3 12:12:20 INFO - Return code: 0 12:12:20 INFO - grabbing minidump binary from tooltool 12:12:20 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')]} 12:12:20 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 0x10c9c2d30>, '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 0x10c7e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:20 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 12:12:20 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 12:12:20 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 12:12:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:20 INFO - Return code: 0 12:12:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:12:21 INFO - Return code: 0 12:12:21 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 12:12:21 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0322 --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 12:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fYgtTvznT8/Render', 12:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:21 INFO - 'HOME': '/Users/cltbld', 12:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:21 INFO - 'LOGNAME': 'cltbld', 12:12:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:21 INFO - 'MOZ_NO_REMOTE': '1', 12:12:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:21 INFO - 'NO_EM_RESTART': '1', 12:12:21 INFO - 'PAGER': '/bin/cat', 12:12:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:21 INFO - 'PWD': '/builds/slave/test', 12:12:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:21 INFO - 'SHELL': '/bin/bash', 12:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EQXC2wH6Xf/Listeners', 12:12:21 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 12:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:21 INFO - 'USER': 'cltbld', 12:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:21 INFO - 'XPC_FLAGS': '0x0', 12:12:21 INFO - 'XPC_SERVICE_NAME': '0', 12:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:21 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 12:12:21 INFO - mozversion application_buildid: 20160527091541 12:12:21 INFO - mozversion application_changeset: eec76f75c79a0a597131357ec1eed49683183c30 12:12:21 INFO - mozversion application_display_name: Nightly 12:12:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:21 INFO - mozversion application_name: Firefox 12:12:21 INFO - mozversion application_remotingname: firefox 12:12:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:12:21 INFO - mozversion application_vendor: Mozilla 12:12:21 INFO - mozversion application_version: 49.0a1 12:12:21 INFO - mozversion platform_buildid: 20160527091541 12:12:21 INFO - mozversion platform_changeset: eec76f75c79a0a597131357ec1eed49683183c30 12:12:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:12:21 INFO - mozversion platform_version: 49.0a1 12:12:21 INFO - using testdate: 1464376341 12:12:21 INFO - actual date: 1464376341 12:12:21 INFO - starting webserver on 'localhost:49276' 12:12:21 INFO - SUITE-START | Running 2 tests 12:12:21 INFO - TEST-START | dromaeo_css 12:12:21 INFO - operating with platform_type : mac_ 12:12:21 INFO - Initialising browser for dromaeo_css test... 12:12:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpyGw3y8/profile http://localhost:49276/getInfo.html) 12:12:24 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 12:12:24 INFO - PROCESS | 1666 | colorDepth:24 12:12:24 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 12:12:24 INFO - PROCESS | 1666 | __metrics 12:12:24 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 12:12:25 INFO - TEST-INFO | 1666: exit 0 12:12:25 INFO - Browser initialized. 12:12:25 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:25 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpyGw3y8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:37 INFO - PROCESS | 1669 | RSS: Main: 252674048 12:12:37 INFO - PROCESS | 1669 | 12:13:26 INFO - PROCESS | 1669 | 1464376406971 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] 12:15:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 12:15:38 INFO - PROCESS | 1669 | RSS: Main: 265142272 12:15:38 INFO - PROCESS | 1669 | 12:18:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 12:18:41 INFO - PROCESS | 1669 | RSS: Main: 276254720 12:18:41 INFO - PROCESS | 1669 | 12:21:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 12:21:44 INFO - PROCESS | 1669 | RSS: Main: 278396928 12:21:44 INFO - PROCESS | 1669 | 12:24:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 12:24:46 INFO - PROCESS | 1669 | RSS: Main: 279883776 12:24:46 INFO - PROCESS | 1669 | 12:27:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 12:27:50 INFO - PROCESS | 1669 | RSS: Main: 283312128 12:27:50 INFO - PROCESS | 1669 | 12:30:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 12:30:53 INFO - PROCESS | 1669 | RSS: Main: 283693056 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | __start_tp_report 12:30:53 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 12:30:53 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 12:30:53 INFO - PROCESS | 1669 | |i|pagename|runs| 12:30:53 INFO - PROCESS | 1669 | |0;dojo.html;3386.441237195862;3465.4108801503744;3475.5829300483947;3493.371193185227;3515.367233897897;5689.516391106756;5712.000399930006;5764.567657425694;5765.971170144154;5776.451237132471;5639.013172694783;5757.222774925383;5777.0756678931275;5819.534437245021;5860.5311575074;5189.948100518994;5256.842294731145;5352.839414817562;5356.705381204032;5412.4858138476975;218.83425564836185;5175.715335656538;5246.1606143017125;5333.333333333333;5423.3491980962335;5248.632595718302;5257.158854583267;5269.525742683177;5305.549028332596;5318.202269659543;4413.470383553962;4595.782117738802;5299.5230429261555;5302.973485132549;5313.681179129265;4543.5456454354635;5224.138017227153;5247.107991641392;5248.370195576517;5293.947060529384;5560.138178582326;5687.943120568782;5701.173329867188;5707.971460142714;5714.542836573064;6109.8601770527275;6137.846553836145;6236;6248.750049997994;6265.248170219603;5476.712972451471;6166.198394208769;6239.157713708637;6259.968700156515;6286.685665716695;5948.494377977877;5964.433378829048;5979.970100149471;5986.491148252404;5990.5507086968655;6183.412575805291;6187.226596675416;6236.126942228092;6242.250929888442;6365.745370185141;5162.251473536391;6206.844828879314;6212.06818977157;6271.184745982993;6278.623282603058;6220.7869465453805;6241.157443745036;6262.6868656566985;6267.6866156691985;6277.905831412532;6169.352218017133;6171.506279497629;6239.563230573816;6266.905996410058;6302.991521356561;6247.5939063960695;6250.968745156336;6254.34329395416;6254.874902501924;6263.31103578606;4944.530269624452;5006.949930500648;5024.924626130611;5054.368203974504;5065.924011139836;4500.549945005473;4533.32000199966;4547.045120524727;4597.793099310539;4599.839005634787;5932.851678707981;5980.252468441445;5992.550558708114;5999.700014999233;6001.909971350433;6166.907496387557;6176.073588961692;6229.377062293825;6258.687065646699;6277.937220627827;5784.248047753765;5906.7637294508595;5935.614185077956;5936.465718085393;5994.820155395345;5483.561315094783;5510.586705997066;5521.806736764194;5531.917021244684;5550.111982082847;5264.868378290496;6167.352427995085;6271.216097987752;6476.89892718239;6497.155369801895;6234.127222188807;6250.531210159165;6252.656103914146;6258.249010118815;6330.240371155491;6434.517411194086;6442;6451.1936007999;6457.935420645921;6481.546291759531;6133.325334213322;6158.907616385847;6230.190075290183;6256;6265.937340626535;4855.684380515255;6098.359672234529;6207.875842483216;6249.437550620466;6264.342244064308;5875.529957603296;6245.188125543832;6252.812415627538;6293.905591416224;6296.5592408530965;6100.664463454553;6139.890808645427;6159.322474527708;6177.227846519185;6230;5077.822276220315;5633;6067.423594758491;6067.726952287246;6071.787487437919;4691.7184968901975;6100.603460775035;6246.812595622138;6272.843178920563;6284.780032698834;5782.971085144715;5783.872144931698;5805.680687562224;5807.245058142584;5827 12:30:53 INFO - PROCESS | 1669 | |1;ext.html;508.73037290236186;520.427529717311;529.3938440485643;531.795483230483;578.1385735254472;39515.40726889095;42749.78625106874;42784.93037674056;42827.35758963618;42866.92832679177;43441.56558434415;43678.563214367845;44210;44354;44405.11189776203;9521;9760.2679799015;9765.560549775277;9785.510724463747;9792.510374481282;9586.281028922824;9669.081437263449;9683.806323873518;9722.125008749212;9746.220302375792;9698.806023879519;9717.174040206555;9720.659776907809;9739.756506087333;9797.853032204523;9688.564014619358;9715.979822118647;9733.513324333826;9736.269779766475;9760.267979901535;9626.807463850753;9730.805383892353;9742.123208911162;9764.609415623367;9772.755681107958;33996.660033399596;40171;40225.19549608992;40714.38928416076;40851.59148408537;3416.8804091856914;3596.730245231618;3651.908702282438;3708.4066549351974;3838;32175.356492870247;32995.84514541979;33590.83204583961;34068.48897266543;34097.829510852534;3287.7698561100733;3309.1561651778643;3321.4353559894876;3437.329720704464;3459.6713312235297;522628;526795;537481.999999996;538236;540751;35505.644943550236;36732.53069877229;67658.66170669116;68328;68331;36567.26865462676;36926.261474770356;37150.25699485995;37504.24991500155;37557.81221093877;15007.324670389859;15251.466198682992;15372.769408458882;15391.153486558123;15430.922845385925;11682.94158529202;11956.98365638913;11993.940030299967;12002.879971200351;12005.09961752872;8030.277275045274;8147.185281471805;8157.95921020403;8232.588370581567;8238.629261683238;3390.694837464591;3430.5540279763472;3448.689617934398;3465.358908601917;3487.5640544931885;962.6678795815433;1040.8594839696696;1098.2751384086464;1110.5113749949996;1147.4434899073942;30978.53532197019;31120.688793112233;31662.68337316597;31889.043328700176;31934.84032579822;995.9542480395555;1012.9898701013043;1059.5391004912733;1108.9168312376603;1137.7951968645723;12925.676858078623;14567.854321456862;15421.270512553272;16219.351225951066;16475.83524164743;687788;695334;697631;702720;705228;62131.689341553;62841.37158628447;62885.37114628795;62887.685561571896;62891.685541572;1412.6115318287552;1428.3929330034694;1440.9783853242209;1457.3441951122038;1463.8682518573592;30410.847945761016;30506.237344066576;30511.847440762653;30726.385472290876;30844.92042778492;42268.788656056524;50956.74521627368;51316.486835131174;51415.48584514257;51908.22137667862;592764;592864;594299;595989;596660;28299;28389.148325550268;28412;29116.12651620455;29202.977895773547;49864.75067624639;50329.245061323374;51325.23012154746;51395.74302128465;51399;40104.197916042096;40555.391669124394;40785;41620;42437;40588.39117413182;40924;41043.97390065272;41083.50796155301;41682.37476437795;39862.00344991399;39868;40328.59671403249;40894;40949.1810163801;31065.53401698931;31559.368812624078;34010.82994585011;35672.821635891654;35804.33837528362;24640.876795615906;26146.738532615193;26162.345941351618;26285.60571591466;26868.59697104506 12:30:53 INFO - PROCESS | 1669 | |2;jquery.html;379.0466975556476;380.54904937648877;387.73246459942646;394.78484226096776;398.1777629195711;19081.950492722895;20898.268560600383;21191.576168476622;21339.893300533477;21344.786552134476;20170.193192272327;20883.582328353423;20884.269050583196;21077.94610269484;21268.042938067785;5259.894802103956;5852.385499522542;6091.360407157251;6103.176071230371;6129.478994285491;5793.971030144864;6064.272287325527;6072.878542429149;6102.1151932969915;6105.755769769204;5931.436513531207;5943.167956486095;5951;5956;5991.2510936132985;5730.7421166047625;5852.414758524156;5905.527557795366;5970.611910225822;5978.8804223915495;5109.642325037249;5147.536721695028;5169.069567477853;5183.455737147583;5190.351206099238;21428.678569821466;23306.718130502817;24975.125870594624;26127.73872261272;26390.47219055608;2000.9054320950693;2337.439014636492;2534.531111744333;2545.083769842855;2631.8420894746378;14987.32557034936;15451.768223476656;15630.843691563052;15822.287997040161;15883.682326353524;1843.7879643840995;1872.953176170593;1914.090806866741;1933.9806601933942;1939.2630800295797;102873.4856325721;118343;122974;123188;123479;9273.165415112673;10467.895321046844;10540;10696.411697356565;10960.945195274133;13390.799138012937;13488.730225395437;13542.59372218835;13546.796798048037;13551.86448135506;6820.624865632438;7033.718651253893;8633.95683021581;8659.696910608098;8739.563021848882;8783.868241976375;8789.956050219707;8815.250703690259;8829.426087304304;8916.821663566692;6462.483001359974;6571.638559879157;6626.966865165643;6657.800265992028;6747.527673062838;1675.5978565144278;1897.9466396149994;1899.8765080269739;1909.2458478900755;1956.5499935015025;879.5030807593816;915.6749353979375;929.4284015330658;956.8995255498072;1006.1296978113879;20164.495887602632;20970.424040859984;20996.580068398853;21002.264920727703;21380.893095534422;970.3256236915277;975.6829030565095;1006.3157053203745;1043.4521876015153;1059.819830628795;10948.233623646269;11449.656510304705;11455.656330310105;11470.770584588261;11490.48292826808;392147;574850;584381;593909;595774;65052;67288.63556822237;87003;115655.42172289253;118145.4092729548;1004.2719028704277;1062.6280801719336;1083.842842787807;1089.2375337263948;1090.8800031996475;18996.14517346695;19151.616967660848;19300.131494082518;19346.32287869918;19377;54742.72628636991;55790.44209558015;56722.86554268974;56846.71576642256;56953;77186.2281377179;77206.6139669298;77424.61287693525;77757;79162.6041869787;7868.370530357443;8006.759797206093;8076.596170191349;8117.79705507367;8280.875786863082;10197.54111064989;12272.693182670504;12357.75284494323;12490.06324525646;12596.496140154657;10781.838272425763;10840.620578279722;10908.727281818017;10997.835032474359;11143.442827858413;10689.465526723789;10722.624708135178;10796.838047429137;11035.22753407286;11077.003069723763;9335.85996210071;10814.297070690694;10856.75147358064;11254.718632034264;11285.605003824827;7950.840983180419;8015.679372825021;8051.758447246358;8140.308073813792;8195.754127376189;10299.897001030193;10991.450427478754;11047.337159770439;11072.335659860435;11125.664920209627 12:30:53 INFO - PROCESS | 1669 | |3;mootools.html;1539.9682212917342;1581.3358389476418;1651.5953591370117;1788.383007862287;1835.8898466092028;7021.82445438863;8479.872801907977;8506.957465212681;8917.464952102879;8944.59749311281;8663.523506207172;8696.39125261232;8791.384603077788;8802.955985220065;8820.823583528325;2347.3192774095533;2374.4776149247223;2380.5833979053596;2381;2386.940326491843;2363.657269695893;2378.1200955646436;2385.6779334789753;2395.9520809583887;2408.771166739157;2362.9881850590723;2371.881405929705;2384.0582969726947;2403.267003563912;2406.3262286559793;2339.7075365579303;2353.305774796431;2378.1795280628157;2385.1413491143176;2388.3073908566494;1986.8807871527754;2328.7089113860766;2367.9289621311386;2371.2174982255815;2374.8456350337174;7854.764357069297;8423.95788021062;8837.779055523662;8848.336374771918;8867.689630862953;5224.582033437316;5376.059189641797;5399.271098401705;5411.215373770781;5416.891662166735;3581.391163502211;3647.3252448296885;3712.7029837613195;3737.8225858854644;3739.6821270192067;1096.901278884896;1098.3958822647512;1104.9558017679283;1107.634480621393;1112.6383925224334;9675;11434.599789007429;11541.653750387502;11550.844915508465;11676.357800320979;4718.764061796896;4732.905341893212;4838.758062096881;4842.854714358575;4863.562279394871;9857.852132217879;9863.950680246697;9876.555555000043;9885.209183265322;9903.405795652285;5271.604629652791;5317.175837745156;5320.414754377015;5361.249425080492;5409.675419474844;4415.40392047076;4782.713037217709;4889.951100488949;4954.5788607968725;4955.306257124005;3970;4011.29802284599;4058.370952502367;4085.7344272622186;4145.937810932838;1250.2623452163086;1319.287584704249;1320.8084827700122;1324.3576865220361;1330.1487048289102;956.679512363366;963.4556475591269;965.3049804141007;967.6855022117699;996.3971797062818;4882.780274887638;5938.970305148447;5958.702064896738;5987.161797348374;6045.33501314855;411.2679430613513;460.7788261634434;907.3920473282916;1005.8994100589882;1120.882307357732;3823.4073718573663;3976.343903255988;4049.979750101231;4075.9388609170887;4090.734102283342;8317.8336433271;9495.43027418357;9690.903090969001;9878.50607469638;9930.304878658424;7982.800429989296;9764.462954537426;9821.754456138511;9825.263105267133;9860.506974651238;7972.920270797334;8105.716299929474;8144.208274179671;8250.216229458312;8253.79365515867;5803.9419605803405;5890.4698577128265;5909.793157239476;5915.940840591711;5992.0712289595185;9759.316847820584;9901.851472227778;9904.504774761377;9910.405375677483;9913.058259465339;8120.96975756061;9698.51507424612;9894.06006429388;10094.444805535766;10106.949465252626;6828.351306625863;7471.663775130023;7629.961850190935;7973.242541958504;8292.543910085004;8287.668493260202;8302.252797248277;8388.958055209685;8515.659373624985;8519.148085191431;7473.92526074754;7595.468317217742;7625.046869141357;7713.19011503784;7744.0319960005;7356.117265927909;7378.335949764547;7417.184109748031;7494.662740176815;7572.091349038151;6917.861642767016;7030.543014704028;7059.541129826544;7064.611446370499;7088.291170882871;4880.414350277847;5026.120428924851;5052.8484145475695;5112.795488180431;5137.357830271216;5027.974860125676;5120.231965205249;5123.795048198031;5164.483551644806;5279.26090347344 12:30:53 INFO - PROCESS | 1669 | |4;prototype.html;267.7295931109579;296.18696677619937;304.98932537361196;313.79289668818586;315.9730874657363;4456.3761073449705;5552.250446189763;5602.691851948141;5622.746976386067;5630.803986445279;4589.495155532889;4905.877353066175;5491.007400966483;5596.58025648076;5622.550195984321;4002.039000889558;4115.629593336599;4241.8303267869405;4245.64139475368;4249.745015299077;3485.372632926085;3671.3024525340234;3680.392735198689;3989.900252493682;4282.207791558556;4238.152369526092;4267.722598031133;4288.270993931023;4290.2706539888295;4295.656347492193;3888.4361767543833;4141.834326626947;4258.020655249279;4270.882255953141;4302.311630139163;3073.7387322077425;3667.669909708139;3679.9448008279896;3691.280200360931;3707.796071216082;4260.446142001551;5141.820036298712;5239.401982395368;5353.464653534655;5445.591580631469;3778.5843557208686;3890.8832735017713;4880.755962201875;4900.975495122537;4909.649846292263;4445.599896009375;5166.664166829181;5169.8449046528285;5837.86161698469;5878.707304151384;3259.3970115528464;3577.94633080504;3795.013296542891;3945.8618948336957;4085.59144085592;5063.87340316495;5287.709175995319;5879.617824841414;6000.429959153874;6211.285702144266;3385.8984230472633;3935.311320518898;4092.3247664135083;4137.565555616617;4168.270552653274;3704.981475092661;4100.65144462724;4196.748195108304;4234.259004674232;4239.40648309237;4881.145799485076;4914.5822605078965;4931.408231012301;4935.087008903364;4938;5161.329027226437;5650.457294217536;5951.404859514014;5956.583039187302;5963.165156878041;1232.617888454564;1319.265166306304;1361.748076605831;1395.3720825628507;1448.2469116059801;773.4469854054378;794.7059587952378;825.640846231891;881.9911800882037;905.515549181185;4827.686200397034;5394.676319420847;5480.862978425492;5481.972590137104;5492.423295553989;5279.102552566073;5564.165375193753;5969.343372229051;6000.379897427757;6058.727357268934;4708.480962684947;5394.298741163623;5431.375391829911;5475.6440831345835;5524.447555244524;2673.5187666220268;2978.508546089914;2980.269833890667;3009.7291243787727;3148.102790704638;5631.831045068655;5841.9415805842245;6154.661493617894;6172.506199504029;6261.749530018839;5023.433362312726;5721.656700597894;5821.883562328814;6320.336364681734;6327.778527751507;5903.085021821625;6387.233531976192;6422;6424.486041116699;6440.80677579664;5650.604457687922;5774.42255774436;6091.664958427329;6210.254769427697;6218.875622487615;5203.401608814884;5425.782968681208;5920.496757775637;6130.233720784902;6176.320604733386;5631.465010823965;5632.324121105494;5877.73550190251;6400.87984602702;6428.774992875227;3765.322241996357;3933.7349999500916;3951.841926322915;3974.3243648580387;3975.1850870570884;5308.601854860824;5383.892322153457;5931.885759976047;6163.38366163398;6165.506759459322;5523.420040895809;5900.350961394329;5923.555733319932;6021.066734656135;6032.00471922128;4943.467525067241;5400.324959380077;5770.653760774199;5873.324567674601;5903.26209223847;4848.757562121809;5003.874903127451;5383.111786555174;5791.449812267828;5809.36097029318;4714.363560919232;5630.718464076862;5955.851103722442;5960.433758792908;5973.462388385066;5812.564057695774;5839.912401313899;5842.211301474246;5873.530117590668;5907.85230369227 12:30:53 INFO - PROCESS | 1669 | |5;yui.html;740.6444906444906;830.6884918155688;842.5606732583291;860.7374750701038;876.3296078499944;14667.853321466782;15028.699426011473;15200.01199922007;15563.92218038911;16178.271977761;15609.609759755984;15718.528444146665;15752.448664296751;15782.21088945551;15785.289661965211;715.763797946679;879.6409547249483;907.6051917415945;922.9815403691922;923.745969858291;901.1709227510673;901.3870568013748;902.7788191892995;902.9413088149282;904.1726819959729;930.042056681619;953.9284553658468;962.1821451766012;963.10431298892;970.5001924009141;811.7929927868386;852.4373913217247;926.5043201886991;954.3939598355059;967.4872317671645;660.7753363856264;689.6724056073325;705.7812078255757;717.0320067908334;717.218232126984;14797.964142509922;14813.925930370388;14819.555413337617;14855;14896.180710060942;13750.381232844446;13752.587422377244;13769.931150344284;13770.724585508335;13975.371108300049;11466.598669046627;11709.997252815847;11858.703532411759;11930.880691193064;12115;3803.3724435468116;3843.865464708722;3877.263319969225;3918.2555314490155;4007.939880901789;85156.7226491603;85900.57049714774;86084.26536540488;86983;88143;10576.320423410983;10837.67486975392;10891.945540272407;10917.454127293604;11022.44887755609;20818;20871;21209.681854772192;21280.255191068547;21315.68026479604;7715.845683086419;7792.493487923267;7948.403869709795;7951.125376208613;7984.880226796603;3778.962210377916;4374.562543745664;4469.932951005738;4503.761465596922;4557.361969324297;3427.485877118402;3532.752707310515;3540.822958852047;3542.326957877995;3588.928221435609;720.83781149241;732.8644200822865;747.1631772414821;749.6439191384048;749.9475036747484;556.5742207211487;627.9026750853625;635.3646353646353;642.6047980492057;650.2457149706413;9169.495677737788;9476.241900647932;9531.285153613506;9644.18024467914;9661;508.217345288252;590.2120668907057;615.6644628677382;618.7153909201728;618.9535784816156;4441.866743997685;4487.169873573399;4513.593776560126;4527.977360113178;4542.182407166676;78844.60577697189;79796;92009;92306.53846730724;94610.52694736482;72290;73407;73828.26171738321;74062.25937740662;81218;56578;71359.6432017847;71582;73119.63440182872;74167.25832741712;7022.929770702228;9553.092456216647;9567;9639.602257672457;9744.75638109053;39940;40229.19541609093;46846.76576617095;46904.53095469002;47264;62264;62522.68738656278;64696.67651661712;65410.34589654043;65716.05703657796;12780.936095319836;12877.227366358049;13190;13371.799423008468;13381.598552043455;14302.356393962089;14482.855171448151;14599.85400145985;14916.248445605046;14951.028183168059;13492.12301200419;13842.930785346009;13913.452069722156;13980.720385592027;14068.929655351658;13273.402696878857;13745.312734363442;13876.72246555043;13922.721545569235;13928.860711393161;9513.476758778334;11937.52249909994;12897.548585799452;13478.351684420784;13513.527026554024;10110.494475276353;10260.179185665278;10353.275270731277;10393.220508461744;11147;9289.86065209036;10101.646442374482;10442.843357349493;11105.777884442428;11136.38749868765 12:30:53 INFO - PROCESS | 1669 | __end_tp_report 12:30:53 INFO - PROCESS | 1669 | __start_cc_report 12:30:53 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,412 12:30:53 INFO - PROCESS | 1669 | __end_cc_report 12:30:53 INFO - PROCESS | 1669 | __startTimestamp1464377453095__endTimestamp 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | ------- Summary: start ------- 12:30:53 INFO - PROCESS | 1669 | Number of tests: 6 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5725.95 Median:5986.49 stddev:777.19 (13.0%) stddev-sans-first:757.59 12:30:53 INFO - PROCESS | 1669 | Values: 3386.4 3465.4 3475.6 3493.4 3515.4 5689.5 5712.0 5764.6 5766.0 5776.5 5639.0 5757.2 5777.1 5819.5 5860.5 5189.9 5256.8 5352.8 5356.7 5412.5 218.8 5175.7 5246.2 5333.3 5423.3 5248.6 5257.2 5269.5 5305.5 5318.2 4413.5 4595.8 5299.5 5303.0 5313.7 4543.5 5224.1 5247.1 5248.4 5293.9 5560.1 5687.9 5701.2 5708.0 5714.5 6109.9 6137.8 6236.0 6248.8 6265.2 5476.7 6166.2 6239.2 6260.0 6286.7 5948.5 5964.4 5980.0 5986.5 5990.6 6183.4 6187.2 6236.1 6242.3 6365.7 5162.3 6206.8 6212.1 6271.2 6278.6 6220.8 6241.2 6262.7 6267.7 6277.9 6169.4 6171.5 6239.6 6266.9 6303.0 6247.6 6251.0 6254.3 6254.9 6263.3 4944.5 5006.9 5024.9 5054.4 5065.9 4500.5 4533.3 4547.0 4597.8 4599.8 5932.9 5980.3 5992.6 5999.7 6001.9 6166.9 6176.1 6229.4 6258.7 6277.9 5784.2 5906.8 5935.6 5936.5 5994.8 5483.6 5510.6 5521.8 5531.9 5550.1 5264.9 6167.4 6271.2 6476.9 6497.2 6234.1 6250.5 6252.7 6258.2 6330.2 6434.5 6442.0 6451.2 6457.9 6481.5 6133.3 6158.9 6230.2 6256.0 6265.9 4855.7 6098.4 6207.9 6249.4 6264.3 5875.5 6245.2 6252.8 6293.9 6296.6 6100.7 6139.9 6159.3 6177.2 6230.0 5077.8 5633.0 6067.4 6067.7 6071.8 4691.7 6100.6 6246.8 6272.8 6284.8 5783.0 5783.9 5805.7 5807.2 5827.0 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:72956.69 Median:40749.69 stddev:164083.13 (402.7%) stddev-sans-first:164453.27 12:30:53 INFO - PROCESS | 1669 | Values: 508.7 520.4 529.4 531.8 578.1 39515.4 42749.8 42784.9 42827.4 42866.9 43441.6 43678.6 44210.0 44354.0 44405.1 9521.0 9760.3 9765.6 9785.5 9792.5 9586.3 9669.1 9683.8 9722.1 9746.2 9698.8 9717.2 9720.7 9739.8 9797.9 9688.6 9716.0 9733.5 9736.3 9760.3 9626.8 9730.8 9742.1 9764.6 9772.8 33996.7 40171.0 40225.2 40714.4 40851.6 3416.9 3596.7 3651.9 3708.4 3838.0 32175.4 32995.8 33590.8 34068.5 34097.8 3287.8 3309.2 3321.4 3437.3 3459.7 522628.0 526795.0 537482.0 538236.0 540751.0 35505.6 36732.5 67658.7 68328.0 68331.0 36567.3 36926.3 37150.3 37504.2 37557.8 15007.3 15251.5 15372.8 15391.2 15430.9 11682.9 11957.0 11993.9 12002.9 12005.1 8030.3 8147.2 8158.0 8232.6 8238.6 3390.7 3430.6 3448.7 3465.4 3487.6 962.7 1040.9 1098.3 1110.5 1147.4 30978.5 31120.7 31662.7 31889.0 31934.8 996.0 1013.0 1059.5 1108.9 1137.8 12925.7 14567.9 15421.3 16219.4 16475.8 687788.0 695334.0 697631.0 702720.0 705228.0 62131.7 62841.4 62885.4 62887.7 62891.7 1412.6 1428.4 1441.0 1457.3 1463.9 30410.8 30506.2 30511.8 30726.4 30844.9 42268.8 50956.7 51316.5 51415.5 51908.2 592764.0 592864.0 594299.0 595989.0 596660.0 28299.0 28389.1 28412.0 29116.1 29203.0 49864.8 50329.2 51325.2 51395.7 51399.0 40104.2 40555.4 40785.0 41620.0 42437.0 40588.4 40924.0 41044.0 41083.5 41682.4 39862.0 39868.0 40328.6 40894.0 40949.2 31065.5 31559.4 34010.8 35672.8 35804.3 24640.9 26146.7 26162.3 26285.6 26868.6 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:32818.00 Median:22367.70 stddev:92139.01 (411.9%) stddev-sans-first:92365.29 12:30:53 INFO - PROCESS | 1669 | Values: 379.0 380.5 387.7 394.8 398.2 19082.0 20898.3 21191.6 21339.9 21344.8 20170.2 20883.6 20884.3 21077.9 21268.0 5259.9 5852.4 6091.4 6103.2 6129.5 5794.0 6064.3 6072.9 6102.1 6105.8 5931.4 5943.2 5951.0 5956.0 5991.3 5730.7 5852.4 5905.5 5970.6 5978.9 5109.6 5147.5 5169.1 5183.5 5190.4 21428.7 23306.7 24975.1 26127.7 26390.5 2000.9 2337.4 2534.5 2545.1 2631.8 14987.3 15451.8 15630.8 15822.3 15883.7 1843.8 1873.0 1914.1 1934.0 1939.3 102873.5 118343.0 122974.0 123188.0 123479.0 9273.2 10467.9 10540.0 10696.4 10960.9 13390.8 13488.7 13542.6 13546.8 13551.9 6820.6 7033.7 8634.0 8659.7 8739.6 8783.9 8790.0 8815.3 8829.4 8916.8 6462.5 6571.6 6627.0 6657.8 6747.5 1675.6 1897.9 1899.9 1909.2 1956.5 879.5 915.7 929.4 956.9 1006.1 20164.5 20970.4 20996.6 21002.3 21380.9 970.3 975.7 1006.3 1043.5 1059.8 10948.2 11449.7 11455.7 11470.8 11490.5 392147.0 574850.0 584381.0 593909.0 595774.0 65052.0 67288.6 87003.0 115655.4 118145.4 1004.3 1062.6 1083.8 1089.2 1090.9 18996.1 19151.6 19300.1 19346.3 19377.0 54742.7 55790.4 56722.9 56846.7 56953.0 77186.2 77206.6 77424.6 77757.0 79162.6 7868.4 8006.8 8076.6 8117.8 8280.9 10197.5 12272.7 12357.8 12490.1 12596.5 10781.8 10840.6 10908.7 10997.8 11143.4 10689.5 10722.6 10796.8 11035.2 11077.0 9335.9 10814.3 10856.8 11254.7 11285.6 7950.8 8015.7 8051.8 8140.3 8195.8 10299.9 10991.5 11047.3 11072.3 11125.7 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5637.59 Median:5368.65 stddev:3049.52 (56.8%) stddev-sans-first:3042.52 12:30:53 INFO - PROCESS | 1669 | Values: 1540.0 1581.3 1651.6 1788.4 1835.9 7021.8 8479.9 8507.0 8917.5 8944.6 8663.5 8696.4 8791.4 8803.0 8820.8 2347.3 2374.5 2380.6 2381.0 2386.9 2363.7 2378.1 2385.7 2396.0 2408.8 2363.0 2371.9 2384.1 2403.3 2406.3 2339.7 2353.3 2378.2 2385.1 2388.3 1986.9 2328.7 2367.9 2371.2 2374.8 7854.8 8424.0 8837.8 8848.3 8867.7 5224.6 5376.1 5399.3 5411.2 5416.9 3581.4 3647.3 3712.7 3737.8 3739.7 1096.9 1098.4 1105.0 1107.6 1112.6 9675.0 11434.6 11541.7 11550.8 11676.4 4718.8 4732.9 4838.8 4842.9 4863.6 9857.9 9864.0 9876.6 9885.2 9903.4 5271.6 5317.2 5320.4 5361.2 5409.7 4415.4 4782.7 4890.0 4954.6 4955.3 3970.0 4011.3 4058.4 4085.7 4145.9 1250.3 1319.3 1320.8 1324.4 1330.1 956.7 963.5 965.3 967.7 996.4 4882.8 5939.0 5958.7 5987.2 6045.3 411.3 460.8 907.4 1005.9 1120.9 3823.4 3976.3 4050.0 4075.9 4090.7 8317.8 9495.4 9690.9 9878.5 9930.3 7982.8 9764.5 9821.8 9825.3 9860.5 7972.9 8105.7 8144.2 8250.2 8253.8 5803.9 5890.5 5909.8 5915.9 5992.1 9759.3 9901.9 9904.5 9910.4 9913.1 8121.0 9698.5 9894.1 10094.4 10106.9 6828.4 7471.7 7630.0 7973.2 8292.5 8287.7 8302.3 8389.0 8515.7 8519.1 7473.9 7595.5 7625.0 7713.2 7744.0 7356.1 7378.3 7417.2 7494.7 7572.1 6917.9 7030.5 7059.5 7064.6 7088.3 4880.4 5026.1 5052.8 5112.8 5137.4 5028.0 5120.2 5123.8 5164.5 5279.3 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4706.19 Median:5298.16 stddev:1483.05 (28.0%) stddev-sans-first:1449.31 12:30:53 INFO - PROCESS | 1669 | Values: 267.7 296.2 305.0 313.8 316.0 4456.4 5552.3 5602.7 5622.7 5630.8 4589.5 4905.9 5491.0 5596.6 5622.6 4002.0 4115.6 4241.8 4245.6 4249.7 3485.4 3671.3 3680.4 3989.9 4282.2 4238.2 4267.7 4288.3 4290.3 4295.7 3888.4 4141.8 4258.0 4270.9 4302.3 3073.7 3667.7 3679.9 3691.3 3707.8 4260.4 5141.8 5239.4 5353.5 5445.6 3778.6 3890.9 4880.8 4901.0 4909.6 4445.6 5166.7 5169.8 5837.9 5878.7 3259.4 3577.9 3795.0 3945.9 4085.6 5063.9 5287.7 5879.6 6000.4 6211.3 3385.9 3935.3 4092.3 4137.6 4168.3 3705.0 4100.7 4196.7 4234.3 4239.4 4881.1 4914.6 4931.4 4935.1 4938.0 5161.3 5650.5 5951.4 5956.6 5963.2 1232.6 1319.3 1361.7 1395.4 1448.2 773.4 794.7 825.6 882.0 905.5 4827.7 5394.7 5480.9 5482.0 5492.4 5279.1 5564.2 5969.3 6000.4 6058.7 4708.5 5394.3 5431.4 5475.6 5524.4 2673.5 2978.5 2980.3 3009.7 3148.1 5631.8 5841.9 6154.7 6172.5 6261.7 5023.4 5721.7 5821.9 6320.3 6327.8 5903.1 6387.2 6422.0 6424.5 6440.8 5650.6 5774.4 6091.7 6210.3 6218.9 5203.4 5425.8 5920.5 6130.2 6176.3 5631.5 5632.3 5877.7 6400.9 6428.8 3765.3 3933.7 3951.8 3974.3 3975.2 5308.6 5383.9 5931.9 6163.4 6165.5 5523.4 5900.4 5923.6 6021.1 6032.0 4943.5 5400.3 5770.7 5873.3 5903.3 4848.8 5003.9 5383.1 5791.4 5809.4 4714.4 5630.7 5955.9 5960.4 5973.5 5812.6 5839.9 5842.2 5873.5 5907.9 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18568.75 Median:46875.65 stddev:25000.04 (53.3%) stddev-sans-first:25034.33 12:30:53 INFO - PROCESS | 1669 | Values: 740.6 830.7 842.6 860.7 876.3 14667.9 15028.7 15200.0 15563.9 16178.3 15609.6 15718.5 15752.4 15782.2 15785.3 715.8 879.6 907.6 923.0 923.7 901.2 901.4 902.8 902.9 904.2 930.0 953.9 962.2 963.1 970.5 811.8 852.4 926.5 954.4 967.5 660.8 689.7 705.8 717.0 717.2 14798.0 14813.9 14819.6 14855.0 14896.2 13750.4 13752.6 13769.9 13770.7 13975.4 11466.6 11710.0 11858.7 11930.9 12115.0 3803.4 3843.9 3877.3 3918.3 4007.9 85156.7 85900.6 86084.3 86983.0 88143.0 10576.3 10837.7 10891.9 10917.5 11022.4 20818.0 20871.0 21209.7 21280.3 21315.7 7715.8 7792.5 7948.4 7951.1 7984.9 3779.0 4374.6 4469.9 4503.8 4557.4 3427.5 3532.8 3540.8 3542.3 3588.9 720.8 732.9 747.2 749.6 749.9 556.6 627.9 635.4 642.6 650.2 9169.5 9476.2 9531.3 9644.2 9661.0 508.2 590.2 615.7 618.7 619.0 4441.9 4487.2 4513.6 4528.0 4542.2 78844.6 79796.0 92009.0 92306.5 94610.5 72290.0 73407.0 73828.3 74062.3 81218.0 56578.0 71359.6 71582.0 73119.6 74167.3 7022.9 9553.1 9567.0 9639.6 9744.8 39940.0 40229.2 46846.8 46904.5 47264.0 62264.0 62522.7 64696.7 65410.3 65716.1 12780.9 12877.2 13190.0 13371.8 13381.6 14302.4 14482.9 14599.9 14916.2 14951.0 13492.1 13842.9 13913.5 13980.7 14068.9 13273.4 13745.3 13876.7 13922.7 13928.9 9513.5 11937.5 12897.5 13478.4 13513.5 10110.5 10260.2 10353.3 10393.2 11147.0 9289.9 10101.6 10442.8 11105.8 11136.4 12:30:53 INFO - PROCESS | 1669 | -------- Summary: end -------- 12:30:53 INFO - PROCESS | 1669 | 12:30:53 INFO - TEST-INFO | 1669: exit 0 12:30:53 INFO - TEST-OK | dromaeo_css | took 1111922ms 12:30:53 INFO - TEST-START | kraken 12:30:53 INFO - operating with platform_type : mac_ 12:30:53 INFO - Initialising browser for kraken test... 12:30:53 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpxLrnLR/profile http://localhost:49276/getInfo.html) 12:30:56 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 12:30:56 INFO - PROCESS | 1679 | colorDepth:24 12:30:56 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 12:30:56 INFO - PROCESS | 1679 | __metrics 12:30:56 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 12:30:57 INFO - TEST-INFO | 1679: exit 0 12:30:57 INFO - Browser initialized. 12:30:57 INFO - Running cycle 1/1 for kraken test... 12:30:57 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpxLrnLR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:09 INFO - PROCESS | 1681 | RSS: Main: 252502016 12:31:09 INFO - PROCESS | 1681 | 12:31:12 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:13 INFO - PROCESS | 1681 | RSS: Main: 265265152 12:31:13 INFO - PROCESS | 1681 | 12:31:15 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 12:31:16 INFO - PROCESS | 1681 | RSS: Main: 270020608 12:31:16 INFO - PROCESS | 1681 | 12:31:19 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 12:31:20 INFO - PROCESS | 1681 | RSS: Main: 270274560 12:31:20 INFO - PROCESS | 1681 | 12:31:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 12:31:22 INFO - PROCESS | 1681 | RSS: Main: 271261696 12:31:22 INFO - PROCESS | 1681 | 12:31:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:25 INFO - PROCESS | 1681 | RSS: Main: 271810560 12:31:25 INFO - PROCESS | 1681 | 12:31:28 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:29 INFO - PROCESS | 1681 | RSS: Main: 271634432 12:31:29 INFO - PROCESS | 1681 | 12:31:32 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:32 INFO - PROCESS | 1681 | RSS: Main: 271839232 12:31:32 INFO - PROCESS | 1681 | 12:31:35 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 12:31:36 INFO - PROCESS | 1681 | RSS: Main: 271863808 12:31:36 INFO - PROCESS | 1681 | 12:31:38 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:38 INFO - PROCESS | 1681 | RSS: Main: 271429632 12:31:38 INFO - PROCESS | 1681 | 12:31:40 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:41 INFO - PROCESS | 1681 | RSS: Main: 271638528 12:31:41 INFO - PROCESS | 1681 | 12:31:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:44 INFO - PROCESS | 1681 | RSS: Main: 272023552 12:31:44 INFO - PROCESS | 1681 | 12:31:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:47 INFO - PROCESS | 1681 | RSS: Main: 271921152 12:31:47 INFO - PROCESS | 1681 | 12:31:50 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:51 INFO - PROCESS | 1681 | RSS: Main: 272240640 12:31:51 INFO - PROCESS | 1681 | 12:31:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 12:31:53 INFO - PROCESS | 1681 | RSS: Main: 272752640 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | __start_tp_report 12:31:53 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 12:31:53 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 12:31:53 INFO - PROCESS | 1681 | |i|pagename|runs| 12:31:53 INFO - PROCESS | 1681 | |0;ai-astar;116;110;98;165;174;140;116;116;114;118 12:31:53 INFO - PROCESS | 1681 | |1;audio-beat-detection;190;151;147;165;120;141;136;122;205;120 12:31:53 INFO - PROCESS | 1681 | |2;audio-dft;195;201;202;234;193;241;274;197;191;198 12:31:53 INFO - PROCESS | 1681 | |3;audio-fft;71;83;77;138;50;50;61;76;127;49 12:31:53 INFO - PROCESS | 1681 | |4;audio-oscillator;70;71;113;70;71;86;116;70;75;119 12:31:53 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;90;98;119;88;92;87;101;117;91;99 12:31:53 INFO - PROCESS | 1681 | |6;imaging-darkroom;95;97;106;150;122;125;154;96;97;104 12:31:53 INFO - PROCESS | 1681 | |7;imaging-desaturate;138;137;94;98;128;95;98;94;126;145 12:31:53 INFO - PROCESS | 1681 | |8;json-parse-financial;56;55;57;53;57;56;59;56;59;57 12:31:53 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;66;71;68;72;76;73;73;69;69;69 12:31:53 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;87;86;88;86;103;110;110;106;121;91 12:31:53 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;160;155;149;144;135;144;149;137;153;139 12:31:53 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;166;205;225;163;227;204;163;164;166;170 12:31:53 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;65;66;65;67;66;65;65;66;66 12:31:53 INFO - PROCESS | 1681 | __end_tp_report 12:31:53 INFO - PROCESS | 1681 | __start_cc_report 12:31:53 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1077 12:31:53 INFO - PROCESS | 1681 | __end_cc_report 12:31:53 INFO - PROCESS | 1681 | __startTimestamp1464377513120__endTimestamp 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | ------- Summary: start ------- 12:31:53 INFO - PROCESS | 1681 | Number of tests: 14 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:126.70 Median:129.00 stddev:24.86 (19.3%) stddev-sans-first:26.07 12:31:53 INFO - PROCESS | 1681 | Values: 116.0 110.0 98.0 165.0 174.0 140.0 116.0 116.0 114.0 118.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:149.00 stddev:29.29 (19.7%) stddev-sans-first:27.19 12:31:53 INFO - PROCESS | 1681 | Values: 190.0 151.0 147.0 165.0 120.0 141.0 136.0 122.0 205.0 120.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:212.60 Median:201.50 stddev:27.69 (13.7%) stddev-sans-first:28.62 12:31:53 INFO - PROCESS | 1681 | Values: 195.0 201.0 202.0 234.0 193.0 241.0 274.0 197.0 191.0 198.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:78.20 Median:66.00 stddev:31.23 (47.3%) stddev-sans-first:33.02 12:31:53 INFO - PROCESS | 1681 | Values: 71.0 83.0 77.0 138.0 50.0 50.0 61.0 76.0 127.0 49.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:70.50 stddev:21.23 (30.1%) stddev-sans-first:21.71 12:31:53 INFO - PROCESS | 1681 | Values: 70.0 71.0 113.0 70.0 71.0 86.0 116.0 70.0 75.0 119.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:90.50 stddev:11.46 (12.7%) stddev-sans-first:11.76 12:31:53 INFO - PROCESS | 1681 | Values: 90.0 98.0 119.0 88.0 92.0 87.0 101.0 117.0 91.0 99.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:124.50 stddev:22.37 (18.0%) stddev-sans-first:22.58 12:31:53 INFO - PROCESS | 1681 | Values: 95.0 97.0 106.0 150.0 122.0 125.0 154.0 96.0 97.0 104.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:115.30 Median:94.00 stddev:21.24 (22.6%) stddev-sans-first:20.89 12:31:53 INFO - PROCESS | 1681 | Values: 138.0 137.0 94.0 98.0 128.0 95.0 98.0 94.0 126.0 145.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.78 (3.1%) stddev-sans-first:1.88 12:31:53 INFO - PROCESS | 1681 | Values: 56.0 55.0 57.0 53.0 57.0 56.0 59.0 56.0 59.0 57.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.50 stddev:2.95 (4.1%) stddev-sans-first:2.62 12:31:53 INFO - PROCESS | 1681 | Values: 66.0 71.0 68.0 72.0 76.0 73.0 73.0 69.0 69.0 69.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:98.80 Median:86.00 stddev:12.73 (14.8%) stddev-sans-first:12.76 12:31:53 INFO - PROCESS | 1681 | Values: 87.0 86.0 88.0 86.0 103.0 110.0 110.0 106.0 121.0 91.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:149.00 stddev:8.17 (5.5%) stddev-sans-first:7.05 12:31:53 INFO - PROCESS | 1681 | Values: 160.0 155.0 149.0 144.0 135.0 144.0 149.0 137.0 153.0 139.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:187.00 stddev:26.83 (14.3%) stddev-sans-first:27.54 12:31:53 INFO - PROCESS | 1681 | Values: 166.0 205.0 225.0 163.0 227.0 204.0 163.0 164.0 166.0 170.0 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:0.71 12:31:53 INFO - PROCESS | 1681 | Values: 68.0 65.0 66.0 65.0 67.0 66.0 65.0 65.0 66.0 66.0 12:31:53 INFO - PROCESS | 1681 | -------- Summary: end -------- 12:31:53 INFO - PROCESS | 1681 | 12:31:53 INFO - TEST-INFO | 1681: exit 0 12:31:53 INFO - TEST-OK | kraken | took 60364ms 12:31:53 INFO - SUITE-END | took 1172s 12:31:54 INFO - Completed test suite (00:19:32) 12:31:54 INFO - javascript benchmark 12:31:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7293.466140978892, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.441237195862, 3465.4108801503744, 3475.5829300483947, 3493.371193185227, 3515.367233897897, 5689.516391106756, 5712.000399930006, 5764.567657425694, 5765.971170144154, 5776.451237132471, 5639.013172694783, 5757.222774925383, 5777.0756678931275, 5819.534437245021, 5860.5311575074, 5189.948100518994, 5256.842294731145, 5352.839414817562, 5356.705381204032, 5412.4858138476975, 218.83425564836185, 5175.715335656538, 5246.1606143017125, 5333.333333333333, 5423.3491980962335, 5248.632595718302, 5257.158854583267, 5269.525742683177, 5305.549028332596, 5318.202269659543, 4413.470383553962, 4595.782117738802, 5299.5230429261555, 5302.973485132549, 5313.681179129265, 4543.5456454354635, 5224.138017227153, 5247.107991641392, 5248.370195576517, 5293.947060529384, 5560.138178582326, 5687.943120568782, 5701.173329867188, 5707.971460142714, 5714.542836573064, 6109.8601770527275, 6137.846553836145, 6236.0, 6248.750049997994, 6265.248170219603, 5476.712972451471, 6166.198394208769, 6239.157713708637, 6259.968700156515, 6286.685665716695, 5948.494377977877, 5964.433378829048, 5979.970100149471, 5986.491148252404, 5990.5507086968655, 6183.412575805291, 6187.226596675416, 6236.126942228092, 6242.250929888442, 6365.745370185141, 5162.251473536391, 6206.844828879314, 6212.06818977157, 6271.184745982993, 6278.623282603058, 6220.7869465453805, 6241.157443745036, 6262.6868656566985, 6267.6866156691985, 6277.905831412532, 6169.352218017133, 6171.506279497629, 6239.563230573816, 6266.905996410058, 6302.991521356561, 6247.5939063960695, 6250.968745156336, 6254.34329395416, 6254.874902501924, 6263.31103578606, 4944.530269624452, 5006.949930500648, 5024.924626130611, 5054.368203974504, 5065.924011139836, 4500.549945005473, 4533.32000199966, 4547.045120524727, 4597.793099310539, 4599.839005634787, 5932.851678707981, 5980.252468441445, 5992.550558708114, 5999.700014999233, 6001.909971350433, 6166.907496387557, 6176.073588961692, 6229.377062293825, 6258.687065646699, 6277.937220627827, 5784.248047753765, 5906.7637294508595, 5935.614185077956, 5936.465718085393, 5994.820155395345, 5483.561315094783, 5510.586705997066, 5521.806736764194, 5531.917021244684, 5550.111982082847, 5264.868378290496, 6167.352427995085, 6271.216097987752, 6476.89892718239, 6497.155369801895, 6234.127222188807, 6250.531210159165, 6252.656103914146, 6258.249010118815, 6330.240371155491, 6434.517411194086, 6442.0, 6451.1936007999, 6457.935420645921, 6481.546291759531, 6133.325334213322, 6158.907616385847, 6230.190075290183, 6256.0, 6265.937340626535, 4855.684380515255, 6098.359672234529, 6207.875842483216, 6249.437550620466, 6264.342244064308, 5875.529957603296, 6245.188125543832, 6252.812415627538, 6293.905591416224, 6296.5592408530965, 6100.664463454553, 6139.890808645427, 6159.322474527708, 6177.227846519185, 6230.0, 5077.822276220315, 5633.0, 6067.423594758491, 6067.726952287246, 6071.787487437919, 4691.7184968901975, 6100.603460775035, 6246.812595622138, 6272.843178920563, 6284.780032698834, 5782.971085144715, 5783.872144931698, 5805.680687562224, 5807.245058142584, 5827.0], "value": 5682.234741862208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.73037290236186, 520.427529717311, 529.3938440485643, 531.795483230483, 578.1385735254472, 39515.40726889095, 42749.78625106874, 42784.93037674056, 42827.35758963618, 42866.92832679177, 43441.56558434415, 43678.563214367845, 44210.0, 44354.0, 44405.11189776203, 9521.0, 9760.2679799015, 9765.560549775277, 9785.510724463747, 9792.510374481282, 9586.281028922824, 9669.081437263449, 9683.806323873518, 9722.125008749212, 9746.220302375792, 9698.806023879519, 9717.174040206555, 9720.659776907809, 9739.756506087333, 9797.853032204523, 9688.564014619358, 9715.979822118647, 9733.513324333826, 9736.269779766475, 9760.267979901535, 9626.807463850753, 9730.805383892353, 9742.123208911162, 9764.609415623367, 9772.755681107958, 33996.660033399596, 40171.0, 40225.19549608992, 40714.38928416076, 40851.59148408537, 3416.8804091856914, 3596.730245231618, 3651.908702282438, 3708.4066549351974, 3838.0, 32175.356492870247, 32995.84514541979, 33590.83204583961, 34068.48897266543, 34097.829510852534, 3287.7698561100733, 3309.1561651778643, 3321.4353559894876, 3437.329720704464, 3459.6713312235297, 522628.0, 526795.0, 537481.999999996, 538236.0, 540751.0, 35505.644943550236, 36732.53069877229, 67658.66170669116, 68328.0, 68331.0, 36567.26865462676, 36926.261474770356, 37150.25699485995, 37504.24991500155, 37557.81221093877, 15007.324670389859, 15251.466198682992, 15372.769408458882, 15391.153486558123, 15430.922845385925, 11682.94158529202, 11956.98365638913, 11993.940030299967, 12002.879971200351, 12005.09961752872, 8030.277275045274, 8147.185281471805, 8157.95921020403, 8232.588370581567, 8238.629261683238, 3390.694837464591, 3430.5540279763472, 3448.689617934398, 3465.358908601917, 3487.5640544931885, 962.6678795815433, 1040.8594839696696, 1098.2751384086464, 1110.5113749949996, 1147.4434899073942, 30978.53532197019, 31120.688793112233, 31662.68337316597, 31889.043328700176, 31934.84032579822, 995.9542480395555, 1012.9898701013043, 1059.5391004912733, 1108.9168312376603, 1137.7951968645723, 12925.676858078623, 14567.854321456862, 15421.270512553272, 16219.351225951066, 16475.83524164743, 687788.0, 695334.0, 697631.0, 702720.0, 705228.0, 62131.689341553, 62841.37158628447, 62885.37114628795, 62887.685561571896, 62891.685541572, 1412.6115318287552, 1428.3929330034694, 1440.9783853242209, 1457.3441951122038, 1463.8682518573592, 30410.847945761016, 30506.237344066576, 30511.847440762653, 30726.385472290876, 30844.92042778492, 42268.788656056524, 50956.74521627368, 51316.486835131174, 51415.48584514257, 51908.22137667862, 592764.0, 592864.0, 594299.0, 595989.0, 596660.0, 28299.0, 28389.148325550268, 28412.0, 29116.12651620455, 29202.977895773547, 49864.75067624639, 50329.245061323374, 51325.23012154746, 51395.74302128465, 51399.0, 40104.197916042096, 40555.391669124394, 40785.0, 41620.0, 42437.0, 40588.39117413182, 40924.0, 41043.97390065272, 41083.50796155301, 41682.37476437795, 39862.00344991399, 39868.0, 40328.59671403249, 40894.0, 40949.1810163801, 31065.53401698931, 31559.368812624078, 34010.82994585011, 35672.821635891654, 35804.33837528362, 24640.876795615906, 26146.738532615193, 26162.345941351618, 26285.60571591466, 26868.59697104506], "value": 19348.639997287606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.0466975556476, 380.54904937648877, 387.73246459942646, 394.78484226096776, 398.1777629195711, 19081.950492722895, 20898.268560600383, 21191.576168476622, 21339.893300533477, 21344.786552134476, 20170.193192272327, 20883.582328353423, 20884.269050583196, 21077.94610269484, 21268.042938067785, 5259.894802103956, 5852.385499522542, 6091.360407157251, 6103.176071230371, 6129.478994285491, 5793.971030144864, 6064.272287325527, 6072.878542429149, 6102.1151932969915, 6105.755769769204, 5931.436513531207, 5943.167956486095, 5951.0, 5956.0, 5991.2510936132985, 5730.7421166047625, 5852.414758524156, 5905.527557795366, 5970.611910225822, 5978.8804223915495, 5109.642325037249, 5147.536721695028, 5169.069567477853, 5183.455737147583, 5190.351206099238, 21428.678569821466, 23306.718130502817, 24975.125870594624, 26127.73872261272, 26390.47219055608, 2000.9054320950693, 2337.439014636492, 2534.531111744333, 2545.083769842855, 2631.8420894746378, 14987.32557034936, 15451.768223476656, 15630.843691563052, 15822.287997040161, 15883.682326353524, 1843.7879643840995, 1872.953176170593, 1914.090806866741, 1933.9806601933942, 1939.2630800295797, 102873.4856325721, 118343.0, 122974.0, 123188.0, 123479.0, 9273.165415112673, 10467.895321046844, 10540.0, 10696.411697356565, 10960.945195274133, 13390.799138012937, 13488.730225395437, 13542.59372218835, 13546.796798048037, 13551.86448135506, 6820.624865632438, 7033.718651253893, 8633.95683021581, 8659.696910608098, 8739.563021848882, 8783.868241976375, 8789.956050219707, 8815.250703690259, 8829.426087304304, 8916.821663566692, 6462.483001359974, 6571.638559879157, 6626.966865165643, 6657.800265992028, 6747.527673062838, 1675.5978565144278, 1897.9466396149994, 1899.8765080269739, 1909.2458478900755, 1956.5499935015025, 879.5030807593816, 915.6749353979375, 929.4284015330658, 956.8995255498072, 1006.1296978113879, 20164.495887602632, 20970.424040859984, 20996.580068398853, 21002.264920727703, 21380.893095534422, 970.3256236915277, 975.6829030565095, 1006.3157053203745, 1043.4521876015153, 1059.819830628795, 10948.233623646269, 11449.656510304705, 11455.656330310105, 11470.770584588261, 11490.48292826808, 392147.0, 574850.0, 584381.0, 593909.0, 595774.0, 65052.0, 67288.63556822237, 87003.0, 115655.42172289253, 118145.4092729548, 1004.2719028704277, 1062.6280801719336, 1083.842842787807, 1089.2375337263948, 1090.8800031996475, 18996.14517346695, 19151.616967660848, 19300.131494082518, 19346.32287869918, 19377.0, 54742.72628636991, 55790.44209558015, 56722.86554268974, 56846.71576642256, 56953.0, 77186.2281377179, 77206.6139669298, 77424.61287693525, 77757.0, 79162.6041869787, 7868.370530357443, 8006.759797206093, 8076.596170191349, 8117.79705507367, 8280.875786863082, 10197.54111064989, 12272.693182670504, 12357.75284494323, 12490.06324525646, 12596.496140154657, 10781.838272425763, 10840.620578279722, 10908.727281818017, 10997.835032474359, 11143.442827858413, 10689.465526723789, 10722.624708135178, 10796.838047429137, 11035.22753407286, 11077.003069723763, 9335.85996210071, 10814.297070690694, 10856.75147358064, 11254.718632034264, 11285.605003824827, 7950.840983180419, 8015.679372825021, 8051.758447246358, 8140.308073813792, 8195.754127376189, 10299.897001030193, 10991.450427478754, 11047.337159770439, 11072.335659860435, 11125.664920209627], "value": 9667.556491113955, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.9682212917342, 1581.3358389476418, 1651.5953591370117, 1788.383007862287, 1835.8898466092028, 7021.82445438863, 8479.872801907977, 8506.957465212681, 8917.464952102879, 8944.59749311281, 8663.523506207172, 8696.39125261232, 8791.384603077788, 8802.955985220065, 8820.823583528325, 2347.3192774095533, 2374.4776149247223, 2380.5833979053596, 2381.0, 2386.940326491843, 2363.657269695893, 2378.1200955646436, 2385.6779334789753, 2395.9520809583887, 2408.771166739157, 2362.9881850590723, 2371.881405929705, 2384.0582969726947, 2403.267003563912, 2406.3262286559793, 2339.7075365579303, 2353.305774796431, 2378.1795280628157, 2385.1413491143176, 2388.3073908566494, 1986.8807871527754, 2328.7089113860766, 2367.9289621311386, 2371.2174982255815, 2374.8456350337174, 7854.764357069297, 8423.95788021062, 8837.779055523662, 8848.336374771918, 8867.689630862953, 5224.582033437316, 5376.059189641797, 5399.271098401705, 5411.215373770781, 5416.891662166735, 3581.391163502211, 3647.3252448296885, 3712.7029837613195, 3737.8225858854644, 3739.6821270192067, 1096.901278884896, 1098.3958822647512, 1104.9558017679283, 1107.634480621393, 1112.6383925224334, 9675.0, 11434.599789007429, 11541.653750387502, 11550.844915508465, 11676.357800320979, 4718.764061796896, 4732.905341893212, 4838.758062096881, 4842.854714358575, 4863.562279394871, 9857.852132217879, 9863.950680246697, 9876.555555000043, 9885.209183265322, 9903.405795652285, 5271.604629652791, 5317.175837745156, 5320.414754377015, 5361.249425080492, 5409.675419474844, 4415.40392047076, 4782.713037217709, 4889.951100488949, 4954.5788607968725, 4955.306257124005, 3970.0, 4011.29802284599, 4058.370952502367, 4085.7344272622186, 4145.937810932838, 1250.2623452163086, 1319.287584704249, 1320.8084827700122, 1324.3576865220361, 1330.1487048289102, 956.679512363366, 963.4556475591269, 965.3049804141007, 967.6855022117699, 996.3971797062818, 4882.780274887638, 5938.970305148447, 5958.702064896738, 5987.161797348374, 6045.33501314855, 411.2679430613513, 460.7788261634434, 907.3920473282916, 1005.8994100589882, 1120.882307357732, 3823.4073718573663, 3976.343903255988, 4049.979750101231, 4075.9388609170887, 4090.734102283342, 8317.8336433271, 9495.43027418357, 9690.903090969001, 9878.50607469638, 9930.304878658424, 7982.800429989296, 9764.462954537426, 9821.754456138511, 9825.263105267133, 9860.506974651238, 7972.920270797334, 8105.716299929474, 8144.208274179671, 8250.216229458312, 8253.79365515867, 5803.9419605803405, 5890.4698577128265, 5909.793157239476, 5915.940840591711, 5992.0712289595185, 9759.316847820584, 9901.851472227778, 9904.504774761377, 9910.405375677483, 9913.058259465339, 8120.96975756061, 9698.51507424612, 9894.06006429388, 10094.444805535766, 10106.949465252626, 6828.351306625863, 7471.663775130023, 7629.961850190935, 7973.242541958504, 8292.543910085004, 8287.668493260202, 8302.252797248277, 8388.958055209685, 8515.659373624985, 8519.148085191431, 7473.92526074754, 7595.468317217742, 7625.046869141357, 7713.19011503784, 7744.0319960005, 7356.117265927909, 7378.335949764547, 7417.184109748031, 7494.662740176815, 7572.091349038151, 6917.861642767016, 7030.543014704028, 7059.541129826544, 7064.611446370499, 7088.291170882871, 4880.414350277847, 5026.120428924851, 5052.8484145475695, 5112.795488180431, 5137.357830271216, 5027.974860125676, 5120.231965205249, 5123.795048198031, 5164.483551644806, 5279.26090347344], "value": 4570.885730726979, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.7295931109579, 296.18696677619937, 304.98932537361196, 313.79289668818586, 315.9730874657363, 4456.3761073449705, 5552.250446189763, 5602.691851948141, 5622.746976386067, 5630.803986445279, 4589.495155532889, 4905.877353066175, 5491.007400966483, 5596.58025648076, 5622.550195984321, 4002.039000889558, 4115.629593336599, 4241.8303267869405, 4245.64139475368, 4249.745015299077, 3485.372632926085, 3671.3024525340234, 3680.392735198689, 3989.900252493682, 4282.207791558556, 4238.152369526092, 4267.722598031133, 4288.270993931023, 4290.2706539888295, 4295.656347492193, 3888.4361767543833, 4141.834326626947, 4258.020655249279, 4270.882255953141, 4302.311630139163, 3073.7387322077425, 3667.669909708139, 3679.9448008279896, 3691.280200360931, 3707.796071216082, 4260.446142001551, 5141.820036298712, 5239.401982395368, 5353.464653534655, 5445.591580631469, 3778.5843557208686, 3890.8832735017713, 4880.755962201875, 4900.975495122537, 4909.649846292263, 4445.599896009375, 5166.664166829181, 5169.8449046528285, 5837.86161698469, 5878.707304151384, 3259.3970115528464, 3577.94633080504, 3795.013296542891, 3945.8618948336957, 4085.59144085592, 5063.87340316495, 5287.709175995319, 5879.617824841414, 6000.429959153874, 6211.285702144266, 3385.8984230472633, 3935.311320518898, 4092.3247664135083, 4137.565555616617, 4168.270552653274, 3704.981475092661, 4100.65144462724, 4196.748195108304, 4234.259004674232, 4239.40648309237, 4881.145799485076, 4914.5822605078965, 4931.408231012301, 4935.087008903364, 4938.0, 5161.329027226437, 5650.457294217536, 5951.404859514014, 5956.583039187302, 5963.165156878041, 1232.617888454564, 1319.265166306304, 1361.748076605831, 1395.3720825628507, 1448.2469116059801, 773.4469854054378, 794.7059587952378, 825.640846231891, 881.9911800882037, 905.515549181185, 4827.686200397034, 5394.676319420847, 5480.862978425492, 5481.972590137104, 5492.423295553989, 5279.102552566073, 5564.165375193753, 5969.343372229051, 6000.379897427757, 6058.727357268934, 4708.480962684947, 5394.298741163623, 5431.375391829911, 5475.6440831345835, 5524.447555244524, 2673.5187666220268, 2978.508546089914, 2980.269833890667, 3009.7291243787727, 3148.102790704638, 5631.831045068655, 5841.9415805842245, 6154.661493617894, 6172.506199504029, 6261.749530018839, 5023.433362312726, 5721.656700597894, 5821.883562328814, 6320.336364681734, 6327.778527751507, 5903.085021821625, 6387.233531976192, 6422.0, 6424.486041116699, 6440.80677579664, 5650.604457687922, 5774.42255774436, 6091.664958427329, 6210.254769427697, 6218.875622487615, 5203.401608814884, 5425.782968681208, 5920.496757775637, 6130.233720784902, 6176.320604733386, 5631.465010823965, 5632.324121105494, 5877.73550190251, 6400.87984602702, 6428.774992875227, 3765.322241996357, 3933.7349999500916, 3951.841926322915, 3974.3243648580387, 3975.1850870570884, 5308.601854860824, 5383.892322153457, 5931.885759976047, 6163.38366163398, 6165.506759459322, 5523.420040895809, 5900.350961394329, 5923.555733319932, 6021.066734656135, 6032.00471922128, 4943.467525067241, 5400.324959380077, 5770.653760774199, 5873.324567674601, 5903.26209223847, 4848.757562121809, 5003.874903127451, 5383.111786555174, 5791.449812267828, 5809.36097029318, 4714.363560919232, 5630.718464076862, 5955.851103722442, 5960.433758792908, 5973.462388385066, 5812.564057695774, 5839.912401313899, 5842.211301474246, 5873.530117590668, 5907.85230369227], "value": 4222.931313113908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.6444906444906, 830.6884918155688, 842.5606732583291, 860.7374750701038, 876.3296078499944, 14667.853321466782, 15028.699426011473, 15200.01199922007, 15563.92218038911, 16178.271977761, 15609.609759755984, 15718.528444146665, 15752.448664296751, 15782.21088945551, 15785.289661965211, 715.763797946679, 879.6409547249483, 907.6051917415945, 922.9815403691922, 923.745969858291, 901.1709227510673, 901.3870568013748, 902.7788191892995, 902.9413088149282, 904.1726819959729, 930.042056681619, 953.9284553658468, 962.1821451766012, 963.10431298892, 970.5001924009141, 811.7929927868386, 852.4373913217247, 926.5043201886991, 954.3939598355059, 967.4872317671645, 660.7753363856264, 689.6724056073325, 705.7812078255757, 717.0320067908334, 717.218232126984, 14797.964142509922, 14813.925930370388, 14819.555413337617, 14855.0, 14896.180710060942, 13750.381232844446, 13752.587422377244, 13769.931150344284, 13770.724585508335, 13975.371108300049, 11466.598669046627, 11709.997252815847, 11858.703532411759, 11930.880691193064, 12115.0, 3803.3724435468116, 3843.865464708722, 3877.263319969225, 3918.2555314490155, 4007.939880901789, 85156.7226491603, 85900.57049714774, 86084.26536540488, 86983.0, 88143.0, 10576.320423410983, 10837.67486975392, 10891.945540272407, 10917.454127293604, 11022.44887755609, 20818.0, 20871.0, 21209.681854772192, 21280.255191068547, 21315.68026479604, 7715.845683086419, 7792.493487923267, 7948.403869709795, 7951.125376208613, 7984.880226796603, 3778.962210377916, 4374.562543745664, 4469.932951005738, 4503.761465596922, 4557.361969324297, 3427.485877118402, 3532.752707310515, 3540.822958852047, 3542.326957877995, 3588.928221435609, 720.83781149241, 732.8644200822865, 747.1631772414821, 749.6439191384048, 749.9475036747484, 556.5742207211487, 627.9026750853625, 635.3646353646353, 642.6047980492057, 650.2457149706413, 9169.495677737788, 9476.241900647932, 9531.285153613506, 9644.18024467914, 9661.0, 508.217345288252, 590.2120668907057, 615.6644628677382, 618.7153909201728, 618.9535784816156, 4441.866743997685, 4487.169873573399, 4513.593776560126, 4527.977360113178, 4542.182407166676, 78844.60577697189, 79796.0, 92009.0, 92306.53846730724, 94610.52694736482, 72290.0, 73407.0, 73828.26171738321, 74062.25937740662, 81218.0, 56578.0, 71359.6432017847, 71582.0, 73119.63440182872, 74167.25832741712, 7022.929770702228, 9553.092456216647, 9567.0, 9639.602257672457, 9744.75638109053, 39940.0, 40229.19541609093, 46846.76576617095, 46904.53095469002, 47264.0, 62264.0, 62522.68738656278, 64696.67651661712, 65410.34589654043, 65716.05703657796, 12780.936095319836, 12877.227366358049, 13190.0, 13371.799423008468, 13381.598552043455, 14302.356393962089, 14482.855171448151, 14599.85400145985, 14916.248445605046, 14951.028183168059, 13492.12301200419, 13842.930785346009, 13913.452069722156, 13980.720385592027, 14068.929655351658, 13273.402696878857, 13745.312734363442, 13876.72246555043, 13922.721545569235, 13928.860711393161, 9513.476758778334, 11937.52249909994, 12897.548585799452, 13478.351684420784, 13513.527026554024, 10110.494475276353, 10260.179185665278, 10353.275270731277, 10393.220508461744, 11147.0, 9289.86065209036, 10101.646442374482, 10442.843357349493, 11105.777884442428, 11136.38749868765], "value": 7336.003714819807, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 98.0, 165.0, 174.0, 140.0, 116.0, 116.0, 114.0, 118.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 151.0, 147.0, 165.0, 120.0, 141.0, 136.0, 122.0, 205.0, 120.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 202.0, 234.0, 193.0, 241.0, 274.0, 197.0, 191.0, 198.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 77.0, 138.0, 50.0, 50.0, 61.0, 76.0, 127.0, 49.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 113.0, 70.0, 71.0, 86.0, 116.0, 70.0, 75.0, 119.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 119.0, 88.0, 92.0, 87.0, 101.0, 117.0, 91.0, 99.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 106.0, 150.0, 122.0, 125.0, 154.0, 96.0, 97.0, 104.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 94.0, 98.0, 128.0, 95.0, 98.0, 94.0, 126.0, 145.0], "value": 115.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 53.0, 57.0, 56.0, 59.0, 56.0, 59.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 72.0, 76.0, 73.0, 73.0, 69.0, 69.0, 69.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 103.0, 110.0, 110.0, 106.0, 121.0, 91.0], "value": 98.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 155.0, 149.0, 144.0, 135.0, 144.0, 149.0, 137.0, 153.0, 139.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 205.0, 225.0, 163.0, 227.0, 204.0, 163.0, 164.0, 166.0, 170.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 65.0, 67.0, 66.0, 65.0, 65.0, 66.0, 66.0], "value": 65.9, "unit": "score"}]}]} 12:31:54 INFO - Return code: 0 12:31:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:31:54 ERROR - # TBPL SUCCESS # 12:31:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:54 INFO - 2016-05-27 12:31:54.447 screenresolution[1686:10485] starting screenresolution argv=screenresolution get 12:31:54 INFO - 2016-05-27 12:31:54.450 screenresolution[1686:10485] Display 0: 1600x1200x32@60 12:31:54 INFO - 2016-05-27 12:31:54.457 screenresolution[1687:10488] starting screenresolution argv=screenresolution list 12:31:54 INFO - Available Modes on Display 0 12:31:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:54 INFO - Intel Iris: 12:31:54 INFO - Chipset Model: Intel Iris 12:31:54 INFO - Type: GPU 12:31:54 INFO - Bus: Built-In 12:31:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:54 INFO - Vendor: Intel (0x8086) 12:31:54 INFO - Device ID: 0x0a2e 12:31:54 INFO - Revision ID: 0x0009 12:31:54 INFO - Displays: 12:31:54 INFO - Display: 12:31:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:54 INFO - Display Serial Number: 1600x1200 60 12:31:54 INFO - Main Display: Yes 12:31:54 INFO - Mirror: Off 12:31:54 INFO - Online: Yes 12:31:54 INFO - Rotation: Supported 12:31:54 INFO - Return code: 0 12:31:54 INFO - Running post-action listener: _resource_record_post_action 12:31:54 INFO - ##### 12:31:54 INFO - ##### Finished run-tests step (success) 12:31:54 INFO - ##### 12:31:54 INFO - Running post-run listener: _resource_record_post_run 12:31:54 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 36816384; Write bytes: 696822272; Read time: 236; Write time: 1957 12:31:54 INFO - TinderboxPrint: CPU usage
54.6% 12:31:54 INFO - TinderboxPrint: I/O read bytes / time
36,816,384 / 236 12:31:54 INFO - TinderboxPrint: I/O write bytes / time
696,822,272 / 1,957 12:31:54 INFO - TinderboxPrint: CPU idle
2,196.9 (45.4%) 12:31:54 INFO - TinderboxPrint: CPU system
974.8 (20.2%) 12:31:54 INFO - TinderboxPrint: CPU user
1,662.0 (34.4%) 12:31:54 INFO - TinderboxPrint: Swap in / out
434,728,960 / 0 12:31:54 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 404091392; Write bytes: 400758272; Read time: 29676; Write time: 832 12:31:54 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29707776; Write bytes: 295216128; Read time: 180; Write time: 1122 12:31:55 INFO - Running post-run listener: _upload_blobber_files 12:31:55 INFO - Blob upload gear active. 12:31:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:31:55 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 12:31:55 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'] 12:31:55 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 12:31:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:56 INFO - (blobuploader) - INFO - Done attempting. 12:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:57 INFO - (blobuploader) - INFO - Done attempting. 12:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:32:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:00 INFO - (blobuploader) - INFO - Done attempting. 12:32:00 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:00 INFO - Return code: 0 12:32:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d86ee5942ad9845e16b7522584d6e126b229d74b55d13a945c549b9903f650970974b4661fbb6ec76351eee0d9ac53be3b04144e4bba276b464ea77de53f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43a157f23c0f38ae6eb8966cd56a48505677c9743e94598d2f561ec209884dc5f2b25762aabea997635e5ca66389e37b959b7804a34d87a2fe3b47813e383c08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8926a7cf6076c855922d33f21b1bba9402112cadb72b0bf00b7dd3232183f72d54978c00f5aa6f104a9249c8c96cdf33c18cddce1c180fc565052d8673747133"} 12:32:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:00 INFO - Contents: 12:32:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d86ee5942ad9845e16b7522584d6e126b229d74b55d13a945c549b9903f650970974b4661fbb6ec76351eee0d9ac53be3b04144e4bba276b464ea77de53f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43a157f23c0f38ae6eb8966cd56a48505677c9743e94598d2f561ec209884dc5f2b25762aabea997635e5ca66389e37b959b7804a34d87a2fe3b47813e383c08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8926a7cf6076c855922d33f21b1bba9402112cadb72b0bf00b7dd3232183f72d54978c00f5aa6f104a9249c8c96cdf33c18cddce1c180fc565052d8673747133"} 12:32:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:00 INFO - Copying logs to upload dir... 12:32:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.088396 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-05-27 12:32:00.496059) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 12:32:00.499460) ========= 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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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/40d86ee5942ad9845e16b7522584d6e126b229d74b55d13a945c549b9903f650970974b4661fbb6ec76351eee0d9ac53be3b04144e4bba276b464ea77de53f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43a157f23c0f38ae6eb8966cd56a48505677c9743e94598d2f561ec209884dc5f2b25762aabea997635e5ca66389e37b959b7804a34d87a2fe3b47813e383c08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8926a7cf6076c855922d33f21b1bba9402112cadb72b0bf00b7dd3232183f72d54978c00f5aa6f104a9249c8c96cdf33c18cddce1c180fc565052d8673747133"} build_url:https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011030 build_url: 'https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/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/40d86ee5942ad9845e16b7522584d6e126b229d74b55d13a945c549b9903f650970974b4661fbb6ec76351eee0d9ac53be3b04144e4bba276b464ea77de53f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43a157f23c0f38ae6eb8966cd56a48505677c9743e94598d2f561ec209884dc5f2b25762aabea997635e5ca66389e37b959b7804a34d87a2fe3b47813e383c08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8926a7cf6076c855922d33f21b1bba9402112cadb72b0bf00b7dd3232183f72d54978c00f5aa6f104a9249c8c96cdf33c18cddce1c180fc565052d8673747133"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 12:32:00.530416) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:32:00.530772) ========= 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.fYgtTvznT8/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.EQXC2wH6Xf/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.005348 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:32:00.581826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 12:32:00.582558) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 12:32:00.583701) ========= ========= Total master_lag: 0.20 =========