builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0103 starttime: 1463400921.85 results: success (0) buildid: 20160516031431 builduid: 9b944444496a425196c22f4db36b59ea revision: f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:21.851283) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:21.851705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:21.851976) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:21.927445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:21.927830) ========= 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.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-16 05:15:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.57M=0.002s 2016-05-16 05:15:22 (6.57 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.461591 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:22.409680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:22.409990) ========= 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.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.020402 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:22.461996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:22.462330) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 --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 f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-16 05:15:22,544 truncating revision to first 12 chars 2016-05-16 05:15:22,544 Setting DEBUG logging. 2016-05-16 05:15:22,544 attempt 1/10 2016-05-16 05:15:22,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f10ea2e1322b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 05:15:23,207 unpacking tar archive at: fx-team-f10ea2e1322b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906665 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:23.389670) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:23.390023) ========= script_repo_revision: f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:23.390802) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:23.391084) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 05:15:23.405870) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-16 05:15:23.406259) ========= /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.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 05:15:23 INFO - MultiFileLogger online at 20160516 05:15:23 in /builds/slave/test 05:15:23 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 05:15:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:23 INFO - {'append_to_log': False, 05:15:23 INFO - 'base_work_dir': '/builds/slave/test', 05:15:23 INFO - 'blob_upload_branch': 'Fx-Team', 05:15:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:23 INFO - 'branch': 'Fx-Team', 05:15:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:23 INFO - 'config_files': ('talos/mac_config.py',), 05:15:23 INFO - 'default_actions': ('clobber', 05:15:23 INFO - 'read-buildbot-config', 05:15:23 INFO - 'download-and-extract', 05:15:23 INFO - 'populate-webroot', 05:15:23 INFO - 'create-virtualenv', 05:15:23 INFO - 'install', 05:15:23 INFO - 'run-tests'), 05:15:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:23 INFO - 'download_minidump_stackwalk': True, 05:15:23 INFO - 'download_symbols': 'ondemand', 05:15:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:23 INFO - 'installer_path': 'installer.exe', 05:15:23 INFO - 'log_level': 'info', 05:15:23 INFO - 'log_name': 'talos', 05:15:23 INFO - 'log_to_console': True, 05:15:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:15:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:15:23 INFO - 'opt_config_files': (), 05:15:23 INFO - 'pip_index': False, 05:15:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:23 INFO - 'cmd': ('bash', 05:15:23 INFO - '-c', 05:15:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:23 INFO - 'enabled': True, 05:15:23 INFO - 'halt_on_failure': False, 05:15:23 INFO - 'name': 'check_screen_resolution'},), 05:15:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:23 INFO - 'cmd': ('bash', 05:15:23 INFO - '-c', 05:15:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:23 INFO - 'enabled': True, 05:15:23 INFO - 'halt_on_failure': False, 05:15:23 INFO - 'name': 'check_screen_resolution'},), 05:15:23 INFO - 'run_cmd_checks_enabled': True, 05:15:23 INFO - 'sps_profile': False, 05:15:23 INFO - 'sps_profile_interval': 0, 05:15:23 INFO - 'suite': 'dromaeojs-e10s', 05:15:23 INFO - 'system_bits': '32', 05:15:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:23 INFO - 'title': 't-yosemite-r7-0103', 05:15:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:23 INFO - 'use_talos_json': True, 05:15:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:23 INFO - 'work_dir': 'build'} 05:15:23 INFO - ##### 05:15:23 INFO - ##### Running clobber step. 05:15:23 INFO - ##### 05:15:23 INFO - Running pre-action listener: _resource_record_pre_action 05:15:23 INFO - Running main action method: clobber 05:15:23 INFO - rmtree: /builds/slave/test/build 05:15:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:24 INFO - Running post-action listener: _resource_record_post_action 05:15:24 INFO - ##### 05:15:24 INFO - ##### Finished clobber step (success) 05:15:24 INFO - ##### 05:15:24 INFO - ##### 05:15:24 INFO - ##### Running read-buildbot-config step. 05:15:24 INFO - ##### 05:15:24 INFO - Running pre-action listener: _resource_record_pre_action 05:15:24 INFO - Running main action method: read_buildbot_config 05:15:24 INFO - Using buildbot properties: 05:15:24 INFO - { 05:15:24 INFO - "project": "", 05:15:24 INFO - "product": "firefox", 05:15:24 INFO - "script_repo_revision": "production", 05:15:24 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:15:24 INFO - "repository": "", 05:15:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:15:24 INFO - "buildid": "20160516031431", 05:15:24 INFO - "pgo_build": "False", 05:15:24 INFO - "basedir": "/builds/slave/test", 05:15:24 INFO - "buildnumber": 9, 05:15:24 INFO - "slavename": "t-yosemite-r7-0103", 05:15:24 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:15:24 INFO - "platform": "yosemite_r7", 05:15:24 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:15:24 INFO - "branch": "fx-team", 05:15:24 INFO - "repo_path": "integration/fx-team", 05:15:24 INFO - "revision": "f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0", 05:15:24 INFO - "stage_platform": "macosx64", 05:15:24 INFO - "builduid": "9b944444496a425196c22f4db36b59ea", 05:15:24 INFO - "slavebuilddir": "test" 05:15:24 INFO - } 05:15:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:15:24 INFO - Running post-action listener: _resource_record_post_action 05:15:24 INFO - ##### 05:15:24 INFO - ##### Finished read-buildbot-config step (success) 05:15:24 INFO - ##### 05:15:24 INFO - ##### 05:15:24 INFO - ##### Running download-and-extract step. 05:15:24 INFO - ##### 05:15:24 INFO - Running pre-action listener: _resource_record_pre_action 05:15:24 INFO - Running main action method: download_and_extract 05:15:24 INFO - mkdir: /builds/slave/test/build/tests 05:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:24 INFO - https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:15:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:41 INFO - Downloaded 1199 bytes. 05:15:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:41 INFO - Using the following test package requirements: 05:15:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:15:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:15:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'jsshell-mac.zip'], 05:15:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:15:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:15:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:15:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:15:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:15:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:15: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')]} 05:15:41 INFO - https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:42 INFO - Downloaded 18267638 bytes. 05:15:42 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'] 05:15:42 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 05:15:45 INFO - Return code: 0 05:15:45 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 05:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:45 INFO - https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:47 INFO - Downloaded 18267638 bytes. 05:15:47 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'] 05:15:47 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 05:15:51 INFO - Return code: 0 05:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:51 INFO - https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:52 INFO - Downloaded 10840194 bytes. 05:15:52 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'] 05:15:52 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 05:15:52 INFO - Return code: 0 05:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:52 INFO - https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:15:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUx89YLaRFWiibE1v0sNaQ/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 05:15:55 INFO - Downloaded 110053889 bytes. 05:15:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:55 INFO - mkdir: /builds/slave/test/properties 05:15:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:15:55 INFO - Writing to file /builds/slave/test/properties/build_url 05:15:55 INFO - Contents: 05:15:55 INFO - build_url:https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:56 INFO - Running post-action listener: _resource_record_post_action 05:15:56 INFO - Running post-action listener: set_extra_try_arguments 05:15:56 INFO - ##### 05:15:56 INFO - ##### Finished download-and-extract step (success) 05:15:56 INFO - ##### 05:15:56 INFO - ##### 05:15:56 INFO - ##### Running populate-webroot step. 05:15:56 INFO - ##### 05:15:56 INFO - Running pre-action listener: _resource_record_pre_action 05:15:56 INFO - Running main action method: populate_webroot 05:15:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:15:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:15:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:15:56 INFO - '--tppagecycles', 05:15:56 INFO - '7'], 05:15:56 INFO - 'tests': ['tsvgm']}}, 05:15:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:15:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:15:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:15:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:15:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['damp', 'tps']}, 05:15:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'tests': ['damp', 'tps']}, 05:15:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['dromaeo_dom']}, 05:15:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:15:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'other-e10s': {'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['a11yr', 05:15:56 INFO - 'ts_paint', 05:15:56 INFO - 'tpaint', 05:15:56 INFO - 'sessionrestore', 05:15:56 INFO - 'sessionrestore_no_auto_restore', 05:15:56 INFO - 'tabpaint']}, 05:15:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['tsvgx', 05:15:56 INFO - 'tsvgr_opacity', 05:15:56 INFO - 'tart', 05:15:56 INFO - 'tscrollx', 05:15:56 INFO - 'cart']}, 05:15:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:15:56 INFO - 'tsvgr_opacity', 05:15:56 INFO - 'tart', 05:15:56 INFO - 'tscrollx', 05:15:56 INFO - 'cart']}, 05:15:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'talos_options': ['--disable-e10s'], 05:15:56 INFO - 'tests': ['tp5o']}, 05:15:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'tests': ['tp5o']}, 05:15:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'talos_options': ['--disable-e10s', 05:15:56 INFO - '--xperf_path', 05:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:56 INFO - 'tests': ['tp5n']}, 05:15:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:56 INFO - 'talos_options': ['--xperf_path', 05:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:56 INFO - 'tests': ['tp5n']}}, 05:15:56 INFO - 'talos.zip': {'path': '', 05:15:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:15:56 INFO - Running post-action listener: _resource_record_post_action 05:15:56 INFO - ##### 05:15:56 INFO - ##### Finished populate-webroot step (success) 05:15:56 INFO - ##### 05:15:56 INFO - ##### 05:15:56 INFO - ##### Running create-virtualenv step. 05:15:56 INFO - ##### 05:15:56 INFO - Running pre-action listener: _resource_record_pre_action 05:15:56 INFO - Running main action method: create_virtualenv 05:15:56 INFO - Creating virtualenv /builds/slave/test/build/venv 05:15:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:15:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:15:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:15:56 INFO - Using real prefix '/tools/python27' 05:15:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:15:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:00 INFO - Installing pip.................done. 05:16:00 INFO - Return code: 0 05:16:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:00 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:00 INFO - 'HOME': '/Users/cltbld', 05:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:00 INFO - 'LOGNAME': 'cltbld', 05:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:00 INFO - 'MOZ_NO_REMOTE': '1', 05:16:00 INFO - 'NO_EM_RESTART': '1', 05:16:00 INFO - 'PAGER': '/bin/cat', 05:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:00 INFO - 'PWD': '/builds/slave/test', 05:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:00 INFO - 'SHELL': '/bin/bash', 05:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:00 INFO - 'USER': 'cltbld', 05:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:00 INFO - 'XPC_FLAGS': '0x0', 05:16:00 INFO - 'XPC_SERVICE_NAME': '0', 05:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:16:00 INFO - Cleaning up... 05:16:00 INFO - Return code: 0 05:16:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:00 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:00 INFO - 'HOME': '/Users/cltbld', 05:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:00 INFO - 'LOGNAME': 'cltbld', 05:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:00 INFO - 'MOZ_NO_REMOTE': '1', 05:16:00 INFO - 'NO_EM_RESTART': '1', 05:16:00 INFO - 'PAGER': '/bin/cat', 05:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:00 INFO - 'PWD': '/builds/slave/test', 05:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:00 INFO - 'SHELL': '/bin/bash', 05:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:00 INFO - 'USER': 'cltbld', 05:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:00 INFO - 'XPC_FLAGS': '0x0', 05:16:00 INFO - 'XPC_SERVICE_NAME': '0', 05:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:00 INFO - Downloading/unpacking psutil>=3.1.1 05:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:04 INFO - Installing collected packages: psutil 05:16:04 INFO - Running setup.py install for psutil 05:16:04 INFO - building 'psutil._psutil_osx' extension 05:16:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:16:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:16:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:16:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:16:04 INFO - building 'psutil._psutil_posix' extension 05:16:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:16:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:16:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:16:04 INFO - ^ 05:16:04 INFO - 1 warning generated. 05:16:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:05 INFO - Successfully installed psutil 05:16:05 INFO - Cleaning up... 05:16:05 INFO - Return code: 0 05:16:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:05 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:16:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:05 INFO - 'HOME': '/Users/cltbld', 05:16:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:05 INFO - 'LOGNAME': 'cltbld', 05:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:05 INFO - 'MOZ_NO_REMOTE': '1', 05:16:05 INFO - 'NO_EM_RESTART': '1', 05:16:05 INFO - 'PAGER': '/bin/cat', 05:16:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:05 INFO - 'PWD': '/builds/slave/test', 05:16:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:05 INFO - 'SHELL': '/bin/bash', 05:16:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:05 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:05 INFO - 'USER': 'cltbld', 05:16:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:05 INFO - 'XPC_FLAGS': '0x0', 05:16:05 INFO - 'XPC_SERVICE_NAME': '0', 05:16:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:16:07 INFO - Installing collected packages: mozsystemmonitor 05:16:07 INFO - Running setup.py install for mozsystemmonitor 05:16:08 INFO - Successfully installed mozsystemmonitor 05:16:08 INFO - Cleaning up... 05:16:08 INFO - Return code: 0 05:16:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:08 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:08 INFO - 'HOME': '/Users/cltbld', 05:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:08 INFO - 'LOGNAME': 'cltbld', 05:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:08 INFO - 'MOZ_NO_REMOTE': '1', 05:16:08 INFO - 'NO_EM_RESTART': '1', 05:16:08 INFO - 'PAGER': '/bin/cat', 05:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:08 INFO - 'PWD': '/builds/slave/test', 05:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:08 INFO - 'SHELL': '/bin/bash', 05:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:08 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:08 INFO - 'USER': 'cltbld', 05:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:08 INFO - 'XPC_FLAGS': '0x0', 05:16:08 INFO - 'XPC_SERVICE_NAME': '0', 05:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:12 INFO - Downloading docopt-0.6.1.tar.gz 05:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:12 INFO - Running setup.py install for blobuploader 05:16:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:12 INFO - Running setup.py install for requests 05:16:12 INFO - Running setup.py install for docopt 05:16:12 INFO - Successfully installed blobuploader requests docopt 05:16:12 INFO - Cleaning up... 05:16:13 INFO - Return code: 0 05:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:13 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:13 INFO - 'HOME': '/Users/cltbld', 05:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:13 INFO - 'LOGNAME': 'cltbld', 05:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:13 INFO - 'MOZ_NO_REMOTE': '1', 05:16:13 INFO - 'NO_EM_RESTART': '1', 05:16:13 INFO - 'PAGER': '/bin/cat', 05:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:13 INFO - 'PWD': '/builds/slave/test', 05:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:13 INFO - 'SHELL': '/bin/bash', 05:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:13 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:13 INFO - 'USER': 'cltbld', 05:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:13 INFO - 'XPC_FLAGS': '0x0', 05:16:13 INFO - 'XPC_SERVICE_NAME': '0', 05:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-stesPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-otu3oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rAUChk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-gdZ8gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-FQrKTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-FyNO5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:13 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-HaLB1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-1rBVpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-KLd4Bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-KtfKiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-tZPmD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5gLL8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kJ0ARz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-03q4NP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jl0gFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-eIQNjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:14 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-gfOmYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:15 INFO - Running setup.py install for manifestparser 05:16:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:15 INFO - Running setup.py install for mozcrash 05:16:15 INFO - Running setup.py install for mozdebug 05:16:15 INFO - Running setup.py install for mozdevice 05:16:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:16:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:15 INFO - Running setup.py install for mozfile 05:16:15 INFO - Running setup.py install for mozhttpd 05:16:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:15 INFO - Running setup.py install for mozinfo 05:16:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Running setup.py install for mozInstall 05:16:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Running setup.py install for mozleak 05:16:16 INFO - Running setup.py install for mozlog 05:16:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Running setup.py install for moznetwork 05:16:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:16 INFO - Running setup.py install for mozprocess 05:16:16 INFO - Running setup.py install for mozprofile 05:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:17 INFO - Running setup.py install for mozrunner 05:16:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:17 INFO - Running setup.py install for mozscreenshot 05:16:17 INFO - Running setup.py install for moztest 05:16:17 INFO - Running setup.py install for mozversion 05:16:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:17 INFO - Cleaning up... 05:16:17 INFO - Return code: 0 05:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:17 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:17 INFO - 'HOME': '/Users/cltbld', 05:16:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:17 INFO - 'LOGNAME': 'cltbld', 05:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:17 INFO - 'MOZ_NO_REMOTE': '1', 05:16:17 INFO - 'NO_EM_RESTART': '1', 05:16:17 INFO - 'PAGER': '/bin/cat', 05:16:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:17 INFO - 'PWD': '/builds/slave/test', 05:16:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:17 INFO - 'SHELL': '/bin/bash', 05:16:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:17 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:17 INFO - 'USER': 'cltbld', 05:16:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:17 INFO - 'XPC_FLAGS': '0x0', 05:16:17 INFO - 'XPC_SERVICE_NAME': '0', 05:16:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:17 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iL0JjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7n7yEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rR4seh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-_Il1X4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-uNXPXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-epND6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kFhmkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-HFiMQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-HPDfxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-JF3XEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:18 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)) 05:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:18 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-wj1Iiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-e0cPSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:19 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)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-OcpH3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NwG5NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:19 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)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rJzDzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-VLL5cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:19 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-X4ctpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:22 INFO - Downloading blessings-1.6.tar.gz 05:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:22 INFO - Installing collected packages: blessings 05:16:22 INFO - Running setup.py install for blessings 05:16:22 INFO - Successfully installed blessings 05:16:22 INFO - Cleaning up... 05:16:22 INFO - Return code: 0 05:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:22 INFO - Reading from file tmpfile_stdout 05:16:22 INFO - Current package versions: 05:16:22 INFO - blessings == 1.6 05:16:22 INFO - blobuploader == 1.2.4 05:16:22 INFO - docopt == 0.6.1 05:16:22 INFO - manifestparser == 1.1 05:16:22 INFO - mozInstall == 1.12 05:16:22 INFO - mozcrash == 0.17 05:16:22 INFO - mozdebug == 0.1 05:16:22 INFO - mozdevice == 0.48 05:16:22 INFO - mozfile == 1.2 05:16:22 INFO - mozhttpd == 0.7 05:16:22 INFO - mozinfo == 0.9 05:16:22 INFO - mozleak == 0.1 05:16:22 INFO - mozlog == 3.2 05:16:22 INFO - moznetwork == 0.27 05:16:22 INFO - mozprocess == 0.22 05:16:22 INFO - mozprofile == 0.28 05:16:22 INFO - mozrunner == 6.11 05:16:22 INFO - mozscreenshot == 0.1 05:16:22 INFO - mozsystemmonitor == 0.3 05:16:22 INFO - moztest == 0.7 05:16:22 INFO - mozversion == 1.4 05:16:22 INFO - psutil == 3.1.1 05:16:22 INFO - requests == 1.2.3 05:16:22 INFO - wsgiref == 0.1.2 05:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:22 INFO - 'HOME': '/Users/cltbld', 05:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:22 INFO - 'LOGNAME': 'cltbld', 05:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:22 INFO - 'MOZ_NO_REMOTE': '1', 05:16:22 INFO - 'NO_EM_RESTART': '1', 05:16:22 INFO - 'PAGER': '/bin/cat', 05:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:22 INFO - 'PWD': '/builds/slave/test', 05:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:22 INFO - 'SHELL': '/bin/bash', 05:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:22 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:22 INFO - 'USER': 'cltbld', 05:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:22 INFO - 'XPC_FLAGS': '0x0', 05:16:22 INFO - 'XPC_SERVICE_NAME': '0', 05:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:16:23 INFO - Cleaning up... 05:16:23 INFO - Return code: 0 05:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16: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', 'jsonschema'] in /builds/slave/test/build 05:16: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 jsonschema 05:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:23 INFO - 'HOME': '/Users/cltbld', 05:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:23 INFO - 'LOGNAME': 'cltbld', 05:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:23 INFO - 'MOZ_NO_REMOTE': '1', 05:16:23 INFO - 'NO_EM_RESTART': '1', 05:16:23 INFO - 'PAGER': '/bin/cat', 05:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:23 INFO - 'PWD': '/builds/slave/test', 05:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:23 INFO - 'SHELL': '/bin/bash', 05:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:23 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:23 INFO - 'USER': 'cltbld', 05:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:23 INFO - 'XPC_FLAGS': '0x0', 05:16:23 INFO - 'XPC_SERVICE_NAME': '0', 05:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:23 INFO - Downloading/unpacking jsonschema 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:26 INFO - warning: no files found matching '*.txt' 05:16:26 INFO - no previously-included directories found matching 'build' 05:16:26 INFO - no previously-included directories found matching 'dist' 05:16:26 INFO - no previously-included directories found matching '.git*' 05:16:26 INFO - Installing collected packages: jsonschema, functools32 05:16:26 INFO - Running setup.py install for functools32 05:16:26 INFO - warning: no files found matching '*.txt' 05:16:26 INFO - no previously-included directories found matching 'build' 05:16:26 INFO - no previously-included directories found matching 'dist' 05:16:26 INFO - no previously-included directories found matching '.git*' 05:16:26 INFO - Successfully installed jsonschema functools32 05:16:26 INFO - Cleaning up... 05:16:26 INFO - Return code: 0 05:16:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:26 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 0x104469a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10446f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd113dc8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, '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.xsg0t8l96m/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.QfNKcmJeIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:16:26 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 05:16:26 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 05:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:26 INFO - 'HOME': '/Users/cltbld', 05:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:26 INFO - 'LOGNAME': 'cltbld', 05:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:26 INFO - 'MOZ_NO_REMOTE': '1', 05:16:26 INFO - 'NO_EM_RESTART': '1', 05:16:26 INFO - 'PAGER': '/bin/cat', 05:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:26 INFO - 'PWD': '/builds/slave/test', 05:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:26 INFO - 'SHELL': '/bin/bash', 05:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:16:26 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:26 INFO - 'USER': 'cltbld', 05:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:26 INFO - 'XPC_FLAGS': '0x0', 05:16:26 INFO - 'XPC_SERVICE_NAME': '0', 05:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:26 INFO - Downloading/unpacking flake8 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:16:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:16:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:30 INFO - no previously-included directories found matching 'docs/_build' 05:16:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:16:30 INFO - Running setup.py install for pep8 05:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:30 INFO - no previously-included directories found matching 'docs/_build' 05:16:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:16:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:16:30 INFO - Cleaning up... 05:16:30 INFO - Return code: 0 05:16:30 INFO - Running post-action listener: _resource_record_post_action 05:16:30 INFO - Running post-action listener: _start_resource_monitoring 05:16:30 INFO - Starting resource monitoring. 05:16:30 INFO - ##### 05:16:30 INFO - ##### Finished create-virtualenv step (success) 05:16:30 INFO - ##### 05:16:30 INFO - ##### 05:16:30 INFO - ##### Running install step. 05:16:30 INFO - ##### 05:16:30 INFO - Running pre-action listener: _resource_record_pre_action 05:16:30 INFO - Running main action method: install 05:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:31 INFO - Reading from file tmpfile_stdout 05:16:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:31 INFO - Reading from file tmpfile_stdout 05:16:31 INFO - Output received: 05:16:31 INFO - Usage: mozinstall [options] installer 05:16:31 INFO - Options: 05:16:31 INFO - -h, --help show this help message and exit 05:16:31 INFO - -d DEST, --destination=DEST 05:16:31 INFO - Directory to install application into. [default: 05:16:31 INFO - "/builds/slave/test"] 05:16:31 INFO - --app=APP Application being installed. [default: firefox] 05:16:31 INFO - mkdir: /builds/slave/test/build/application 05:16:31 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'] 05:16:31 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 05:17:04 INFO - Reading from file tmpfile_stdout 05:17:04 INFO - Output received: 05:17:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:17:04 INFO - Running post-action listener: _resource_record_post_action 05:17:04 INFO - ##### 05:17:04 INFO - ##### Finished install step (success) 05:17:04 INFO - ##### 05:17:04 INFO - ##### 05:17:04 INFO - ##### Running run-tests step. 05:17:04 INFO - ##### 05:17:04 INFO - Running pre-action listener: _resource_record_pre_action 05:17:04 INFO - Running main action method: run_tests 05:17:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:04 INFO - 2016-05-16 05:17:04.624 screenresolution[1978:16705] starting screenresolution argv=screenresolution get 05:17:04 INFO - 2016-05-16 05:17:04.627 screenresolution[1978:16705] Display 0: 1600x1200x32@60 05:17:04 INFO - 2016-05-16 05:17:04.633 screenresolution[1979:16707] starting screenresolution argv=screenresolution list 05:17:04 INFO - Available Modes on Display 0 05:17:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:04 INFO - Intel Iris: 05:17:04 INFO - Chipset Model: Intel Iris 05:17:04 INFO - Type: GPU 05:17:04 INFO - Bus: Built-In 05:17:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:04 INFO - Vendor: Intel (0x8086) 05:17:04 INFO - Device ID: 0x0a2e 05:17:04 INFO - Revision ID: 0x0009 05:17:04 INFO - Displays: 05:17:04 INFO - Display: 05:17:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:04 INFO - Display Serial Number: 1600x1200 60 05:17:04 INFO - Main Display: Yes 05:17:04 INFO - Mirror: Off 05:17:04 INFO - Online: Yes 05:17:04 INFO - Rotation: Supported 05:17:04 INFO - Return code: 0 05:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:04 INFO - Python 2.7.3 05:17:04 INFO - Return code: 0 05:17:04 INFO - grabbing minidump binary from tooltool 05:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:04 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 0x10430dd30>, '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 0x104131db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104304b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:17:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:17:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:17:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:04 INFO - Return code: 0 05:17:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:17:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:17:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:17:06 INFO - Return code: 0 05:17:06 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/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:17:06 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/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xsg0t8l96m/Render', 05:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:06 INFO - 'HOME': '/Users/cltbld', 05:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:06 INFO - 'LOGNAME': 'cltbld', 05:17:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:06 INFO - 'MOZ_NO_REMOTE': '1', 05:17:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:06 INFO - 'NO_EM_RESTART': '1', 05:17:06 INFO - 'PAGER': '/bin/cat', 05:17:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:06 INFO - 'PWD': '/builds/slave/test', 05:17:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:06 INFO - 'SHELL': '/bin/bash', 05:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QfNKcmJeIx/Listeners', 05:17:06 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 05:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:06 INFO - 'USER': 'cltbld', 05:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:06 INFO - 'XPC_FLAGS': '0x0', 05:17:06 INFO - 'XPC_SERVICE_NAME': '0', 05:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:06 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/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:17:06 INFO - mozversion application_buildid: 20160516031431 05:17:06 INFO - mozversion application_changeset: f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 05:17:06 INFO - mozversion application_display_name: Nightly 05:17:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:06 INFO - mozversion application_name: Firefox 05:17:06 INFO - mozversion application_remotingname: firefox 05:17:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:17:06 INFO - mozversion application_vendor: Mozilla 05:17:06 INFO - mozversion application_version: 49.0a1 05:17:06 INFO - mozversion platform_buildid: 20160516031431 05:17:06 INFO - mozversion platform_changeset: f10ea2e1322bce13823bd6c4b263a31ca1d9bdc0 05:17:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:17:06 INFO - mozversion platform_version: 49.0a1 05:17:06 INFO - using testdate: 1463401026 05:17:06 INFO - actual date: 1463401026 05:17:06 INFO - starting webserver on 'localhost:49681' 05:17:06 INFO - SUITE-START | Running 2 tests 05:17:06 INFO - TEST-START | dromaeo_css 05:17:06 INFO - operating with platform_type : mac_ 05:17:06 INFO - Initialising browser for dromaeo_css test... 05:17:06 INFO - TEST-INFO | started process 1997 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpJtvY6q/profile http://localhost:49681/getInfo.html) 05:17:09 INFO - PROCESS | 1997 | __metrics Screen width/height:1600/1200 05:17:09 INFO - PROCESS | 1997 | colorDepth:24 05:17:09 INFO - PROCESS | 1997 | Browser inner width/height: 1024/689 05:17:09 INFO - PROCESS | 1997 | __metrics 05:17:09 ERROR - PROCESS | 1997 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:17:10 INFO - TEST-INFO | 1997: exit 0 05:17:10 INFO - Browser initialized. 05:17:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:10 INFO - TEST-INFO | started process 2000 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpJtvY6q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:22 INFO - PROCESS | 2000 | RSS: Main: 249516032 05:17:22 INFO - PROCESS | 2000 | 05:18:11 INFO - PROCESS | 2000 | 1463401091893 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:20:24 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49681/tests/dromaeo/cssquery-ext.html) 05:20:25 INFO - PROCESS | 2000 | RSS: Main: 261271552 05:20:25 INFO - PROCESS | 2000 | 05:23:27 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-ext.html (next: http://localhost:49681/tests/dromaeo/cssquery-jquery.html) 05:23:28 INFO - PROCESS | 2000 | RSS: Main: 273121280 05:23:28 INFO - PROCESS | 2000 | 05:26:29 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49681/tests/dromaeo/cssquery-mootools.html) 05:26:30 INFO - PROCESS | 2000 | RSS: Main: 275628032 05:26:30 INFO - PROCESS | 2000 | 05:29:32 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49681/tests/dromaeo/cssquery-prototype.html) 05:29:32 INFO - PROCESS | 2000 | RSS: Main: 276721664 05:29:32 INFO - PROCESS | 2000 | 05:32:35 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49681/tests/dromaeo/cssquery-yui.html) 05:32:36 INFO - PROCESS | 2000 | RSS: Main: 279908352 05:32:36 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | Cycle 1(1): loaded http://localhost:49681/tests/dromaeo/cssquery-yui.html (next: http://localhost:49681/tests/dromaeo/cssquery-dojo.html) 05:35:39 INFO - PROCESS | 2000 | RSS: Main: 280932352 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | __start_tp_report 05:35:39 INFO - PROCESS | 2000 | _x_x_mozilla_page_load 05:35:39 INFO - PROCESS | 2000 | _x_x_mozilla_page_load_details 05:35:39 INFO - PROCESS | 2000 | |i|pagename|runs| 05:35:39 INFO - PROCESS | 2000 | |0;dojo.html;2849.914502564923;3409.556757621507;3508.3334166508375;3508.403571392863;3512.7189824814013;5737.225474560933;5790.797322093727;5906.114082887569;5923.407659234074;5958.374370691069;5725.083986562151;5850.619709718865;5860.267466566679;5909.113632955059;5912.88174236515;5212.791488340471;5246.763895624687;5338.599605029619;5352.678839269656;5396.487333703311;4463.3081872309685;5293.258943747878;5424.728763561826;5459.952517805823;5489.58828087895;5206.245094461282;5236.21456781482;5280.709560974145;5298.4436634153435;5360.758765855546;5301.814436494743;5317.494837990424;5321.920171197435;5325.520703136697;5334.946650533484;5145.125328694131;5254.737263136866;5290.788368465257;5302.655327403745;5366.8926621467745;5496.31296087989;5699.886002279973;5702.429757024306;5720.027595308808;5772.913406298909;6241.251049873998;6301.991681331011;6324.81025569229;6334.588251763621;6341.4292713656;6247.969085100953;6271.62370257786;6285.8742825143245;6333.7466501339895;6344.1435406220035;5948.791792287292;6008.609440386448;6016.669083200422;6022.69886505673;6068.393160683941;6391.552591318563;6425.614463132133;6453.774117905851;6464.127342808754;6473.708683109271;6100.793122958854;6197.566170368031;6208.037754148114;6284;6311.810645680637;6299.307076221612;6338.873222535523;6361;6361.4592759615025;6362.077498762654;5033.2701758245585;6235.532335074797;6235.781747638811;6272.843178920563;6357.809265722036;6193.659348735772;6212.850622634827;6269.279032911182;6337.207849018872;6345.397187267202;5028.32117664118;5033.446320904697;5038.773255203525;5048.697078175322;5069.543741063322;4478.820847166142;4497.482789479186;4533.659975501851;4550.203714349976;4593.219152744042;5825.679587622722;5842.298924129047;5933.881322373529;6005.429484199081;6068.908966365508;6272.983559114764;6356.777512787123;6384;6453.451456626145;6461.903071453932;5961.850953726105;5969.492593129546;5977.491913187428;5979.5515336349945;5981.043033114681;4614.492405835355;5291.550218231417;5368.651037682538;5465.890682186334;5493.340799104053;6592.76925307612;6617.602943823386;6622.006698995189;6624.30444803308;6637.303083176197;6247.6563788990225;6345.17512723343;6415.8716825662295;6422.068800024063;6428.325025872216;6479.416852483299;6480.449161821298;6534.901976470452;6583.703733331916;6597.109390232257;6299.181106456132;6318.241810982711;6337.6197428154455;6351.650659213603;6354.17395738551;6227.532935029982;6259.96870015647;6363.936360636334;6373;6393.041043843461;5745.166950792195;5924.289085309791;6192.938070619236;6245.781397651061;6252.968735156295;6241.875162496815;6254;6258.749650014131;6281.277653069956;6292.9370706294185;4505.7296562206375;6062.484688801324;6067.54493412987;6094.08588711679;6236.407541283571;6146.569740118149;6230.7819226328675;6246.3753624637175;6256.499480041494;6265.937340626535;5253.212018197301;6093.75624974996;6131.264248290234;6180.4746596538;6181.660008699385 05:35:39 INFO - PROCESS | 2000 | |1;ext.html;493.9478909921864;508.9337837231004;556.3157316500702;561.3320149022663;563.0231548263762;40213.396799048;43485.78257108718;43805;43857.56142438575;44044.55955440445;44714;45305;45329.54670453294;45406.54593454073;45513.17947282113;9427.198688111484;9791;9816.11654951056;9909.90090099097;10035.698929032103;9825.312228144032;9880.456574888378;9902.90097099027;9907.702768916908;9921.057499537532;9805.264605154642;9838.966908474615;9914.801703965917;9952.60189592419;9960.850587241197;9871.901280987242;9877.703668889944;9883;9893.802123957481;9929.702108936779;9693;9701.417914925056;9720.173785228193;9777.92442831288;9808.46053467059;40173.19653606941;40476.79761601173;40624;40886.38670419946;41065.38401923973;3219.7585181111513;3247.5291082792864;3258.8370581471067;3719.4420836874683;4017.2367250222364;29839.70160298391;29932.551011734842;30047.549286760717;30202.6408811604;30951.84524077365;2760.2961244882626;2767.986160069207;2771.293320203335;2890.898818541341;2915.3149009982635;517467;521579;524120;527006;530728.3463582696;67325.6238344506;67598;68048.6597567009;68108;68178;37783.62216377856;38087.42868856969;38620;39487.40768888469;70467.94298085533;17567.560810979827;17749.201285942163;17907.283708651506;17914.91042544779;17922.462326130237;13388.464461421434;13399.196048237138;13459.259740714158;13504.594862154152;13560.59318220455;19084.236630534622;19801.900990494956;21478.785212147992;21585.892070539547;21613.67579486309;7513.361364283987;7931.484453510503;8413.495190288602;8417.326613870875;8439.746807595782;911.9361644684808;1048.486241741541;1070.3577853287966;1095.1840878545534;1100.273819279272;28339.858300708365;30339.544906826417;31015.844920775253;31066.53400198999;31191.064268071994;1081.5944020992129;1101.1191047162238;1166.1778446195412;1190.7082764722697;1192.7852986462347;13459.730805383837;15846.366145354288;16114;16130;16261.663688703453;712350;713589;715679;715800;715810;54620.18069728957;64789.3802654928;65014;65070;65557;1445.7542217822995;1486.9330880110422;1499.6475828180369;1514.833368329483;1515.3862685612344;24131.517369652858;29865.701342987162;30570.847145764128;30864.537031944088;30903.845480772452;57175.714121429126;57697;57699.42300576941;57817.843643127744;57841.710791445774;484158;505261;506347;506539;508819.4559027181;27660.585091223245;28017.57973630438;28181.013664521644;28365.858170709016;28692.569611456263;47387.28919066286;47430;48330;48721.26918096302;48724.75637621931;31601.20996975094;36235.09412264715;39848.80453586397;39892.800535997136;40708.389374160004;28984.13047608491;37437.06407339729;39823.402648960866;39897.80051099726;40513.79743101266;33288.500672490416;38744.41883371808;39064.63273785404;39752;40811.591884081965;34217.65782342244;34760.13099672528;35316.47025294571;35529.1117722059;35605.93182204538;24371.512569748862;25527.617085743357;25924.74075259223;26021.69891505455;26559.734402655726 05:35:39 INFO - PROCESS | 2000 | |2;jquery.html;388.4561613740761;390.5684218938073;393.6575179593754;395.8337498250734;415.83366653338663;18596.907015464938;19464.318748843794;19531.609367812634;19604.60790784187;21540.676889846625;20501.589968200627;20887.060082296295;21437.57124857502;21911.6713249301;22180.556388872214;5509.752061157247;5812.273465816773;5844.590878638497;5860.706964651772;5910.822675319726;5587.776488940458;5765.596408251424;5826.126081087849;5834.854128646796;5952.642841429506;5842.47417732404;5858.5606079543995;5866.941330586704;5868.471837534621;5877.2947246330505;5402.837914862561;5792.971035144818;5845.8830823383505;5858.062709966406;6560.442362399202;5671.801486947937;5691.715414229313;5850.736716847752;5851.005329094064;5856.824295271149;13561.593152205449;13796.793048104286;13878.306084695725;14009.789853152211;14025.368858401294;1831.5604254978844;1876.4933467963574;2094.8268969377195;2263.46808500004;2275.2264230161823;9764.267679923963;10235;10249.487525623763;10307.175425965905;10462.633807816766;1632.6122795623626;1660.103544086192;1780.652772709322;1792.09499202903;1838.8161183881505;126405.36797316046;131306.6869331304;132056;132165;132994;10271.537780799881;11030.669079927615;11745.354005529607;11835.822462663067;12031.458584363725;15244;15270.694586108215;15282.847171528365;15290.617734556725;15296.311665975058;6482.481401487869;7315.597642129627;8738.956305218433;8914.910850891409;8954.955225223963;8697.260732837653;8779.824403511893;8786.16531429513;8840.160184782435;8937.508437035895;4173.436586060903;4254.532001479834;4637.443506779208;4760.714357138583;4818.325434439182;1839.59528903641;2022.1304838919407;2026.371824734337;2035.664115420939;2093.3301343570215;912.8995810460844;931.4550987672152;933.8179055084194;937.4890684576922;1004.1113614451117;18009.369672061413;19969.800301997086;20836.062377193062;20861.044096799003;20883.37349879506;957.3346524165638;976.5703090640237;985.7437066362654;985.9211263098935;1015.4161357219629;10753.78492430147;10998.835017474745;11005.17461190414;11041.723956901142;11091.22361434708;610314;613586;617012;623406;624881;129064.41871162524;130866;131884;132839.67160328466;133408;1024.948752562369;1041.7551875309296;1065.1265961911313;1086.5925278020743;1088.9891101088888;17978.01120938361;18344.633107338046;18487.89072655645;18685.813141868406;18769.624607508045;35736;43061;48880.26679599738;50383.74808125936;51037.48962510328;63873.680631596544;74214;74410.25589744034;75524;76226;6819.352161544645;7748.806279843049;8081.111077781322;8310.335173186251;8398.076211616839;11474;12590.874091259337;12610;12767.978561714855;12852.678683032998;9152.359334846496;10824.891751082389;10868.619598314337;10884.965928236805;10963;10070.496475176065;10266.127379172854;10773.676789696321;10864.076553492725;10898.945505272422;9475.47884866339;11157.888421115686;11230.719232018939;11233.639183315903;11243.943780281046;6358.77744278948;7369.115706115086;7539.359154471931;8090;8539.103394143774;11030.779384412106;11219.270747401393;11273.379964102052;11358.772824543628;11442.313461192021 05:35:39 INFO - PROCESS | 2000 | |3;mootools.html;1603.3666701652844;1627.5768300241937;1900.1354383755383;1971.1031480676286;1997.3808119482972;8302.252797248246;8648.91351086489;8882.733517994455;9393.812123757521;9409.153176214139;8796.340274479424;8940.06129356418;8952.91047089529;8955;9064.728058158249;2413.662087307774;2447.8286519943613;2475.331660451677;2477.715062767778;2491.289982355029;2427.1990243219693;2468.8642124683133;2482.913098041569;2491.19036313198;2491.725910149882;2440.841345312549;2452.9632055519182;2455.5457240410583;2459.8155138364696;2468.6420469031978;2424.7575242475787;2433.5741245282093;2437.8415402998835;2446.0704932125855;2448.081969261527;2358.0921345282018;2363.444590521226;2364.9054037838464;2372.406898275431;2380.190735150057;8922.598483068277;8925.687600933936;8999.5050272235;9013.684521041798;9048.728538143867;5443.510084092451;5506.504414602665;5593.972030139864;5600.747966341524;5615.0454422748235;3527.7883327000204;3535.5680949215607;3558.822058897045;3626.746127771057;3630.146915474888;1023.5496381592076;1031.7833255016455;1031.85038169465;1037.9221558383151;1040.3653771199563;9919.801603967953;11692.824607630891;11958.222715523463;12070.577529786504;12285.754284914341;4354.629856462237;4955.529224723646;4964.702117872939;5025.748712564357;5117.974410127926;10449.634262800766;10483;10489.842652360221;10490.31812932157;10509.421981791074;4913.729744864067;5243.134882744381;5456.672599644034;5533.972330138324;5540.778368865281;4534.410526631583;4911.656184067081;4916.287138364989;5078.644494885322;5090.720010399464;3644.890653280406;4180.331147016463;4182.853600124042;4184.121334519724;4212.915741685149;1332.9800052999212;1346.5219846954385;1350.9797353039712;1355.004072007074;1357.151780137414;773.628658244046;883.0242581946984;889.4440974391006;898.4833720610675;918.237862574062;5831.708414579254;5930.406959304035;5974.731137098841;6081.8479538011015;6151.323354431009;448.6948874765191;454.9158405694891;459.9632029437637;463.1246943277209;466.0957741980547;3607.2244467439627;3898.629630185128;3943.645071943539;4016.176683779818;4031.5766844481495;10145.746356341151;10960.835587466194;11068.343597536534;11161.944190278997;11196;10802.675919722264;10987.890121098846;11121;11141.275817071864;11176.329420234812;6969.6515174241085;8349.16318409961;8402.075771665111;8473.576321183917;8494.06565277819;5183.714895680774;5798.797042103506;5842.912356314744;5891.558133140117;5957.285125784933;10108.797824043331;10270.6405275815;10271.486425678537;10288.382697038202;10295.794084118426;10198.184145268213;10297.639582614573;10443.530041148319;10535.42055186972;10539.841902371623;7124.3944264738075;7569.091708994956;7763.61181940912;7771.4559980800805;7858.921410785819;8529;8557.61490732906;8608.311335093304;8610.65557377698;8616.181462761027;6574.309697481695;7527.623618818928;7637.847243054996;7708.306252437307;7750.302472777477;7493.812654683677;7502.212267711811;7543.43424243173;7573.545587264782;7614.238576142341;7170.928290717235;7239.457040722073;7251.456140789356;7268.236835132235;7274.30894065063;4800.207965685623;4839.322494850653;4886;4996.800127994839;5058.1148299048255;4721.433427988663;4958.603311735124;5048.368953880765;5058.317127187783;5060.671056381323 05:35:39 INFO - PROCESS | 2000 | |4;prototype.html;303.52649866208714;323.46951000359417;327.255493751715;327.9376918385507;332.56766203934876;4886.2148575312085;5088.60835407358;5286.444923283053;5514.283143191385;5576.944230557693;4614.930776038362;5474.467149198231;5526.47498487644;5535.861603459915;5546.639468434559;3520.9965159929425;3865.111024464375;4139.56534563871;4185.656032621726;4194.412782210493;3196.4086643970822;4142;4152.709310348277;4164.396162556428;4204.810783514749;3382.391169589473;3834.5206849143856;3983.3427484465174;4206.72656277341;4254.532001479834;3316.403047451458;3478.4260597001467;4038.223181800013;4129.690273229489;4221.2190744712325;3242.886498972525;3256.609206895164;3427.057559171223;3553.928921421583;3562.87529936451;4441.023744433696;4899.510048995072;5278.313819203479;5310.867228319285;5329.467053294639;4223.387608796707;4391.736495810262;4460.190080249455;4769.236922092448;4781.987347970756;5048.924266136011;5744.6840423776675;5760.279965004374;5777.480026797566;5800.564957628195;3452.896413107611;3608.855645774166;3757.304898593769;3898.727089103764;3914.9608503915165;5364;5367.624266301361;5597.132444471114;5680.31836179657;6164.907526387108;3950.7036972227197;3953.9802300988886;3964.940525892114;3983.1635356575443;4001.8799436016966;4039.4950631171105;4045.6763458923215;4057.89855253621;4071.6742660587083;4084.959150408458;3975.463312452782;4057.634812866856;4546.227141385906;4712.151812673683;4757.4072130688855;4819.783109760069;4941.876453088629;5640.717964101778;5719.542436605062;5871.768727883377;1184.810430331143;1228.5147366790068;1315.8749918757703;1389.1803835736964;1392.6587985943509;749.4416659588642;782.3037496628006;843.696269343036;870.7605408512583;896.7354630384052;4579.992401671627;4686.531346865286;4808.345657300477;5432.29380180574;5433;5317.548008419328;5998.520118390518;5998.70006499682;6028.608140470856;6049.971504844187;4769.6661233714;5171.456997015334;5241.449647786927;5382.407935127133;5459.808906688247;2604.6744156980376;2846.2742000789667;2991.401719656034;3140.2934339773824;3154.8264845433723;5138.434772175057;5283.471652834763;6165.722542485599;6214.751409943642;6244.531660125509;5770.509506691894;5807.651540907559;6108.083787431832;6116.235470566179;6151.985527091693;5042.873928151826;5170.560502357266;6197.504199663926;6203.379662033851;6208.709384026473;5764.250647415809;5960.165576819162;5961.91057134152;6005.6396616203165;6029.849253768691;5850.473457388855;5867.031939729897;5869.268565773193;5904.055351143968;5912.320083190316;5582.967151077064;5678.77284908599;5872.593895540491;6233.906491402542;6265;3927.5286965564314;3943.72393932422;3964.3042730943234;3964.6431821135084;3973.344398174269;5127.948720512747;5362.785488580413;5685.886282274414;5858.297004359504;6004.759809607567;5420.349558053059;5806.838632273478;5807.419258074159;5830.708464576839;5853.970730146322;4714.834980775657;4864.464908859952;5563.221149039218;5855.765769369177;5904.996150654313;5484.670919744828;5747.36778954323;5754.165645981393;5760.954861024276;5766.452187042224;4803;5514.814314922311;5868.735906884286;5921.259842519685;5935;4722.7874745635845;5241.895162096813;5810.5061069809535;5838.416158384298;5843.451485356299 05:35:39 INFO - PROCESS | 2000 | |5;yui.html;606.3602898941617;744.8510297940412;750.4634186556611;751.488987488508;789.7551758954725;12596.748065038693;13553.915686745062;15456.459023934165;15475.226238688078;15501.534953951385;13843.792343114861;13848.861511384883;14195.71608567828;14231.217283049427;14240;731.0715391452878;894.168423366269;899.6806133822481;926.6571368593629;936.0545848692801;914.8719179314901;920.282179899676;924.0251534630962;930.1256818590504;931.757742986825;946.6970569417755;956.7273327101742;990.6730778843;993.6174572789492;994.4828689081673;947.6683160893666;975.7024107647164;987.6148302162162;989.1839232633107;989.6783545347719;718.9137303523559;719.5107327017625;722.0685315942428;736.7973807203008;738.5162718419443;14549.709005819825;14641.780373294408;14697.632559186106;14715.969882108257;14720.558383248519;10304;12366;12497.25016499013;12499.250044997329;12672.049596280316;8009.47938384009;8895.288376929895;9075.7293978843;9856;10104.44425556594;3818.1409182933894;4178.143480586503;4180.24755544002;4186.748795072305;4315.762633055181;77556.61221693855;78068.8099942126;78112;78751;80086;9204.079592040742;9317.16145546904;9331.253499720005;9347.158755712018;9405.529723513797;19848.007599619963;20224.89887550553;20428.79571204269;20440.591188176153;20444.28445004418;6861.382475577222;7144.821379465451;7229.566226026455;7343.816404589821;7400.259974002664;3301.257217126127;3885;3897.3569361055675;3917.745346552522;3930.5283365995692;3526.4357702767434;3544.1848374873407;3600.855965761393;3604.333198358312;3610.2598967211657;647.8509942713108;648.5654611410366;651.9543631945813;676.2324761395753;687.3779229797042;477.6226780843095;549.2424242424258;559.6781850436015;566.7562947932346;573.8594044459135;7683.346915512244;7816.179301173295;7972;8012.31895288907;8049.65939135264;499.83505443203654;510.34675615212586;515.0240294641633;543.6439132368305;557.3729554251468;3180.7137357637926;3581.4806853006166;3663.560372755233;3746.119661879456;3774.3206222880144;69838;70483;91285.08714912766;92212.85042173119;93262;64765;84280;84950;85285.14714852771;86644;56171.43828561744;57730.845383092106;75100.62449687716;75422;75593.86609200867;7149.177844547944;7326.890096648448;7339.266073392618;7532.39740820744;7746.380289576933;45123;46193.53806462027;46377.53622463732;46419.53580464152;46579.53420465752;62132.446688833144;62903.6854815723;63999.68000159969;64280.35719642744;65162;9588.520573971413;9598.136167744937;9648.083432073941;9713.757156070873;9949.253805964438;8604.82790344177;10285.588576457158;10425.436184572374;10629.627963021258;10730.517126729159;10026.147777438999;10157.847632285373;10345.63790267337;10479.738006549896;10496;10098.949505252427;10166.491675416051;10198.745031373977;10481;10554.313969591953;9993.050660187271;10000.849987250343;10116.29185956976;10316.43259620698;10383.84424233622;8623.82752344937;8908.55457227149;8912.55437228123;8913.955430222808;10081.596736130767;9800.313978021755;9934.403935763878;9969.750756231153;10015;10094.444805535766 05:35:39 INFO - PROCESS | 2000 | __end_tp_report 05:35:39 INFO - PROCESS | 2000 | __start_cc_report 05:35:39 INFO - PROCESS | 2000 | _x_x_mozilla_cycle_collect,420 05:35:39 INFO - PROCESS | 2000 | __end_cc_report 05:35:39 INFO - PROCESS | 2000 | __startTimestamp1463402139392__endTimestamp 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | ------- Summary: start ------- 05:35:39 INFO - PROCESS | 2000 | Number of tests: 6 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#0] dojo.html Cycles:165 Average:5839.56 Median:6093.76 stddev:692.15 (11.4%) stddev-sans-first:653.33 05:35:39 INFO - PROCESS | 2000 | Values: 2849.9 3409.6 3508.3 3508.4 3512.7 5737.2 5790.8 5906.1 5923.4 5958.4 5725.1 5850.6 5860.3 5909.1 5912.9 5212.8 5246.8 5338.6 5352.7 5396.5 4463.3 5293.3 5424.7 5460.0 5489.6 5206.2 5236.2 5280.7 5298.4 5360.8 5301.8 5317.5 5321.9 5325.5 5334.9 5145.1 5254.7 5290.8 5302.7 5366.9 5496.3 5699.9 5702.4 5720.0 5772.9 6241.3 6302.0 6324.8 6334.6 6341.4 6248.0 6271.6 6285.9 6333.7 6344.1 5948.8 6008.6 6016.7 6022.7 6068.4 6391.6 6425.6 6453.8 6464.1 6473.7 6100.8 6197.6 6208.0 6284.0 6311.8 6299.3 6338.9 6361.0 6361.5 6362.1 5033.3 6235.5 6235.8 6272.8 6357.8 6193.7 6212.9 6269.3 6337.2 6345.4 5028.3 5033.4 5038.8 5048.7 5069.5 4478.8 4497.5 4533.7 4550.2 4593.2 5825.7 5842.3 5933.9 6005.4 6068.9 6273.0 6356.8 6384.0 6453.5 6461.9 5961.9 5969.5 5977.5 5979.6 5981.0 4614.5 5291.6 5368.7 5465.9 5493.3 6592.8 6617.6 6622.0 6624.3 6637.3 6247.7 6345.2 6415.9 6422.1 6428.3 6479.4 6480.4 6534.9 6583.7 6597.1 6299.2 6318.2 6337.6 6351.7 6354.2 6227.5 6260.0 6363.9 6373.0 6393.0 5745.2 5924.3 6192.9 6245.8 6253.0 6241.9 6254.0 6258.7 6281.3 6292.9 4505.7 6062.5 6067.5 6094.1 6236.4 6146.6 6230.8 6246.4 6256.5 6265.9 5253.2 6093.8 6131.3 6180.5 6181.7 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#1] ext.html Cycles:180 Average:71641.88 Median:22095.22 stddev:157358.25 (712.2%) stddev-sans-first:157709.01 05:35:39 INFO - PROCESS | 2000 | Values: 493.9 508.9 556.3 561.3 563.0 40213.4 43485.8 43805.0 43857.6 44044.6 44714.0 45305.0 45329.5 45406.5 45513.2 9427.2 9791.0 9816.1 9909.9 10035.7 9825.3 9880.5 9902.9 9907.7 9921.1 9805.3 9839.0 9914.8 9952.6 9960.9 9871.9 9877.7 9883.0 9893.8 9929.7 9693.0 9701.4 9720.2 9777.9 9808.5 40173.2 40476.8 40624.0 40886.4 41065.4 3219.8 3247.5 3258.8 3719.4 4017.2 29839.7 29932.6 30047.5 30202.6 30951.8 2760.3 2768.0 2771.3 2890.9 2915.3 517467.0 521579.0 524120.0 527006.0 530728.3 67325.6 67598.0 68048.7 68108.0 68178.0 37783.6 38087.4 38620.0 39487.4 70467.9 17567.6 17749.2 17907.3 17914.9 17922.5 13388.5 13399.2 13459.3 13504.6 13560.6 19084.2 19801.9 21478.8 21585.9 21613.7 7513.4 7931.5 8413.5 8417.3 8439.7 911.9 1048.5 1070.4 1095.2 1100.3 28339.9 30339.5 31015.8 31066.5 31191.1 1081.6 1101.1 1166.2 1190.7 1192.8 13459.7 15846.4 16114.0 16130.0 16261.7 712350.0 713589.0 715679.0 715800.0 715810.0 54620.2 64789.4 65014.0 65070.0 65557.0 1445.8 1486.9 1499.6 1514.8 1515.4 24131.5 29865.7 30570.8 30864.5 30903.8 57175.7 57697.0 57699.4 57817.8 57841.7 484158.0 505261.0 506347.0 506539.0 508819.5 27660.6 28017.6 28181.0 28365.9 28692.6 47387.3 47430.0 48330.0 48721.3 48724.8 31601.2 36235.1 39848.8 39892.8 40708.4 28984.1 37437.1 39823.4 39897.8 40513.8 33288.5 38744.4 39064.6 39752.0 40811.6 34217.7 34760.1 35316.5 35529.1 35605.9 24371.5 25527.6 25924.7 26021.7 26559.7 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#2] jquery.html Cycles:180 Average:35316.49 Median:11490.20 stddev:103334.35 (899.3%) stddev-sans-first:103590.95 05:35:39 INFO - PROCESS | 2000 | Values: 388.5 390.6 393.7 395.8 415.8 18596.9 19464.3 19531.6 19604.6 21540.7 20501.6 20887.1 21437.6 21911.7 22180.6 5509.8 5812.3 5844.6 5860.7 5910.8 5587.8 5765.6 5826.1 5834.9 5952.6 5842.5 5858.6 5866.9 5868.5 5877.3 5402.8 5793.0 5845.9 5858.1 6560.4 5671.8 5691.7 5850.7 5851.0 5856.8 13561.6 13796.8 13878.3 14009.8 14025.4 1831.6 1876.5 2094.8 2263.5 2275.2 9764.3 10235.0 10249.5 10307.2 10462.6 1632.6 1660.1 1780.7 1792.1 1838.8 126405.4 131306.7 132056.0 132165.0 132994.0 10271.5 11030.7 11745.4 11835.8 12031.5 15244.0 15270.7 15282.8 15290.6 15296.3 6482.5 7315.6 8739.0 8914.9 8955.0 8697.3 8779.8 8786.2 8840.2 8937.5 4173.4 4254.5 4637.4 4760.7 4818.3 1839.6 2022.1 2026.4 2035.7 2093.3 912.9 931.5 933.8 937.5 1004.1 18009.4 19969.8 20836.1 20861.0 20883.4 957.3 976.6 985.7 985.9 1015.4 10753.8 10998.8 11005.2 11041.7 11091.2 610314.0 613586.0 617012.0 623406.0 624881.0 129064.4 130866.0 131884.0 132839.7 133408.0 1024.9 1041.8 1065.1 1086.6 1089.0 17978.0 18344.6 18487.9 18685.8 18769.6 35736.0 43061.0 48880.3 50383.7 51037.5 63873.7 74214.0 74410.3 75524.0 76226.0 6819.4 7748.8 8081.1 8310.3 8398.1 11474.0 12590.9 12610.0 12768.0 12852.7 9152.4 10824.9 10868.6 10885.0 10963.0 10070.5 10266.1 10773.7 10864.1 10898.9 9475.5 11157.9 11230.7 11233.6 11243.9 6358.8 7369.1 7539.4 8090.0 8539.1 11030.8 11219.3 11273.4 11358.8 11442.3 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#3] mootools.html Cycles:180 Average:5824.08 Median:2593.76 stddev:3295.54 (127.1%) stddev-sans-first:3289.52 05:35:39 INFO - PROCESS | 2000 | Values: 1603.4 1627.6 1900.1 1971.1 1997.4 8302.3 8648.9 8882.7 9393.8 9409.2 8796.3 8940.1 8952.9 8955.0 9064.7 2413.7 2447.8 2475.3 2477.7 2491.3 2427.2 2468.9 2482.9 2491.2 2491.7 2440.8 2453.0 2455.5 2459.8 2468.6 2424.8 2433.6 2437.8 2446.1 2448.1 2358.1 2363.4 2364.9 2372.4 2380.2 8922.6 8925.7 8999.5 9013.7 9048.7 5443.5 5506.5 5594.0 5600.7 5615.0 3527.8 3535.6 3558.8 3626.7 3630.1 1023.5 1031.8 1031.9 1037.9 1040.4 9919.8 11692.8 11958.2 12070.6 12285.8 4354.6 4955.5 4964.7 5025.7 5118.0 10449.6 10483.0 10489.8 10490.3 10509.4 4913.7 5243.1 5456.7 5534.0 5540.8 4534.4 4911.7 4916.3 5078.6 5090.7 3644.9 4180.3 4182.9 4184.1 4212.9 1333.0 1346.5 1351.0 1355.0 1357.2 773.6 883.0 889.4 898.5 918.2 5831.7 5930.4 5974.7 6081.8 6151.3 448.7 454.9 460.0 463.1 466.1 3607.2 3898.6 3943.6 4016.2 4031.6 10145.7 10960.8 11068.3 11161.9 11196.0 10802.7 10987.9 11121.0 11141.3 11176.3 6969.7 8349.2 8402.1 8473.6 8494.1 5183.7 5798.8 5842.9 5891.6 5957.3 10108.8 10270.6 10271.5 10288.4 10295.8 10198.2 10297.6 10443.5 10535.4 10539.8 7124.4 7569.1 7763.6 7771.5 7858.9 8529.0 8557.6 8608.3 8610.7 8616.2 6574.3 7527.6 7637.8 7708.3 7750.3 7493.8 7502.2 7543.4 7573.5 7614.2 7170.9 7239.5 7251.5 7268.2 7274.3 4800.2 4839.3 4886.0 4996.8 5058.1 4721.4 4958.6 5048.4 5058.3 5060.7 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#4] prototype.html Cycles:180 Average:4622.91 Median:5206.45 stddev:1460.42 (28.1%) stddev-sans-first:1428.08 05:35:39 INFO - PROCESS | 2000 | Values: 303.5 323.5 327.3 327.9 332.6 4886.2 5088.6 5286.4 5514.3 5576.9 4614.9 5474.5 5526.5 5535.9 5546.6 3521.0 3865.1 4139.6 4185.7 4194.4 3196.4 4142.0 4152.7 4164.4 4204.8 3382.4 3834.5 3983.3 4206.7 4254.5 3316.4 3478.4 4038.2 4129.7 4221.2 3242.9 3256.6 3427.1 3553.9 3562.9 4441.0 4899.5 5278.3 5310.9 5329.5 4223.4 4391.7 4460.2 4769.2 4782.0 5048.9 5744.7 5760.3 5777.5 5800.6 3452.9 3608.9 3757.3 3898.7 3915.0 5364.0 5367.6 5597.1 5680.3 6164.9 3950.7 3954.0 3964.9 3983.2 4001.9 4039.5 4045.7 4057.9 4071.7 4085.0 3975.5 4057.6 4546.2 4712.2 4757.4 4819.8 4941.9 5640.7 5719.5 5871.8 1184.8 1228.5 1315.9 1389.2 1392.7 749.4 782.3 843.7 870.8 896.7 4580.0 4686.5 4808.3 5432.3 5433.0 5317.5 5998.5 5998.7 6028.6 6050.0 4769.7 5171.5 5241.4 5382.4 5459.8 2604.7 2846.3 2991.4 3140.3 3154.8 5138.4 5283.5 6165.7 6214.8 6244.5 5770.5 5807.7 6108.1 6116.2 6152.0 5042.9 5170.6 6197.5 6203.4 6208.7 5764.3 5960.2 5961.9 6005.6 6029.8 5850.5 5867.0 5869.3 5904.1 5912.3 5583.0 5678.8 5872.6 6233.9 6265.0 3927.5 3943.7 3964.3 3964.6 3973.3 5127.9 5362.8 5685.9 5858.3 6004.8 5420.3 5806.8 5807.4 5830.7 5854.0 4714.8 4864.5 5563.2 5855.8 5905.0 5484.7 5747.4 5754.2 5761.0 5766.5 4803.0 5514.8 5868.7 5921.3 5935.0 4722.8 5241.9 5810.5 5838.4 5843.5 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - PROCESS | 2000 | [#5] yui.html Cycles:180 Average:17479.61 Median:32706.78 stddev:24832.75 (75.9%) stddev-sans-first:24870.09 05:35:39 INFO - PROCESS | 2000 | Values: 606.4 744.9 750.5 751.5 789.8 12596.7 13553.9 15456.5 15475.2 15501.5 13843.8 13848.9 14195.7 14231.2 14240.0 731.1 894.2 899.7 926.7 936.1 914.9 920.3 924.0 930.1 931.8 946.7 956.7 990.7 993.6 994.5 947.7 975.7 987.6 989.2 989.7 718.9 719.5 722.1 736.8 738.5 14549.7 14641.8 14697.6 14716.0 14720.6 10304.0 12366.0 12497.3 12499.3 12672.0 8009.5 8895.3 9075.7 9856.0 10104.4 3818.1 4178.1 4180.2 4186.7 4315.8 77556.6 78068.8 78112.0 78751.0 80086.0 9204.1 9317.2 9331.3 9347.2 9405.5 19848.0 20224.9 20428.8 20440.6 20444.3 6861.4 7144.8 7229.6 7343.8 7400.3 3301.3 3885.0 3897.4 3917.7 3930.5 3526.4 3544.2 3600.9 3604.3 3610.3 647.9 648.6 652.0 676.2 687.4 477.6 549.2 559.7 566.8 573.9 7683.3 7816.2 7972.0 8012.3 8049.7 499.8 510.3 515.0 543.6 557.4 3180.7 3581.5 3663.6 3746.1 3774.3 69838.0 70483.0 91285.1 92212.9 93262.0 64765.0 84280.0 84950.0 85285.1 86644.0 56171.4 57730.8 75100.6 75422.0 75593.9 7149.2 7326.9 7339.3 7532.4 7746.4 45123.0 46193.5 46377.5 46419.5 46579.5 62132.4 62903.7 63999.7 64280.4 65162.0 9588.5 9598.1 9648.1 9713.8 9949.3 8604.8 10285.6 10425.4 10629.6 10730.5 10026.1 10157.8 10345.6 10479.7 10496.0 10098.9 10166.5 10198.7 10481.0 10554.3 9993.1 10000.8 10116.3 10316.4 10383.8 8623.8 8908.6 8912.6 8914.0 10081.6 9800.3 9934.4 9969.8 10015.0 10094.4 05:35:39 INFO - PROCESS | 2000 | -------- Summary: end -------- 05:35:39 INFO - PROCESS | 2000 | 05:35:39 INFO - TEST-INFO | 2000: exit 0 05:35:40 INFO - TEST-OK | dromaeo_css | took 1113729ms 05:35:40 INFO - TEST-START | kraken 05:35:40 INFO - operating with platform_type : mac_ 05:35:40 INFO - Initialising browser for kraken test... 05:35:40 INFO - TEST-INFO | started process 2005 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpO7l7ZJ/profile http://localhost:49681/getInfo.html) 05:35:42 INFO - PROCESS | 2005 | __metrics Screen width/height:1600/1200 05:35:42 INFO - PROCESS | 2005 | colorDepth:24 05:35:42 INFO - PROCESS | 2005 | Browser inner width/height: 1024/689 05:35:42 INFO - PROCESS | 2005 | __metrics 05:35:42 ERROR - PROCESS | 2005 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:35:43 INFO - TEST-INFO | 2005: exit 0 05:35:43 INFO - Browser initialized. 05:35:43 INFO - Running cycle 1/1 for kraken test... 05:35:43 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpO7l7ZJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:35:55 INFO - PROCESS | 2008 | RSS: Main: 250019840 05:35:55 INFO - PROCESS | 2008 | 05:35:58 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49681/tests/kraken/driver.html?testName=audio-beat-detection) 05:35:59 INFO - PROCESS | 2008 | RSS: Main: 261828608 05:35:59 INFO - PROCESS | 2008 | 05:36:02 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49681/tests/kraken/driver.html?testName=audio-dft) 05:36:02 INFO - PROCESS | 2008 | RSS: Main: 266473472 05:36:02 INFO - PROCESS | 2008 | 05:36:06 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49681/tests/kraken/driver.html?testName=audio-fft) 05:36:06 INFO - PROCESS | 2008 | RSS: Main: 267304960 05:36:06 INFO - PROCESS | 2008 | 05:36:08 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49681/tests/kraken/driver.html?testName=audio-oscillator) 05:36:09 INFO - PROCESS | 2008 | RSS: Main: 268447744 05:36:09 INFO - PROCESS | 2008 | 05:36:11 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49681/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:12 INFO - PROCESS | 2008 | RSS: Main: 267911168 05:36:12 INFO - PROCESS | 2008 | 05:36:15 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49681/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:16 INFO - PROCESS | 2008 | RSS: Main: 270278656 05:36:16 INFO - PROCESS | 2008 | 05:36:18 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49681/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:19 INFO - PROCESS | 2008 | RSS: Main: 270647296 05:36:19 INFO - PROCESS | 2008 | 05:36:22 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49681/tests/kraken/driver.html?testName=json-parse-financial) 05:36:23 INFO - PROCESS | 2008 | RSS: Main: 270991360 05:36:23 INFO - PROCESS | 2008 | 05:36:24 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49681/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:25 INFO - PROCESS | 2008 | RSS: Main: 270094336 05:36:25 INFO - PROCESS | 2008 | 05:36:27 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:28 INFO - PROCESS | 2008 | RSS: Main: 270082048 05:36:28 INFO - PROCESS | 2008 | 05:36:30 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:31 INFO - PROCESS | 2008 | RSS: Main: 270573568 05:36:31 INFO - PROCESS | 2008 | 05:36:34 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:34 INFO - PROCESS | 2008 | RSS: Main: 270495744 05:36:34 INFO - PROCESS | 2008 | 05:36:37 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:38 INFO - PROCESS | 2008 | RSS: Main: 270450688 05:36:38 INFO - PROCESS | 2008 | 05:36:39 INFO - PROCESS | 2008 | Cycle 1(1): loaded http://localhost:49681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49681/tests/kraken/driver.html?testName=ai-astar) 05:36:40 INFO - PROCESS | 2008 | RSS: Main: 272089088 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | __start_tp_report 05:36:40 INFO - PROCESS | 2008 | _x_x_mozilla_page_load 05:36:40 INFO - PROCESS | 2008 | _x_x_mozilla_page_load_details 05:36:40 INFO - PROCESS | 2008 | |i|pagename|runs| 05:36:40 INFO - PROCESS | 2008 | |0;ai-astar;111;116;106;117;113;117;106;114;116;117 05:36:40 INFO - PROCESS | 2008 | |1;audio-beat-detection;155;123;147;205;155;167;154;146;203;141 05:36:40 INFO - PROCESS | 2008 | |2;audio-dft;220;209;237;197;198;198;198;195;191;200 05:36:40 INFO - PROCESS | 2008 | |3;audio-fft;68;81;75;186;86;78;80;89;134;60 05:36:40 INFO - PROCESS | 2008 | |4;audio-oscillator;79;93;154;76;98;66;107;81;104;126 05:36:40 INFO - PROCESS | 2008 | |5;imaging-gaussian-blur;88;85;88;85;89;85;83;128;76;129 05:36:40 INFO - PROCESS | 2008 | |6;imaging-darkroom;94;108;92;94;94;94;93;95;94;95 05:36:40 INFO - PROCESS | 2008 | |7;imaging-desaturate;93;92;90;111;117;90;90;92;92;94 05:36:40 INFO - PROCESS | 2008 | |8;json-parse-financial;57;60;60;59;61;56;58;116;61;57 05:36:40 INFO - PROCESS | 2008 | |9;json-stringify-tinderbox;63;66;70;70;73;70;67;66;67;68 05:36:40 INFO - PROCESS | 2008 | |10;stanford-crypto-aes;85;89;90;86;81;86;88;89;103;75 05:36:40 INFO - PROCESS | 2008 | |11;stanford-crypto-ccm;148;147;144;148;142;136;135;136;214;142 05:36:40 INFO - PROCESS | 2008 | |12;stanford-crypto-pbkdf2;165;163;165;162;164;171;172;165;176;166 05:36:40 INFO - PROCESS | 2008 | |13;stanford-crypto-sha256-iterative;67;65;68;65;67;66;66;66;63;63 05:36:40 INFO - PROCESS | 2008 | __end_tp_report 05:36:40 INFO - PROCESS | 2008 | __start_cc_report 05:36:40 INFO - PROCESS | 2008 | _x_x_mozilla_cycle_collect,1075 05:36:40 INFO - PROCESS | 2008 | __end_cc_report 05:36:40 INFO - PROCESS | 2008 | __startTimestamp1463402200129__endTimestamp 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | ------- Summary: start ------- 05:36:40 INFO - PROCESS | 2008 | Number of tests: 14 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#0] ai-astar Cycles:10 Average:113.30 Median:116.00 stddev:4.32 (3.7%) stddev-sans-first:4.50 05:36:40 INFO - PROCESS | 2008 | Values: 111.0 116.0 106.0 117.0 113.0 117.0 106.0 114.0 116.0 117.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#1] audio-beat-detection Cycles:10 Average:159.60 Median:155.00 stddev:26.04 (16.8%) stddev-sans-first:27.57 05:36:40 INFO - PROCESS | 2008 | Values: 155.0 123.0 147.0 205.0 155.0 167.0 154.0 146.0 203.0 141.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#2] audio-dft Cycles:10 Average:204.30 Median:199.00 stddev:14.11 (7.1%) stddev-sans-first:13.78 05:36:40 INFO - PROCESS | 2008 | Values: 220.0 209.0 237.0 197.0 198.0 198.0 198.0 195.0 191.0 200.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#3] audio-fft Cycles:10 Average:93.70 Median:79.00 stddev:37.96 (48.0%) stddev-sans-first:39.10 05:36:40 INFO - PROCESS | 2008 | Values: 68.0 81.0 75.0 186.0 86.0 78.0 80.0 89.0 134.0 60.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:77.50 stddev:26.29 (33.9%) stddev-sans-first:26.93 05:36:40 INFO - PROCESS | 2008 | Values: 79.0 93.0 154.0 76.0 98.0 66.0 107.0 81.0 104.0 126.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:85.00 stddev:18.75 (22.1%) stddev-sans-first:19.78 05:36:40 INFO - PROCESS | 2008 | Values: 88.0 85.0 88.0 85.0 89.0 85.0 83.0 128.0 76.0 129.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:94.00 stddev:4.55 (4.8%) stddev-sans-first:4.80 05:36:40 INFO - PROCESS | 2008 | Values: 94.0 108.0 92.0 94.0 94.0 94.0 93.0 95.0 94.0 95.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:92.00 stddev:9.63 (10.5%) stddev-sans-first:10.15 05:36:40 INFO - PROCESS | 2008 | Values: 93.0 92.0 90.0 111.0 117.0 90.0 90.0 92.0 92.0 94.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:59.50 stddev:18.18 (30.6%) stddev-sans-first:19.08 05:36:40 INFO - PROCESS | 2008 | Values: 57.0 60.0 60.0 59.0 61.0 56.0 58.0 116.0 61.0 57.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:69.00 stddev:2.83 (4.1%) stddev-sans-first:2.35 05:36:40 INFO - PROCESS | 2008 | Values: 63.0 66.0 70.0 70.0 73.0 70.0 67.0 66.0 67.0 68.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.00 stddev:7.15 (8.2%) stddev-sans-first:7.54 05:36:40 INFO - PROCESS | 2008 | Values: 85.0 89.0 90.0 86.0 81.0 86.0 88.0 89.0 103.0 75.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:145.50 stddev:23.30 (16.0%) stddev-sans-first:24.71 05:36:40 INFO - PROCESS | 2008 | Values: 148.0 147.0 144.0 148.0 142.0 136.0 135.0 136.0 214.0 142.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:165.50 stddev:4.53 (2.7%) stddev-sans-first:4.76 05:36:40 INFO - PROCESS | 2008 | Values: 165.0 163.0 165.0 162.0 164.0 171.0 172.0 165.0 176.0 166.0 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - PROCESS | 2008 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.65 (2.5%) stddev-sans-first:1.67 05:36:40 INFO - PROCESS | 2008 | Values: 67.0 65.0 68.0 65.0 67.0 66.0 66.0 66.0 63.0 63.0 05:36:40 INFO - PROCESS | 2008 | -------- Summary: end -------- 05:36:40 INFO - PROCESS | 2008 | 05:36:40 INFO - TEST-INFO | 2008: exit 0 05:36:40 INFO - TEST-OK | kraken | took 60901ms 05:36:40 INFO - SUITE-END | took 1174s 05:36:41 INFO - Completed test suite (00:19:35) 05:36:41 INFO - javascript benchmark 05:36:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7217.279488716432, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2849.914502564923, 3409.556757621507, 3508.3334166508375, 3508.403571392863, 3512.7189824814013, 5737.225474560933, 5790.797322093727, 5906.114082887569, 5923.407659234074, 5958.374370691069, 5725.083986562151, 5850.619709718865, 5860.267466566679, 5909.113632955059, 5912.88174236515, 5212.791488340471, 5246.763895624687, 5338.599605029619, 5352.678839269656, 5396.487333703311, 4463.3081872309685, 5293.258943747878, 5424.728763561826, 5459.952517805823, 5489.58828087895, 5206.245094461282, 5236.21456781482, 5280.709560974145, 5298.4436634153435, 5360.758765855546, 5301.814436494743, 5317.494837990424, 5321.920171197435, 5325.520703136697, 5334.946650533484, 5145.125328694131, 5254.737263136866, 5290.788368465257, 5302.655327403745, 5366.8926621467745, 5496.31296087989, 5699.886002279973, 5702.429757024306, 5720.027595308808, 5772.913406298909, 6241.251049873998, 6301.991681331011, 6324.81025569229, 6334.588251763621, 6341.4292713656, 6247.969085100953, 6271.62370257786, 6285.8742825143245, 6333.7466501339895, 6344.1435406220035, 5948.791792287292, 6008.609440386448, 6016.669083200422, 6022.69886505673, 6068.393160683941, 6391.552591318563, 6425.614463132133, 6453.774117905851, 6464.127342808754, 6473.708683109271, 6100.793122958854, 6197.566170368031, 6208.037754148114, 6284.0, 6311.810645680637, 6299.307076221612, 6338.873222535523, 6361.0, 6361.4592759615025, 6362.077498762654, 5033.2701758245585, 6235.532335074797, 6235.781747638811, 6272.843178920563, 6357.809265722036, 6193.659348735772, 6212.850622634827, 6269.279032911182, 6337.207849018872, 6345.397187267202, 5028.32117664118, 5033.446320904697, 5038.773255203525, 5048.697078175322, 5069.543741063322, 4478.820847166142, 4497.482789479186, 4533.659975501851, 4550.203714349976, 4593.219152744042, 5825.679587622722, 5842.298924129047, 5933.881322373529, 6005.429484199081, 6068.908966365508, 6272.983559114764, 6356.777512787123, 6384.0, 6453.451456626145, 6461.903071453932, 5961.850953726105, 5969.492593129546, 5977.491913187428, 5979.5515336349945, 5981.043033114681, 4614.492405835355, 5291.550218231417, 5368.651037682538, 5465.890682186334, 5493.340799104053, 6592.76925307612, 6617.602943823386, 6622.006698995189, 6624.30444803308, 6637.303083176197, 6247.6563788990225, 6345.17512723343, 6415.8716825662295, 6422.068800024063, 6428.325025872216, 6479.416852483299, 6480.449161821298, 6534.901976470452, 6583.703733331916, 6597.109390232257, 6299.181106456132, 6318.241810982711, 6337.6197428154455, 6351.650659213603, 6354.17395738551, 6227.532935029982, 6259.96870015647, 6363.936360636334, 6373.0, 6393.041043843461, 5745.166950792195, 5924.289085309791, 6192.938070619236, 6245.781397651061, 6252.968735156295, 6241.875162496815, 6254.0, 6258.749650014131, 6281.277653069956, 6292.9370706294185, 4505.7296562206375, 6062.484688801324, 6067.54493412987, 6094.08588711679, 6236.407541283571, 6146.569740118149, 6230.7819226328675, 6246.3753624637175, 6256.499480041494, 6265.937340626535, 5253.212018197301, 6093.75624974996, 6131.264248290234, 6180.4746596538, 6181.660008699385], "value": 5794.734870605363, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.9478909921864, 508.9337837231004, 556.3157316500702, 561.3320149022663, 563.0231548263762, 40213.396799048, 43485.78257108718, 43805.0, 43857.56142438575, 44044.55955440445, 44714.0, 45305.0, 45329.54670453294, 45406.54593454073, 45513.17947282113, 9427.198688111484, 9791.0, 9816.11654951056, 9909.90090099097, 10035.698929032103, 9825.312228144032, 9880.456574888378, 9902.90097099027, 9907.702768916908, 9921.057499537532, 9805.264605154642, 9838.966908474615, 9914.801703965917, 9952.60189592419, 9960.850587241197, 9871.901280987242, 9877.703668889944, 9883.0, 9893.802123957481, 9929.702108936779, 9693.0, 9701.417914925056, 9720.173785228193, 9777.92442831288, 9808.46053467059, 40173.19653606941, 40476.79761601173, 40624.0, 40886.38670419946, 41065.38401923973, 3219.7585181111513, 3247.5291082792864, 3258.8370581471067, 3719.4420836874683, 4017.2367250222364, 29839.70160298391, 29932.551011734842, 30047.549286760717, 30202.6408811604, 30951.84524077365, 2760.2961244882626, 2767.986160069207, 2771.293320203335, 2890.898818541341, 2915.3149009982635, 517467.0, 521579.0, 524120.0, 527006.0, 530728.3463582696, 67325.6238344506, 67598.0, 68048.6597567009, 68108.0, 68178.0, 37783.62216377856, 38087.42868856969, 38620.0, 39487.40768888469, 70467.94298085533, 17567.560810979827, 17749.201285942163, 17907.283708651506, 17914.91042544779, 17922.462326130237, 13388.464461421434, 13399.196048237138, 13459.259740714158, 13504.594862154152, 13560.59318220455, 19084.236630534622, 19801.900990494956, 21478.785212147992, 21585.892070539547, 21613.67579486309, 7513.361364283987, 7931.484453510503, 8413.495190288602, 8417.326613870875, 8439.746807595782, 911.9361644684808, 1048.486241741541, 1070.3577853287966, 1095.1840878545534, 1100.273819279272, 28339.858300708365, 30339.544906826417, 31015.844920775253, 31066.53400198999, 31191.064268071994, 1081.5944020992129, 1101.1191047162238, 1166.1778446195412, 1190.7082764722697, 1192.7852986462347, 13459.730805383837, 15846.366145354288, 16114.0, 16130.0, 16261.663688703453, 712350.0, 713589.0, 715679.0, 715800.0, 715810.0, 54620.18069728957, 64789.3802654928, 65014.0, 65070.0, 65557.0, 1445.7542217822995, 1486.9330880110422, 1499.6475828180369, 1514.833368329483, 1515.3862685612344, 24131.517369652858, 29865.701342987162, 30570.847145764128, 30864.537031944088, 30903.845480772452, 57175.714121429126, 57697.0, 57699.42300576941, 57817.843643127744, 57841.710791445774, 484158.0, 505261.0, 506347.0, 506539.0, 508819.4559027181, 27660.585091223245, 28017.57973630438, 28181.013664521644, 28365.858170709016, 28692.569611456263, 47387.28919066286, 47430.0, 48330.0, 48721.26918096302, 48724.75637621931, 31601.20996975094, 36235.09412264715, 39848.80453586397, 39892.800535997136, 40708.389374160004, 28984.13047608491, 37437.06407339729, 39823.402648960866, 39897.80051099726, 40513.79743101266, 33288.500672490416, 38744.41883371808, 39064.63273785404, 39752.0, 40811.591884081965, 34217.65782342244, 34760.13099672528, 35316.47025294571, 35529.1117722059, 35605.93182204538, 24371.512569748862, 25527.617085743357, 25924.74075259223, 26021.69891505455, 26559.734402655726], "value": 20478.83343665537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.4561613740761, 390.5684218938073, 393.6575179593754, 395.8337498250734, 415.83366653338663, 18596.907015464938, 19464.318748843794, 19531.609367812634, 19604.60790784187, 21540.676889846625, 20501.589968200627, 20887.060082296295, 21437.57124857502, 21911.6713249301, 22180.556388872214, 5509.752061157247, 5812.273465816773, 5844.590878638497, 5860.706964651772, 5910.822675319726, 5587.776488940458, 5765.596408251424, 5826.126081087849, 5834.854128646796, 5952.642841429506, 5842.47417732404, 5858.5606079543995, 5866.941330586704, 5868.471837534621, 5877.2947246330505, 5402.837914862561, 5792.971035144818, 5845.8830823383505, 5858.062709966406, 6560.442362399202, 5671.801486947937, 5691.715414229313, 5850.736716847752, 5851.005329094064, 5856.824295271149, 13561.593152205449, 13796.793048104286, 13878.306084695725, 14009.789853152211, 14025.368858401294, 1831.5604254978844, 1876.4933467963574, 2094.8268969377195, 2263.46808500004, 2275.2264230161823, 9764.267679923963, 10235.0, 10249.487525623763, 10307.175425965905, 10462.633807816766, 1632.6122795623626, 1660.103544086192, 1780.652772709322, 1792.09499202903, 1838.8161183881505, 126405.36797316046, 131306.6869331304, 132056.0, 132165.0, 132994.0, 10271.537780799881, 11030.669079927615, 11745.354005529607, 11835.822462663067, 12031.458584363725, 15244.0, 15270.694586108215, 15282.847171528365, 15290.617734556725, 15296.311665975058, 6482.481401487869, 7315.597642129627, 8738.956305218433, 8914.910850891409, 8954.955225223963, 8697.260732837653, 8779.824403511893, 8786.16531429513, 8840.160184782435, 8937.508437035895, 4173.436586060903, 4254.532001479834, 4637.443506779208, 4760.714357138583, 4818.325434439182, 1839.59528903641, 2022.1304838919407, 2026.371824734337, 2035.664115420939, 2093.3301343570215, 912.8995810460844, 931.4550987672152, 933.8179055084194, 937.4890684576922, 1004.1113614451117, 18009.369672061413, 19969.800301997086, 20836.062377193062, 20861.044096799003, 20883.37349879506, 957.3346524165638, 976.5703090640237, 985.7437066362654, 985.9211263098935, 1015.4161357219629, 10753.78492430147, 10998.835017474745, 11005.17461190414, 11041.723956901142, 11091.22361434708, 610314.0, 613586.0, 617012.0, 623406.0, 624881.0, 129064.41871162524, 130866.0, 131884.0, 132839.67160328466, 133408.0, 1024.948752562369, 1041.7551875309296, 1065.1265961911313, 1086.5925278020743, 1088.9891101088888, 17978.01120938361, 18344.633107338046, 18487.89072655645, 18685.813141868406, 18769.624607508045, 35736.0, 43061.0, 48880.26679599738, 50383.74808125936, 51037.48962510328, 63873.680631596544, 74214.0, 74410.25589744034, 75524.0, 76226.0, 6819.352161544645, 7748.806279843049, 8081.111077781322, 8310.335173186251, 8398.076211616839, 11474.0, 12590.874091259337, 12610.0, 12767.978561714855, 12852.678683032998, 9152.359334846496, 10824.891751082389, 10868.619598314337, 10884.965928236805, 10963.0, 10070.496475176065, 10266.127379172854, 10773.676789696321, 10864.076553492725, 10898.945505272422, 9475.47884866339, 11157.888421115686, 11230.719232018939, 11233.639183315903, 11243.943780281046, 6358.77744278948, 7369.115706115086, 7539.359154471931, 8090.0, 8539.103394143774, 11030.779384412106, 11219.270747401393, 11273.379964102052, 11358.772824543628, 11442.313461192021], "value": 9363.0546659072, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1603.3666701652844, 1627.5768300241937, 1900.1354383755383, 1971.1031480676286, 1997.3808119482972, 8302.252797248246, 8648.91351086489, 8882.733517994455, 9393.812123757521, 9409.153176214139, 8796.340274479424, 8940.06129356418, 8952.91047089529, 8955.0, 9064.728058158249, 2413.662087307774, 2447.8286519943613, 2475.331660451677, 2477.715062767778, 2491.289982355029, 2427.1990243219693, 2468.8642124683133, 2482.913098041569, 2491.19036313198, 2491.725910149882, 2440.841345312549, 2452.9632055519182, 2455.5457240410583, 2459.8155138364696, 2468.6420469031978, 2424.7575242475787, 2433.5741245282093, 2437.8415402998835, 2446.0704932125855, 2448.081969261527, 2358.0921345282018, 2363.444590521226, 2364.9054037838464, 2372.406898275431, 2380.190735150057, 8922.598483068277, 8925.687600933936, 8999.5050272235, 9013.684521041798, 9048.728538143867, 5443.510084092451, 5506.504414602665, 5593.972030139864, 5600.747966341524, 5615.0454422748235, 3527.7883327000204, 3535.5680949215607, 3558.822058897045, 3626.746127771057, 3630.146915474888, 1023.5496381592076, 1031.7833255016455, 1031.85038169465, 1037.9221558383151, 1040.3653771199563, 9919.801603967953, 11692.824607630891, 11958.222715523463, 12070.577529786504, 12285.754284914341, 4354.629856462237, 4955.529224723646, 4964.702117872939, 5025.748712564357, 5117.974410127926, 10449.634262800766, 10483.0, 10489.842652360221, 10490.31812932157, 10509.421981791074, 4913.729744864067, 5243.134882744381, 5456.672599644034, 5533.972330138324, 5540.778368865281, 4534.410526631583, 4911.656184067081, 4916.287138364989, 5078.644494885322, 5090.720010399464, 3644.890653280406, 4180.331147016463, 4182.853600124042, 4184.121334519724, 4212.915741685149, 1332.9800052999212, 1346.5219846954385, 1350.9797353039712, 1355.004072007074, 1357.151780137414, 773.628658244046, 883.0242581946984, 889.4440974391006, 898.4833720610675, 918.237862574062, 5831.708414579254, 5930.406959304035, 5974.731137098841, 6081.8479538011015, 6151.323354431009, 448.6948874765191, 454.9158405694891, 459.9632029437637, 463.1246943277209, 466.0957741980547, 3607.2244467439627, 3898.629630185128, 3943.645071943539, 4016.176683779818, 4031.5766844481495, 10145.746356341151, 10960.835587466194, 11068.343597536534, 11161.944190278997, 11196.0, 10802.675919722264, 10987.890121098846, 11121.0, 11141.275817071864, 11176.329420234812, 6969.6515174241085, 8349.16318409961, 8402.075771665111, 8473.576321183917, 8494.06565277819, 5183.714895680774, 5798.797042103506, 5842.912356314744, 5891.558133140117, 5957.285125784933, 10108.797824043331, 10270.6405275815, 10271.486425678537, 10288.382697038202, 10295.794084118426, 10198.184145268213, 10297.639582614573, 10443.530041148319, 10535.42055186972, 10539.841902371623, 7124.3944264738075, 7569.091708994956, 7763.61181940912, 7771.4559980800805, 7858.921410785819, 8529.0, 8557.61490732906, 8608.311335093304, 8610.65557377698, 8616.181462761027, 6574.309697481695, 7527.623618818928, 7637.847243054996, 7708.306252437307, 7750.302472777477, 7493.812654683677, 7502.212267711811, 7543.43424243173, 7573.545587264782, 7614.238576142341, 7170.928290717235, 7239.457040722073, 7251.456140789356, 7268.236835132235, 7274.30894065063, 4800.207965685623, 4839.322494850653, 4886.0, 4996.800127994839, 5058.1148299048255, 4721.433427988663, 4958.603311735124, 5048.368953880765, 5058.317127187783, 5060.671056381323], "value": 4599.616245965363, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.52649866208714, 323.46951000359417, 327.255493751715, 327.9376918385507, 332.56766203934876, 4886.2148575312085, 5088.60835407358, 5286.444923283053, 5514.283143191385, 5576.944230557693, 4614.930776038362, 5474.467149198231, 5526.47498487644, 5535.861603459915, 5546.639468434559, 3520.9965159929425, 3865.111024464375, 4139.56534563871, 4185.656032621726, 4194.412782210493, 3196.4086643970822, 4142.0, 4152.709310348277, 4164.396162556428, 4204.810783514749, 3382.391169589473, 3834.5206849143856, 3983.3427484465174, 4206.72656277341, 4254.532001479834, 3316.403047451458, 3478.4260597001467, 4038.223181800013, 4129.690273229489, 4221.2190744712325, 3242.886498972525, 3256.609206895164, 3427.057559171223, 3553.928921421583, 3562.87529936451, 4441.023744433696, 4899.510048995072, 5278.313819203479, 5310.867228319285, 5329.467053294639, 4223.387608796707, 4391.736495810262, 4460.190080249455, 4769.236922092448, 4781.987347970756, 5048.924266136011, 5744.6840423776675, 5760.279965004374, 5777.480026797566, 5800.564957628195, 3452.896413107611, 3608.855645774166, 3757.304898593769, 3898.727089103764, 3914.9608503915165, 5364.0, 5367.624266301361, 5597.132444471114, 5680.31836179657, 6164.907526387108, 3950.7036972227197, 3953.9802300988886, 3964.940525892114, 3983.1635356575443, 4001.8799436016966, 4039.4950631171105, 4045.6763458923215, 4057.89855253621, 4071.6742660587083, 4084.959150408458, 3975.463312452782, 4057.634812866856, 4546.227141385906, 4712.151812673683, 4757.4072130688855, 4819.783109760069, 4941.876453088629, 5640.717964101778, 5719.542436605062, 5871.768727883377, 1184.810430331143, 1228.5147366790068, 1315.8749918757703, 1389.1803835736964, 1392.6587985943509, 749.4416659588642, 782.3037496628006, 843.696269343036, 870.7605408512583, 896.7354630384052, 4579.992401671627, 4686.531346865286, 4808.345657300477, 5432.29380180574, 5433.0, 5317.548008419328, 5998.520118390518, 5998.70006499682, 6028.608140470856, 6049.971504844187, 4769.6661233714, 5171.456997015334, 5241.449647786927, 5382.407935127133, 5459.808906688247, 2604.6744156980376, 2846.2742000789667, 2991.401719656034, 3140.2934339773824, 3154.8264845433723, 5138.434772175057, 5283.471652834763, 6165.722542485599, 6214.751409943642, 6244.531660125509, 5770.509506691894, 5807.651540907559, 6108.083787431832, 6116.235470566179, 6151.985527091693, 5042.873928151826, 5170.560502357266, 6197.504199663926, 6203.379662033851, 6208.709384026473, 5764.250647415809, 5960.165576819162, 5961.91057134152, 6005.6396616203165, 6029.849253768691, 5850.473457388855, 5867.031939729897, 5869.268565773193, 5904.055351143968, 5912.320083190316, 5582.967151077064, 5678.77284908599, 5872.593895540491, 6233.906491402542, 6265.0, 3927.5286965564314, 3943.72393932422, 3964.3042730943234, 3964.6431821135084, 3973.344398174269, 5127.948720512747, 5362.785488580413, 5685.886282274414, 5858.297004359504, 6004.759809607567, 5420.349558053059, 5806.838632273478, 5807.419258074159, 5830.708464576839, 5853.970730146322, 4714.834980775657, 4864.464908859952, 5563.221149039218, 5855.765769369177, 5904.996150654313, 5484.670919744828, 5747.36778954323, 5754.165645981393, 5760.954861024276, 5766.452187042224, 4803.0, 5514.814314922311, 5868.735906884286, 5921.259842519685, 5935.0, 4722.7874745635845, 5241.895162096813, 5810.5061069809535, 5838.416158384298, 5843.451485356299], "value": 4155.700476631083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.3602898941617, 744.8510297940412, 750.4634186556611, 751.488987488508, 789.7551758954725, 12596.748065038693, 13553.915686745062, 15456.459023934165, 15475.226238688078, 15501.534953951385, 13843.792343114861, 13848.861511384883, 14195.71608567828, 14231.217283049427, 14240.0, 731.0715391452878, 894.168423366269, 899.6806133822481, 926.6571368593629, 936.0545848692801, 914.8719179314901, 920.282179899676, 924.0251534630962, 930.1256818590504, 931.757742986825, 946.6970569417755, 956.7273327101742, 990.6730778843, 993.6174572789492, 994.4828689081673, 947.6683160893666, 975.7024107647164, 987.6148302162162, 989.1839232633107, 989.6783545347719, 718.9137303523559, 719.5107327017625, 722.0685315942428, 736.7973807203008, 738.5162718419443, 14549.709005819825, 14641.780373294408, 14697.632559186106, 14715.969882108257, 14720.558383248519, 10304.0, 12366.0, 12497.25016499013, 12499.250044997329, 12672.049596280316, 8009.47938384009, 8895.288376929895, 9075.7293978843, 9856.0, 10104.44425556594, 3818.1409182933894, 4178.143480586503, 4180.24755544002, 4186.748795072305, 4315.762633055181, 77556.61221693855, 78068.8099942126, 78112.0, 78751.0, 80086.0, 9204.079592040742, 9317.16145546904, 9331.253499720005, 9347.158755712018, 9405.529723513797, 19848.007599619963, 20224.89887550553, 20428.79571204269, 20440.591188176153, 20444.28445004418, 6861.382475577222, 7144.821379465451, 7229.566226026455, 7343.816404589821, 7400.259974002664, 3301.257217126127, 3885.0, 3897.3569361055675, 3917.745346552522, 3930.5283365995692, 3526.4357702767434, 3544.1848374873407, 3600.855965761393, 3604.333198358312, 3610.2598967211657, 647.8509942713108, 648.5654611410366, 651.9543631945813, 676.2324761395753, 687.3779229797042, 477.6226780843095, 549.2424242424258, 559.6781850436015, 566.7562947932346, 573.8594044459135, 7683.346915512244, 7816.179301173295, 7972.0, 8012.31895288907, 8049.65939135264, 499.83505443203654, 510.34675615212586, 515.0240294641633, 543.6439132368305, 557.3729554251468, 3180.7137357637926, 3581.4806853006166, 3663.560372755233, 3746.119661879456, 3774.3206222880144, 69838.0, 70483.0, 91285.08714912766, 92212.85042173119, 93262.0, 64765.0, 84280.0, 84950.0, 85285.14714852771, 86644.0, 56171.43828561744, 57730.845383092106, 75100.62449687716, 75422.0, 75593.86609200867, 7149.177844547944, 7326.890096648448, 7339.266073392618, 7532.39740820744, 7746.380289576933, 45123.0, 46193.53806462027, 46377.53622463732, 46419.53580464152, 46579.53420465752, 62132.446688833144, 62903.6854815723, 63999.68000159969, 64280.35719642744, 65162.0, 9588.520573971413, 9598.136167744937, 9648.083432073941, 9713.757156070873, 9949.253805964438, 8604.82790344177, 10285.588576457158, 10425.436184572374, 10629.627963021258, 10730.517126729159, 10026.147777438999, 10157.847632285373, 10345.63790267337, 10479.738006549896, 10496.0, 10098.949505252427, 10166.491675416051, 10198.745031373977, 10481.0, 10554.313969591953, 9993.050660187271, 10000.849987250343, 10116.29185956976, 10316.43259620698, 10383.84424233622, 8623.82752344937, 8908.55457227149, 8912.55437228123, 8913.955430222808, 10081.596736130767, 9800.313978021755, 9934.403935763878, 9969.750756231153, 10015.0, 10094.444805535766], "value": 6653.854303226166, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 106.0, 117.0, 113.0, 117.0, 106.0, 114.0, 116.0, 117.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 147.0, 205.0, 155.0, 167.0, 154.0, 146.0, 203.0, 141.0], "value": 159.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 209.0, 237.0, 197.0, 198.0, 198.0, 198.0, 195.0, 191.0, 200.0], "value": 204.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 75.0, 186.0, 86.0, 78.0, 80.0, 89.0, 134.0, 60.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 93.0, 154.0, 76.0, 98.0, 66.0, 107.0, 81.0, 104.0, 126.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 89.0, 85.0, 83.0, 128.0, 76.0, 129.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 108.0, 92.0, 94.0, 94.0, 94.0, 93.0, 95.0, 94.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 90.0, 111.0, 117.0, 90.0, 90.0, 92.0, 92.0, 94.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 60.0, 59.0, 61.0, 56.0, 58.0, 116.0, 61.0, 57.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 70.0, 70.0, 73.0, 70.0, 67.0, 66.0, 67.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 90.0, 86.0, 81.0, 86.0, 88.0, 89.0, 103.0, 75.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 144.0, 148.0, 142.0, 136.0, 135.0, 136.0, 214.0, 142.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 165.0, 162.0, 164.0, 171.0, 172.0, 165.0, 176.0, 166.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 65.0, 67.0, 66.0, 66.0, 66.0, 63.0, 63.0], "value": 65.6, "unit": "score"}]}]} 05:36:41 INFO - Return code: 0 05:36:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:36:41 ERROR - # TBPL SUCCESS # 05:36:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:41 INFO - 2016-05-16 05:36:41.456 screenresolution[2013:19459] starting screenresolution argv=screenresolution get 05:36:41 INFO - 2016-05-16 05:36:41.459 screenresolution[2013:19459] Display 0: 1600x1200x32@60 05:36:41 INFO - 2016-05-16 05:36:41.465 screenresolution[2014:19462] starting screenresolution argv=screenresolution list 05:36:41 INFO - Available Modes on Display 0 05:36:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:41 INFO - Intel Iris: 05:36:41 INFO - Chipset Model: Intel Iris 05:36:41 INFO - Type: GPU 05:36:41 INFO - Bus: Built-In 05:36:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:41 INFO - Vendor: Intel (0x8086) 05:36:41 INFO - Device ID: 0x0a2e 05:36:41 INFO - Revision ID: 0x0009 05:36:41 INFO - Displays: 05:36:41 INFO - Display: 05:36:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:41 INFO - Display Serial Number: 1600x1200 60 05:36:41 INFO - Main Display: Yes 05:36:41 INFO - Mirror: Off 05:36:41 INFO - Online: Yes 05:36:41 INFO - Rotation: Supported 05:36:41 INFO - Return code: 0 05:36:41 INFO - Running post-action listener: _resource_record_post_action 05:36:41 INFO - ##### 05:36:41 INFO - ##### Finished run-tests step (success) 05:36:41 INFO - ##### 05:36:41 INFO - Running post-run listener: _resource_record_post_run 05:36:41 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 45056; Write bytes: 697531392; Read time: 2; Write time: 2224 05:36:41 INFO - TinderboxPrint: CPU usage
54.3% 05:36:41 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 2 05:36:41 INFO - TinderboxPrint: I/O write bytes / time
697,531,392 / 2,224 05:36:41 INFO - TinderboxPrint: CPU idle
2,211.6 (45.7%) 05:36:41 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 05:36:41 INFO - TinderboxPrint: CPU user
1,656.3 (34.2%) 05:36:41 INFO - TinderboxPrint: Swap in / out
394,174,464 / 0 05:36:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380252672; Write bytes: 374433280; Read time: 28334; Write time: 700 05:36:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 45056; Write bytes: 298643456; Read time: 2; Write time: 1389 05:36:42 INFO - Running post-run listener: _upload_blobber_files 05:36:42 INFO - Blob upload gear active. 05:36:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:36:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:36:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:36:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:36:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:36:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:43 INFO - (blobuploader) - INFO - Done attempting. 05:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:44 INFO - (blobuploader) - INFO - Done attempting. 05:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:36:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:46 INFO - (blobuploader) - INFO - Done attempting. 05:36:46 INFO - (blobuploader) - INFO - Iteration through files over. 05:36:46 INFO - Return code: 0 05:36:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:36:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:36:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c728b84803df8539d258b0e83b3c5d2f06aae0a3d599aee4b6d224cd806f5c78afca87eba0a0ba8f8b4bcf47d666f40c86afdbcdf425458f10843c98760706ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd95f424973db0a68e143424a1cea26ba6bbf735bfc983e7fc04a4640e9b5f1a4c30a5031076142e86077339eb35168644cfa0e6927e0ebdb77a371e00cdfc2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e42fec609b967ca62b52bc56657d59be387c6fc16ec9172a3127d5b6a5b20bf8118672a5c712a4c407dab5f294e0693ea83755b051e2da04071926ff9e655b3"} 05:36:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:36:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:36:46 INFO - Contents: 05:36:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c728b84803df8539d258b0e83b3c5d2f06aae0a3d599aee4b6d224cd806f5c78afca87eba0a0ba8f8b4bcf47d666f40c86afdbcdf425458f10843c98760706ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd95f424973db0a68e143424a1cea26ba6bbf735bfc983e7fc04a4640e9b5f1a4c30a5031076142e86077339eb35168644cfa0e6927e0ebdb77a371e00cdfc2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e42fec609b967ca62b52bc56657d59be387c6fc16ec9172a3127d5b6a5b20bf8118672a5c712a4c407dab5f294e0693ea83755b051e2da04071926ff9e655b3"} 05:36:46 INFO - Running post-run listener: copy_logs_to_upload_dir 05:36:46 INFO - Copying logs to upload dir... 05:36:46 INFO - mkdir: /builds/slave/test/build/upload/logs 05:36:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.097654 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-16 05:36:46.538641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 05:36:46.541993) ========= 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.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/c728b84803df8539d258b0e83b3c5d2f06aae0a3d599aee4b6d224cd806f5c78afca87eba0a0ba8f8b4bcf47d666f40c86afdbcdf425458f10843c98760706ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd95f424973db0a68e143424a1cea26ba6bbf735bfc983e7fc04a4640e9b5f1a4c30a5031076142e86077339eb35168644cfa0e6927e0ebdb77a371e00cdfc2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e42fec609b967ca62b52bc56657d59be387c6fc16ec9172a3127d5b6a5b20bf8118672a5c712a4c407dab5f294e0693ea83755b051e2da04071926ff9e655b3"} build_url:https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011049 build_url: 'https://queue.taskcluster.net/v1/task/WUx89YLaRFWiibE1v0sNaQ/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/c728b84803df8539d258b0e83b3c5d2f06aae0a3d599aee4b6d224cd806f5c78afca87eba0a0ba8f8b4bcf47d666f40c86afdbcdf425458f10843c98760706ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd95f424973db0a68e143424a1cea26ba6bbf735bfc983e7fc04a4640e9b5f1a4c30a5031076142e86077339eb35168644cfa0e6927e0ebdb77a371e00cdfc2a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e42fec609b967ca62b52bc56657d59be387c6fc16ec9172a3127d5b6a5b20bf8118672a5c712a4c407dab5f294e0693ea83755b051e2da04071926ff9e655b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 05:36:46.569846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:36:46.570165) ========= 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.xsg0t8l96m/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.QfNKcmJeIx/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005155 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 05:36:46.620557) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 05:36:46.620890) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 05:36:46.621265) ========= ========= Total master_lag: 0.17 =========