builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0280 starttime: 1464574875.84 results: success (0) buildid: 20160529173637 builduid: 3c631515640a438ab8acb6c772b83c9c revision: 1cafe268e0149021d977aca6bf5af1478e52324d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:15.839993) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:15.840562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:15.840898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.011426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.011735) ========= 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.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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-29 19:21:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2016-05-29 19:21:16 (200 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153860 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.188314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.188762) ========= 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.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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.026101 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.237949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.238363) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1cafe268e0149021d977aca6bf5af1478e52324d --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 1cafe268e0149021d977aca6bf5af1478e52324d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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-29 19:21:16,313 truncating revision to first 12 chars 2016-05-29 19:21:16,313 Setting DEBUG logging. 2016-05-29 19:21:16,313 attempt 1/10 2016-05-29 19:21:16,313 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1cafe268e014?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-29 19:21:16,654 unpacking tar archive at: fx-team-1cafe268e014/testing/mozharness/ program finished with exit code 0 elapsedTime=0.601847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.860662) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.860987) ========= script_repo_revision: 1cafe268e0149021d977aca6bf5af1478e52324d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.861399) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.861714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 19:21:16.877995) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2016-05-29 19:21:16.878410) ========= /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.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:21:17 INFO - MultiFileLogger online at 20160529 19:21:17 in /builds/slave/test 19:21:17 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 19:21:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:21:17 INFO - {'append_to_log': False, 19:21:17 INFO - 'base_work_dir': '/builds/slave/test', 19:21:17 INFO - 'blob_upload_branch': 'Fx-Team', 19:21:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:21:17 INFO - 'branch': 'Fx-Team', 19:21:17 INFO - 'buildbot_json_path': 'buildprops.json', 19:21:17 INFO - 'config_files': ('talos/mac_config.py',), 19:21:17 INFO - 'default_actions': ('clobber', 19:21:17 INFO - 'read-buildbot-config', 19:21:17 INFO - 'download-and-extract', 19:21:17 INFO - 'populate-webroot', 19:21:17 INFO - 'create-virtualenv', 19:21:17 INFO - 'install', 19:21:17 INFO - 'run-tests'), 19:21:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:21:17 INFO - 'download_minidump_stackwalk': True, 19:21:17 INFO - 'download_symbols': 'ondemand', 19:21:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:21:17 INFO - 'tooltool.py': '/tools/tooltool.py', 19:21:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:21:17 INFO - '/tools/misc-python/virtualenv.py')}, 19:21:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:21:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:21:17 INFO - 'installer_path': 'installer.exe', 19:21:17 INFO - 'log_level': 'info', 19:21:17 INFO - 'log_name': 'talos', 19:21:17 INFO - 'log_to_console': True, 19:21:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:21:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:21:17 INFO - 'opt_config_files': (), 19:21:17 INFO - 'pip_index': False, 19:21:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:17 INFO - 'cmd': ('bash', 19:21:17 INFO - '-c', 19:21:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:17 INFO - 'enabled': True, 19:21:17 INFO - 'halt_on_failure': False, 19:21:17 INFO - 'name': 'check_screen_resolution'},), 19:21:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:17 INFO - 'cmd': ('bash', 19:21:17 INFO - '-c', 19:21:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:17 INFO - 'enabled': True, 19:21:17 INFO - 'halt_on_failure': False, 19:21:17 INFO - 'name': 'check_screen_resolution'},), 19:21:17 INFO - 'run_cmd_checks_enabled': True, 19:21:17 INFO - 'sps_profile': False, 19:21:17 INFO - 'sps_profile_interval': 0, 19:21:17 INFO - 'suite': 'dromaeojs-e10s', 19:21:17 INFO - 'system_bits': '32', 19:21:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:21:17 INFO - 'title': 't-yosemite-r7-0280', 19:21:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:21:17 INFO - 'use_talos_json': True, 19:21:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:21:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:21:17 INFO - 'work_dir': 'build'} 19:21:17 INFO - ##### 19:21:17 INFO - ##### Running clobber step. 19:21:17 INFO - ##### 19:21:17 INFO - Running pre-action listener: _resource_record_pre_action 19:21:17 INFO - Running main action method: clobber 19:21:17 INFO - rmtree: /builds/slave/test/build 19:21:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:21:19 INFO - Running post-action listener: _resource_record_post_action 19:21:19 INFO - ##### 19:21:19 INFO - ##### Finished clobber step (success) 19:21:19 INFO - ##### 19:21:19 INFO - ##### 19:21:19 INFO - ##### Running read-buildbot-config step. 19:21:19 INFO - ##### 19:21:19 INFO - Running pre-action listener: _resource_record_pre_action 19:21:19 INFO - Running main action method: read_buildbot_config 19:21:19 INFO - Using buildbot properties: 19:21:19 INFO - { 19:21:19 INFO - "project": "", 19:21:19 INFO - "product": "firefox", 19:21:19 INFO - "script_repo_revision": "production", 19:21:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 19:21:19 INFO - "repository": "", 19:21:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 19:21:19 INFO - "buildid": "20160529173637", 19:21:19 INFO - "pgo_build": "False", 19:21:19 INFO - "basedir": "/builds/slave/test", 19:21:19 INFO - "buildnumber": 686, 19:21:19 INFO - "slavename": "t-yosemite-r7-0280", 19:21:19 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:21:19 INFO - "platform": "yosemite_r7", 19:21:19 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:21:19 INFO - "branch": "fx-team", 19:21:19 INFO - "repo_path": "integration/fx-team", 19:21:19 INFO - "revision": "1cafe268e0149021d977aca6bf5af1478e52324d", 19:21:19 INFO - "stage_platform": "macosx64", 19:21:19 INFO - "builduid": "3c631515640a438ab8acb6c772b83c9c", 19:21:19 INFO - "slavebuilddir": "test" 19:21:19 INFO - } 19:21:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:21:19 INFO - Running post-action listener: _resource_record_post_action 19:21:19 INFO - ##### 19:21:19 INFO - ##### Finished read-buildbot-config step (success) 19:21:19 INFO - ##### 19:21:19 INFO - ##### 19:21:19 INFO - ##### Running download-and-extract step. 19:21:19 INFO - ##### 19:21:19 INFO - Running pre-action listener: _resource_record_pre_action 19:21:19 INFO - Running main action method: download_and_extract 19:21:19 INFO - mkdir: /builds/slave/test/build/tests 19:21:19 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')]} 19:21:19 INFO - https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:21:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:49 INFO - Downloaded 1199 bytes. 19:21:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:49 INFO - Using the following test package requirements: 19:21:49 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:21:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:21:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:21:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'jsshell-mac.zip'], 19:21:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:21:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:21:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:21:49 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:21:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:21:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:21:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:21:49 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')]} 19:21:49 INFO - https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:21:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:51 INFO - Downloaded 18684681 bytes. 19:21:51 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'] 19:21:51 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 19:21:54 INFO - Return code: 0 19:21:54 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 19:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:54 INFO - https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:21:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:55 INFO - Downloaded 18684681 bytes. 19:21:55 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'] 19:21:55 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 19:21:59 INFO - Return code: 0 19:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:59 INFO - https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:21:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:21:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:21:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:21:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:22:00 INFO - Downloaded 10834034 bytes. 19:22:00 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'] 19:22:00 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 19:22:00 INFO - Return code: 0 19:22: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')]} 19:22:00 INFO - https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:22:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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 19:22:08 INFO - Downloaded 110466251 bytes. 19:22:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:08 INFO - mkdir: /builds/slave/test/properties 19:22:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:22:08 INFO - Writing to file /builds/slave/test/properties/build_url 19:22:08 INFO - Contents: 19:22:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:09 INFO - Running post-action listener: _resource_record_post_action 19:22:09 INFO - Running post-action listener: set_extra_try_arguments 19:22:09 INFO - ##### 19:22:09 INFO - ##### Finished download-and-extract step (success) 19:22:09 INFO - ##### 19:22:09 INFO - ##### 19:22:09 INFO - ##### Running populate-webroot step. 19:22:09 INFO - ##### 19:22:09 INFO - Running pre-action listener: _resource_record_pre_action 19:22:09 INFO - Running main action method: populate_webroot 19:22:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:22:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:22:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:22:09 INFO - '--tppagecycles', 19:22:09 INFO - '7'], 19:22:09 INFO - 'tests': ['tsvgm']}}, 19:22:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:22:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:22:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:22:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:22:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['damp', 'tps']}, 19:22:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'tests': ['damp', 'tps']}, 19:22:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['dromaeo_dom']}, 19:22:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:22:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'other-e10s': {'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['a11yr', 19:22:09 INFO - 'ts_paint', 19:22:09 INFO - 'tpaint', 19:22:09 INFO - 'sessionrestore', 19:22:09 INFO - 'sessionrestore_no_auto_restore', 19:22:09 INFO - 'tabpaint']}, 19:22:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['tsvgx', 19:22:09 INFO - 'tsvgr_opacity', 19:22:09 INFO - 'tart', 19:22:09 INFO - 'tscrollx', 19:22:09 INFO - 'cart']}, 19:22:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:22:09 INFO - 'tsvgr_opacity', 19:22:09 INFO - 'tart', 19:22:09 INFO - 'tscrollx', 19:22:09 INFO - 'cart']}, 19:22:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'talos_options': ['--disable-e10s'], 19:22:09 INFO - 'tests': ['tp5o']}, 19:22:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'tests': ['tp5o']}, 19:22:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'talos_options': ['--disable-e10s', 19:22:09 INFO - '--xperf_path', 19:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:09 INFO - 'tests': ['tp5n']}, 19:22:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:09 INFO - 'talos_options': ['--xperf_path', 19:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:09 INFO - 'tests': ['tp5n']}}, 19:22:09 INFO - 'talos.zip': {'path': '', 19:22:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:22:09 INFO - Running post-action listener: _resource_record_post_action 19:22:09 INFO - ##### 19:22:09 INFO - ##### Finished populate-webroot step (success) 19:22:09 INFO - ##### 19:22:09 INFO - ##### 19:22:09 INFO - ##### Running create-virtualenv step. 19:22:09 INFO - ##### 19:22:09 INFO - Running pre-action listener: _resource_record_pre_action 19:22:09 INFO - Running main action method: create_virtualenv 19:22:09 INFO - Creating virtualenv /builds/slave/test/build/venv 19:22:09 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 19:22:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:22:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:22:09 INFO - Using real prefix '/tools/python27' 19:22:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:22:10 INFO - Installing distribute.............................................................................................................................................................................................done. 19:22:13 INFO - Installing pip.................done. 19:22:13 INFO - Return code: 0 19:22:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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')]} 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:22:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:13 INFO - 'HOME': '/Users/cltbld', 19:22:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:13 INFO - 'LOGNAME': 'cltbld', 19:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:13 INFO - 'MOZ_NO_REMOTE': '1', 19:22:13 INFO - 'NO_EM_RESTART': '1', 19:22:13 INFO - 'PAGER': '/bin/cat', 19:22:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:13 INFO - 'PWD': '/builds/slave/test', 19:22:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:13 INFO - 'SHELL': '/bin/bash', 19:22:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:13 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:13 INFO - 'USER': 'cltbld', 19:22:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:13 INFO - 'XPC_FLAGS': '0x0', 19:22:13 INFO - 'XPC_SERVICE_NAME': '0', 19:22:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:13 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 19:22:13 INFO - Cleaning up... 19:22:13 INFO - Return code: 0 19:22:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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')]} 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:22:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:13 INFO - 'HOME': '/Users/cltbld', 19:22:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:13 INFO - 'LOGNAME': 'cltbld', 19:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:13 INFO - 'MOZ_NO_REMOTE': '1', 19:22:13 INFO - 'NO_EM_RESTART': '1', 19:22:13 INFO - 'PAGER': '/bin/cat', 19:22:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:13 INFO - 'PWD': '/builds/slave/test', 19:22:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:13 INFO - 'SHELL': '/bin/bash', 19:22:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:13 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:13 INFO - 'USER': 'cltbld', 19:22:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:13 INFO - 'XPC_FLAGS': '0x0', 19:22:13 INFO - 'XPC_SERVICE_NAME': '0', 19:22:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:13 INFO - Downloading/unpacking psutil>=3.1.1 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:22:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:17 INFO - Installing collected packages: psutil 19:22:17 INFO - Running setup.py install for psutil 19:22:17 INFO - building 'psutil._psutil_osx' extension 19:22:17 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 19:22:17 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 19:22:17 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 19:22:17 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 19:22:17 INFO - building 'psutil._psutil_posix' extension 19:22:17 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 19:22:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:22:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:22:17 INFO - ^ 19:22:18 INFO - 1 warning generated. 19:22:18 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 19:22:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:18 INFO - Successfully installed psutil 19:22:18 INFO - Cleaning up... 19:22:18 INFO - Return code: 0 19:22:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:18 INFO - 'HOME': '/Users/cltbld', 19:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:18 INFO - 'LOGNAME': 'cltbld', 19:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:18 INFO - 'MOZ_NO_REMOTE': '1', 19:22:18 INFO - 'NO_EM_RESTART': '1', 19:22:18 INFO - 'PAGER': '/bin/cat', 19:22:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:18 INFO - 'PWD': '/builds/slave/test', 19:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:18 INFO - 'SHELL': '/bin/bash', 19:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:18 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:18 INFO - 'USER': 'cltbld', 19:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:18 INFO - 'XPC_FLAGS': '0x0', 19:22:18 INFO - 'XPC_SERVICE_NAME': '0', 19:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:18 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 19:22:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:22:21 INFO - Installing collected packages: mozsystemmonitor 19:22:21 INFO - Running setup.py install for mozsystemmonitor 19:22:21 INFO - Successfully installed mozsystemmonitor 19:22:21 INFO - Cleaning up... 19:22:21 INFO - Return code: 0 19:22:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:22:21 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')]} 19:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:21 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')]} 19:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:21 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:21 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 19:22:21 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 19:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:21 INFO - 'HOME': '/Users/cltbld', 19:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:21 INFO - 'LOGNAME': 'cltbld', 19:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:21 INFO - 'MOZ_NO_REMOTE': '1', 19:22:21 INFO - 'NO_EM_RESTART': '1', 19:22:21 INFO - 'PAGER': '/bin/cat', 19:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:21 INFO - 'PWD': '/builds/slave/test', 19:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:21 INFO - 'SHELL': '/bin/bash', 19:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:21 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:21 INFO - 'USER': 'cltbld', 19:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:21 INFO - 'XPC_FLAGS': '0x0', 19:22:21 INFO - 'XPC_SERVICE_NAME': '0', 19:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:22 INFO - Downloading/unpacking blobuploader==1.2.4 19:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:24 INFO - Downloading blobuploader-1.2.4.tar.gz 19:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:22:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:22:24 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 19:22:24 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 19:22:24 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 19:22:24 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 19:22:24 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 19:22:24 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 19:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:22:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22:25 INFO - Downloading docopt-0.6.1.tar.gz 19:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:22:25 INFO - Installing collected packages: blobuploader, requests, docopt 19:22:25 INFO - Running setup.py install for blobuploader 19:22:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:22:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:22:25 INFO - Running setup.py install for requests 19:22:26 INFO - Running setup.py install for docopt 19:22:26 INFO - Successfully installed blobuploader requests docopt 19:22:26 INFO - Cleaning up... 19:22:26 INFO - Return code: 0 19:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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')]} 19:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:26 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:26 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 19:22:26 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 19:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:26 INFO - 'HOME': '/Users/cltbld', 19:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:26 INFO - 'LOGNAME': 'cltbld', 19:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:26 INFO - 'MOZ_NO_REMOTE': '1', 19:22:26 INFO - 'NO_EM_RESTART': '1', 19:22:26 INFO - 'PAGER': '/bin/cat', 19:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:26 INFO - 'PWD': '/builds/slave/test', 19:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:26 INFO - 'SHELL': '/bin/bash', 19:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:26 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:26 INFO - 'USER': 'cltbld', 19:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:26 INFO - 'XPC_FLAGS': '0x0', 19:22:26 INFO - 'XPC_SERVICE_NAME': '0', 19:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:26 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-UM7bZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-vE_kpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-7fn4Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-SVcpRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-o18IZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-z9JBeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-S8uZob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-NfHdIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-IdjUBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-UmYgvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:27 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-gADpRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-yteDQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-PDxdk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-ok0dxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-MWqJ3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-1B8Wkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:28 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-2kwAU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:22:28 INFO - Running setup.py install for manifestparser 19:22:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:22:28 INFO - Running setup.py install for mozcrash 19:22:28 INFO - Running setup.py install for mozdebug 19:22:29 INFO - Running setup.py install for mozdevice 19:22:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Running setup.py install for mozfile 19:22:29 INFO - Running setup.py install for mozhttpd 19:22:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Running setup.py install for mozinfo 19:22:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Running setup.py install for mozInstall 19:22:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:22:29 INFO - Running setup.py install for mozleak 19:22:29 INFO - Running setup.py install for mozlog 19:22:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Running setup.py install for moznetwork 19:22:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Running setup.py install for mozprocess 19:22:30 INFO - Running setup.py install for mozprofile 19:22:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Running setup.py install for mozrunner 19:22:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:22:30 INFO - Running setup.py install for mozscreenshot 19:22:30 INFO - Running setup.py install for moztest 19:22:31 INFO - Running setup.py install for mozversion 19:22:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:22:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:22:31 INFO - Cleaning up... 19:22:31 INFO - Return code: 0 19:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:31 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:31 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 19:22:31 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 19:22:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:31 INFO - 'HOME': '/Users/cltbld', 19:22:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:31 INFO - 'LOGNAME': 'cltbld', 19:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:31 INFO - 'MOZ_NO_REMOTE': '1', 19:22:31 INFO - 'NO_EM_RESTART': '1', 19:22:31 INFO - 'PAGER': '/bin/cat', 19:22:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:31 INFO - 'PWD': '/builds/slave/test', 19:22:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:31 INFO - 'SHELL': '/bin/bash', 19:22:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:31 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:31 INFO - 'USER': 'cltbld', 19:22:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:31 INFO - 'XPC_FLAGS': '0x0', 19:22:31 INFO - 'XPC_SERVICE_NAME': '0', 19:22:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:31 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-GOJXEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:31 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)) 19:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:31 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-EQ1PyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:31 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)) 19:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:31 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-OszwHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:31 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)) 19:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:31 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-P0w3Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:31 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)) 19:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:31 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-ULqdYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-N_yjQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-RZRM5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-G6FQtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-pJJHav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-S3duRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-lHf42J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-osI8Wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-njL0jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-7tphYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:32 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)) 19:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:32 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-G995XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:33 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)) 19:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:33 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-sx_ppQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:33 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)) 19:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:33 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-U0WzuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:33 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)) 19:22:33 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)) 19:22:33 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)) 19:22:33 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)) 19:22:33 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)) 19:22:33 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)) 19:22:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:22:33 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 19:22:33 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 19:22:33 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 19:22:33 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 19:22:33 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 19:22:33 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 19:22:35 INFO - Downloading blessings-1.6.tar.gz 19:22:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:22:35 INFO - Installing collected packages: blessings 19:22:35 INFO - Running setup.py install for blessings 19:22:35 INFO - Successfully installed blessings 19:22:35 INFO - Cleaning up... 19:22:36 INFO - Return code: 0 19:22:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:22:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:36 INFO - Reading from file tmpfile_stdout 19:22:36 INFO - Current package versions: 19:22:36 INFO - blessings == 1.6 19:22:36 INFO - blobuploader == 1.2.4 19:22:36 INFO - docopt == 0.6.1 19:22:36 INFO - manifestparser == 1.1 19:22:36 INFO - mozInstall == 1.12 19:22:36 INFO - mozcrash == 0.17 19:22:36 INFO - mozdebug == 0.1 19:22:36 INFO - mozdevice == 0.48 19:22:36 INFO - mozfile == 1.2 19:22:36 INFO - mozhttpd == 0.7 19:22:36 INFO - mozinfo == 0.9 19:22:36 INFO - mozleak == 0.1 19:22:36 INFO - mozlog == 3.2 19:22:36 INFO - moznetwork == 0.27 19:22:36 INFO - mozprocess == 0.22 19:22:36 INFO - mozprofile == 0.28 19:22:36 INFO - mozrunner == 6.11 19:22:36 INFO - mozscreenshot == 0.1 19:22:36 INFO - mozsystemmonitor == 0.3 19:22:36 INFO - moztest == 0.7 19:22:36 INFO - mozversion == 1.4 19:22:36 INFO - psutil == 3.1.1 19:22:36 INFO - requests == 1.2.3 19:22:36 INFO - wsgiref == 0.1.2 19:22:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:36 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')]} 19:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:36 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')]} 19:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:36 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:36 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 19:22:36 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 19:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:36 INFO - 'HOME': '/Users/cltbld', 19:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:36 INFO - 'LOGNAME': 'cltbld', 19:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:36 INFO - 'MOZ_NO_REMOTE': '1', 19:22:36 INFO - 'NO_EM_RESTART': '1', 19:22:36 INFO - 'PAGER': '/bin/cat', 19:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:36 INFO - 'PWD': '/builds/slave/test', 19:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:36 INFO - 'SHELL': '/bin/bash', 19:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:36 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:36 INFO - 'USER': 'cltbld', 19:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:36 INFO - 'XPC_FLAGS': '0x0', 19:22:36 INFO - 'XPC_SERVICE_NAME': '0', 19:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 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)) 19:22:36 INFO - Cleaning up... 19:22:36 INFO - Return code: 0 19:22:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:22:36 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')]} 19:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:36 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')]} 19:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:36 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:36 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 19:22:36 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 19:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:36 INFO - 'HOME': '/Users/cltbld', 19:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:36 INFO - 'LOGNAME': 'cltbld', 19:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:36 INFO - 'MOZ_NO_REMOTE': '1', 19:22:36 INFO - 'NO_EM_RESTART': '1', 19:22:36 INFO - 'PAGER': '/bin/cat', 19:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:36 INFO - 'PWD': '/builds/slave/test', 19:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:36 INFO - 'SHELL': '/bin/bash', 19:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:36 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:36 INFO - 'USER': 'cltbld', 19:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:36 INFO - 'XPC_FLAGS': '0x0', 19:22:36 INFO - 'XPC_SERVICE_NAME': '0', 19:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:36 INFO - Downloading/unpacking jsonschema 19:22:36 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 19:22:36 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 19:22:36 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 19:22:36 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 19:22:36 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 19:22:36 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 19:22:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:22:39 INFO - Downloading/unpacking functools32 (from jsonschema) 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 INFO - Downloading functools32-3.2.3-2.tar.gz 19:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:22:39 INFO - warning: no files found matching '*.txt' 19:22:39 INFO - no previously-included directories found matching 'build' 19:22:39 INFO - no previously-included directories found matching 'dist' 19:22:39 INFO - no previously-included directories found matching '.git*' 19:22:39 INFO - Installing collected packages: jsonschema, functools32 19:22:39 INFO - Running setup.py install for functools32 19:22:39 INFO - warning: no files found matching '*.txt' 19:22:39 INFO - no previously-included directories found matching 'build' 19:22:39 INFO - no previously-included directories found matching 'dist' 19:22:39 INFO - no previously-included directories found matching '.git*' 19:22:39 INFO - Successfully installed jsonschema functools32 19:22:39 INFO - Cleaning up... 19:22:40 INFO - Return code: 0 19:22:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:22:40 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')]} 19:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:40 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')]} 19:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:40 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 0x10d6b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcafbd92710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, '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.G5H6i3alpV/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.TFZywAZY2f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 19:22:40 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 19:22:40 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 19:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:40 INFO - 'HOME': '/Users/cltbld', 19:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:40 INFO - 'LOGNAME': 'cltbld', 19:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:40 INFO - 'MOZ_NO_REMOTE': '1', 19:22:40 INFO - 'NO_EM_RESTART': '1', 19:22:40 INFO - 'PAGER': '/bin/cat', 19:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:40 INFO - 'PWD': '/builds/slave/test', 19:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:40 INFO - 'SHELL': '/bin/bash', 19:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:22:40 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:40 INFO - 'USER': 'cltbld', 19:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:40 INFO - 'XPC_FLAGS': '0x0', 19:22:40 INFO - 'XPC_SERVICE_NAME': '0', 19:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:40 INFO - Downloading/unpacking flake8 19:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:22:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:22:42 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 19:22:42 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 19:22:42 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 19:22:42 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 19:22:42 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 19:22:42 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 19:22:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:22:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:22:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:44 INFO - no previously-included directories found matching 'docs/_build' 19:22:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:22:44 INFO - Running setup.py install for pep8 19:22:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:44 INFO - no previously-included directories found matching 'docs/_build' 19:22:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:22:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:22:44 INFO - Cleaning up... 19:22:44 INFO - Return code: 0 19:22:44 INFO - Running post-action listener: _resource_record_post_action 19:22:44 INFO - Running post-action listener: _start_resource_monitoring 19:22:44 INFO - Starting resource monitoring. 19:22:44 INFO - ##### 19:22:44 INFO - ##### Finished create-virtualenv step (success) 19:22:44 INFO - ##### 19:22:44 INFO - ##### 19:22:44 INFO - ##### Running install step. 19:22:44 INFO - ##### 19:22:44 INFO - Running pre-action listener: _resource_record_pre_action 19:22:44 INFO - Running main action method: install 19:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:44 INFO - Reading from file tmpfile_stdout 19:22:44 INFO - Detecting whether we're running mozinstall >=1.0... 19:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:22:44 INFO - Reading from file tmpfile_stdout 19:22:44 INFO - Output received: 19:22:44 INFO - Usage: mozinstall [options] installer 19:22:44 INFO - Options: 19:22:44 INFO - -h, --help show this help message and exit 19:22:44 INFO - -d DEST, --destination=DEST 19:22:44 INFO - Directory to install application into. [default: 19:22:44 INFO - "/builds/slave/test"] 19:22:44 INFO - --app=APP Application being installed. [default: firefox] 19:22:44 INFO - mkdir: /builds/slave/test/build/application 19:22:44 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'] 19:22:44 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 19:23:18 INFO - Reading from file tmpfile_stdout 19:23:18 INFO - Output received: 19:23:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:23:18 INFO - Running post-action listener: _resource_record_post_action 19:23:18 INFO - ##### 19:23:18 INFO - ##### Finished install step (success) 19:23:18 INFO - ##### 19:23:18 INFO - ##### 19:23:18 INFO - ##### Running run-tests step. 19:23:18 INFO - ##### 19:23:18 INFO - Running pre-action listener: _resource_record_pre_action 19:23:18 INFO - Running main action method: run_tests 19:23:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:18 INFO - 2016-05-29 19:23:18.790 screenresolution[1651:8168] starting screenresolution argv=screenresolution get 19:23:18 INFO - 2016-05-29 19:23:18.793 screenresolution[1651:8168] Display 0: 1600x1200x32@60 19:23:18 INFO - 2016-05-29 19:23:18.799 screenresolution[1652:8170] starting screenresolution argv=screenresolution list 19:23:18 INFO - Available Modes on Display 0 19:23:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:19 INFO - Intel Iris: 19:23:19 INFO - Chipset Model: Intel Iris 19:23:19 INFO - Type: GPU 19:23:19 INFO - Bus: Built-In 19:23:19 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:19 INFO - Vendor: Intel (0x8086) 19:23:19 INFO - Device ID: 0x0a2e 19:23:19 INFO - Revision ID: 0x0009 19:23:19 INFO - Displays: 19:23:19 INFO - Display: 19:23:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:19 INFO - Display Serial Number: 1600x1200 60 19:23:19 INFO - Main Display: Yes 19:23:19 INFO - Mirror: Off 19:23:19 INFO - Online: Yes 19:23:19 INFO - Rotation: Supported 19:23:19 INFO - Return code: 0 19:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:23:19 INFO - Python 2.7.3 19:23:19 INFO - Return code: 0 19:23:19 INFO - grabbing minidump binary from tooltool 19:23:19 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')]} 19:23:19 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 0x10d557d30>, '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 0x10d37bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d54eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:23:19 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 19:23:19 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 19:23:19 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 19:23:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:23:19 INFO - Return code: 0 19:23:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:23:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:23:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:23:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:23:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:23:20 INFO - Return code: 0 19:23:20 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/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0280', '--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 19:23:20 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/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0280 --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 19:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5H6i3alpV/Render', 19:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:20 INFO - 'HOME': '/Users/cltbld', 19:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:20 INFO - 'LOGNAME': 'cltbld', 19:23:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:23:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:20 INFO - 'MOZ_NO_REMOTE': '1', 19:23:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:23:20 INFO - 'NO_EM_RESTART': '1', 19:23:20 INFO - 'PAGER': '/bin/cat', 19:23:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:20 INFO - 'PWD': '/builds/slave/test', 19:23:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:20 INFO - 'SHELL': '/bin/bash', 19:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TFZywAZY2f/Listeners', 19:23:20 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 19:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:20 INFO - 'USER': 'cltbld', 19:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:20 INFO - 'XPC_FLAGS': '0x0', 19:23:20 INFO - 'XPC_SERVICE_NAME': '0', 19:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:20 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/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0280', '--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 19:23:20 INFO - mozversion application_buildid: 20160529173637 19:23:20 INFO - mozversion application_changeset: 1cafe268e0149021d977aca6bf5af1478e52324d 19:23:20 INFO - mozversion application_display_name: Nightly 19:23:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:23:20 INFO - mozversion application_name: Firefox 19:23:20 INFO - mozversion application_remotingname: firefox 19:23:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:23:20 INFO - mozversion application_vendor: Mozilla 19:23:20 INFO - mozversion application_version: 49.0a1 19:23:20 INFO - mozversion platform_buildid: 20160529173637 19:23:20 INFO - mozversion platform_changeset: 1cafe268e0149021d977aca6bf5af1478e52324d 19:23:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:23:20 INFO - mozversion platform_version: 49.0a1 19:23:20 INFO - using testdate: 1464575000 19:23:20 INFO - actual date: 1464575000 19:23:20 INFO - starting webserver on 'localhost:49277' 19:23:20 INFO - SUITE-START | Running 2 tests 19:23:20 INFO - TEST-START | dromaeo_css 19:23:20 INFO - operating with platform_type : mac_ 19:23:20 INFO - Initialising browser for dromaeo_css test... 19:23:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmp0zT8or/profile http://localhost:49277/getInfo.html) 19:23:23 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 19:23:23 INFO - PROCESS | 1670 | colorDepth:24 19:23:23 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 19:23:23 INFO - PROCESS | 1670 | __metrics 19:23:23 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:23:24 INFO - TEST-INFO | 1670: exit 0 19:23:24 INFO - Browser initialized. 19:23:24 INFO - Running cycle 1/1 for dromaeo_css test... 19:23:24 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmp0zT8or/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:36 INFO - PROCESS | 1674 | RSS: Main: 252575744 19:23:36 INFO - PROCESS | 1674 | 19:24:26 INFO - PROCESS | 1674 | 1464575066071 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] 19:26:36 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 19:26:37 INFO - PROCESS | 1674 | RSS: Main: 265121792 19:26:37 INFO - PROCESS | 1674 | 19:29:39 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 19:29:40 INFO - PROCESS | 1674 | RSS: Main: 276762624 19:29:40 INFO - PROCESS | 1674 | 19:32:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 19:32:42 INFO - PROCESS | 1674 | RSS: Main: 278188032 19:32:42 INFO - PROCESS | 1674 | 19:35:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 19:35:45 INFO - PROCESS | 1674 | RSS: Main: 279801856 19:35:45 INFO - PROCESS | 1674 | 19:38:47 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 19:38:48 INFO - PROCESS | 1674 | RSS: Main: 283303936 19:38:48 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 19:41:51 INFO - PROCESS | 1674 | RSS: Main: 283295744 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | __start_tp_report 19:41:51 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 19:41:51 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 19:41:51 INFO - PROCESS | 1674 | |i|pagename|runs| 19:41:51 INFO - PROCESS | 1674 | |0;dojo.html;3389.1018879996823;3408.1649995751036;3422.264213194163;3437.8452969616364;3454.447288433851;5476.91784623231;5526.751296191671;5589.329280486348;5590.972045139779;5622.550195984321;5440.455954404568;5513.007658621446;5527.143292789624;5577.470140336672;5640.887182256352;5020.573251273637;5051.595872330223;5128.282040514331;5176.430592634826;5195.272661827347;275.3309086850159;5139.306193663863;5154.020736060155;5160.200168973796;5201.453847346031;5083.7712302946275;5104.515071068261;5128.282040514331;5139.25480805283;5143.12566863634;5050.191969284933;5148.304978827848;5162.819301324474;5167.250748641463;5199.272101905736;5033.974830125862;5079.517445842676;5083.110455670243;5099.949000509984;5121.539061484485;4515.63874890008;5119.692818430906;5473.644213126134;5518.420565840569;5555.388907220203;4865.8053677853195;5749.540036797046;6076.240469941257;6102.542309326818;6121.020636698151;6091.573589848713;6127.2953610334935;6136.691761626206;6149.938500614981;6157.415045570664;5658.377578466366;5676.2620859288445;5685.431456854281;5687.203791469197;5708.115242137475;6096.969515152396;6121.5139442231075;6176.474999624992;6178.351273116348;6212.502999760009;6058.0610005449225;6071.271447426248;6073.5748497604745;6075.99746041907;6101.054336577838;6131.233595800525;6141.877162456815;6170.10533472653;6170.7223174957235;6177.4749146323065;5867;6021.217241758631;6033.095035744585;6093.817185484442;6106.145139680449;4875.43932447773;5346.823698786261;5936.37668044849;5961.225040744675;6005.429484198994;4537.455505339381;4774.474807771142;4857.077155340474;4860.4896485869185;4878.463369029404;4307.633851122627;4340.696151269444;4389.824407023683;4394.582514661102;4421.5578442156175;5528;5588.1059030555725;5645.971770141123;5674.77300907959;5705.62913410627;6131.172291740558;6131.417515336036;6169.259688837368;6179.536534759911;6189.81430557084;5507.421720719347;5552.611317207757;5589;5611.887762244733;5643.7742490299925;5280.392754833167;5283.233931079972;5284.656497327662;5300.04599172145;5316.122839731401;6270.404311590391;6282.780102696383;6313.905291420632;6315.463185629273;6320.367963203643;4853.9029219416125;6094.085887116968;6131.080337949344;6144.218176728878;6177.752889884534;6275.748970041147;6315.842103947438;6336;6336.081268216175;6341.302456729848;6093.817185484442;6122.418370254819;6136.386361363828;6136.907946380898;6137.355577664281;5966.671833049224;6000.939990600038;6021.548383871314;6057;6120.479759220516;4748.525147485224;5730.312362516524;5817.76728930838;5845.707714614337;5853.0635098384055;5735.856603584777;5844.766209351748;5858.033424484912;5868.471837534642;5868.618539794899;5537.335519737658;5550.7779688812;5653.689047102449;5697.7436015380235;5713.314402271754;5866.677332746569;5942.286925568959;5972.372900845522;5981.7607295707685;5986.401359864153;5657.858553536194;5788.450097240755;5805.82582522525;5845.970770146122;5856.677882716492 19:41:51 INFO - PROCESS | 1674 | |1;ext.html;525.5322762741158;526.744528903482;536.2385412713945;555.6971450559445;570.0537108400057;40148;43209.78395108028;43244.78377608115;43418.6974390768;43608.78195609021;44323.33514997269;44951;44998;45120.54879451205;45171.77414112933;9610.038996100386;9701;9766.90233097667;9767.365121267096;9834.901650983471;9681.322307438482;9723;9735.97772233916;9764.414135151877;9794.951025244864;9733.124018838304;9780;9815.950920245388;9829.950850245774;9831.754206144868;9689.224862011057;9736;9774.853377199348;9789.951050244774;9791.804163916753;9643.324967252294;9743.025697430272;9762.853557196648;9774.609015639437;9777.560009799505;41191.38212926778;41419;41529.37705934414;41966.37050444246;42070;2899.347646779479;3221.4040402525375;3408.7613867029318;3556.964430355715;3673.981630091859;26452.809623566987;30714.385712285854;31511.054668359986;31562.368752624818;31580.684193158006;2633.4074833162576;3167.0973772474963;3207.1661368044474;3239;3320.568326117613;466691;471076;473224;474106.6294668539;474256;35515.467267991;35762.642373576455;35998.28003439916;36105;36200.456993144595;36269;36721.81639091788;37203.4419483708;38544.22911541753;38748.03129921774;17557.824421755875;17603.823961760474;17706.29174833018;17727.73408398875;17757.11214439273;13036.739265214643;13041.673958151123;13367.799483007762;13454.932725336506;13455.923526118082;16945.915270423568;20869.895650521652;20930.58138837245;21087.472813179793;21149.78850211509;7023.92976070243;7732.497387669783;8295.958520207481;8298.460600061097;8386.371022173362;932.5477143585356;1040.370575801629;1069.5080263078914;1154.7748189103045;1188.6255829413708;30208.848955755082;32181.51727724086;32260;32343.35313293721;32675;1105.9778804423865;1112.805259079674;1115.2137742891405;1152.3546813784308;1227.1225025710678;16581.336746530003;16634.168291585614;16668.58328541796;16842.747358789627;16932.661346772995;552207;657136;658230;660209;671430;62252;63282.36717632857;63505.36494635087;64254.67872660607;64578;1336.5188532128425;1410.0904916328911;1411.6400317918865;1417.6526750946084;1426.6433391652088;31325.686743132275;31422.52866206963;31428.52857207098;31830.68169318277;32267.677323226468;55087.173692393844;56218.875622488136;56388.71805640945;56567;56651.15023274572;590930;608721;612475;613641.1815291097;614991;27773.861130695026;27799.30501737473;28183.295417614725;28691.856540718;29154.271143221587;51579.22631160461;52034.479655202966;52101;52971;53138;42444.363334550624;42617;42686.57313426826;42790.35814462723;43661.56338436702;41666.95832604209;41911.952201195214;41916;42263.7886810564;42425.15149696927;32976.67023329736;34387.96836094921;40114;40475.797621011705;40818.38772418476;42097;42213;43292;45263;45803.77098114488;29156.708432916246;31705.890293839606;31956.52065218977;32879.67120328766;33885 19:41:51 INFO - PROCESS | 1674 | |2;jquery.html;321.99839000805;375.87408218246884;384.5827277404017;393.64768532163697;401.85734064407137;17726.468205953825;19247.903760481215;19942.800571994274;20522.589548209027;20554.177832886704;11421.885781142186;12104;20414.795852041476;20448.897755511203;20916.372508824723;5190.480951904808;5249.343832020997;5369.812056578021;5378.973105134469;5387.272718183054;5353.277307563468;5666.688332141731;5917.319508256541;5918.674472903988;6079.270487541479;5866.6480011199255;5992;6022.789202377918;6024.186734790791;6034.818955431322;5324.866878328034;5438.564914806806;5439.401665816757;5443.374011988612;5455.699936503491;4996.2505624156665;5144.845654630367;5161.458046905059;5280.6039547034125;5293.523582877521;13080.018998575146;13315.067945243836;21191.68212476814;25581.48837023249;26077.73922260772;2227.7103976483118;2251.887405629728;2292.083166733303;2429.5869702150676;2434.9878250608813;10101;10205.285630005903;10427.270091093627;13431.3955871986;14627.78058329126;1773.893566386021;1782.9286828526842;1842.8709990300683;1914.463950093962;1916.8466522678152;122372.38813805874;125192;125309;129283;129893.35053324673;11586.826197607043;11773.88226117745;11792.41037948099;11836.763264734656;11963.342016189194;12189.817152742717;14762.70474590502;14912.254387280593;14976.850231497763;15034;6990.965045174843;7137.750178743719;8787.209151176297;8818.164601752234;8899.02110767815;8720.258778003814;8723.214910658071;8756.693515726918;8819.250363719155;8841.823163536694;4505.481869584974;4552.499225085238;4616.038183771951;4617.861464156081;4643.512431194744;1617.9110148941686;1935.5064458563259;1961.8430525557922;1972.7139564763029;2024.2813801100397;891.1623074310128;906.8730377747121;930.1442672741094;962.2302158273353;964.9723044957099;20638.690419643717;21172.47068823292;21348.35954921355;21480.892595537236;21504;944.6599224279254;952.2382094324513;952.7046615549064;957.0477375011768;987.5457289646699;11102.723186833457;11247.156463265288;11374.94312528432;11377.601783937522;11398;594939;599459;607957;609703;619956;81328.18671813325;124452;127513;137423;141058.2947085258;988.243993345106;992.1814503035025;1037.766502536923;1040.6149724601946;1046.3826342457835;18532.907335463235;19083.90458047701;19167.32914348047;19259.711104333164;19334;34794.82602586971;52433;53249.467505326;53570;54450;68236;74276;78919.81620275586;79662;80422.59788701253;7564.810879728051;7841.372690184658;7948;8016.238457346542;8042.919570804217;8785.560721964004;11910.805290606746;12493.812592810933;12541.623751287476;12719.682007949876;8766.517841518778;9517.428954262767;10893.864518740238;10943.781124377627;10993;10504.369737815756;10740.46297685097;10830.837537437103;10913.454327283764;11072.944635276772;7052.118485189351;9443.716688499357;10477.795053568938;10928.235023548275;11087.667369978913;6710.429613482908;7917.208279172037;8028.3577313813585;8062.717804876801;8102.108768035393;8492.915070849212;10545.167920499573;11001.944990274997;11151.16366272549;11154.330740155616 19:41:51 INFO - PROCESS | 1674 | |3;mootools.html;1440.7118576284743;1536.7771673107395;1727.9459529686897;1755.7893052833654;1895.7156426536026;7614.847703045943;8061.153578874222;8283.751487455378;8625.48247105174;9088.363814532986;8317.209865062825;8770.298376129911;8863.689770858022;8965.32760042996;8966.462012279268;2367.869767162805;2373.655819906117;2402.9879850600723;2403.0748161957686;2407.061246114008;2338.8012018978407;2396.7363590005084;2412.457197130649;2428.8421252618614;2429.599116145843;1950.8146726061073;2241.4844585745286;2295.7130358705163;2356.964645530318;2389.2712722619594;2368.5499755046485;2387.3792813868517;2389.2115601851433;2390.6055500842426;2397.5444665513496;1917.7506924099916;2350.9647355289685;2354.3525530479083;2356.3991182248587;2396.808255339586;8773.780655483664;8789.25291350236;8836.955815220947;8929.955350223272;8942.955285223532;5245.895082098375;5289.523942845162;5356.196570514454;5422.674639521641;5454.836354909359;3236.9514457282926;3397.915052123692;3650.5071815305123;3650.543682039745;3678.301122786662;912.2154946745793;1035.11497669493;1085.8696956365209;1098.5880294889416;1110.3337997201616;9258.768530786718;9418.576164072701;11860.881391186063;11871.881281187163;11904.404779760976;4228.8582082838075;4732.716037037789;4744.762761861962;4827.275908613666;4878.56092951636;9656.37233579815;9934.652287169914;10048.995100489892;10100.191984641211;10100.595976161017;5337.119375303109;5358.4373640767935;5417.458254174551;5430.8370748876805;5450.972745136328;4441.045175287264;4775.785089670973;4939.506049395031;4949.802007919643;4992.550670439605;3760.360738674377;4111.465509483748;4124.783188959264;4128.236276288896;4140.840564641905;1203.1938601137083;1319.736052789446;1322.285965578596;1323.1267363540017;1338.1837079381569;921.2952091649893;930.1628534319032;933.7572231219796;944.9935818353568;975.1662328708852;5884.411558844081;5885.47030767233;6012.7294271757;6019.69901504923;6032.906764168215;422.0503866300823;448.0836688971062;942.5758408716105;985.7437066362797;1121.3944469986116;3786.4320351946876;3971;3989.122393073519;4021.939670904939;4087.91824163521;10322.690319290434;10709.892901071045;10827.566897324019;10835.729106772395;10841.783164336826;9447.433154010643;10456.849746527876;10688.839667404995;10732.356058636507;10826.296290741077;7744.6514906829325;8035.799105022421;8150.4294699370475;8206.466579672302;8221.095679475253;5894.20428242199;5975;6007.519398448201;6051.243594550681;6066.908996365147;9840.212782977487;10055.39667619916;10100.292979491365;10125.291229614151;10145.543450544596;9640.951795240979;9785;9972.950135249277;10206.234532410248;10296.433696146783;6978.860422791618;7595.810104747426;7688.923110768821;7951.125376208728;8298.70954516589;8268.627911744106;8310.335173186251;8519.020312664123;8520.062793092631;8547.700830470903;7350.632468376667;7506.061742282215;7556.508826926232;7576.848463030598;7616.657250423855;7358.521696089737;7412.592307423143;7422.628868556442;7515.6993720250575;7517.699292028257;5691.402402747817;6960.37356637905;7011.719531218694;7091.219965803861;7106.964465177848;4824.372831531879;4836.540528649773;4970.080535101018;4984.725840078685;5124.79500819978;4376.671749618855;4966.553010229097;5080.49195080489;5146.073706732824;5248.055350036877 19:41:51 INFO - PROCESS | 1674 | |4;prototype.html;264.8874228452908;313.1560444601798;314.3132256020595;318.17275084779567;321.73939109321435;5108.373055130853;5199.960007998397;5587.988324203284;5638.351589567196;5677.716114194289;4469.620082293;4903.289023091649;5154.304168937192;5571.659902840806;5596;3129.358481511285;3153.164411430961;3683.355412802762;3932.7640341579454;4206.41110244566;3308.834558272089;4220.324748040314;4246.341817018367;4246.619848549219;4276.636485898703;3676.7058635309245;4209.200251952119;4218.578142185772;4251.8511852085185;4258.318669012959;3982.8406863725418;4225.746455212697;4229.111886503838;4268.466441694788;4286.61420472159;3154.069549482908;3395.1002984209204;3658.70730341572;3721.106934335767;3746.363118269873;4702.694324868907;5398.676079435208;5441.129419292934;5476.972615136899;5554.86112847178;4612.285095810154;4959.107360675077;4989.625778066659;5053.090443720129;5066.341375621182;5949.6430214187285;5966.791162309299;5975.043992961148;5982.9401705983255;6044.758209671608;3580.695640870529;4092.8335424403904;4113.547509773923;4156.854510092163;4168.854090106862;5474.042042642522;5671.347795003586;5752.36723960364;6282.842928926813;6294.3705629437145;3384.6784555467684;4044.6157615026527;4176.812043458051;4185.832566697298;4195.664346852244;4126.452580282394;4250.4899412070135;4267.914641707149;4278.422412974271;4294.892627684333;3658.853645854136;3888.5128291277706;4205.453291072202;4840;4989.650724449254;5319.627626066138;5930.458080898911;5960.956832554286;6001.309849367379;6035.456808887134;1326.257295914291;1441.5963530211557;1473.2339183624456;1473.8798513129884;1475.2181343887758;735.6248313360225;769.0579040675128;888.5024386343669;922.0594993106994;963.8000689142063;4402.647788177002;4402.691811573159;5368.785248590013;5389.56883449331;5477.643953143112;5201.621570283878;5954.970225148846;5957.404259574095;6026.939730602726;6067.393260673897;4618.607418369409;5279.340082489689;5426.294581704432;5431.37539182999;5446.673199608036;2730.890764369443;2826.5901444290876;3169.6196456425378;3217.2761128746315;3234.9191270218435;5522.861928451741;5674.744636491285;6129.1419201311855;6293.748250069946;6310.968445157837;6200.0159948816845;6234.096056071844;6239.161181662333;6241.78153764616;6251.593646412968;5158.871028224249;6239.500839932794;6298.748050077946;6299.622022678654;6328.208973878173;5628.902364038973;5755.309362876482;5788.53691704671;6048.879022419615;6076.969615152073;5358.651687640291;5758.568107391879;5959;5984.730687119177;5995.5803093783015;5201.453847345974;5459.48863522784;5848.502877255481;6132.846678833065;6337.683115844282;4087.8773636790943;4090.5500394957126;4095.89760255996;4103;4120.269486815485;5521.116621340566;5567.832965011056;5578.49793518569;5704.572157088152;6048.399188349053;5136.178211486144;5690.231818704422;5692.943070569241;5749.080147176432;5849.795257165979;4663.673542851968;4833.468318485035;5550.666959982414;5812.244408226903;5960.642361458326;4573.885652858572;5068.974655126701;5441.374241962225;5709.286339207599;5716.9142462862255;4764.857054288377;5193.948390385852;5337.092694241911;5745.913811292918;5924.229850119457;5176.7152806596;5608.383077861514;5753.798617048383;5826.475617194473;5835.270591176103 19:41:51 INFO - PROCESS | 1674 | |5;yui.html;696.72131147541;702.3608516250216;735.1545722419221;793.6388943030921;851.948883067016;14378.928105359471;15193.316300766464;15312.617184570392;15401.768973465407;15402.460913868003;13984.580462586138;13991.440342386293;14014.859851401483;14090.295485225723;14123.576292711236;700.894865770136;865.4677373415336;886.8536691445904;907.0430695616122;908.0238743350924;905.4295793650024;908.9772755681095;911.8723378726984;912.8813254276935;915.5834095486571;967.5404183013083;967.7000129959735;968.2350942755215;968.6416026070328;971.3394891473795;950.87638606981;959.0170075672422;962.0475729028282;963.0898800633439;964.2382517810921;687.3332867118887;687.5840116729406;687.845234822161;709.7373971630478;719.6869361827622;14158.221297828615;14204.85795142056;14449.205293708841;14450.783238251435;14480;12119.151659383846;14002.229877356742;14167.85832141686;14175.078619889777;14307.642308942255;11348.886511134948;11611.767764644745;11643.068554515703;12048.277103373826;12136.575219867262;3849.8460061597784;3923.3722604383447;4004.3392840181336;4017.13631569214;4019.3569028955226;78975.6051219746;80019.79970300393;80152.59923700344;81854.59072704599;83192.99999999939;10862.610314874055;10871.130309575216;10968.616098436516;10973.725656858642;11209.327440353605;21765.782342176375;21932.793696346853;21966.89016554939;21987.670184947237;21996.780032199473;7974.5215287082965;8057.717979870677;8079.151689072681;8091.39314551411;8143.75568732939;3769.4911186989407;4324.070324880166;4398.47218333802;4398.758068306274;4400.163968845909;3001.9699803002127;3172.1752344390625;3542.079059444563;3576.1774791798034;3585.515955346047;656.9638669873108;657.8125234308295;661.3089321658878;662.5110955731835;667.6861874918781;563.7463141586303;610.2920612089954;672.2034389248756;682.382443888282;707.4566931879883;9010.909890901139;9226.81546369069;9339.813203735886;9350.812983740287;9428.481433521221;577.6967092276589;659.3472462262325;702.8102412348637;708.2280314457266;711.6086152616041;3585.731070169748;4446.3108218226225;4497.965467942392;4521.371947575943;4533.7279763213655;91068;95591.52204239073;96319;96535.51732241434;97680.02319976709;77743.61128194323;79380.60309698415;80976;81677.36645267061;83508;27089.72910270872;64994.02508962369;65150.696986060015;65334.673326634016;65639.67180164068;7159.355657990805;8574.22831945128;8690.652373904974;8704.477731336141;8885.644574216958;44565.66303010915;44879.775601121786;45035.32447013232;45269.64191074273;46026.07947841092;51338.74330628473;53228.73385633047;54476.455235448724;59138.40861591501;63865;10575.471226438493;10670.519826607977;12905.612831615066;12996.610101696964;13000.154989925624;14276.000679952303;14459.783103253249;14542.345594448063;14844.925775371486;15072.246387680352;13324.933375333061;13485;13508.054436189372;13527.932360338134;13682.863171368557;13706.794398083837;13763.518276860263;13786.724265514835;13928.233947133005;13982.322121345504;12978.221306721627;13175.538856139989;13360.599182024554;13372.598822035354;13605.482806504098;11007.394593297446;11116.944415277872;11537.192396532162;11617.244879082833;11644.6506604802;10869;11129.499172537093;11278.605248816251;11399;11407.315561066363 19:41:51 INFO - PROCESS | 1674 | __end_tp_report 19:41:51 INFO - PROCESS | 1674 | __start_cc_report 19:41:51 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,415 19:41:51 INFO - PROCESS | 1674 | __end_cc_report 19:41:51 INFO - PROCESS | 1674 | __startTimestamp1464576111503__endTimestamp 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | ------- Summary: start ------- 19:41:51 INFO - PROCESS | 1674 | Number of tests: 6 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5555.46 Median:5697.74 stddev:748.72 (13.1%) stddev-sans-first:731.47 19:41:51 INFO - PROCESS | 1674 | Values: 3389.1 3408.2 3422.3 3437.8 3454.4 5476.9 5526.8 5589.3 5591.0 5622.6 5440.5 5513.0 5527.1 5577.5 5640.9 5020.6 5051.6 5128.3 5176.4 5195.3 275.3 5139.3 5154.0 5160.2 5201.5 5083.8 5104.5 5128.3 5139.3 5143.1 5050.2 5148.3 5162.8 5167.3 5199.3 5034.0 5079.5 5083.1 5099.9 5121.5 4515.6 5119.7 5473.6 5518.4 5555.4 4865.8 5749.5 6076.2 6102.5 6121.0 6091.6 6127.3 6136.7 6149.9 6157.4 5658.4 5676.3 5685.4 5687.2 5708.1 6097.0 6121.5 6176.5 6178.4 6212.5 6058.1 6071.3 6073.6 6076.0 6101.1 6131.2 6141.9 6170.1 6170.7 6177.5 5867.0 6021.2 6033.1 6093.8 6106.1 4875.4 5346.8 5936.4 5961.2 6005.4 4537.5 4774.5 4857.1 4860.5 4878.5 4307.6 4340.7 4389.8 4394.6 4421.6 5528.0 5588.1 5646.0 5674.8 5705.6 6131.2 6131.4 6169.3 6179.5 6189.8 5507.4 5552.6 5589.0 5611.9 5643.8 5280.4 5283.2 5284.7 5300.0 5316.1 6270.4 6282.8 6313.9 6315.5 6320.4 4853.9 6094.1 6131.1 6144.2 6177.8 6275.7 6315.8 6336.0 6336.1 6341.3 6093.8 6122.4 6136.4 6136.9 6137.4 5966.7 6000.9 6021.5 6057.0 6120.5 4748.5 5730.3 5817.8 5845.7 5853.1 5735.9 5844.8 5858.0 5868.5 5868.6 5537.3 5550.8 5653.7 5697.7 5713.3 5866.7 5942.3 5972.4 5981.8 5986.4 5657.9 5788.5 5805.8 5846.0 5856.7 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:70845.76 Median:41941.19 stddev:154534.79 (368.5%) stddev-sans-first:154878.11 19:41:51 INFO - PROCESS | 1674 | Values: 525.5 526.7 536.2 555.7 570.1 40148.0 43209.8 43244.8 43418.7 43608.8 44323.3 44951.0 44998.0 45120.5 45171.8 9610.0 9701.0 9766.9 9767.4 9834.9 9681.3 9723.0 9736.0 9764.4 9795.0 9733.1 9780.0 9816.0 9830.0 9831.8 9689.2 9736.0 9774.9 9790.0 9791.8 9643.3 9743.0 9762.9 9774.6 9777.6 41191.4 41419.0 41529.4 41966.4 42070.0 2899.3 3221.4 3408.8 3557.0 3674.0 26452.8 30714.4 31511.1 31562.4 31580.7 2633.4 3167.1 3207.2 3239.0 3320.6 466691.0 471076.0 473224.0 474106.6 474256.0 35515.5 35762.6 35998.3 36105.0 36200.5 36269.0 36721.8 37203.4 38544.2 38748.0 17557.8 17603.8 17706.3 17727.7 17757.1 13036.7 13041.7 13367.8 13454.9 13455.9 16945.9 20869.9 20930.6 21087.5 21149.8 7023.9 7732.5 8296.0 8298.5 8386.4 932.5 1040.4 1069.5 1154.8 1188.6 30208.8 32181.5 32260.0 32343.4 32675.0 1106.0 1112.8 1115.2 1152.4 1227.1 16581.3 16634.2 16668.6 16842.7 16932.7 552207.0 657136.0 658230.0 660209.0 671430.0 62252.0 63282.4 63505.4 64254.7 64578.0 1336.5 1410.1 1411.6 1417.7 1426.6 31325.7 31422.5 31428.5 31830.7 32267.7 55087.2 56218.9 56388.7 56567.0 56651.2 590930.0 608721.0 612475.0 613641.2 614991.0 27773.9 27799.3 28183.3 28691.9 29154.3 51579.2 52034.5 52101.0 52971.0 53138.0 42444.4 42617.0 42686.6 42790.4 43661.6 41667.0 41912.0 41916.0 42263.8 42425.2 32976.7 34388.0 40114.0 40475.8 40818.4 42097.0 42213.0 43292.0 45263.0 45803.8 29156.7 31705.9 31956.5 32879.7 33885.0 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:34843.95 Median:13199.87 stddev:101312.17 (767.5%) stddev-sans-first:101563.22 19:41:51 INFO - PROCESS | 1674 | Values: 322.0 375.9 384.6 393.6 401.9 17726.5 19247.9 19942.8 20522.6 20554.2 11421.9 12104.0 20414.8 20448.9 20916.4 5190.5 5249.3 5369.8 5379.0 5387.3 5353.3 5666.7 5917.3 5918.7 6079.3 5866.6 5992.0 6022.8 6024.2 6034.8 5324.9 5438.6 5439.4 5443.4 5455.7 4996.3 5144.8 5161.5 5280.6 5293.5 13080.0 13315.1 21191.7 25581.5 26077.7 2227.7 2251.9 2292.1 2429.6 2435.0 10101.0 10205.3 10427.3 13431.4 14627.8 1773.9 1782.9 1842.9 1914.5 1916.8 122372.4 125192.0 125309.0 129283.0 129893.4 11586.8 11773.9 11792.4 11836.8 11963.3 12189.8 14762.7 14912.3 14976.9 15034.0 6991.0 7137.8 8787.2 8818.2 8899.0 8720.3 8723.2 8756.7 8819.3 8841.8 4505.5 4552.5 4616.0 4617.9 4643.5 1617.9 1935.5 1961.8 1972.7 2024.3 891.2 906.9 930.1 962.2 965.0 20638.7 21172.5 21348.4 21480.9 21504.0 944.7 952.2 952.7 957.0 987.5 11102.7 11247.2 11374.9 11377.6 11398.0 594939.0 599459.0 607957.0 609703.0 619956.0 81328.2 124452.0 127513.0 137423.0 141058.3 988.2 992.2 1037.8 1040.6 1046.4 18532.9 19083.9 19167.3 19259.7 19334.0 34794.8 52433.0 53249.5 53570.0 54450.0 68236.0 74276.0 78919.8 79662.0 80422.6 7564.8 7841.4 7948.0 8016.2 8042.9 8785.6 11910.8 12493.8 12541.6 12719.7 8766.5 9517.4 10893.9 10943.8 10993.0 10504.4 10740.5 10830.8 10913.5 11072.9 7052.1 9443.7 10477.8 10928.2 11087.7 6710.4 7917.2 8028.4 8062.7 8102.1 8492.9 10545.2 11001.9 11151.2 11154.3 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5695.62 Median:4968.32 stddev:3162.54 (63.7%) stddev-sans-first:3155.25 19:41:51 INFO - PROCESS | 1674 | Values: 1440.7 1536.8 1727.9 1755.8 1895.7 7614.8 8061.2 8283.8 8625.5 9088.4 8317.2 8770.3 8863.7 8965.3 8966.5 2367.9 2373.7 2403.0 2403.1 2407.1 2338.8 2396.7 2412.5 2428.8 2429.6 1950.8 2241.5 2295.7 2357.0 2389.3 2368.5 2387.4 2389.2 2390.6 2397.5 1917.8 2351.0 2354.4 2356.4 2396.8 8773.8 8789.3 8837.0 8930.0 8943.0 5245.9 5289.5 5356.2 5422.7 5454.8 3237.0 3397.9 3650.5 3650.5 3678.3 912.2 1035.1 1085.9 1098.6 1110.3 9258.8 9418.6 11860.9 11871.9 11904.4 4228.9 4732.7 4744.8 4827.3 4878.6 9656.4 9934.7 10049.0 10100.2 10100.6 5337.1 5358.4 5417.5 5430.8 5451.0 4441.0 4775.8 4939.5 4949.8 4992.6 3760.4 4111.5 4124.8 4128.2 4140.8 1203.2 1319.7 1322.3 1323.1 1338.2 921.3 930.2 933.8 945.0 975.2 5884.4 5885.5 6012.7 6019.7 6032.9 422.1 448.1 942.6 985.7 1121.4 3786.4 3971.0 3989.1 4021.9 4087.9 10322.7 10709.9 10827.6 10835.7 10841.8 9447.4 10456.8 10688.8 10732.4 10826.3 7744.7 8035.8 8150.4 8206.5 8221.1 5894.2 5975.0 6007.5 6051.2 6066.9 9840.2 10055.4 10100.3 10125.3 10145.5 9641.0 9785.0 9973.0 10206.2 10296.4 6978.9 7595.8 7688.9 7951.1 8298.7 8268.6 8310.3 8519.0 8520.1 8547.7 7350.6 7506.1 7556.5 7576.8 7616.7 7358.5 7412.6 7422.6 7515.7 7517.7 5691.4 6960.4 7011.7 7091.2 7107.0 4824.4 4836.5 4970.1 4984.7 5124.8 4376.7 4966.6 5080.5 5146.1 5248.1 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4701.76 Median:5299.48 stddev:1465.22 (27.6%) stddev-sans-first:1430.98 19:41:51 INFO - PROCESS | 1674 | Values: 264.9 313.2 314.3 318.2 321.7 5108.4 5200.0 5588.0 5638.4 5677.7 4469.6 4903.3 5154.3 5571.7 5596.0 3129.4 3153.2 3683.4 3932.8 4206.4 3308.8 4220.3 4246.3 4246.6 4276.6 3676.7 4209.2 4218.6 4251.9 4258.3 3982.8 4225.7 4229.1 4268.5 4286.6 3154.1 3395.1 3658.7 3721.1 3746.4 4702.7 5398.7 5441.1 5477.0 5554.9 4612.3 4959.1 4989.6 5053.1 5066.3 5949.6 5966.8 5975.0 5982.9 6044.8 3580.7 4092.8 4113.5 4156.9 4168.9 5474.0 5671.3 5752.4 6282.8 6294.4 3384.7 4044.6 4176.8 4185.8 4195.7 4126.5 4250.5 4267.9 4278.4 4294.9 3658.9 3888.5 4205.5 4840.0 4989.7 5319.6 5930.5 5961.0 6001.3 6035.5 1326.3 1441.6 1473.2 1473.9 1475.2 735.6 769.1 888.5 922.1 963.8 4402.6 4402.7 5368.8 5389.6 5477.6 5201.6 5955.0 5957.4 6026.9 6067.4 4618.6 5279.3 5426.3 5431.4 5446.7 2730.9 2826.6 3169.6 3217.3 3234.9 5522.9 5674.7 6129.1 6293.7 6311.0 6200.0 6234.1 6239.2 6241.8 6251.6 5158.9 6239.5 6298.7 6299.6 6328.2 5628.9 5755.3 5788.5 6048.9 6077.0 5358.7 5758.6 5959.0 5984.7 5995.6 5201.5 5459.5 5848.5 6132.8 6337.7 4087.9 4090.6 4095.9 4103.0 4120.3 5521.1 5567.8 5578.5 5704.6 6048.4 5136.2 5690.2 5692.9 5749.1 5849.8 4663.7 4833.5 5550.7 5812.2 5960.6 4573.9 5069.0 5441.4 5709.3 5716.9 4764.9 5193.9 5337.1 5745.9 5924.2 5176.7 5608.4 5753.8 5826.5 5835.3 19:41:51 INFO - PROCESS | 1674 | 19:41:51 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18243.92 Median:24766.64 stddev:24787.71 (100.1%) stddev-sans-first:24822.22 19:41:51 INFO - PROCESS | 1674 | Values: 696.7 702.4 735.2 793.6 851.9 14378.9 15193.3 15312.6 15401.8 15402.5 13984.6 13991.4 14014.9 14090.3 14123.6 700.9 865.5 886.9 907.0 908.0 905.4 909.0 911.9 912.9 915.6 967.5 967.7 968.2 968.6 971.3 950.9 959.0 962.0 963.1 964.2 687.3 687.6 687.8 709.7 719.7 14158.2 14204.9 14449.2 14450.8 14480.0 12119.2 14002.2 14167.9 14175.1 14307.6 11348.9 11611.8 11643.1 12048.3 12136.6 3849.8 3923.4 4004.3 4017.1 4019.4 78975.6 80019.8 80152.6 81854.6 83193.0 10862.6 10871.1 10968.6 10973.7 11209.3 21765.8 21932.8 21966.9 21987.7 21996.8 7974.5 8057.7 8079.2 8091.4 8143.8 3769.5 4324.1 4398.5 4398.8 4400.2 3002.0 3172.2 3542.1 3576.2 3585.5 657.0 657.8 661.3 662.5 667.7 563.7 610.3 672.2 682.4 707.5 9010.9 9226.8 9339.8 9350.8 9428.5 577.7 659.3 702.8 708.2 711.6 3585.7 4446.3 4498.0 4521.4 4533.7 91068.0 95591.5 96319.0 96535.5 97680.0 77743.6 79380.6 80976.0 81677.4 83508.0 27089.7 64994.0 65150.7 65334.7 65639.7 7159.4 8574.2 8690.7 8704.5 8885.6 44565.7 44879.8 45035.3 45269.6 46026.1 51338.7 53228.7 54476.5 59138.4 63865.0 10575.5 10670.5 12905.6 12996.6 13000.2 14276.0 14459.8 14542.3 14844.9 15072.2 13324.9 13485.0 13508.1 13527.9 13682.9 13706.8 13763.5 13786.7 13928.2 13982.3 12978.2 13175.5 13360.6 13372.6 13605.5 11007.4 11116.9 11537.2 11617.2 11644.7 10869.0 11129.5 11278.6 11399.0 11407.3 19:41:51 INFO - PROCESS | 1674 | -------- Summary: end -------- 19:41:51 INFO - PROCESS | 1674 | 19:41:52 INFO - TEST-INFO | 1674: exit 0 19:41:52 INFO - TEST-OK | dromaeo_css | took 1111589ms 19:41:52 INFO - TEST-START | kraken 19:41:52 INFO - operating with platform_type : mac_ 19:41:52 INFO - Initialising browser for kraken test... 19:41:52 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpJei1Hw/profile http://localhost:49277/getInfo.html) 19:41:54 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 19:41:54 INFO - PROCESS | 1683 | colorDepth:24 19:41:54 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 19:41:54 INFO - PROCESS | 1683 | __metrics 19:41:55 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:41:55 INFO - TEST-INFO | 1683: exit 0 19:41:55 INFO - Browser initialized. 19:41:55 INFO - Running cycle 1/1 for kraken test... 19:41:55 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpJei1Hw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:07 INFO - PROCESS | 1685 | RSS: Main: 252637184 19:42:07 INFO - PROCESS | 1685 | 19:42:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 19:42:11 INFO - PROCESS | 1685 | RSS: Main: 265408512 19:42:11 INFO - PROCESS | 1685 | 19:42:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 19:42:14 INFO - PROCESS | 1685 | RSS: Main: 270196736 19:42:14 INFO - PROCESS | 1685 | 19:42:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 19:42:18 INFO - PROCESS | 1685 | RSS: Main: 270483456 19:42:18 INFO - PROCESS | 1685 | 19:42:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 19:42:21 INFO - PROCESS | 1685 | RSS: Main: 271302656 19:42:21 INFO - PROCESS | 1685 | 19:42:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:42:24 INFO - PROCESS | 1685 | RSS: Main: 271831040 19:42:24 INFO - PROCESS | 1685 | 19:42:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 19:42:27 INFO - PROCESS | 1685 | RSS: Main: 271953920 19:42:27 INFO - PROCESS | 1685 | 19:42:30 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 19:42:31 INFO - PROCESS | 1685 | RSS: Main: 272183296 19:42:31 INFO - PROCESS | 1685 | 19:42:34 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 19:42:34 INFO - PROCESS | 1685 | RSS: Main: 272359424 19:42:34 INFO - PROCESS | 1685 | 19:42:36 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:42:36 INFO - PROCESS | 1685 | RSS: Main: 271544320 19:42:36 INFO - PROCESS | 1685 | 19:42:38 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:42:39 INFO - PROCESS | 1685 | RSS: Main: 271859712 19:42:39 INFO - PROCESS | 1685 | 19:42:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:42:42 INFO - PROCESS | 1685 | RSS: Main: 272240640 19:42:42 INFO - PROCESS | 1685 | 19:42:45 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:42:45 INFO - PROCESS | 1685 | RSS: Main: 272179200 19:42:45 INFO - PROCESS | 1685 | 19:42:48 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:42:49 INFO - PROCESS | 1685 | RSS: Main: 272470016 19:42:49 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 19:42:51 INFO - PROCESS | 1685 | RSS: Main: 273195008 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | __start_tp_report 19:42:51 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 19:42:51 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 19:42:51 INFO - PROCESS | 1685 | |i|pagename|runs| 19:42:51 INFO - PROCESS | 1685 | |0;ai-astar;114;110;116;115;115;129;127;164;170;124 19:42:51 INFO - PROCESS | 1685 | |1;audio-beat-detection;158;119;143;163;126;151;143;122;166;121 19:42:51 INFO - PROCESS | 1685 | |2;audio-dft;197;207;261;271;233;194;200;200;192;193 19:42:51 INFO - PROCESS | 1685 | |3;audio-fft;71;74;74;141;58;58;59;60;123;67 19:42:51 INFO - PROCESS | 1685 | |4;audio-oscillator;69;73;122;72;71;90;114;72;73;112 19:42:51 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;90;86;89;85;88;99;124;87;95;123 19:42:51 INFO - PROCESS | 1685 | |6;imaging-darkroom;96;96;96;96;98;96;95;97;95;95 19:42:51 INFO - PROCESS | 1685 | |7;imaging-desaturate;122;122;91;131;85;145;150;107;101;90 19:42:51 INFO - PROCESS | 1685 | |8;json-parse-financial;55;57;59;58;59;58;56;56;58;58 19:42:51 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;59;102;114;69;77;72;67;76;72;70 19:42:51 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;89;88;90;104;113;111;88;87;115;87 19:42:51 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;151;148;146;153;144;140;137;144;140;142 19:42:51 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;207;156;155;148;175;239;233;225;162;194 19:42:51 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;76;93;80;68;58;71;85;83;86;91 19:42:51 INFO - PROCESS | 1685 | __end_tp_report 19:42:51 INFO - PROCESS | 1685 | __start_cc_report 19:42:51 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1072 19:42:51 INFO - PROCESS | 1685 | __end_cc_report 19:42:51 INFO - PROCESS | 1685 | __startTimestamp1464576171680__endTimestamp 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | ------- Summary: start ------- 19:42:51 INFO - PROCESS | 1685 | Number of tests: 14 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:128.40 Median:125.50 stddev:21.29 (17.0%) stddev-sans-first:21.93 19:42:51 INFO - PROCESS | 1685 | Values: 114.0 110.0 116.0 115.0 115.0 129.0 127.0 164.0 170.0 124.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:147.00 stddev:18.18 (12.4%) stddev-sans-first:18.24 19:42:51 INFO - PROCESS | 1685 | Values: 158.0 119.0 143.0 163.0 126.0 151.0 143.0 122.0 166.0 121.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:214.80 Median:203.50 stddev:29.57 (14.5%) stddev-sans-first:30.65 19:42:51 INFO - PROCESS | 1685 | Values: 197.0 207.0 261.0 271.0 233.0 194.0 200.0 200.0 192.0 193.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:78.50 Median:63.50 stddev:29.21 (46.0%) stddev-sans-first:30.85 19:42:51 INFO - PROCESS | 1685 | Values: 71.0 74.0 74.0 141.0 58.0 58.0 59.0 60.0 123.0 67.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:72.00 stddev:21.11 (29.3%) stddev-sans-first:21.38 19:42:51 INFO - PROCESS | 1685 | Values: 69.0 73.0 122.0 72.0 71.0 90.0 114.0 72.0 73.0 112.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:88.50 stddev:14.80 (16.7%) stddev-sans-first:15.50 19:42:51 INFO - PROCESS | 1685 | Values: 90.0 86.0 89.0 85.0 88.0 99.0 124.0 87.0 95.0 123.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 19:42:51 INFO - PROCESS | 1685 | Values: 96.0 96.0 96.0 96.0 98.0 96.0 95.0 97.0 95.0 95.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:114.40 Median:147.50 stddev:23.18 (15.7%) stddev-sans-first:24.42 19:42:51 INFO - PROCESS | 1685 | Values: 122.0 122.0 91.0 131.0 85.0 145.0 150.0 107.0 101.0 90.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.35 (2.3%) stddev-sans-first:1.12 19:42:51 INFO - PROCESS | 1685 | Values: 55.0 57.0 59.0 58.0 59.0 58.0 56.0 56.0 58.0 58.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:77.80 Median:71.00 stddev:16.92 (23.8%) stddev-sans-first:16.52 19:42:51 INFO - PROCESS | 1685 | Values: 59.0 102.0 114.0 69.0 77.0 72.0 67.0 76.0 72.0 70.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:87.50 stddev:12.02 (13.7%) stddev-sans-first:12.37 19:42:51 INFO - PROCESS | 1685 | Values: 89.0 88.0 90.0 104.0 113.0 111.0 88.0 87.0 115.0 87.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:145.00 stddev:5.08 (3.5%) stddev-sans-first:4.82 19:42:51 INFO - PROCESS | 1685 | Values: 151.0 148.0 146.0 153.0 144.0 140.0 137.0 144.0 140.0 142.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.40 Median:200.50 stddev:34.85 (17.4%) stddev-sans-first:36.38 19:42:51 INFO - PROCESS | 1685 | Values: 207.0 156.0 155.0 148.0 175.0 239.0 233.0 225.0 162.0 194.0 19:42:51 INFO - PROCESS | 1685 | 19:42:51 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:84.00 stddev:10.94 (13.0%) stddev-sans-first:11.54 19:42:51 INFO - PROCESS | 1685 | Values: 76.0 93.0 80.0 68.0 58.0 71.0 85.0 83.0 86.0 91.0 19:42:51 INFO - PROCESS | 1685 | -------- Summary: end -------- 19:42:51 INFO - PROCESS | 1685 | 19:42:52 INFO - TEST-INFO | 1685: exit 0 19:42:52 INFO - TEST-OK | kraken | took 60401ms 19:42:52 INFO - SUITE-END | took 1171s 19:42:52 INFO - Completed test suite (00:19:32) 19:42:52 INFO - javascript benchmark 19:42:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7276.951627722297, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.1018879996823, 3408.1649995751036, 3422.264213194163, 3437.8452969616364, 3454.447288433851, 5476.91784623231, 5526.751296191671, 5589.329280486348, 5590.972045139779, 5622.550195984321, 5440.455954404568, 5513.007658621446, 5527.143292789624, 5577.470140336672, 5640.887182256352, 5020.573251273637, 5051.595872330223, 5128.282040514331, 5176.430592634826, 5195.272661827347, 275.3309086850159, 5139.306193663863, 5154.020736060155, 5160.200168973796, 5201.453847346031, 5083.7712302946275, 5104.515071068261, 5128.282040514331, 5139.25480805283, 5143.12566863634, 5050.191969284933, 5148.304978827848, 5162.819301324474, 5167.250748641463, 5199.272101905736, 5033.974830125862, 5079.517445842676, 5083.110455670243, 5099.949000509984, 5121.539061484485, 4515.63874890008, 5119.692818430906, 5473.644213126134, 5518.420565840569, 5555.388907220203, 4865.8053677853195, 5749.540036797046, 6076.240469941257, 6102.542309326818, 6121.020636698151, 6091.573589848713, 6127.2953610334935, 6136.691761626206, 6149.938500614981, 6157.415045570664, 5658.377578466366, 5676.2620859288445, 5685.431456854281, 5687.203791469197, 5708.115242137475, 6096.969515152396, 6121.5139442231075, 6176.474999624992, 6178.351273116348, 6212.502999760009, 6058.0610005449225, 6071.271447426248, 6073.5748497604745, 6075.99746041907, 6101.054336577838, 6131.233595800525, 6141.877162456815, 6170.10533472653, 6170.7223174957235, 6177.4749146323065, 5867.0, 6021.217241758631, 6033.095035744585, 6093.817185484442, 6106.145139680449, 4875.43932447773, 5346.823698786261, 5936.37668044849, 5961.225040744675, 6005.429484198994, 4537.455505339381, 4774.474807771142, 4857.077155340474, 4860.4896485869185, 4878.463369029404, 4307.633851122627, 4340.696151269444, 4389.824407023683, 4394.582514661102, 4421.5578442156175, 5528.0, 5588.1059030555725, 5645.971770141123, 5674.77300907959, 5705.62913410627, 6131.172291740558, 6131.417515336036, 6169.259688837368, 6179.536534759911, 6189.81430557084, 5507.421720719347, 5552.611317207757, 5589.0, 5611.887762244733, 5643.7742490299925, 5280.392754833167, 5283.233931079972, 5284.656497327662, 5300.04599172145, 5316.122839731401, 6270.404311590391, 6282.780102696383, 6313.905291420632, 6315.463185629273, 6320.367963203643, 4853.9029219416125, 6094.085887116968, 6131.080337949344, 6144.218176728878, 6177.752889884534, 6275.748970041147, 6315.842103947438, 6336.0, 6336.081268216175, 6341.302456729848, 6093.817185484442, 6122.418370254819, 6136.386361363828, 6136.907946380898, 6137.355577664281, 5966.671833049224, 6000.939990600038, 6021.548383871314, 6057.0, 6120.479759220516, 4748.525147485224, 5730.312362516524, 5817.76728930838, 5845.707714614337, 5853.0635098384055, 5735.856603584777, 5844.766209351748, 5858.033424484912, 5868.471837534642, 5868.618539794899, 5537.335519737658, 5550.7779688812, 5653.689047102449, 5697.7436015380235, 5713.314402271754, 5866.677332746569, 5942.286925568959, 5972.372900845522, 5981.7607295707685, 5986.401359864153, 5657.858553536194, 5788.450097240755, 5805.82582522525, 5845.970770146122, 5856.677882716492], "value": 5513.1356529466375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.5322762741158, 526.744528903482, 536.2385412713945, 555.6971450559445, 570.0537108400057, 40148.0, 43209.78395108028, 43244.78377608115, 43418.6974390768, 43608.78195609021, 44323.33514997269, 44951.0, 44998.0, 45120.54879451205, 45171.77414112933, 9610.038996100386, 9701.0, 9766.90233097667, 9767.365121267096, 9834.901650983471, 9681.322307438482, 9723.0, 9735.97772233916, 9764.414135151877, 9794.951025244864, 9733.124018838304, 9780.0, 9815.950920245388, 9829.950850245774, 9831.754206144868, 9689.224862011057, 9736.0, 9774.853377199348, 9789.951050244774, 9791.804163916753, 9643.324967252294, 9743.025697430272, 9762.853557196648, 9774.609015639437, 9777.560009799505, 41191.38212926778, 41419.0, 41529.37705934414, 41966.37050444246, 42070.0, 2899.347646779479, 3221.4040402525375, 3408.7613867029318, 3556.964430355715, 3673.981630091859, 26452.809623566987, 30714.385712285854, 31511.054668359986, 31562.368752624818, 31580.684193158006, 2633.4074833162576, 3167.0973772474963, 3207.1661368044474, 3239.0, 3320.568326117613, 466691.0, 471076.0, 473224.0, 474106.6294668539, 474256.0, 35515.467267991, 35762.642373576455, 35998.28003439916, 36105.0, 36200.456993144595, 36269.0, 36721.81639091788, 37203.4419483708, 38544.22911541753, 38748.03129921774, 17557.824421755875, 17603.823961760474, 17706.29174833018, 17727.73408398875, 17757.11214439273, 13036.739265214643, 13041.673958151123, 13367.799483007762, 13454.932725336506, 13455.923526118082, 16945.915270423568, 20869.895650521652, 20930.58138837245, 21087.472813179793, 21149.78850211509, 7023.92976070243, 7732.497387669783, 8295.958520207481, 8298.460600061097, 8386.371022173362, 932.5477143585356, 1040.370575801629, 1069.5080263078914, 1154.7748189103045, 1188.6255829413708, 30208.848955755082, 32181.51727724086, 32260.0, 32343.35313293721, 32675.0, 1105.9778804423865, 1112.805259079674, 1115.2137742891405, 1152.3546813784308, 1227.1225025710678, 16581.336746530003, 16634.168291585614, 16668.58328541796, 16842.747358789627, 16932.661346772995, 552207.0, 657136.0, 658230.0, 660209.0, 671430.0, 62252.0, 63282.36717632857, 63505.36494635087, 64254.67872660607, 64578.0, 1336.5188532128425, 1410.0904916328911, 1411.6400317918865, 1417.6526750946084, 1426.6433391652088, 31325.686743132275, 31422.52866206963, 31428.52857207098, 31830.68169318277, 32267.677323226468, 55087.173692393844, 56218.875622488136, 56388.71805640945, 56567.0, 56651.15023274572, 590930.0, 608721.0, 612475.0, 613641.1815291097, 614991.0, 27773.861130695026, 27799.30501737473, 28183.295417614725, 28691.856540718, 29154.271143221587, 51579.22631160461, 52034.479655202966, 52101.0, 52971.0, 53138.0, 42444.363334550624, 42617.0, 42686.57313426826, 42790.35814462723, 43661.56338436702, 41666.95832604209, 41911.952201195214, 41916.0, 42263.7886810564, 42425.15149696927, 32976.67023329736, 34387.96836094921, 40114.0, 40475.797621011705, 40818.38772418476, 42097.0, 42213.0, 43292.0, 45263.0, 45803.77098114488, 29156.708432916246, 31705.890293839606, 31956.52065218977, 32879.67120328766, 33885.0], "value": 20497.145964178184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.99839000805, 375.87408218246884, 384.5827277404017, 393.64768532163697, 401.85734064407137, 17726.468205953825, 19247.903760481215, 19942.800571994274, 20522.589548209027, 20554.177832886704, 11421.885781142186, 12104.0, 20414.795852041476, 20448.897755511203, 20916.372508824723, 5190.480951904808, 5249.343832020997, 5369.812056578021, 5378.973105134469, 5387.272718183054, 5353.277307563468, 5666.688332141731, 5917.319508256541, 5918.674472903988, 6079.270487541479, 5866.6480011199255, 5992.0, 6022.789202377918, 6024.186734790791, 6034.818955431322, 5324.866878328034, 5438.564914806806, 5439.401665816757, 5443.374011988612, 5455.699936503491, 4996.2505624156665, 5144.845654630367, 5161.458046905059, 5280.6039547034125, 5293.523582877521, 13080.018998575146, 13315.067945243836, 21191.68212476814, 25581.48837023249, 26077.73922260772, 2227.7103976483118, 2251.887405629728, 2292.083166733303, 2429.5869702150676, 2434.9878250608813, 10101.0, 10205.285630005903, 10427.270091093627, 13431.3955871986, 14627.78058329126, 1773.893566386021, 1782.9286828526842, 1842.8709990300683, 1914.463950093962, 1916.8466522678152, 122372.38813805874, 125192.0, 125309.0, 129283.0, 129893.35053324673, 11586.826197607043, 11773.88226117745, 11792.41037948099, 11836.763264734656, 11963.342016189194, 12189.817152742717, 14762.70474590502, 14912.254387280593, 14976.850231497763, 15034.0, 6990.965045174843, 7137.750178743719, 8787.209151176297, 8818.164601752234, 8899.02110767815, 8720.258778003814, 8723.214910658071, 8756.693515726918, 8819.250363719155, 8841.823163536694, 4505.481869584974, 4552.499225085238, 4616.038183771951, 4617.861464156081, 4643.512431194744, 1617.9110148941686, 1935.5064458563259, 1961.8430525557922, 1972.7139564763029, 2024.2813801100397, 891.1623074310128, 906.8730377747121, 930.1442672741094, 962.2302158273353, 964.9723044957099, 20638.690419643717, 21172.47068823292, 21348.35954921355, 21480.892595537236, 21504.0, 944.6599224279254, 952.2382094324513, 952.7046615549064, 957.0477375011768, 987.5457289646699, 11102.723186833457, 11247.156463265288, 11374.94312528432, 11377.601783937522, 11398.0, 594939.0, 599459.0, 607957.0, 609703.0, 619956.0, 81328.18671813325, 124452.0, 127513.0, 137423.0, 141058.2947085258, 988.243993345106, 992.1814503035025, 1037.766502536923, 1040.6149724601946, 1046.3826342457835, 18532.907335463235, 19083.90458047701, 19167.32914348047, 19259.711104333164, 19334.0, 34794.82602586971, 52433.0, 53249.467505326, 53570.0, 54450.0, 68236.0, 74276.0, 78919.81620275586, 79662.0, 80422.59788701253, 7564.810879728051, 7841.372690184658, 7948.0, 8016.238457346542, 8042.919570804217, 8785.560721964004, 11910.805290606746, 12493.812592810933, 12541.623751287476, 12719.682007949876, 8766.517841518778, 9517.428954262767, 10893.864518740238, 10943.781124377627, 10993.0, 10504.369737815756, 10740.46297685097, 10830.837537437103, 10913.454327283764, 11072.944635276772, 7052.118485189351, 9443.716688499357, 10477.795053568938, 10928.235023548275, 11087.667369978913, 6710.429613482908, 7917.208279172037, 8028.3577313813585, 8062.717804876801, 8102.108768035393, 8492.915070849212, 10545.167920499573, 11001.944990274997, 11151.16366272549, 11154.330740155616], "value": 9332.265531610296, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.7118576284743, 1536.7771673107395, 1727.9459529686897, 1755.7893052833654, 1895.7156426536026, 7614.847703045943, 8061.153578874222, 8283.751487455378, 8625.48247105174, 9088.363814532986, 8317.209865062825, 8770.298376129911, 8863.689770858022, 8965.32760042996, 8966.462012279268, 2367.869767162805, 2373.655819906117, 2402.9879850600723, 2403.0748161957686, 2407.061246114008, 2338.8012018978407, 2396.7363590005084, 2412.457197130649, 2428.8421252618614, 2429.599116145843, 1950.8146726061073, 2241.4844585745286, 2295.7130358705163, 2356.964645530318, 2389.2712722619594, 2368.5499755046485, 2387.3792813868517, 2389.2115601851433, 2390.6055500842426, 2397.5444665513496, 1917.7506924099916, 2350.9647355289685, 2354.3525530479083, 2356.3991182248587, 2396.808255339586, 8773.780655483664, 8789.25291350236, 8836.955815220947, 8929.955350223272, 8942.955285223532, 5245.895082098375, 5289.523942845162, 5356.196570514454, 5422.674639521641, 5454.836354909359, 3236.9514457282926, 3397.915052123692, 3650.5071815305123, 3650.543682039745, 3678.301122786662, 912.2154946745793, 1035.11497669493, 1085.8696956365209, 1098.5880294889416, 1110.3337997201616, 9258.768530786718, 9418.576164072701, 11860.881391186063, 11871.881281187163, 11904.404779760976, 4228.8582082838075, 4732.716037037789, 4744.762761861962, 4827.275908613666, 4878.56092951636, 9656.37233579815, 9934.652287169914, 10048.995100489892, 10100.191984641211, 10100.595976161017, 5337.119375303109, 5358.4373640767935, 5417.458254174551, 5430.8370748876805, 5450.972745136328, 4441.045175287264, 4775.785089670973, 4939.506049395031, 4949.802007919643, 4992.550670439605, 3760.360738674377, 4111.465509483748, 4124.783188959264, 4128.236276288896, 4140.840564641905, 1203.1938601137083, 1319.736052789446, 1322.285965578596, 1323.1267363540017, 1338.1837079381569, 921.2952091649893, 930.1628534319032, 933.7572231219796, 944.9935818353568, 975.1662328708852, 5884.411558844081, 5885.47030767233, 6012.7294271757, 6019.69901504923, 6032.906764168215, 422.0503866300823, 448.0836688971062, 942.5758408716105, 985.7437066362797, 1121.3944469986116, 3786.4320351946876, 3971.0, 3989.122393073519, 4021.939670904939, 4087.91824163521, 10322.690319290434, 10709.892901071045, 10827.566897324019, 10835.729106772395, 10841.783164336826, 9447.433154010643, 10456.849746527876, 10688.839667404995, 10732.356058636507, 10826.296290741077, 7744.6514906829325, 8035.799105022421, 8150.4294699370475, 8206.466579672302, 8221.095679475253, 5894.20428242199, 5975.0, 6007.519398448201, 6051.243594550681, 6066.908996365147, 9840.212782977487, 10055.39667619916, 10100.292979491365, 10125.291229614151, 10145.543450544596, 9640.951795240979, 9785.0, 9972.950135249277, 10206.234532410248, 10296.433696146783, 6978.860422791618, 7595.810104747426, 7688.923110768821, 7951.125376208728, 8298.70954516589, 8268.627911744106, 8310.335173186251, 8519.020312664123, 8520.062793092631, 8547.700830470903, 7350.632468376667, 7506.061742282215, 7556.508826926232, 7576.848463030598, 7616.657250423855, 7358.521696089737, 7412.592307423143, 7422.628868556442, 7515.6993720250575, 7517.699292028257, 5691.402402747817, 6960.37356637905, 7011.719531218694, 7091.219965803861, 7106.964465177848, 4824.372831531879, 4836.540528649773, 4970.080535101018, 4984.725840078685, 5124.79500819978, 4376.671749618855, 4966.553010229097, 5080.49195080489, 5146.073706732824, 5248.055350036877], "value": 4576.343526551868, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.8874228452908, 313.1560444601798, 314.3132256020595, 318.17275084779567, 321.73939109321435, 5108.373055130853, 5199.960007998397, 5587.988324203284, 5638.351589567196, 5677.716114194289, 4469.620082293, 4903.289023091649, 5154.304168937192, 5571.659902840806, 5596.0, 3129.358481511285, 3153.164411430961, 3683.355412802762, 3932.7640341579454, 4206.41110244566, 3308.834558272089, 4220.324748040314, 4246.341817018367, 4246.619848549219, 4276.636485898703, 3676.7058635309245, 4209.200251952119, 4218.578142185772, 4251.8511852085185, 4258.318669012959, 3982.8406863725418, 4225.746455212697, 4229.111886503838, 4268.466441694788, 4286.61420472159, 3154.069549482908, 3395.1002984209204, 3658.70730341572, 3721.106934335767, 3746.363118269873, 4702.694324868907, 5398.676079435208, 5441.129419292934, 5476.972615136899, 5554.86112847178, 4612.285095810154, 4959.107360675077, 4989.625778066659, 5053.090443720129, 5066.341375621182, 5949.6430214187285, 5966.791162309299, 5975.043992961148, 5982.9401705983255, 6044.758209671608, 3580.695640870529, 4092.8335424403904, 4113.547509773923, 4156.854510092163, 4168.854090106862, 5474.042042642522, 5671.347795003586, 5752.36723960364, 6282.842928926813, 6294.3705629437145, 3384.6784555467684, 4044.6157615026527, 4176.812043458051, 4185.832566697298, 4195.664346852244, 4126.452580282394, 4250.4899412070135, 4267.914641707149, 4278.422412974271, 4294.892627684333, 3658.853645854136, 3888.5128291277706, 4205.453291072202, 4840.0, 4989.650724449254, 5319.627626066138, 5930.458080898911, 5960.956832554286, 6001.309849367379, 6035.456808887134, 1326.257295914291, 1441.5963530211557, 1473.2339183624456, 1473.8798513129884, 1475.2181343887758, 735.6248313360225, 769.0579040675128, 888.5024386343669, 922.0594993106994, 963.8000689142063, 4402.647788177002, 4402.691811573159, 5368.785248590013, 5389.56883449331, 5477.643953143112, 5201.621570283878, 5954.970225148846, 5957.404259574095, 6026.939730602726, 6067.393260673897, 4618.607418369409, 5279.340082489689, 5426.294581704432, 5431.37539182999, 5446.673199608036, 2730.890764369443, 2826.5901444290876, 3169.6196456425378, 3217.2761128746315, 3234.9191270218435, 5522.861928451741, 5674.744636491285, 6129.1419201311855, 6293.748250069946, 6310.968445157837, 6200.0159948816845, 6234.096056071844, 6239.161181662333, 6241.78153764616, 6251.593646412968, 5158.871028224249, 6239.500839932794, 6298.748050077946, 6299.622022678654, 6328.208973878173, 5628.902364038973, 5755.309362876482, 5788.53691704671, 6048.879022419615, 6076.969615152073, 5358.651687640291, 5758.568107391879, 5959.0, 5984.730687119177, 5995.5803093783015, 5201.453847345974, 5459.48863522784, 5848.502877255481, 6132.846678833065, 6337.683115844282, 4087.8773636790943, 4090.5500394957126, 4095.89760255996, 4103.0, 4120.269486815485, 5521.116621340566, 5567.832965011056, 5578.49793518569, 5704.572157088152, 6048.399188349053, 5136.178211486144, 5690.231818704422, 5692.943070569241, 5749.080147176432, 5849.795257165979, 4663.673542851968, 4833.468318485035, 5550.666959982414, 5812.244408226903, 5960.642361458326, 4573.885652858572, 5068.974655126701, 5441.374241962225, 5709.286339207599, 5716.9142462862255, 4764.857054288377, 5193.948390385852, 5337.092694241911, 5745.913811292918, 5924.229850119457, 5176.7152806596, 5608.383077861514, 5753.798617048383, 5826.475617194473, 5835.270591176103], "value": 4231.464345331602, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.72131147541, 702.3608516250216, 735.1545722419221, 793.6388943030921, 851.948883067016, 14378.928105359471, 15193.316300766464, 15312.617184570392, 15401.768973465407, 15402.460913868003, 13984.580462586138, 13991.440342386293, 14014.859851401483, 14090.295485225723, 14123.576292711236, 700.894865770136, 865.4677373415336, 886.8536691445904, 907.0430695616122, 908.0238743350924, 905.4295793650024, 908.9772755681095, 911.8723378726984, 912.8813254276935, 915.5834095486571, 967.5404183013083, 967.7000129959735, 968.2350942755215, 968.6416026070328, 971.3394891473795, 950.87638606981, 959.0170075672422, 962.0475729028282, 963.0898800633439, 964.2382517810921, 687.3332867118887, 687.5840116729406, 687.845234822161, 709.7373971630478, 719.6869361827622, 14158.221297828615, 14204.85795142056, 14449.205293708841, 14450.783238251435, 14480.0, 12119.151659383846, 14002.229877356742, 14167.85832141686, 14175.078619889777, 14307.642308942255, 11348.886511134948, 11611.767764644745, 11643.068554515703, 12048.277103373826, 12136.575219867262, 3849.8460061597784, 3923.3722604383447, 4004.3392840181336, 4017.13631569214, 4019.3569028955226, 78975.6051219746, 80019.79970300393, 80152.59923700344, 81854.59072704599, 83192.99999999939, 10862.610314874055, 10871.130309575216, 10968.616098436516, 10973.725656858642, 11209.327440353605, 21765.782342176375, 21932.793696346853, 21966.89016554939, 21987.670184947237, 21996.780032199473, 7974.5215287082965, 8057.717979870677, 8079.151689072681, 8091.39314551411, 8143.75568732939, 3769.4911186989407, 4324.070324880166, 4398.47218333802, 4398.758068306274, 4400.163968845909, 3001.9699803002127, 3172.1752344390625, 3542.079059444563, 3576.1774791798034, 3585.515955346047, 656.9638669873108, 657.8125234308295, 661.3089321658878, 662.5110955731835, 667.6861874918781, 563.7463141586303, 610.2920612089954, 672.2034389248756, 682.382443888282, 707.4566931879883, 9010.909890901139, 9226.81546369069, 9339.813203735886, 9350.812983740287, 9428.481433521221, 577.6967092276589, 659.3472462262325, 702.8102412348637, 708.2280314457266, 711.6086152616041, 3585.731070169748, 4446.3108218226225, 4497.965467942392, 4521.371947575943, 4533.7279763213655, 91068.0, 95591.52204239073, 96319.0, 96535.51732241434, 97680.02319976709, 77743.61128194323, 79380.60309698415, 80976.0, 81677.36645267061, 83508.0, 27089.72910270872, 64994.02508962369, 65150.696986060015, 65334.673326634016, 65639.67180164068, 7159.355657990805, 8574.22831945128, 8690.652373904974, 8704.477731336141, 8885.644574216958, 44565.66303010915, 44879.775601121786, 45035.32447013232, 45269.64191074273, 46026.07947841092, 51338.74330628473, 53228.73385633047, 54476.455235448724, 59138.40861591501, 63865.0, 10575.471226438493, 10670.519826607977, 12905.612831615066, 12996.610101696964, 13000.154989925624, 14276.000679952303, 14459.783103253249, 14542.345594448063, 14844.925775371486, 15072.246387680352, 13324.933375333061, 13485.0, 13508.054436189372, 13527.932360338134, 13682.863171368557, 13706.794398083837, 13763.518276860263, 13786.724265514835, 13928.233947133005, 13982.322121345504, 12978.221306721627, 13175.538856139989, 13360.599182024554, 13372.598822035354, 13605.482806504098, 11007.394593297446, 11116.944415277872, 11537.192396532162, 11617.244879082833, 11644.6506604802, 10869.0, 11129.499172537093, 11278.605248816251, 11399.0, 11407.315561066363], "value": 7270.475147717379, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 116.0, 115.0, 115.0, 129.0, 127.0, 164.0, 170.0, 124.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 119.0, 143.0, 163.0, 126.0, 151.0, 143.0, 122.0, 166.0, 121.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 207.0, 261.0, 271.0, 233.0, 194.0, 200.0, 200.0, 192.0, 193.0], "value": 214.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 74.0, 141.0, 58.0, 58.0, 59.0, 60.0, 123.0, 67.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 122.0, 72.0, 71.0, 90.0, 114.0, 72.0, 73.0, 112.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 85.0, 88.0, 99.0, 124.0, 87.0, 95.0, 123.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 95.0, 97.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 91.0, 131.0, 85.0, 145.0, 150.0, 107.0, 101.0, 90.0], "value": 114.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 59.0, 58.0, 59.0, 58.0, 56.0, 56.0, 58.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 102.0, 114.0, 69.0, 77.0, 72.0, 67.0, 76.0, 72.0, 70.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 104.0, 113.0, 111.0, 88.0, 87.0, 115.0, 87.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 146.0, 153.0, 144.0, 140.0, 137.0, 144.0, 140.0, 142.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 156.0, 155.0, 148.0, 175.0, 239.0, 233.0, 225.0, 162.0, 194.0], "value": 189.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 93.0, 80.0, 68.0, 58.0, 71.0, 85.0, 83.0, 86.0, 91.0], "value": 79.1, "unit": "score"}]}]} 19:42:52 INFO - Return code: 0 19:42:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:42:52 ERROR - # TBPL SUCCESS # 19:42:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:42:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:42:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:42:52 INFO - 2016-05-29 19:42:52.992 screenresolution[1690:10779] starting screenresolution argv=screenresolution get 19:42:52 INFO - 2016-05-29 19:42:52.995 screenresolution[1690:10779] Display 0: 1600x1200x32@60 19:42:53 INFO - 2016-05-29 19:42:53.001 screenresolution[1691:10782] starting screenresolution argv=screenresolution list 19:42:53 INFO - Available Modes on Display 0 19:42:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:42:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:42:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:42:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:42:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:42:53 INFO - Intel Iris: 19:42:53 INFO - Chipset Model: Intel Iris 19:42:53 INFO - Type: GPU 19:42:53 INFO - Bus: Built-In 19:42:53 INFO - VRAM (Dynamic, Max): 1536 MB 19:42:53 INFO - Vendor: Intel (0x8086) 19:42:53 INFO - Device ID: 0x0a2e 19:42:53 INFO - Revision ID: 0x0009 19:42:53 INFO - Displays: 19:42:53 INFO - Display: 19:42:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:42:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:42:53 INFO - Display Serial Number: 1600x1200 60 19:42:53 INFO - Main Display: Yes 19:42:53 INFO - Mirror: Off 19:42:53 INFO - Online: Yes 19:42:53 INFO - Rotation: Supported 19:42:53 INFO - Return code: 0 19:42:53 INFO - Running post-action listener: _resource_record_post_action 19:42:53 INFO - ##### 19:42:53 INFO - ##### Finished run-tests step (success) 19:42:53 INFO - ##### 19:42:53 INFO - Running post-run listener: _resource_record_post_run 19:42:53 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 31436288; Write bytes: 699904512; Read time: 196; Write time: 1999 19:42:53 INFO - TinderboxPrint: CPU usage
54.4% 19:42:53 INFO - TinderboxPrint: I/O read bytes / time
31,436,288 / 196 19:42:53 INFO - TinderboxPrint: I/O write bytes / time
699,904,512 / 1,999 19:42:53 INFO - TinderboxPrint: CPU idle
2,204.5 (45.6%) 19:42:53 INFO - TinderboxPrint: CPU system
971.9 (20.1%) 19:42:53 INFO - TinderboxPrint: CPU user
1,657.3 (34.3%) 19:42:53 INFO - TinderboxPrint: Swap in / out
426,037,248 / 0 19:42:53 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393896448; Write bytes: 389143552; Read time: 29352; Write time: 866 19:42:53 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 29654528; Write bytes: 297412096; Read time: 181; Write time: 1097 19:42:53 INFO - Running post-run listener: _upload_blobber_files 19:42:53 INFO - Blob upload gear active. 19:42:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:42:53 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 19:42:53 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'] 19:42:53 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 19:42:54 INFO - (blobuploader) - INFO - Open directory for files ... 19:42:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:42:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:42:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:55 INFO - (blobuploader) - INFO - Done attempting. 19:42:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:42:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:42:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:56 INFO - (blobuploader) - INFO - Done attempting. 19:42:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:42:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:42:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:58 INFO - (blobuploader) - INFO - Done attempting. 19:42:58 INFO - (blobuploader) - INFO - Iteration through files over. 19:42:58 INFO - Return code: 0 19:42:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:42:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:42:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4508407c228eb830e18b0e86d7b8fb2553760674199d746a0cbab848a4552b0a58fd0b1679ce98d6fa2799c2e4d981005947a3e69e702ad045b3ddc32c413efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ccc2cc45a9966cb1555271eb325178dbdb2591d17c3c78cae5be02b326b56bd242306dd5e9cfca15c67c2b84de7a534c388600f7e96af4533efa663f0ea70eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d34138d4fe33700af9c51960aae17075d46a1d85d0c954b4bdbd7698bbe3431680e689e19e9e01ba70dec6e52d5093df99d28ad4524868666408037e8d8e101c"} 19:42:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:42:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:42:58 INFO - Contents: 19:42:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4508407c228eb830e18b0e86d7b8fb2553760674199d746a0cbab848a4552b0a58fd0b1679ce98d6fa2799c2e4d981005947a3e69e702ad045b3ddc32c413efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ccc2cc45a9966cb1555271eb325178dbdb2591d17c3c78cae5be02b326b56bd242306dd5e9cfca15c67c2b84de7a534c388600f7e96af4533efa663f0ea70eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d34138d4fe33700af9c51960aae17075d46a1d85d0c954b4bdbd7698bbe3431680e689e19e9e01ba70dec6e52d5093df99d28ad4524868666408037e8d8e101c"} 19:42:58 INFO - Running post-run listener: copy_logs_to_upload_dir 19:42:58 INFO - Copying logs to upload dir... 19:42:58 INFO - mkdir: /builds/slave/test/build/upload/logs 19:42:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1301.555652 ========= master_lag: 1.92 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2016-05-29 19:43:00.355864) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-29 19:43:00.357045) ========= 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.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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/4508407c228eb830e18b0e86d7b8fb2553760674199d746a0cbab848a4552b0a58fd0b1679ce98d6fa2799c2e4d981005947a3e69e702ad045b3ddc32c413efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ccc2cc45a9966cb1555271eb325178dbdb2591d17c3c78cae5be02b326b56bd242306dd5e9cfca15c67c2b84de7a534c388600f7e96af4533efa663f0ea70eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d34138d4fe33700af9c51960aae17075d46a1d85d0c954b4bdbd7698bbe3431680e689e19e9e01ba70dec6e52d5093df99d28ad4524868666408037e8d8e101c"} build_url:https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/Ih7F-J_-QIyKgFvqn8Vc-A/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/4508407c228eb830e18b0e86d7b8fb2553760674199d746a0cbab848a4552b0a58fd0b1679ce98d6fa2799c2e4d981005947a3e69e702ad045b3ddc32c413efc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ccc2cc45a9966cb1555271eb325178dbdb2591d17c3c78cae5be02b326b56bd242306dd5e9cfca15c67c2b84de7a534c388600f7e96af4533efa663f0ea70eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d34138d4fe33700af9c51960aae17075d46a1d85d0c954b4bdbd7698bbe3431680e689e19e9e01ba70dec6e52d5093df99d28ad4524868666408037e8d8e101c"}' ========= master_lag: 4.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-29 19:43:04.883181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:43:04.883649) ========= 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.G5H6i3alpV/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.TFZywAZY2f/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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.005092 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 19:43:05.048985) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 19:43:05.049490) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 19:43:05.057942) ========= ========= Total master_lag: 6.66 =========