builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0198 starttime: 1464870373.54 results: success (0) buildid: 20160602030245 builduid: 92531ca0f230476298410a1b7fd6f4da revision: 58ac3bd12914f8d2d683176139e5630b4e32c2ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:13.540616) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:13.548235) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:13.548610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:13.571551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:13.571989) ========= 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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-06-02 05:26:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.43M=0.001s 2016-06-02 05:26:14 (8.43 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.462383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:14.055672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:14.056071) ========= 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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.021715 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:14.105527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 05:26:14.105942) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 58ac3bd12914f8d2d683176139e5630b4e32c2ca --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 58ac3bd12914f8d2d683176139e5630b4e32c2ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-06-02 05:26:14,180 truncating revision to first 12 chars 2016-06-02 05:26:14,180 Setting DEBUG logging. 2016-06-02 05:26:14,180 attempt 1/10 2016-06-02 05:26:14,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/58ac3bd12914?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 05:26:15,618 unpacking tar archive at: fx-team-58ac3bd12914/testing/mozharness/ program finished with exit code 0 elapsedTime=1.725186 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 05:26:15.851709) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:15.852084) ========= script_repo_revision: 58ac3bd12914f8d2d683176139e5630b4e32c2ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:15.852533) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:15.852837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:26:15.869399) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-06-02 05:26:15.869776) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:26:16 INFO - MultiFileLogger online at 20160602 05:26:16 in /builds/slave/test 05:26:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:26:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:26:16 INFO - {'append_to_log': False, 05:26:16 INFO - 'base_work_dir': '/builds/slave/test', 05:26:16 INFO - 'blob_upload_branch': 'Fx-Team', 05:26:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:26:16 INFO - 'branch': 'Fx-Team', 05:26:16 INFO - 'buildbot_json_path': 'buildprops.json', 05:26:16 INFO - 'config_files': ('talos/mac_config.py',), 05:26:16 INFO - 'default_actions': ('clobber', 05:26:16 INFO - 'read-buildbot-config', 05:26:16 INFO - 'download-and-extract', 05:26:16 INFO - 'populate-webroot', 05:26:16 INFO - 'create-virtualenv', 05:26:16 INFO - 'install', 05:26:16 INFO - 'run-tests'), 05:26:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:26:16 INFO - 'download_minidump_stackwalk': True, 05:26:16 INFO - 'download_symbols': 'ondemand', 05:26:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:26:16 INFO - 'tooltool.py': '/tools/tooltool.py', 05:26:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:26:16 INFO - '/tools/misc-python/virtualenv.py')}, 05:26:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:26:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:26:16 INFO - 'installer_path': 'installer.exe', 05:26:16 INFO - 'log_level': 'info', 05:26:16 INFO - 'log_name': 'talos', 05:26:16 INFO - 'log_to_console': True, 05:26:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:26:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:26:16 INFO - 'opt_config_files': (), 05:26:16 INFO - 'pip_index': False, 05:26:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:26:16 INFO - 'cmd': ('bash', 05:26:16 INFO - '-c', 05:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:26:16 INFO - 'enabled': True, 05:26:16 INFO - 'halt_on_failure': False, 05:26:16 INFO - 'name': 'check_screen_resolution'},), 05:26:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:26:16 INFO - 'cmd': ('bash', 05:26:16 INFO - '-c', 05:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:26:16 INFO - 'enabled': True, 05:26:16 INFO - 'halt_on_failure': False, 05:26:16 INFO - 'name': 'check_screen_resolution'},), 05:26:16 INFO - 'run_cmd_checks_enabled': True, 05:26:16 INFO - 'sps_profile': False, 05:26:16 INFO - 'sps_profile_interval': 0, 05:26:16 INFO - 'suite': 'dromaeojs', 05:26:16 INFO - 'system_bits': '32', 05:26:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:26:16 INFO - 'title': 't-yosemite-r7-0198', 05:26:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:26:16 INFO - 'use_talos_json': True, 05:26:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:26:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:26:16 INFO - 'work_dir': 'build'} 05:26:16 INFO - ##### 05:26:16 INFO - ##### Running clobber step. 05:26:16 INFO - ##### 05:26:16 INFO - Running pre-action listener: _resource_record_pre_action 05:26:16 INFO - Running main action method: clobber 05:26:16 INFO - rmtree: /builds/slave/test/build 05:26:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:26:17 INFO - Running post-action listener: _resource_record_post_action 05:26:17 INFO - ##### 05:26:17 INFO - ##### Finished clobber step (success) 05:26:17 INFO - ##### 05:26:17 INFO - ##### 05:26:17 INFO - ##### Running read-buildbot-config step. 05:26:17 INFO - ##### 05:26:17 INFO - Running pre-action listener: _resource_record_pre_action 05:26:17 INFO - Running main action method: read_buildbot_config 05:26:17 INFO - Using buildbot properties: 05:26:17 INFO - { 05:26:17 INFO - "project": "", 05:26:17 INFO - "product": "firefox", 05:26:17 INFO - "script_repo_revision": "production", 05:26:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:26:17 INFO - "repository": "", 05:26:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:26:17 INFO - "buildid": "20160602030245", 05:26:17 INFO - "pgo_build": "False", 05:26:17 INFO - "basedir": "/builds/slave/test", 05:26:18 INFO - "buildnumber": 50, 05:26:18 INFO - "slavename": "t-yosemite-r7-0198", 05:26:18 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:26:18 INFO - "platform": "yosemite_r7", 05:26:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:26:18 INFO - "branch": "fx-team", 05:26:18 INFO - "repo_path": "integration/fx-team", 05:26:18 INFO - "revision": "58ac3bd12914f8d2d683176139e5630b4e32c2ca", 05:26:18 INFO - "stage_platform": "macosx64", 05:26:18 INFO - "builduid": "92531ca0f230476298410a1b7fd6f4da", 05:26:18 INFO - "slavebuilddir": "test" 05:26:18 INFO - } 05:26:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:26:18 INFO - Running post-action listener: _resource_record_post_action 05:26:18 INFO - ##### 05:26:18 INFO - ##### Finished read-buildbot-config step (success) 05:26:18 INFO - ##### 05:26:18 INFO - ##### 05:26:18 INFO - ##### Running download-and-extract step. 05:26:18 INFO - ##### 05:26:18 INFO - Running pre-action listener: _resource_record_pre_action 05:26:18 INFO - Running main action method: download_and_extract 05:26:18 INFO - mkdir: /builds/slave/test/build/tests 05:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:18 INFO - https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:26:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:26:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:26:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:26:19 INFO - Downloaded 1199 bytes. 05:26:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:26:19 INFO - Using the following test package requirements: 05:26:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:26:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:26:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:26:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'jsshell-mac.zip'], 05:26:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:26:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:26:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:26:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:26:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:26:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:26:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:26:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:26: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')]} 05:26:19 INFO - https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:26:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:26:21 INFO - Downloaded 18713675 bytes. 05:26:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:26:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:26:24 INFO - Return code: 0 05:26:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:24 INFO - https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:26:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:26:40 INFO - Downloaded 18713675 bytes. 05:26:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:26:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:26:43 INFO - Return code: 0 05:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:43 INFO - https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:26:44 INFO - Downloaded 10834055 bytes. 05:26:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:26:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:26:45 INFO - Return code: 0 05:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:45 INFO - https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:26:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:26:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:26:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:26:52 INFO - Downloaded 111074527 bytes. 05:26:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:26:52 INFO - mkdir: /builds/slave/test/properties 05:26:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:26:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:26:52 INFO - Contents: 05:26:52 INFO - build_url:https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:26:52 INFO - Running post-action listener: _resource_record_post_action 05:26:52 INFO - Running post-action listener: set_extra_try_arguments 05:26:52 INFO - ##### 05:26:52 INFO - ##### Finished download-and-extract step (success) 05:26:52 INFO - ##### 05:26:52 INFO - ##### 05:26:52 INFO - ##### Running populate-webroot step. 05:26:52 INFO - ##### 05:26:52 INFO - Running pre-action listener: _resource_record_pre_action 05:26:52 INFO - Running main action method: populate_webroot 05:26:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:26:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:26:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:26:52 INFO - '--tppagecycles', 05:26:52 INFO - '7'], 05:26:52 INFO - 'tests': ['tsvgm']}}, 05:26:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:26:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:26:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:26:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:26:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['damp', 'tps']}, 05:26:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['damp', 'tps']}, 05:26:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['dromaeo_dom']}, 05:26:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:26:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore', 05:26:52 INFO - 'tabpaint']}, 05:26:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['tsvgx', 05:26:52 INFO - 'tsvgr_opacity', 05:26:52 INFO - 'tart', 05:26:52 INFO - 'tscrollx', 05:26:52 INFO - 'cart']}, 05:26:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:26:52 INFO - 'tsvgr_opacity', 05:26:52 INFO - 'tart', 05:26:52 INFO - 'tscrollx', 05:26:52 INFO - 'cart']}, 05:26:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--disable-e10s'], 05:26:52 INFO - 'tests': ['tp5o']}, 05:26:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['tp5o']}, 05:26:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--disable-e10s', 05:26:52 INFO - '--xperf_path', 05:26:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:52 INFO - 'tests': ['tp5n']}, 05:26:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--xperf_path', 05:26:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:52 INFO - 'tests': ['tp5n']}}, 05:26:52 INFO - 'talos.zip': {'path': '', 05:26:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:26:52 INFO - Running post-action listener: _resource_record_post_action 05:26:52 INFO - ##### 05:26:52 INFO - ##### Finished populate-webroot step (success) 05:26:52 INFO - ##### 05:26:52 INFO - ##### 05:26:52 INFO - ##### Running create-virtualenv step. 05:26:52 INFO - ##### 05:26:52 INFO - Running pre-action listener: _resource_record_pre_action 05:26:52 INFO - Running main action method: create_virtualenv 05:26:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:26:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:26:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:26:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:26:52 INFO - Using real prefix '/tools/python27' 05:26:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:26:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:26:56 INFO - Installing pip.................done. 05:26:56 INFO - Return code: 0 05:26:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:56 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:56 INFO - 'HOME': '/Users/cltbld', 05:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:56 INFO - 'LOGNAME': 'cltbld', 05:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:56 INFO - 'MOZ_NO_REMOTE': '1', 05:26:56 INFO - 'NO_EM_RESTART': '1', 05:26:56 INFO - 'PAGER': '/bin/cat', 05:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:56 INFO - 'PWD': '/builds/slave/test', 05:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:56 INFO - 'SHELL': '/bin/bash', 05:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:26:56 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:56 INFO - 'USER': 'cltbld', 05:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:56 INFO - 'XPC_FLAGS': '0x0', 05:26:56 INFO - 'XPC_SERVICE_NAME': '0', 05:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:26:56 INFO - Cleaning up... 05:26:56 INFO - Return code: 0 05:26:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:56 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:56 INFO - 'HOME': '/Users/cltbld', 05:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:56 INFO - 'LOGNAME': 'cltbld', 05:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:56 INFO - 'MOZ_NO_REMOTE': '1', 05:26:56 INFO - 'NO_EM_RESTART': '1', 05:26:56 INFO - 'PAGER': '/bin/cat', 05:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:56 INFO - 'PWD': '/builds/slave/test', 05:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:56 INFO - 'SHELL': '/bin/bash', 05:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:26:56 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:56 INFO - 'USER': 'cltbld', 05:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:56 INFO - 'XPC_FLAGS': '0x0', 05:26:56 INFO - 'XPC_SERVICE_NAME': '0', 05:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:57 INFO - Downloading/unpacking psutil>=3.1.1 05:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:27:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:00 INFO - Installing collected packages: psutil 05:27:00 INFO - Running setup.py install for psutil 05:27:00 INFO - building 'psutil._psutil_osx' extension 05:27:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:27:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:27:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:27:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:27:01 INFO - building 'psutil._psutil_posix' extension 05:27:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:27:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:27:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:27:01 INFO - ^ 05:27:01 INFO - 1 warning generated. 05:27:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:01 INFO - Successfully installed psutil 05:27:01 INFO - Cleaning up... 05:27:01 INFO - Return code: 0 05:27:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:01 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:01 INFO - 'HOME': '/Users/cltbld', 05:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:01 INFO - 'LOGNAME': 'cltbld', 05:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:01 INFO - 'MOZ_NO_REMOTE': '1', 05:27:01 INFO - 'NO_EM_RESTART': '1', 05:27:01 INFO - 'PAGER': '/bin/cat', 05:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:01 INFO - 'PWD': '/builds/slave/test', 05:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:01 INFO - 'SHELL': '/bin/bash', 05:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:01 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:01 INFO - 'USER': 'cltbld', 05:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:01 INFO - 'XPC_FLAGS': '0x0', 05:27:01 INFO - 'XPC_SERVICE_NAME': '0', 05:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:27:04 INFO - Installing collected packages: mozsystemmonitor 05:27:04 INFO - Running setup.py install for mozsystemmonitor 05:27:04 INFO - Successfully installed mozsystemmonitor 05:27:04 INFO - Cleaning up... 05:27:04 INFO - Return code: 0 05:27:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:04 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:04 INFO - 'HOME': '/Users/cltbld', 05:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:04 INFO - 'LOGNAME': 'cltbld', 05:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:04 INFO - 'MOZ_NO_REMOTE': '1', 05:27:04 INFO - 'NO_EM_RESTART': '1', 05:27:04 INFO - 'PAGER': '/bin/cat', 05:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:04 INFO - 'PWD': '/builds/slave/test', 05:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:04 INFO - 'SHELL': '/bin/bash', 05:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:04 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:04 INFO - 'USER': 'cltbld', 05:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:04 INFO - 'XPC_FLAGS': '0x0', 05:27:04 INFO - 'XPC_SERVICE_NAME': '0', 05:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:27:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:27:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:08 INFO - Downloading docopt-0.6.1.tar.gz 05:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:27:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:27:08 INFO - Running setup.py install for blobuploader 05:27:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:27:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:27:08 INFO - Running setup.py install for requests 05:27:09 INFO - Running setup.py install for docopt 05:27:09 INFO - Successfully installed blobuploader requests docopt 05:27:09 INFO - Cleaning up... 05:27:09 INFO - Return code: 0 05:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:09 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:09 INFO - 'HOME': '/Users/cltbld', 05:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:09 INFO - 'LOGNAME': 'cltbld', 05:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:09 INFO - 'MOZ_NO_REMOTE': '1', 05:27:09 INFO - 'NO_EM_RESTART': '1', 05:27:09 INFO - 'PAGER': '/bin/cat', 05:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:09 INFO - 'PWD': '/builds/slave/test', 05:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:09 INFO - 'SHELL': '/bin/bash', 05:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:09 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:09 INFO - 'USER': 'cltbld', 05:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:09 INFO - 'XPC_FLAGS': '0x0', 05:27:09 INFO - 'XPC_SERVICE_NAME': '0', 05:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-WkMocY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-mt6yZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-riXw0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-nLalPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-v3swh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FOIjTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-hxarRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-7Z6zNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-viciwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-n8ZcMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-R1oBnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-LhlvBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ybJtad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-3lvLmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-BHFbg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-kGEKfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-rHpnGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:27:11 INFO - Running setup.py install for manifestparser 05:27:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:27:11 INFO - Running setup.py install for mozcrash 05:27:11 INFO - Running setup.py install for mozdebug 05:27:11 INFO - Running setup.py install for mozdevice 05:27:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:27:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:27:11 INFO - Running setup.py install for mozfile 05:27:12 INFO - Running setup.py install for mozhttpd 05:27:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozinfo 05:27:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozInstall 05:27:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozleak 05:27:12 INFO - Running setup.py install for mozlog 05:27:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for moznetwork 05:27:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:27:12 INFO - Running setup.py install for mozprocess 05:27:13 INFO - Running setup.py install for mozprofile 05:27:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozrunner 05:27:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Running setup.py install for mozscreenshot 05:27:13 INFO - Running setup.py install for moztest 05:27:13 INFO - Running setup.py install for mozversion 05:27:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:27:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:27:13 INFO - Cleaning up... 05:27:13 INFO - Return code: 0 05:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:13 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:13 INFO - 'HOME': '/Users/cltbld', 05:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:13 INFO - 'LOGNAME': 'cltbld', 05:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:13 INFO - 'MOZ_NO_REMOTE': '1', 05:27:13 INFO - 'NO_EM_RESTART': '1', 05:27:13 INFO - 'PAGER': '/bin/cat', 05:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:13 INFO - 'PWD': '/builds/slave/test', 05:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:13 INFO - 'SHELL': '/bin/bash', 05:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:13 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:13 INFO - 'USER': 'cltbld', 05:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:13 INFO - 'XPC_FLAGS': '0x0', 05:27:13 INFO - 'XPC_SERVICE_NAME': '0', 05:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Gefy6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-fpPD_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-gV4gnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-jAe523-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-b78R2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Fsjd4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-D06E7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:14 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-3JFkq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-wrAzMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FHEaAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-LBrS8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ITvhxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-wTTusA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-h4Sbnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-IbtA3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Lcujya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:15 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-jh3rZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:27:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:18 INFO - Downloading blessings-1.6.tar.gz 05:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:27:18 INFO - Installing collected packages: blessings 05:27:18 INFO - Running setup.py install for blessings 05:27:19 INFO - Successfully installed blessings 05:27:19 INFO - Cleaning up... 05:27:19 INFO - Return code: 0 05:27:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:19 INFO - Reading from file tmpfile_stdout 05:27:19 INFO - Current package versions: 05:27:19 INFO - blessings == 1.6 05:27:19 INFO - blobuploader == 1.2.4 05:27:19 INFO - docopt == 0.6.1 05:27:19 INFO - manifestparser == 1.1 05:27:19 INFO - mozInstall == 1.12 05:27:19 INFO - mozcrash == 0.17 05:27:19 INFO - mozdebug == 0.1 05:27:19 INFO - mozdevice == 0.48 05:27:19 INFO - mozfile == 1.2 05:27:19 INFO - mozhttpd == 0.7 05:27:19 INFO - mozinfo == 0.9 05:27:19 INFO - mozleak == 0.1 05:27:19 INFO - mozlog == 3.2 05:27:19 INFO - moznetwork == 0.27 05:27:19 INFO - mozprocess == 0.22 05:27:19 INFO - mozprofile == 0.28 05:27:19 INFO - mozrunner == 6.11 05:27:19 INFO - mozscreenshot == 0.1 05:27:19 INFO - mozsystemmonitor == 0.3 05:27:19 INFO - moztest == 0.7 05:27:19 INFO - mozversion == 1.4 05:27:19 INFO - psutil == 3.1.1 05:27:19 INFO - requests == 1.2.3 05:27:19 INFO - wsgiref == 0.1.2 05:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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')]} 05:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:19 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 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:19 INFO - 'HOME': '/Users/cltbld', 05:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:19 INFO - 'LOGNAME': 'cltbld', 05:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:19 INFO - 'MOZ_NO_REMOTE': '1', 05:27:19 INFO - 'NO_EM_RESTART': '1', 05:27:19 INFO - 'PAGER': '/bin/cat', 05:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:19 INFO - 'PWD': '/builds/slave/test', 05:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:19 INFO - 'SHELL': '/bin/bash', 05:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:19 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:19 INFO - 'USER': 'cltbld', 05:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:19 INFO - 'XPC_FLAGS': '0x0', 05:27:19 INFO - 'XPC_SERVICE_NAME': '0', 05:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:27:20 INFO - Cleaning up... 05:27:20 INFO - Return code: 0 05:27:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:20 INFO - 'HOME': '/Users/cltbld', 05:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:20 INFO - 'LOGNAME': 'cltbld', 05:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:20 INFO - 'MOZ_NO_REMOTE': '1', 05:27:20 INFO - 'NO_EM_RESTART': '1', 05:27:20 INFO - 'PAGER': '/bin/cat', 05:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:20 INFO - 'PWD': '/builds/slave/test', 05:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:20 INFO - 'SHELL': '/bin/bash', 05:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:20 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:20 INFO - 'USER': 'cltbld', 05:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:20 INFO - 'XPC_FLAGS': '0x0', 05:27:20 INFO - 'XPC_SERVICE_NAME': '0', 05:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:20 INFO - Downloading/unpacking jsonschema 05:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:27:22 INFO - Downloading/unpacking functools32 (from jsonschema) 05:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:23 INFO - Downloading functools32-3.2.3-2.tar.gz 05:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:27:23 INFO - warning: no files found matching '*.txt' 05:27:23 INFO - no previously-included directories found matching 'build' 05:27:23 INFO - no previously-included directories found matching 'dist' 05:27:23 INFO - no previously-included directories found matching '.git*' 05:27:23 INFO - Installing collected packages: jsonschema, functools32 05:27:23 INFO - Running setup.py install for functools32 05:27:23 INFO - warning: no files found matching '*.txt' 05:27:23 INFO - no previously-included directories found matching 'build' 05:27:23 INFO - no previously-included directories found matching 'dist' 05:27:23 INFO - no previously-included directories found matching '.git*' 05:27:23 INFO - Successfully installed jsonschema functools32 05:27:23 INFO - Cleaning up... 05:27:23 INFO - Return code: 0 05:27:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9cb564890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, '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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:27:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:23 INFO - 'HOME': '/Users/cltbld', 05:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:23 INFO - 'LOGNAME': 'cltbld', 05:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:23 INFO - 'MOZ_NO_REMOTE': '1', 05:27:23 INFO - 'NO_EM_RESTART': '1', 05:27:23 INFO - 'PAGER': '/bin/cat', 05:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:23 INFO - 'PWD': '/builds/slave/test', 05:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:23 INFO - 'SHELL': '/bin/bash', 05:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:27:23 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:23 INFO - 'USER': 'cltbld', 05:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:23 INFO - 'XPC_FLAGS': '0x0', 05:27:23 INFO - 'XPC_SERVICE_NAME': '0', 05:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:23 INFO - Downloading/unpacking flake8 05:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:27:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:27:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:27:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:27:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:27:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:27:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:27:27 INFO - no previously-included directories found matching 'docs/_build' 05:27:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:27:27 INFO - Running setup.py install for pep8 05:27:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:27:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:27:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:27:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:27:27 INFO - no previously-included directories found matching 'docs/_build' 05:27:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:27:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:27:27 INFO - Cleaning up... 05:27:27 INFO - Return code: 0 05:27:27 INFO - Running post-action listener: _resource_record_post_action 05:27:27 INFO - Running post-action listener: _start_resource_monitoring 05:27:27 INFO - Starting resource monitoring. 05:27:27 INFO - ##### 05:27:27 INFO - ##### Finished create-virtualenv step (success) 05:27:27 INFO - ##### 05:27:27 INFO - ##### 05:27:27 INFO - ##### Running install step. 05:27:27 INFO - ##### 05:27:27 INFO - Running pre-action listener: _resource_record_pre_action 05:27:27 INFO - Running main action method: install 05:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:28 INFO - Reading from file tmpfile_stdout 05:27:28 INFO - Detecting whether we're running mozinstall >=1.0... 05:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:27:28 INFO - Reading from file tmpfile_stdout 05:27:28 INFO - Output received: 05:27:28 INFO - Usage: mozinstall [options] installer 05:27:28 INFO - Options: 05:27:28 INFO - -h, --help show this help message and exit 05:27:28 INFO - -d DEST, --destination=DEST 05:27:28 INFO - Directory to install application into. [default: 05:27:28 INFO - "/builds/slave/test"] 05:27:28 INFO - --app=APP Application being installed. [default: firefox] 05:27:28 INFO - mkdir: /builds/slave/test/build/application 05:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:28:01 INFO - Reading from file tmpfile_stdout 05:28:01 INFO - Output received: 05:28:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:28:01 INFO - Running post-action listener: _resource_record_post_action 05:28:01 INFO - ##### 05:28:01 INFO - ##### Finished install step (success) 05:28:01 INFO - ##### 05:28:01 INFO - ##### 05:28:01 INFO - ##### Running run-tests step. 05:28:01 INFO - ##### 05:28:01 INFO - Running pre-action listener: _resource_record_pre_action 05:28:01 INFO - Running main action method: run_tests 05:28:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:28:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:28:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:28:02 INFO - 2016-06-02 05:28:02.010 screenresolution[1945:19275] starting screenresolution argv=screenresolution get 05:28:02 INFO - 2016-06-02 05:28:02.013 screenresolution[1945:19275] Display 0: 1600x1200x32@60 05:28:02 INFO - 2016-06-02 05:28:02.019 screenresolution[1946:19277] starting screenresolution argv=screenresolution list 05:28:02 INFO - Available Modes on Display 0 05:28:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:28:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:28:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:28:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:28:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:28:02 INFO - Intel Iris: 05:28:02 INFO - Chipset Model: Intel Iris 05:28:02 INFO - Type: GPU 05:28:02 INFO - Bus: Built-In 05:28:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:28:02 INFO - Vendor: Intel (0x8086) 05:28:02 INFO - Device ID: 0x0a2e 05:28:02 INFO - Revision ID: 0x0009 05:28:02 INFO - Displays: 05:28:02 INFO - Display: 05:28:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:28:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:28:02 INFO - Display Serial Number: 1600x1200 60 05:28:02 INFO - Main Display: Yes 05:28:02 INFO - Mirror: Off 05:28:02 INFO - Online: Yes 05:28:02 INFO - Rotation: Supported 05:28:02 INFO - Return code: 0 05:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:28:02 INFO - Python 2.7.3 05:28:02 INFO - Return code: 0 05:28:02 INFO - grabbing minidump binary from tooltool 05:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:02 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 0x101274d30>, '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 0x101098db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:28:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:28:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:28:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:28:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:28:02 INFO - Return code: 0 05:28:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:28:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:28:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:28:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:28:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:28:03 INFO - Return code: 0 05:28:03 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:28:03 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0198 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RDCSgY6SxT/Render', 05:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:03 INFO - 'HOME': '/Users/cltbld', 05:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:03 INFO - 'LOGNAME': 'cltbld', 05:28:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:28:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:03 INFO - 'MOZ_NO_REMOTE': '1', 05:28:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:28:03 INFO - 'NO_EM_RESTART': '1', 05:28:03 INFO - 'PAGER': '/bin/cat', 05:28:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:03 INFO - 'PWD': '/builds/slave/test', 05:28:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:03 INFO - 'SHELL': '/bin/bash', 05:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OUDl9BqNTq/Listeners', 05:28:03 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 05:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:03 INFO - 'USER': 'cltbld', 05:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:03 INFO - 'XPC_FLAGS': '0x0', 05:28:03 INFO - 'XPC_SERVICE_NAME': '0', 05:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:28:03 INFO - mozversion application_buildid: 20160602030245 05:28:03 INFO - mozversion application_changeset: 58ac3bd12914f8d2d683176139e5630b4e32c2ca 05:28:03 INFO - mozversion application_display_name: Nightly 05:28:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:28:03 INFO - mozversion application_name: Firefox 05:28:03 INFO - mozversion application_remotingname: firefox 05:28:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:28:03 INFO - mozversion application_vendor: Mozilla 05:28:03 INFO - mozversion application_version: 49.0a1 05:28:03 INFO - mozversion platform_buildid: 20160602030245 05:28:03 INFO - mozversion platform_changeset: 58ac3bd12914f8d2d683176139e5630b4e32c2ca 05:28:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:28:03 INFO - mozversion platform_version: 49.0a1 05:28:03 INFO - using testdate: 1464870483 05:28:03 INFO - actual date: 1464870483 05:28:03 INFO - starting webserver on 'localhost:57192' 05:28:03 INFO - SUITE-START | Running 2 tests 05:28:03 INFO - TEST-START | dromaeo_css 05:28:03 INFO - operating with platform_type : mac_ 05:28:03 INFO - Initialising browser for dromaeo_css test... 05:28:03 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpWCDPaz/profile http://localhost:57192/getInfo.html) 05:28:05 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 05:28:05 INFO - PROCESS | 1964 | colorDepth:24 05:28:05 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 05:28:05 INFO - PROCESS | 1964 | __metrics 05:28:05 INFO - PROCESS | 1964 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:28:05 ERROR - PROCESS | 1964 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:28:06 INFO - TEST-INFO | 1964: exit 0 05:28:06 INFO - Browser initialized. 05:28:06 INFO - Running cycle 1/1 for dromaeo_css test... 05:28:06 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpWCDPaz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:18 INFO - PROCESS | 1966 | RSS: Main: 242229248 05:28:18 INFO - PROCESS | 1966 | 05:29:09 INFO - PROCESS | 1966 | 1464870549892 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:31:20 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57192/tests/dromaeo/cssquery-ext.html) 05:31:21 INFO - PROCESS | 1966 | RSS: Main: 371208192 05:31:21 INFO - PROCESS | 1966 | 05:34:24 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-ext.html (next: http://localhost:57192/tests/dromaeo/cssquery-jquery.html) 05:34:25 INFO - PROCESS | 1966 | RSS: Main: 372285440 05:34:25 INFO - PROCESS | 1966 | 05:37:27 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57192/tests/dromaeo/cssquery-mootools.html) 05:37:28 INFO - PROCESS | 1966 | RSS: Main: 373362688 05:37:28 INFO - PROCESS | 1966 | 05:40:30 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57192/tests/dromaeo/cssquery-prototype.html) 05:40:31 INFO - PROCESS | 1966 | RSS: Main: 369115136 05:40:31 INFO - PROCESS | 1966 | 05:43:34 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57192/tests/dromaeo/cssquery-yui.html) 05:43:35 INFO - PROCESS | 1966 | RSS: Main: 338280448 05:43:35 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:57192/tests/dromaeo/cssquery-yui.html (next: http://localhost:57192/tests/dromaeo/cssquery-dojo.html) 05:46:38 INFO - PROCESS | 1966 | RSS: Main: 378572800 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | __start_tp_report 05:46:38 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 05:46:38 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 05:46:38 INFO - PROCESS | 1966 | |i|pagename|runs| 05:46:38 INFO - PROCESS | 1966 | |0;dojo.html;3384.3400536895306;3402.897913062609;3407.7614566980305;3408.437607794713;3474.8783792567265;5501.697406642633;5534.16987451882;5538.363088244853;5580.023495888218;5649.265595472582;5539.723013849301;5568.832935011946;5649.8587535311635;5681.687507187103;5704.600677952545;5033.84898453047;5050.520200580939;5117.539421452068;5130.794768209285;5205.947940520603;5195.714235717034;5221.138512145491;5222.764975576109;5240.842774716764;5263.578913686915;5059.316992206061;5061.645684802065;5090.643654944155;5108.846734597968;5132.8973420531565;5054.545090941796;5075.619328550373;5082.771275292621;5179.81870634526;5182.974085129588;5079.288899554065;5115.795368185268;5121.00140472608;5171.612129090296;5174.948250517485;4466.218411777926;5451.3458384993655;5557.3886872444;5565.916511252334;5649.915251271234;6047.485963693091;6118.4799292060225;6125.877482450326;6134.69326533676;6138.24286178211;5591.776328946838;6075.8152160328755;6106.938930610681;6202.81391558254;6212;5542.944570554324;5617.43825617435;5731.627444216113;5742.7415766290615;5747.3677895431465;5092.668976516515;6275.843103922347;6282.465990390777;6327.240731112296;6342.746290148435;5986.940130598726;6000.849978750566;6053.606515576562;6063.999440092335;6098.69506524672;5996.730147143389;6107.114468402146;6143.631382116998;6145.139680444741;6153.384661533811;5618.595351162209;5732.570057245724;6012.909806352908;6113.694315284218;6123.571350005457;6074.271087469532;6098.190188918898;6103.82966888341;6128.754849805958;6141.631502109798;4866;4876.926846097312;4903.141950158708;4913.828016019422;4927.457979622239;4272.5727427257025;4292.699511034198;4328.567143285646;4349.978250108792;4375.890602734893;4690.460597031385;5428.647137936022;5513.062779327524;5597.356304025007;5598.804041858515;6085.847853803691;6109.313572000111;6119.563485741553;6121.605402471149;6144.542183126696;5546.140348246028;5600.439956004367;5600.803971861047;5604.971975140098;5639.830805075854;5281.234221037928;5294.97352513235;5297.947020529822;5300.9734951325;5335.1730481775385;6195.19462469885;6230.532710046674;6279.403456671609;6284.371562843679;6323.494120470259;5185.611079169002;6205.503559715122;6217.96891015542;6218.689065546614;6229.4393504584805;6274.152989346562;6298.7480500781285;6314.778982735582;6315.336889626523;6325.652089135142;5839.678817665069;5905.232319798399;5931.673757943355;6045.939540604538;6060.545459090674;6146;6150.537848506262;6176.104464852661;6198.6280823150755;6199.132121503083;5928.229330187048;5941.0197317442135;5945.910811337747;5955.612885162451;5968.194293770285;5764.423557644202;5815.709214539341;5829.795957141479;5914.112883067574;5916.852078698067;5806.65160090396;5809.651420914759;5809.738561764815;5810.82567522975;5866.178734977192;5972.402759723993;5982.7307771149535;6012.947734146419;6017.969910150421;6022.54830887694;5670.74481648335;5693.6299140557385;5707.315122185364;5766.423357664368;5780.653160810365 05:46:38 INFO - PROCESS | 1966 | |1;ext.html;494.9232868905319;499.8575406009286;506.03347606072396;515.0163188310328;552.7788884446221;31488.740450381985;32104.67895321046;36150.63849361506;43294.78352608236;43805.34291985615;45759;46243.843903902416;46268;46309;46594.06811863769;9518.048195180496;9759.609615615367;9764.170045546138;9784.070513301225;9794.167495762833;9440.05599440054;9582.568784404719;9596.760080997961;9610.663626773065;9611.759706007337;9625.51872406377;9648.75878103046;9664.613415463373;9680.612775488971;9701.514924253794;9574.52127393631;9606;9620.182284505825;9621.374610650286;9630.18143457807;9590.712278631652;9660.903390966141;9673;9677.854832177523;9702.466364349957;42436.363454548205;42699.57300427018;42734.35898461495;42757.78621106874;42799.786001070104;3527.8588856445713;3601.981990090033;3615.403458429382;3618.5476815398074;3640.8179591020607;31592.684073159206;32828.835855820565;33079.83460082708;33194.8381806638;33378;3334;3337.6161741399806;3356;3412.7440441966705;3450.4996775467666;503731;503941;504252.99999999633;504642.95357046695;506668;33534.161645959044;35016;64760.352396475435;65159.674201629634;65673.34326656768;36576;36727.26545469076;36765.44851827225;36799;36844.078898027765;15494.922525387301;15658.530244092695;15668;15669.686606267811;15674.902756807141;13128.60614181576;13216.074874758868;13238.271895045673;13240.801387979189;13252.933735331262;7887.566183859943;7916.050073991043;7925.524468531907;7959.681612735426;8026.277635012877;3279.7868138570916;3301.0688747082745;3303;3309.0900002499507;3337.3825842219267;944.1691311645708;998.3311181508407;1003.2926786615419;1066.0367058076158;1172.2380452705809;31498.842505787325;31567.84216078905;31601.525977110363;32262.838685806422;32304.030879073667;855.6748435594434;1083.3391631105019;1139.188689982931;1142.5429828068839;1156.9247998880214;16321.755173672404;16998.57503562421;17038.48884533466;17048;17442.47672569825;676964;692339;692626;694733;696875;62831.42921427001;63352.366476334646;63421.36578634247;63506.729865402434;63581.046284305776;1319.920804751718;1362.2507620808515;1429.1425144912969;1434.476416108113;1478.637733755237;27265.31836704098;30913.69086309108;32131;32381;32475.18812029718;53989.460105398444;54371.72814135904;54976.450235497134;55054.17418738642;55113.44886551244;593944;598153.009234951;598219;599125.0262492133;599943;24218.878905605357;26615.467690646467;26649.733502665502;27159.59260611132;27758.861205693844;49712.751436242586;50149.49850501448;50306.49693503018;50985.49014509956;51077.23384149167;39264.60735392724;40484.79757601193;40903.38644920269;41372.58627413688;41460.792696036326;39790.40314395229;39904;40165.397519036655;40663;41273.380899285934;38837.2232555353;39316.60683393129;39445.80277098596;39716.404253936795;39723.80138099291;33206.66793332036;34293.82853085719;35063;35438.82280588581;35491.46762798508;25202.747972520774;25687;25795.484090318463;25840.741592583832;26902 05:46:38 INFO - PROCESS | 1966 | |2;jquery.html;392.7996721671947;394.43004857980213;396.18979187561433;396.68661757211805;419.59299479504887;19937.10283037265;20111.698324525107;21133.68299475509;21161.153553857846;21195.834229117394;19813.40559783205;20429;20606.48483787906;20679.069441875115;20708.27521036764;4121.237571049358;5306.893862122755;5309.123994540896;5336.91994620081;5337.626366154371;5171.276021357013;5278.762455689484;5343.919841202385;5463.836084917459;5467.45325467454;5461.808836690717;5474.39781624021;5478.01395748765;5481.177823326493;5483.8080667176655;4680.3681502997;5455.645383050109;5585.776568937237;5945.346011938684;5947.851303717442;5597.7201139943245;5748.396418376094;5759.769609215627;5767.307923049261;5939.287285525721;12902.741945161044;13163;13185.934070329587;13220.140690855063;18563.90718046401;2137.1878686099185;2208.5582883423217;2218.7781221877844;2259.9548009039727;2462.2243993142283;9479.2416606672;10168.59325626974;10189;10219.693409197736;10276.691699249035;1835.9816401836079;1944.474991752219;1948.5128717820544;1974.7334109895123;1974.792646772097;66603;69673.25816854568;89348;97675.51162244215;102734.99999999926;11075.446227688744;11447.599334023269;11631.302121872715;11634.6509604712;11727.706807329885;13237.470501179845;13869.583912482642;13870.791938120936;14176.645583860485;14850;7417.925820741832;7808.804779880435;7859.6463159157975;8267.8346433071;8790.692325768567;8453.619587118594;8524.31805455562;8542.402031857835;8605.139486051345;8739.956300218586;3674.9081272967856;4568.428946381702;4577.359169716243;4618;4667.953320466752;1879.1355976250804;1938.4669215965723;1951.4048215294188;1961.3821646181407;1984.087319832893;839.9496030238205;905.9501727405056;976.4873441443299;1001.0040010189762;1020.8315627921459;20154.39536813898;20737.27419540309;20799;20957;21005.634633748756;924.191332583989;950.9904900951041;954.5418199264392;957.621739412928;971.2084651009546;8994.190522852974;9628.75928101803;10322.967703229617;11108.888911110947;11155.776884462266;585488;589093;601751;601981;602222;131915;132967;134061.3296933509;134738.54722348513;136359.95460067908;987.5950860147306;994.4033579852176;1007.214372789211;1016.1819735113305;1018.1701912940929;16218.91890540587;18313.3590324338;18542.05308442594;18694.906525467828;18845;47142.764286178346;51861.740691296305;52785;53085.73457132844;53637.46362536325;68040.63918721498;73200.6339968318;74055.25944740484;74996.62501687674;75930.62034689791;7974.800629984296;8003.279704826594;8019.117897031205;8305.418620696493;8412.747617571482;11487.942560287145;12566.623001309976;12577.559785407468;12695;12780.297083660487;9539;10752.30110042876;10951.616693415692;11030.558777649125;11077.224594278323;11212.775744484901;11268.605598804003;11292.604758833402;11442.82835757481;11542.480588373375;11451.427428628369;11675.941620291844;11833.467493962964;11884.286942783461;11915.046796256105;7578.825282081286;7698;8084.312833409226;8372.87440688378;8545.444546104483;8372.958135209285;10181.778186617654;10393.428361440194;10816.513256903301;11642.94178529102 05:46:38 INFO - PROCESS | 1966 | |3;mootools.html;1344.8251727275454;1542.583502454337;1574.90550566966;1812.9002904840236;1982.4052784164746;7775.688972441103;8506.234438900497;8721.08428614995;8731.912680873189;8872.20150186483;8457.43537445573;8709.782255443617;8712.651493940235;8715.172058654434;8722.127787221274;2107.4625970377533;2314.317276403465;2368.301351101425;2403.110848985869;2403.531311394279;2401.411654144737;2421.648860915166;2423.587990041697;2425.9029638814427;2434.853908765471;2308.6883270758485;2344.9413764655933;2345.413646588353;2349.130821596012;2366.7869891709743;2093.748750149984;2299.597570425169;2336.906523739048;2376.8930398132125;2378.096323397115;2387.749286324928;2406.350285422926;2409.9397515062265;2410.14439873844;2413.179518963543;8734.956325218332;8785.868211976825;8792.252658524034;8798.912010879938;8803;5058.544730974193;5120.539151476348;5250;5277.6305658603915;5280.471952804727;3425.160835595295;3523.6828685418177;3597.190632107781;3598.7660802048044;3609.6029436761937;1061.294239330844;1083.4041277297533;1088.3850624397269;1089.340948726025;1101.9669409917715;11236.381998990053;11243.831342529787;11244.718882027933;11248.325100493996;11255.718607034807;4554.521775213621;4637.628989680885;4673.158831410379;4705.623550115983;4707.670463067552;9569.61721531131;9576.090271424204;9581.664641737505;9585.233181345475;9588.945216026232;5250.842474725764;5251.448597897166;5254.185601231815;5258.605604579672;5263.131583288715;3810.390337546035;4443.822247110144;4600.079984003212;4889.217725163957;4898.706077635354;3888.747231429948;3926.430667553189;3976.6619837313574;4020.9396859047138;4028.8187031583648;1111.7331840358256;1276.9170003949714;1287.9162854414433;1289.8903593194495;1300.7203451258024;908.2960705453327;915.5193523400135;919.3656377099779;940.149165005671;967.1537404770826;5071.46749591295;5686.2323586315315;5724.8282551523525;5912.586118971631;5935.495482884004;436.47622852577035;437.343984023964;440.61886468205415;447.1258689262484;447.63965008168736;3737.2151848112203;3812.409076593077;3826.7129965252716;3853.9036524087123;3865.4781604483037;9453.810923781486;9817.116459518678;10591.629292974863;10651.84022239667;10661.84007239892;10465.895341046493;10578.312409693346;10578.735531611772;10581.418022008864;10590.576376944837;7725.7295994641045;8070.556119413489;8086.231807978233;8192.262696357237;8198.795030124294;5704.572157088152;5874.794382196774;5894.882102358015;5954.940450595439;6083.513318934386;9610;9768;9850.655227067018;9863.96428375039;9864.950675246579;9743.853842192231;10061.245406594388;10121.443320617436;10228.284020118817;10236.181105511392;6995.650217489004;7230.457715671451;7364.5581265124265;7494.6252687366505;7639.770806875803;8267.29727973129;8466.830663386574;8485.915140848512;8496.022957359983;8506.957465212634;7242.746503872339;7332.450066244946;7470.663820127998;7568.356689681439;7622.69509219625;7266.091738532684;7387.187409385071;7420.183779784327;7484.925150748423;7512.286332798375;6905.930940690529;6952.930470695228;6973.546719463218;6994.790156295319;6995.090638217;4454.5990860822685;4873.87815304606;4898.926516102332;4917.360743103373;4971.502849715;4945.727984960718;4955.8017679292425;4984.053029924303;4996.575291100152;5080.339555857715 05:46:38 INFO - PROCESS | 1966 | |4;prototype.html;307.70152951636913;313.21851979311623;316.6469386633903;319.21473175987086;323.9530268111125;4311.5688431156905;4438.711483753554;5089.592832573395;5192.324997750287;5227.640813388527;4332.1335732853395;5191.325127733398;5193.376794784631;5195.896082078356;5200.063988482072;3488.982555087221;3727.869524566634;3900.108447403007;3903.921921561567;3950.5259368875777;3504.649535046488;3631.4008188648972;3686.889393318205;3943.684505239574;3997.3011470125225;3407.3526030054336;3857.864974725885;3915.00167457298;3946.921061578781;3959.227950549644;3951.5379309655323;3965.9603403966166;3990.64084232419;4001.539822920358;4003.539592946819;3049.9390012199856;3281.8523166457567;3307.4046671599103;3487.5291835602125;3489.9651003489894;4316.848910288125;4378.5183629800695;4753.976230118862;4933.30933669283;4969.35398398206;3870.168533152159;4028.315186418316;4048.898777530556;4128.979355103235;4382.320740285261;5042.899142017176;5064.696118232881;5436;5453.872989534085;5465.7155568441385;3706.5737440194453;3710.1652128271194;3725.925481490355;3728.179800443898;3751.1934933989323;5228.875791704763;5357.464253574651;5578.219049333015;5699.287589051369;5758.74085666146;3753.5683396409217;3777.4522694209186;3784.1863999240295;3801.6008319126645;3814.939932436453;3689.594144644087;3765.3056124743425;3833.923321533554;3847.845646306153;3878.980605097013;3511.719062474996;3960.386140148282;4085.6313135100077;4166.062635906957;4364.214821731157;4619.399478067897;5259.815906443256;5259.868503287372;5375.166849138312;5519.254900588369;1140.0423644138964;1297.9681153483004;1310.4103153580927;1316.8683131686946;1327.4026687990442;680.0173748932745;768.9887797546209;787.9487833290817;802.4142376065505;812.2811311988814;3787.886363409102;3923.7645741255615;4246.426732391088;4294.9785251073545;4298.41971333866;4353.978230108829;4438.667099967515;4710.574054362006;4718.976405117953;4732.787024583902;3601.549806274216;3778.6410291022307;3980.641742243212;4014.538328092307;4081.918361632751;2083.4687154775434;2282.429392651837;2397.2808157552663;2492.7756501914914;2513.183215454948;4310.719803212781;4943.950560494421;4946.159152944008;4968.552830245295;4992.500749924979;4452.866414007585;4765.761711914391;4840.83057093;4911.16510193268;4960.255961605788;4097.340577066293;4386.824527018884;4676.836310729108;4911.3724157575525;4956.281339205867;4116.727201874577;4299.656027517729;4422.147714724049;4746.881327966828;4757.429108507002;3930.646241838134;4005;4307.741535507879;4514.367988481549;4595.425571803525;4596.448426188878;4698.801805539566;5364.544013758874;5601.691906944994;5638.2670252866865;3221.5811944447073;3760.6530188132037;3766.8681596145098;3772.396416573335;3790.3935370340614;5288.074586947192;5420.349558053059;5640.5769567281795;5643.137764537611;5681.289838770153;4917.852464426073;5074.391073071255;5309.964556911365;5318.335208098988;5528;4836.879078023077;5440.911817636409;5466.972665136649;5477.4796394342475;5530.419305972815;4654.6741728078705;4708.081924024782;5157.355330583677;5259.395169555397;5404.378496472956;5087.669301495391;5152.896942061213;5480.369757477941;5510.338759348904;5573;4569.5887370135515;4800.5199480051715;5397.865053373697;5431.619786614899;5434.809781657623 05:46:38 INFO - PROCESS | 1966 | |5;yui.html;711.580167701056;736.7089999450214;737.4211244173323;781.644351819922;827.1067247372836;15173.772393414107;15224.847751522482;15559.066456012633;15600.765988510168;16041.518754437357;12836.871631283684;12852.678683032906;12859.09986300957;12871.163374380658;12882.484700611987;735.1545722419233;754.8414832885101;900.2527901841472;909.3725329522641;922.3451349541833;924.3483344242327;924.6809850601566;930.7207837648713;933.2021121940745;934.103260869566;990.8761404824397;994.8905620381752;996.1433167475965;997.2969056814965;998.5606333213399;771.8610650083011;946.0492205333595;973.2749101919045;977.8484334928097;986.8865080515761;718.0987860235372;718.3893690363202;719.1801346465035;721.0374150509076;726.0670038999859;14938.32777525014;15000.24998750069;15002;15012.624684382869;15012.949093563455;13607.319634018359;13701.383437745326;13729.588112356545;13870.098443601133;13910.930445347709;10371.79256414875;12511.499540018389;12526.498940042387;12544.686382840411;12671.873281267162;4543.204939135671;4563.498015218356;4673.345731597579;4718.646101542398;4737;100739.49630251802;102289.48855255677;103032;103141;105134.94865051328;9859.161971232383;9907.207423406111;10286.022827831344;10398.116160126474;10737.946310268555;19491.902540487208;20731.585368292548;21916.561668766535;21957.890210549165;21983.560328793654;7554.320111190019;7756.495827771177;7894.250046245712;7968.721094761656;8050.396220283502;4410.25025745624;4410.3825464435;4470.865874023784;4472.664550158751;4594.081183763194;3460.567429071366;3559.5906470756195;3566.821658917044;3568.982155089208;3846.8845934622004;622.2501885228289;743.5129989856653;751.9849603008018;757.3184134279082;759.8860170974398;628.3339659960457;685.8422562810581;700.3451772592642;706.7526365771938;715.9176694680074;9178.724638260863;9455;9537.332386732862;9632.662856799978;9667.920579955573;605.8273392083236;685.6160550091964;701.1446035836271;707.2361849202848;720.8270015196315;4461.5092339842595;4657.115148121846;4679.625629949596;4699.483056863743;4756.476787553366;94752.05247947571;140593;142591.86112208324;146749;147583.35874885655;125897;127740;129608;129827.35086324697;132286;75156;93902;95363.52318238365;97586.51206743921;104394.95605043853;9840.55717492729;9877.567752675965;10037.247206459399;10044.698659040241;10046.54790534413;49681;50617.49382506275;54320.18519722286;56762.432375675715;60944.695276523336;80243.59878200572;80743.59628201822;80914.59542702249;84415.64254701238;87993;10462.581496740046;12370.257784532956;12664.6833829155;12672.429740661508;12907;14919.776203356741;14949.177795221367;15043.247837608295;15172.24138793078;15529.45646902353;12837.037222208559;13891.791623125458;13931.164130152223;14095;14292.499762508261;13714.108582942077;13877.444902203799;14049.297535122998;14161.787573186617;14729.852701473277;13680.179189248676;13730.65673358174;13877;14201.43194272259;14332;11105.278156919774;11313.943430282796;11448.656540303804;11785.764284714429;12207.206531575419;11393.145514086642;11443.25618834773;11686.357250350971;11696.70758231017;11723.941380293385 05:46:38 INFO - PROCESS | 1966 | __end_tp_report 05:46:38 INFO - PROCESS | 1966 | __start_cc_report 05:46:38 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,1270 05:46:38 INFO - PROCESS | 1966 | __end_cc_report 05:46:38 INFO - PROCESS | 1966 | __startTimestamp1464871598628__endTimestamp 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | ------- Summary: start ------- 05:46:38 INFO - PROCESS | 1966 | Number of tests: 6 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5616.54 Median:5747.37 stddev:624.36 (10.9%) stddev-sans-first:601.21 05:46:38 INFO - PROCESS | 1966 | Values: 3384.3 3402.9 3407.8 3408.4 3474.9 5501.7 5534.2 5538.4 5580.0 5649.3 5539.7 5568.8 5649.9 5681.7 5704.6 5033.8 5050.5 5117.5 5130.8 5205.9 5195.7 5221.1 5222.8 5240.8 5263.6 5059.3 5061.6 5090.6 5108.8 5132.9 5054.5 5075.6 5082.8 5179.8 5183.0 5079.3 5115.8 5121.0 5171.6 5174.9 4466.2 5451.3 5557.4 5565.9 5649.9 6047.5 6118.5 6125.9 6134.7 6138.2 5591.8 6075.8 6106.9 6202.8 6212.0 5542.9 5617.4 5731.6 5742.7 5747.4 5092.7 6275.8 6282.5 6327.2 6342.7 5986.9 6000.8 6053.6 6064.0 6098.7 5996.7 6107.1 6143.6 6145.1 6153.4 5618.6 5732.6 6012.9 6113.7 6123.6 6074.3 6098.2 6103.8 6128.8 6141.6 4866.0 4876.9 4903.1 4913.8 4927.5 4272.6 4292.7 4328.6 4350.0 4375.9 4690.5 5428.6 5513.1 5597.4 5598.8 6085.8 6109.3 6119.6 6121.6 6144.5 5546.1 5600.4 5600.8 5605.0 5639.8 5281.2 5295.0 5297.9 5301.0 5335.2 6195.2 6230.5 6279.4 6284.4 6323.5 5185.6 6205.5 6218.0 6218.7 6229.4 6274.2 6298.7 6314.8 6315.3 6325.7 5839.7 5905.2 5931.7 6045.9 6060.5 6146.0 6150.5 6176.1 6198.6 6199.1 5928.2 5941.0 5945.9 5955.6 5968.2 5764.4 5815.7 5829.8 5914.1 5916.9 5806.7 5809.7 5809.7 5810.8 5866.2 5972.4 5982.7 6012.9 6018.0 6022.5 5670.7 5693.6 5707.3 5766.4 5780.7 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:72141.88 Median:40325.10 stddev:161431.47 (400.3%) stddev-sans-first:161794.70 05:46:38 INFO - PROCESS | 1966 | Values: 494.9 499.9 506.0 515.0 552.8 31488.7 32104.7 36150.6 43294.8 43805.3 45759.0 46243.8 46268.0 46309.0 46594.1 9518.0 9759.6 9764.2 9784.1 9794.2 9440.1 9582.6 9596.8 9610.7 9611.8 9625.5 9648.8 9664.6 9680.6 9701.5 9574.5 9606.0 9620.2 9621.4 9630.2 9590.7 9660.9 9673.0 9677.9 9702.5 42436.4 42699.6 42734.4 42757.8 42799.8 3527.9 3602.0 3615.4 3618.5 3640.8 31592.7 32828.8 33079.8 33194.8 33378.0 3334.0 3337.6 3356.0 3412.7 3450.5 503731.0 503941.0 504253.0 504643.0 506668.0 33534.2 35016.0 64760.4 65159.7 65673.3 36576.0 36727.3 36765.4 36799.0 36844.1 15494.9 15658.5 15668.0 15669.7 15674.9 13128.6 13216.1 13238.3 13240.8 13252.9 7887.6 7916.1 7925.5 7959.7 8026.3 3279.8 3301.1 3303.0 3309.1 3337.4 944.2 998.3 1003.3 1066.0 1172.2 31498.8 31567.8 31601.5 32262.8 32304.0 855.7 1083.3 1139.2 1142.5 1156.9 16321.8 16998.6 17038.5 17048.0 17442.5 676964.0 692339.0 692626.0 694733.0 696875.0 62831.4 63352.4 63421.4 63506.7 63581.0 1319.9 1362.3 1429.1 1434.5 1478.6 27265.3 30913.7 32131.0 32381.0 32475.2 53989.5 54371.7 54976.5 55054.2 55113.4 593944.0 598153.0 598219.0 599125.0 599943.0 24218.9 26615.5 26649.7 27159.6 27758.9 49712.8 50149.5 50306.5 50985.5 51077.2 39264.6 40484.8 40903.4 41372.6 41460.8 39790.4 39904.0 40165.4 40663.0 41273.4 38837.2 39316.6 39445.8 39716.4 39723.8 33206.7 34293.8 35063.0 35438.8 35491.5 25202.7 25687.0 25795.5 25840.7 26902.0 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:33628.55 Median:3068.57 stddev:99104.26 (3229.7%) stddev-sans-first:99350.86 05:46:38 INFO - PROCESS | 1966 | Values: 392.8 394.4 396.2 396.7 419.6 19937.1 20111.7 21133.7 21161.2 21195.8 19813.4 20429.0 20606.5 20679.1 20708.3 4121.2 5306.9 5309.1 5336.9 5337.6 5171.3 5278.8 5343.9 5463.8 5467.5 5461.8 5474.4 5478.0 5481.2 5483.8 4680.4 5455.6 5585.8 5945.3 5947.9 5597.7 5748.4 5759.8 5767.3 5939.3 12902.7 13163.0 13185.9 13220.1 18563.9 2137.2 2208.6 2218.8 2260.0 2462.2 9479.2 10168.6 10189.0 10219.7 10276.7 1836.0 1944.5 1948.5 1974.7 1974.8 66603.0 69673.3 89348.0 97675.5 102735.0 11075.4 11447.6 11631.3 11634.7 11727.7 13237.5 13869.6 13870.8 14176.6 14850.0 7417.9 7808.8 7859.6 8267.8 8790.7 8453.6 8524.3 8542.4 8605.1 8740.0 3674.9 4568.4 4577.4 4618.0 4668.0 1879.1 1938.5 1951.4 1961.4 1984.1 839.9 906.0 976.5 1001.0 1020.8 20154.4 20737.3 20799.0 20957.0 21005.6 924.2 951.0 954.5 957.6 971.2 8994.2 9628.8 10323.0 11108.9 11155.8 585488.0 589093.0 601751.0 601981.0 602222.0 131915.0 132967.0 134061.3 134738.5 136360.0 987.6 994.4 1007.2 1016.2 1018.2 16218.9 18313.4 18542.1 18694.9 18845.0 47142.8 51861.7 52785.0 53085.7 53637.5 68040.6 73200.6 74055.3 74996.6 75930.6 7974.8 8003.3 8019.1 8305.4 8412.7 11487.9 12566.6 12577.6 12695.0 12780.3 9539.0 10752.3 10951.6 11030.6 11077.2 11212.8 11268.6 11292.6 11442.8 11542.5 11451.4 11675.9 11833.5 11884.3 11915.0 7578.8 7698.0 8084.3 8372.9 8545.4 8373.0 10181.8 10393.4 10816.5 11642.9 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:5628.79 Median:4931.54 stddev:3150.99 (63.9%) stddev-sans-first:3143.38 05:46:38 INFO - PROCESS | 1966 | Values: 1344.8 1542.6 1574.9 1812.9 1982.4 7775.7 8506.2 8721.1 8731.9 8872.2 8457.4 8709.8 8712.7 8715.2 8722.1 2107.5 2314.3 2368.3 2403.1 2403.5 2401.4 2421.6 2423.6 2425.9 2434.9 2308.7 2344.9 2345.4 2349.1 2366.8 2093.7 2299.6 2336.9 2376.9 2378.1 2387.7 2406.4 2409.9 2410.1 2413.2 8735.0 8785.9 8792.3 8798.9 8803.0 5058.5 5120.5 5250.0 5277.6 5280.5 3425.2 3523.7 3597.2 3598.8 3609.6 1061.3 1083.4 1088.4 1089.3 1102.0 11236.4 11243.8 11244.7 11248.3 11255.7 4554.5 4637.6 4673.2 4705.6 4707.7 9569.6 9576.1 9581.7 9585.2 9588.9 5250.8 5251.4 5254.2 5258.6 5263.1 3810.4 4443.8 4600.1 4889.2 4898.7 3888.7 3926.4 3976.7 4020.9 4028.8 1111.7 1276.9 1287.9 1289.9 1300.7 908.3 915.5 919.4 940.1 967.2 5071.5 5686.2 5724.8 5912.6 5935.5 436.5 437.3 440.6 447.1 447.6 3737.2 3812.4 3826.7 3853.9 3865.5 9453.8 9817.1 10591.6 10651.8 10661.8 10465.9 10578.3 10578.7 10581.4 10590.6 7725.7 8070.6 8086.2 8192.3 8198.8 5704.6 5874.8 5894.9 5954.9 6083.5 9610.0 9768.0 9850.7 9864.0 9865.0 9743.9 10061.2 10121.4 10228.3 10236.2 6995.7 7230.5 7364.6 7494.6 7639.8 8267.3 8466.8 8485.9 8496.0 8507.0 7242.7 7332.5 7470.7 7568.4 7622.7 7266.1 7387.2 7420.2 7484.9 7512.3 6905.9 6952.9 6973.5 6994.8 6995.1 4454.6 4873.9 4898.9 4917.4 4971.5 4945.7 4955.8 4984.1 4996.6 5080.3 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:4150.53 Median:4380.42 stddev:1265.93 (28.9%) stddev-sans-first:1236.19 05:46:38 INFO - PROCESS | 1966 | Values: 307.7 313.2 316.6 319.2 324.0 4311.6 4438.7 5089.6 5192.3 5227.6 4332.1 5191.3 5193.4 5195.9 5200.1 3489.0 3727.9 3900.1 3903.9 3950.5 3504.6 3631.4 3686.9 3943.7 3997.3 3407.4 3857.9 3915.0 3946.9 3959.2 3951.5 3966.0 3990.6 4001.5 4003.5 3049.9 3281.9 3307.4 3487.5 3490.0 4316.8 4378.5 4754.0 4933.3 4969.4 3870.2 4028.3 4048.9 4129.0 4382.3 5042.9 5064.7 5436.0 5453.9 5465.7 3706.6 3710.2 3725.9 3728.2 3751.2 5228.9 5357.5 5578.2 5699.3 5758.7 3753.6 3777.5 3784.2 3801.6 3814.9 3689.6 3765.3 3833.9 3847.8 3879.0 3511.7 3960.4 4085.6 4166.1 4364.2 4619.4 5259.8 5259.9 5375.2 5519.3 1140.0 1298.0 1310.4 1316.9 1327.4 680.0 769.0 787.9 802.4 812.3 3787.9 3923.8 4246.4 4295.0 4298.4 4354.0 4438.7 4710.6 4719.0 4732.8 3601.5 3778.6 3980.6 4014.5 4081.9 2083.5 2282.4 2397.3 2492.8 2513.2 4310.7 4944.0 4946.2 4968.6 4992.5 4452.9 4765.8 4840.8 4911.2 4960.3 4097.3 4386.8 4676.8 4911.4 4956.3 4116.7 4299.7 4422.1 4746.9 4757.4 3930.6 4005.0 4307.7 4514.4 4595.4 4596.4 4698.8 5364.5 5601.7 5638.3 3221.6 3760.7 3766.9 3772.4 3790.4 5288.1 5420.3 5640.6 5643.1 5681.3 4917.9 5074.4 5310.0 5318.3 5528.0 4836.9 5440.9 5467.0 5477.5 5530.4 4654.7 4708.1 5157.4 5259.4 5404.4 5087.7 5152.9 5480.4 5510.3 5573.0 4569.6 4800.5 5397.9 5431.6 5434.8 05:46:38 INFO - PROCESS | 1966 | 05:46:38 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:23390.39 Median:4128.57 stddev:36530.85 (884.8%) stddev-sans-first:36593.64 05:46:38 INFO - PROCESS | 1966 | Values: 711.6 736.7 737.4 781.6 827.1 15173.8 15224.8 15559.1 15600.8 16041.5 12836.9 12852.7 12859.1 12871.2 12882.5 735.2 754.8 900.3 909.4 922.3 924.3 924.7 930.7 933.2 934.1 990.9 994.9 996.1 997.3 998.6 771.9 946.0 973.3 977.8 986.9 718.1 718.4 719.2 721.0 726.1 14938.3 15000.2 15002.0 15012.6 15012.9 13607.3 13701.4 13729.6 13870.1 13910.9 10371.8 12511.5 12526.5 12544.7 12671.9 4543.2 4563.5 4673.3 4718.6 4737.0 100739.5 102289.5 103032.0 103141.0 105134.9 9859.2 9907.2 10286.0 10398.1 10737.9 19491.9 20731.6 21916.6 21957.9 21983.6 7554.3 7756.5 7894.3 7968.7 8050.4 4410.3 4410.4 4470.9 4472.7 4594.1 3460.6 3559.6 3566.8 3569.0 3846.9 622.3 743.5 752.0 757.3 759.9 628.3 685.8 700.3 706.8 715.9 9178.7 9455.0 9537.3 9632.7 9667.9 605.8 685.6 701.1 707.2 720.8 4461.5 4657.1 4679.6 4699.5 4756.5 94752.1 140593.0 142591.9 146749.0 147583.4 125897.0 127740.0 129608.0 129827.4 132286.0 75156.0 93902.0 95363.5 97586.5 104395.0 9840.6 9877.6 10037.2 10044.7 10046.5 49681.0 50617.5 54320.2 56762.4 60944.7 80243.6 80743.6 80914.6 84415.6 87993.0 10462.6 12370.3 12664.7 12672.4 12907.0 14919.8 14949.2 15043.2 15172.2 15529.5 12837.0 13891.8 13931.2 14095.0 14292.5 13714.1 13877.4 14049.3 14161.8 14729.9 13680.2 13730.7 13877.0 14201.4 14332.0 11105.3 11313.9 11448.7 11785.8 12207.2 11393.1 11443.3 11686.4 11696.7 11723.9 05:46:38 INFO - PROCESS | 1966 | -------- Summary: end -------- 05:46:38 INFO - PROCESS | 1966 | 05:46:39 INFO - TEST-INFO | 1966: exit 0 05:46:39 INFO - TEST-OK | dromaeo_css | took 1115536ms 05:46:39 INFO - TEST-START | kraken 05:46:39 INFO - operating with platform_type : mac_ 05:46:39 INFO - Initialising browser for kraken test... 05:46:39 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmphPb7bY/profile http://localhost:57192/getInfo.html) 05:46:41 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 05:46:41 INFO - PROCESS | 1969 | colorDepth:24 05:46:41 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 05:46:41 INFO - PROCESS | 1969 | __metrics 05:46:41 INFO - PROCESS | 1969 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:46:41 ERROR - PROCESS | 1969 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:46:42 INFO - TEST-INFO | 1969: exit 0 05:46:42 INFO - Browser initialized. 05:46:42 INFO - Running cycle 1/1 for kraken test... 05:46:42 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmphPb7bY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:54 INFO - PROCESS | 1970 | RSS: Main: 242257920 05:46:54 INFO - PROCESS | 1970 | 05:46:57 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57192/tests/kraken/driver.html?testName=audio-beat-detection) 05:46:57 INFO - PROCESS | 1970 | RSS: Main: 292024320 05:46:57 INFO - PROCESS | 1970 | 05:47:00 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57192/tests/kraken/driver.html?testName=audio-dft) 05:47:01 INFO - PROCESS | 1970 | RSS: Main: 302256128 05:47:01 INFO - PROCESS | 1970 | 05:47:04 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57192/tests/kraken/driver.html?testName=audio-fft) 05:47:05 INFO - PROCESS | 1970 | RSS: Main: 387768320 05:47:05 INFO - PROCESS | 1970 | 05:47:07 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57192/tests/kraken/driver.html?testName=audio-oscillator) 05:47:08 INFO - PROCESS | 1970 | RSS: Main: 288591872 05:47:08 INFO - PROCESS | 1970 | 05:47:10 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57192/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:47:10 INFO - PROCESS | 1970 | RSS: Main: 292356096 05:47:10 INFO - PROCESS | 1970 | 05:47:13 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57192/tests/kraken/driver.html?testName=imaging-darkroom) 05:47:14 INFO - PROCESS | 1970 | RSS: Main: 331096064 05:47:14 INFO - PROCESS | 1970 | 05:47:16 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57192/tests/kraken/driver.html?testName=imaging-desaturate) 05:47:17 INFO - PROCESS | 1970 | RSS: Main: 332849152 05:47:17 INFO - PROCESS | 1970 | 05:47:20 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57192/tests/kraken/driver.html?testName=json-parse-financial) 05:47:20 INFO - PROCESS | 1970 | RSS: Main: 329416704 05:47:20 INFO - PROCESS | 1970 | 05:47:22 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57192/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:47:23 INFO - PROCESS | 1970 | RSS: Main: 309977088 05:47:23 INFO - PROCESS | 1970 | 05:47:25 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:47:25 INFO - PROCESS | 1970 | RSS: Main: 317464576 05:47:25 INFO - PROCESS | 1970 | 05:47:27 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:47:28 INFO - PROCESS | 1970 | RSS: Main: 320135168 05:47:28 INFO - PROCESS | 1970 | 05:47:31 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:47:32 INFO - PROCESS | 1970 | RSS: Main: 324943872 05:47:32 INFO - PROCESS | 1970 | 05:47:34 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:47:35 INFO - PROCESS | 1970 | RSS: Main: 325378048 05:47:35 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:57192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57192/tests/kraken/driver.html?testName=ai-astar) 05:47:37 INFO - PROCESS | 1970 | RSS: Main: 369442816 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | __start_tp_report 05:47:37 INFO - PROCESS | 1970 | _x_x_mozilla_page_load 05:47:37 INFO - PROCESS | 1970 | _x_x_mozilla_page_load_details 05:47:37 INFO - PROCESS | 1970 | |i|pagename|runs| 05:47:37 INFO - PROCESS | 1970 | |0;ai-astar;111;106;111;110;110;111;110;110;111;109 05:47:37 INFO - PROCESS | 1970 | |1;audio-beat-detection;155;116;115;173;117;118;140;117;150;118 05:47:37 INFO - PROCESS | 1970 | |2;audio-dft;180;186;183;194;269;184;179;184;185;181 05:47:37 INFO - PROCESS | 1970 | |3;audio-fft;78;73;62;118;87;82;71;60;82;54 05:47:37 INFO - PROCESS | 1970 | |4;audio-oscillator;85;84;92;69;71;68;95;69;67;98 05:47:37 INFO - PROCESS | 1970 | |5;imaging-gaussian-blur;86;83;87;82;86;83;86;84;86;83 05:47:37 INFO - PROCESS | 1970 | |6;imaging-darkroom;92;92;92;92;92;93;93;92;93;93 05:47:37 INFO - PROCESS | 1970 | |7;imaging-desaturate;85;86;83;84;85;100;139;85;89;87 05:47:37 INFO - PROCESS | 1970 | |8;json-parse-financial;52;54;55;56;52;56;57;57;53;55 05:47:37 INFO - PROCESS | 1970 | |9;json-stringify-tinderbox;67;70;77;78;74;71;71;72;71;73 05:47:37 INFO - PROCESS | 1970 | |10;stanford-crypto-aes;82;87;85;86;86;86;83;87;122;80 05:47:37 INFO - PROCESS | 1970 | |11;stanford-crypto-ccm;142;127;144;142;144;135;163;154;165;144 05:47:37 INFO - PROCESS | 1970 | |12;stanford-crypto-pbkdf2;164;165;165;166;167;213;186;164;166;161 05:47:37 INFO - PROCESS | 1970 | |13;stanford-crypto-sha256-iterative;66;68;68;64;65;67;66;67;66;65 05:47:37 INFO - PROCESS | 1970 | __end_tp_report 05:47:37 INFO - PROCESS | 1970 | __start_cc_report 05:47:37 INFO - PROCESS | 1970 | _x_x_mozilla_cycle_collect,1941 05:47:37 INFO - PROCESS | 1970 | __end_cc_report 05:47:37 INFO - PROCESS | 1970 | __startTimestamp1464871657578__endTimestamp 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | ------- Summary: start ------- 05:47:37 INFO - PROCESS | 1970 | Number of tests: 14 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:1.52 (1.4%) stddev-sans-first:1.56 05:47:37 INFO - PROCESS | 1970 | Values: 111.0 106.0 111.0 110.0 110.0 111.0 110.0 110.0 111.0 109.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:129.00 stddev:21.04 (16.3%) stddev-sans-first:20.59 05:47:37 INFO - PROCESS | 1970 | Values: 155.0 116.0 115.0 173.0 117.0 118.0 140.0 117.0 150.0 118.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#2] audio-dft Cycles:10 Average:192.50 Median:184.50 stddev:27.20 (14.7%) stddev-sans-first:28.47 05:47:37 INFO - PROCESS | 1970 | Values: 180.0 186.0 183.0 194.0 269.0 184.0 179.0 184.0 185.0 181.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#3] audio-fft Cycles:10 Average:76.70 Median:75.50 stddev:18.03 (23.9%) stddev-sans-first:19.12 05:47:37 INFO - PROCESS | 1970 | Values: 78.0 73.0 62.0 118.0 87.0 82.0 71.0 60.0 82.0 54.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:84.50 stddev:12.34 (14.6%) stddev-sans-first:12.94 05:47:37 INFO - PROCESS | 1970 | Values: 85.0 84.0 92.0 69.0 71.0 68.0 95.0 69.0 67.0 98.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.81 05:47:37 INFO - PROCESS | 1970 | Values: 86.0 83.0 87.0 82.0 86.0 83.0 86.0 84.0 86.0 83.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:92.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 05:47:37 INFO - PROCESS | 1970 | Values: 92.0 92.0 92.0 92.0 92.0 93.0 93.0 92.0 93.0 93.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:85.00 stddev:17.11 (20.1%) stddev-sans-first:17.94 05:47:37 INFO - PROCESS | 1970 | Values: 85.0 86.0 83.0 84.0 85.0 100.0 139.0 85.0 89.0 87.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.50 stddev:1.89 (3.4%) stddev-sans-first:1.73 05:47:37 INFO - PROCESS | 1970 | Values: 52.0 54.0 55.0 56.0 52.0 56.0 57.0 57.0 53.0 55.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:3.27 (4.5%) stddev-sans-first:2.83 05:47:37 INFO - PROCESS | 1970 | Values: 67.0 70.0 77.0 78.0 74.0 71.0 71.0 72.0 71.0 73.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:86.00 stddev:12.03 (14.0%) stddev-sans-first:12.53 05:47:37 INFO - PROCESS | 1970 | Values: 82.0 87.0 85.0 86.0 86.0 86.0 83.0 87.0 122.0 80.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:144.00 stddev:11.74 (8.2%) stddev-sans-first:12.36 05:47:37 INFO - PROCESS | 1970 | Values: 142.0 127.0 144.0 142.0 144.0 135.0 163.0 154.0 165.0 144.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:166.00 stddev:16.06 (9.7%) stddev-sans-first:16.79 05:47:37 INFO - PROCESS | 1970 | Values: 164.0 165.0 165.0 166.0 167.0 213.0 186.0 164.0 166.0 161.0 05:47:37 INFO - PROCESS | 1970 | 05:47:37 INFO - PROCESS | 1970 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.32 (2.0%) stddev-sans-first:1.39 05:47:37 INFO - PROCESS | 1970 | Values: 66.0 68.0 68.0 64.0 65.0 67.0 66.0 67.0 66.0 65.0 05:47:37 INFO - PROCESS | 1970 | -------- Summary: end -------- 05:47:37 INFO - PROCESS | 1970 | 05:47:38 INFO - TEST-INFO | 1970: exit 0 05:47:38 INFO - TEST-OK | kraken | took 59142ms 05:47:38 INFO - SUITE-END | took 1174s 05:47:38 INFO - Completed test suite (00:19:35) 05:47:38 INFO - javascript benchmark 05:47:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3384.3400536895306, 3402.897913062609, 3407.7614566980305, 3408.437607794713, 3474.8783792567265, 5501.697406642633, 5534.16987451882, 5538.363088244853, 5580.023495888218, 5649.265595472582, 5539.723013849301, 5568.832935011946, 5649.8587535311635, 5681.687507187103, 5704.600677952545, 5033.84898453047, 5050.520200580939, 5117.539421452068, 5130.794768209285, 5205.947940520603, 5195.714235717034, 5221.138512145491, 5222.764975576109, 5240.842774716764, 5263.578913686915, 5059.316992206061, 5061.645684802065, 5090.643654944155, 5108.846734597968, 5132.8973420531565, 5054.545090941796, 5075.619328550373, 5082.771275292621, 5179.81870634526, 5182.974085129588, 5079.288899554065, 5115.795368185268, 5121.00140472608, 5171.612129090296, 5174.948250517485, 4466.218411777926, 5451.3458384993655, 5557.3886872444, 5565.916511252334, 5649.915251271234, 6047.485963693091, 6118.4799292060225, 6125.877482450326, 6134.69326533676, 6138.24286178211, 5591.776328946838, 6075.8152160328755, 6106.938930610681, 6202.81391558254, 6212.0, 5542.944570554324, 5617.43825617435, 5731.627444216113, 5742.7415766290615, 5747.3677895431465, 5092.668976516515, 6275.843103922347, 6282.465990390777, 6327.240731112296, 6342.746290148435, 5986.940130598726, 6000.849978750566, 6053.606515576562, 6063.999440092335, 6098.69506524672, 5996.730147143389, 6107.114468402146, 6143.631382116998, 6145.139680444741, 6153.384661533811, 5618.595351162209, 5732.570057245724, 6012.909806352908, 6113.694315284218, 6123.571350005457, 6074.271087469532, 6098.190188918898, 6103.82966888341, 6128.754849805958, 6141.631502109798, 4866.0, 4876.926846097312, 4903.141950158708, 4913.828016019422, 4927.457979622239, 4272.5727427257025, 4292.699511034198, 4328.567143285646, 4349.978250108792, 4375.890602734893, 4690.460597031385, 5428.647137936022, 5513.062779327524, 5597.356304025007, 5598.804041858515, 6085.847853803691, 6109.313572000111, 6119.563485741553, 6121.605402471149, 6144.542183126696, 5546.140348246028, 5600.439956004367, 5600.803971861047, 5604.971975140098, 5639.830805075854, 5281.234221037928, 5294.97352513235, 5297.947020529822, 5300.9734951325, 5335.1730481775385, 6195.19462469885, 6230.532710046674, 6279.403456671609, 6284.371562843679, 6323.494120470259, 5185.611079169002, 6205.503559715122, 6217.96891015542, 6218.689065546614, 6229.4393504584805, 6274.152989346562, 6298.7480500781285, 6314.778982735582, 6315.336889626523, 6325.652089135142, 5839.678817665069, 5905.232319798399, 5931.673757943355, 6045.939540604538, 6060.545459090674, 6146.0, 6150.537848506262, 6176.104464852661, 6198.6280823150755, 6199.132121503083, 5928.229330187048, 5941.0197317442135, 5945.910811337747, 5955.612885162451, 5968.194293770285, 5764.423557644202, 5815.709214539341, 5829.795957141479, 5914.112883067574, 5916.852078698067, 5806.65160090396, 5809.651420914759, 5809.738561764815, 5810.82567522975, 5866.178734977192, 5972.402759723993, 5982.7307771149535, 6012.947734146419, 6017.969910150421, 6022.54830887694, 5670.74481648335, 5693.6299140557385, 5707.315122185364, 5766.423357664368, 5780.653160810365], "value": 5578.955550599513, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.9232868905319, 499.8575406009286, 506.03347606072396, 515.0163188310328, 552.7788884446221, 31488.740450381985, 32104.67895321046, 36150.63849361506, 43294.78352608236, 43805.34291985615, 45759.0, 46243.843903902416, 46268.0, 46309.0, 46594.06811863769, 9518.048195180496, 9759.609615615367, 9764.170045546138, 9784.070513301225, 9794.167495762833, 9440.05599440054, 9582.568784404719, 9596.760080997961, 9610.663626773065, 9611.759706007337, 9625.51872406377, 9648.75878103046, 9664.613415463373, 9680.612775488971, 9701.514924253794, 9574.52127393631, 9606.0, 9620.182284505825, 9621.374610650286, 9630.18143457807, 9590.712278631652, 9660.903390966141, 9673.0, 9677.854832177523, 9702.466364349957, 42436.363454548205, 42699.57300427018, 42734.35898461495, 42757.78621106874, 42799.786001070104, 3527.8588856445713, 3601.981990090033, 3615.403458429382, 3618.5476815398074, 3640.8179591020607, 31592.684073159206, 32828.835855820565, 33079.83460082708, 33194.8381806638, 33378.0, 3334.0, 3337.6161741399806, 3356.0, 3412.7440441966705, 3450.4996775467666, 503731.0, 503941.0, 504252.99999999633, 504642.95357046695, 506668.0, 33534.161645959044, 35016.0, 64760.352396475435, 65159.674201629634, 65673.34326656768, 36576.0, 36727.26545469076, 36765.44851827225, 36799.0, 36844.078898027765, 15494.922525387301, 15658.530244092695, 15668.0, 15669.686606267811, 15674.902756807141, 13128.60614181576, 13216.074874758868, 13238.271895045673, 13240.801387979189, 13252.933735331262, 7887.566183859943, 7916.050073991043, 7925.524468531907, 7959.681612735426, 8026.277635012877, 3279.7868138570916, 3301.0688747082745, 3303.0, 3309.0900002499507, 3337.3825842219267, 944.1691311645708, 998.3311181508407, 1003.2926786615419, 1066.0367058076158, 1172.2380452705809, 31498.842505787325, 31567.84216078905, 31601.525977110363, 32262.838685806422, 32304.030879073667, 855.6748435594434, 1083.3391631105019, 1139.188689982931, 1142.5429828068839, 1156.9247998880214, 16321.755173672404, 16998.57503562421, 17038.48884533466, 17048.0, 17442.47672569825, 676964.0, 692339.0, 692626.0, 694733.0, 696875.0, 62831.42921427001, 63352.366476334646, 63421.36578634247, 63506.729865402434, 63581.046284305776, 1319.920804751718, 1362.2507620808515, 1429.1425144912969, 1434.476416108113, 1478.637733755237, 27265.31836704098, 30913.69086309108, 32131.0, 32381.0, 32475.18812029718, 53989.460105398444, 54371.72814135904, 54976.450235497134, 55054.17418738642, 55113.44886551244, 593944.0, 598153.009234951, 598219.0, 599125.0262492133, 599943.0, 24218.878905605357, 26615.467690646467, 26649.733502665502, 27159.59260611132, 27758.861205693844, 49712.751436242586, 50149.49850501448, 50306.49693503018, 50985.49014509956, 51077.23384149167, 39264.60735392724, 40484.79757601193, 40903.38644920269, 41372.58627413688, 41460.792696036326, 39790.40314395229, 39904.0, 40165.397519036655, 40663.0, 41273.380899285934, 38837.2232555353, 39316.60683393129, 39445.80277098596, 39716.404253936795, 39723.80138099291, 33206.66793332036, 34293.82853085719, 35063.0, 35438.82280588581, 35491.46762798508, 25202.747972520774, 25687.0, 25795.484090318463, 25840.741592583832, 26902.0], "value": 19317.402722616393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.7996721671947, 394.43004857980213, 396.18979187561433, 396.68661757211805, 419.59299479504887, 19937.10283037265, 20111.698324525107, 21133.68299475509, 21161.153553857846, 21195.834229117394, 19813.40559783205, 20429.0, 20606.48483787906, 20679.069441875115, 20708.27521036764, 4121.237571049358, 5306.893862122755, 5309.123994540896, 5336.91994620081, 5337.626366154371, 5171.276021357013, 5278.762455689484, 5343.919841202385, 5463.836084917459, 5467.45325467454, 5461.808836690717, 5474.39781624021, 5478.01395748765, 5481.177823326493, 5483.8080667176655, 4680.3681502997, 5455.645383050109, 5585.776568937237, 5945.346011938684, 5947.851303717442, 5597.7201139943245, 5748.396418376094, 5759.769609215627, 5767.307923049261, 5939.287285525721, 12902.741945161044, 13163.0, 13185.934070329587, 13220.140690855063, 18563.90718046401, 2137.1878686099185, 2208.5582883423217, 2218.7781221877844, 2259.9548009039727, 2462.2243993142283, 9479.2416606672, 10168.59325626974, 10189.0, 10219.693409197736, 10276.691699249035, 1835.9816401836079, 1944.474991752219, 1948.5128717820544, 1974.7334109895123, 1974.792646772097, 66603.0, 69673.25816854568, 89348.0, 97675.51162244215, 102734.99999999926, 11075.446227688744, 11447.599334023269, 11631.302121872715, 11634.6509604712, 11727.706807329885, 13237.470501179845, 13869.583912482642, 13870.791938120936, 14176.645583860485, 14850.0, 7417.925820741832, 7808.804779880435, 7859.6463159157975, 8267.8346433071, 8790.692325768567, 8453.619587118594, 8524.31805455562, 8542.402031857835, 8605.139486051345, 8739.956300218586, 3674.9081272967856, 4568.428946381702, 4577.359169716243, 4618.0, 4667.953320466752, 1879.1355976250804, 1938.4669215965723, 1951.4048215294188, 1961.3821646181407, 1984.087319832893, 839.9496030238205, 905.9501727405056, 976.4873441443299, 1001.0040010189762, 1020.8315627921459, 20154.39536813898, 20737.27419540309, 20799.0, 20957.0, 21005.634633748756, 924.191332583989, 950.9904900951041, 954.5418199264392, 957.621739412928, 971.2084651009546, 8994.190522852974, 9628.75928101803, 10322.967703229617, 11108.888911110947, 11155.776884462266, 585488.0, 589093.0, 601751.0, 601981.0, 602222.0, 131915.0, 132967.0, 134061.3296933509, 134738.54722348513, 136359.95460067908, 987.5950860147306, 994.4033579852176, 1007.214372789211, 1016.1819735113305, 1018.1701912940929, 16218.91890540587, 18313.3590324338, 18542.05308442594, 18694.906525467828, 18845.0, 47142.764286178346, 51861.740691296305, 52785.0, 53085.73457132844, 53637.46362536325, 68040.63918721498, 73200.6339968318, 74055.25944740484, 74996.62501687674, 75930.62034689791, 7974.800629984296, 8003.279704826594, 8019.117897031205, 8305.418620696493, 8412.747617571482, 11487.942560287145, 12566.623001309976, 12577.559785407468, 12695.0, 12780.297083660487, 9539.0, 10752.30110042876, 10951.616693415692, 11030.558777649125, 11077.224594278323, 11212.775744484901, 11268.605598804003, 11292.604758833402, 11442.82835757481, 11542.480588373375, 11451.427428628369, 11675.941620291844, 11833.467493962964, 11884.286942783461, 11915.046796256105, 7578.825282081286, 7698.0, 8084.312833409226, 8372.87440688378, 8545.444546104483, 8372.958135209285, 10181.778186617654, 10393.428361440194, 10816.513256903301, 11642.94178529102], "value": 9209.13056673361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.8251727275454, 1542.583502454337, 1574.90550566966, 1812.9002904840236, 1982.4052784164746, 7775.688972441103, 8506.234438900497, 8721.08428614995, 8731.912680873189, 8872.20150186483, 8457.43537445573, 8709.782255443617, 8712.651493940235, 8715.172058654434, 8722.127787221274, 2107.4625970377533, 2314.317276403465, 2368.301351101425, 2403.110848985869, 2403.531311394279, 2401.411654144737, 2421.648860915166, 2423.587990041697, 2425.9029638814427, 2434.853908765471, 2308.6883270758485, 2344.9413764655933, 2345.413646588353, 2349.130821596012, 2366.7869891709743, 2093.748750149984, 2299.597570425169, 2336.906523739048, 2376.8930398132125, 2378.096323397115, 2387.749286324928, 2406.350285422926, 2409.9397515062265, 2410.14439873844, 2413.179518963543, 8734.956325218332, 8785.868211976825, 8792.252658524034, 8798.912010879938, 8803.0, 5058.544730974193, 5120.539151476348, 5250.0, 5277.6305658603915, 5280.471952804727, 3425.160835595295, 3523.6828685418177, 3597.190632107781, 3598.7660802048044, 3609.6029436761937, 1061.294239330844, 1083.4041277297533, 1088.3850624397269, 1089.340948726025, 1101.9669409917715, 11236.381998990053, 11243.831342529787, 11244.718882027933, 11248.325100493996, 11255.718607034807, 4554.521775213621, 4637.628989680885, 4673.158831410379, 4705.623550115983, 4707.670463067552, 9569.61721531131, 9576.090271424204, 9581.664641737505, 9585.233181345475, 9588.945216026232, 5250.842474725764, 5251.448597897166, 5254.185601231815, 5258.605604579672, 5263.131583288715, 3810.390337546035, 4443.822247110144, 4600.079984003212, 4889.217725163957, 4898.706077635354, 3888.747231429948, 3926.430667553189, 3976.6619837313574, 4020.9396859047138, 4028.8187031583648, 1111.7331840358256, 1276.9170003949714, 1287.9162854414433, 1289.8903593194495, 1300.7203451258024, 908.2960705453327, 915.5193523400135, 919.3656377099779, 940.149165005671, 967.1537404770826, 5071.46749591295, 5686.2323586315315, 5724.8282551523525, 5912.586118971631, 5935.495482884004, 436.47622852577035, 437.343984023964, 440.61886468205415, 447.1258689262484, 447.63965008168736, 3737.2151848112203, 3812.409076593077, 3826.7129965252716, 3853.9036524087123, 3865.4781604483037, 9453.810923781486, 9817.116459518678, 10591.629292974863, 10651.84022239667, 10661.84007239892, 10465.895341046493, 10578.312409693346, 10578.735531611772, 10581.418022008864, 10590.576376944837, 7725.7295994641045, 8070.556119413489, 8086.231807978233, 8192.262696357237, 8198.795030124294, 5704.572157088152, 5874.794382196774, 5894.882102358015, 5954.940450595439, 6083.513318934386, 9610.0, 9768.0, 9850.655227067018, 9863.96428375039, 9864.950675246579, 9743.853842192231, 10061.245406594388, 10121.443320617436, 10228.284020118817, 10236.181105511392, 6995.650217489004, 7230.457715671451, 7364.5581265124265, 7494.6252687366505, 7639.770806875803, 8267.29727973129, 8466.830663386574, 8485.915140848512, 8496.022957359983, 8506.957465212634, 7242.746503872339, 7332.450066244946, 7470.663820127998, 7568.356689681439, 7622.69509219625, 7266.091738532684, 7387.187409385071, 7420.183779784327, 7484.925150748423, 7512.286332798375, 6905.930940690529, 6952.930470695228, 6973.546719463218, 6994.790156295319, 6995.090638217, 4454.5990860822685, 4873.87815304606, 4898.926516102332, 4917.360743103373, 4971.502849715, 4945.727984960718, 4955.8017679292425, 4984.053029924303, 4996.575291100152, 5080.339555857715], "value": 4461.521219520221, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.70152951636913, 313.21851979311623, 316.6469386633903, 319.21473175987086, 323.9530268111125, 4311.5688431156905, 4438.711483753554, 5089.592832573395, 5192.324997750287, 5227.640813388527, 4332.1335732853395, 5191.325127733398, 5193.376794784631, 5195.896082078356, 5200.063988482072, 3488.982555087221, 3727.869524566634, 3900.108447403007, 3903.921921561567, 3950.5259368875777, 3504.649535046488, 3631.4008188648972, 3686.889393318205, 3943.684505239574, 3997.3011470125225, 3407.3526030054336, 3857.864974725885, 3915.00167457298, 3946.921061578781, 3959.227950549644, 3951.5379309655323, 3965.9603403966166, 3990.64084232419, 4001.539822920358, 4003.539592946819, 3049.9390012199856, 3281.8523166457567, 3307.4046671599103, 3487.5291835602125, 3489.9651003489894, 4316.848910288125, 4378.5183629800695, 4753.976230118862, 4933.30933669283, 4969.35398398206, 3870.168533152159, 4028.315186418316, 4048.898777530556, 4128.979355103235, 4382.320740285261, 5042.899142017176, 5064.696118232881, 5436.0, 5453.872989534085, 5465.7155568441385, 3706.5737440194453, 3710.1652128271194, 3725.925481490355, 3728.179800443898, 3751.1934933989323, 5228.875791704763, 5357.464253574651, 5578.219049333015, 5699.287589051369, 5758.74085666146, 3753.5683396409217, 3777.4522694209186, 3784.1863999240295, 3801.6008319126645, 3814.939932436453, 3689.594144644087, 3765.3056124743425, 3833.923321533554, 3847.845646306153, 3878.980605097013, 3511.719062474996, 3960.386140148282, 4085.6313135100077, 4166.062635906957, 4364.214821731157, 4619.399478067897, 5259.815906443256, 5259.868503287372, 5375.166849138312, 5519.254900588369, 1140.0423644138964, 1297.9681153483004, 1310.4103153580927, 1316.8683131686946, 1327.4026687990442, 680.0173748932745, 768.9887797546209, 787.9487833290817, 802.4142376065505, 812.2811311988814, 3787.886363409102, 3923.7645741255615, 4246.426732391088, 4294.9785251073545, 4298.41971333866, 4353.978230108829, 4438.667099967515, 4710.574054362006, 4718.976405117953, 4732.787024583902, 3601.549806274216, 3778.6410291022307, 3980.641742243212, 4014.538328092307, 4081.918361632751, 2083.4687154775434, 2282.429392651837, 2397.2808157552663, 2492.7756501914914, 2513.183215454948, 4310.719803212781, 4943.950560494421, 4946.159152944008, 4968.552830245295, 4992.500749924979, 4452.866414007585, 4765.761711914391, 4840.83057093, 4911.16510193268, 4960.255961605788, 4097.340577066293, 4386.824527018884, 4676.836310729108, 4911.3724157575525, 4956.281339205867, 4116.727201874577, 4299.656027517729, 4422.147714724049, 4746.881327966828, 4757.429108507002, 3930.646241838134, 4005.0, 4307.741535507879, 4514.367988481549, 4595.425571803525, 4596.448426188878, 4698.801805539566, 5364.544013758874, 5601.691906944994, 5638.2670252866865, 3221.5811944447073, 3760.6530188132037, 3766.8681596145098, 3772.396416573335, 3790.3935370340614, 5288.074586947192, 5420.349558053059, 5640.5769567281795, 5643.137764537611, 5681.289838770153, 4917.852464426073, 5074.391073071255, 5309.964556911365, 5318.335208098988, 5528.0, 4836.879078023077, 5440.911817636409, 5466.972665136649, 5477.4796394342475, 5530.419305972815, 4654.6741728078705, 4708.081924024782, 5157.355330583677, 5259.395169555397, 5404.378496472956, 5087.669301495391, 5152.896942061213, 5480.369757477941, 5510.338759348904, 5573.0, 4569.5887370135515, 4800.5199480051715, 5397.865053373697, 5431.619786614899, 5434.809781657623], "value": 3756.160790560701, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.580167701056, 736.7089999450214, 737.4211244173323, 781.644351819922, 827.1067247372836, 15173.772393414107, 15224.847751522482, 15559.066456012633, 15600.765988510168, 16041.518754437357, 12836.871631283684, 12852.678683032906, 12859.09986300957, 12871.163374380658, 12882.484700611987, 735.1545722419233, 754.8414832885101, 900.2527901841472, 909.3725329522641, 922.3451349541833, 924.3483344242327, 924.6809850601566, 930.7207837648713, 933.2021121940745, 934.103260869566, 990.8761404824397, 994.8905620381752, 996.1433167475965, 997.2969056814965, 998.5606333213399, 771.8610650083011, 946.0492205333595, 973.2749101919045, 977.8484334928097, 986.8865080515761, 718.0987860235372, 718.3893690363202, 719.1801346465035, 721.0374150509076, 726.0670038999859, 14938.32777525014, 15000.24998750069, 15002.0, 15012.624684382869, 15012.949093563455, 13607.319634018359, 13701.383437745326, 13729.588112356545, 13870.098443601133, 13910.930445347709, 10371.79256414875, 12511.499540018389, 12526.498940042387, 12544.686382840411, 12671.873281267162, 4543.204939135671, 4563.498015218356, 4673.345731597579, 4718.646101542398, 4737.0, 100739.49630251802, 102289.48855255677, 103032.0, 103141.0, 105134.94865051328, 9859.161971232383, 9907.207423406111, 10286.022827831344, 10398.116160126474, 10737.946310268555, 19491.902540487208, 20731.585368292548, 21916.561668766535, 21957.890210549165, 21983.560328793654, 7554.320111190019, 7756.495827771177, 7894.250046245712, 7968.721094761656, 8050.396220283502, 4410.25025745624, 4410.3825464435, 4470.865874023784, 4472.664550158751, 4594.081183763194, 3460.567429071366, 3559.5906470756195, 3566.821658917044, 3568.982155089208, 3846.8845934622004, 622.2501885228289, 743.5129989856653, 751.9849603008018, 757.3184134279082, 759.8860170974398, 628.3339659960457, 685.8422562810581, 700.3451772592642, 706.7526365771938, 715.9176694680074, 9178.724638260863, 9455.0, 9537.332386732862, 9632.662856799978, 9667.920579955573, 605.8273392083236, 685.6160550091964, 701.1446035836271, 707.2361849202848, 720.8270015196315, 4461.5092339842595, 4657.115148121846, 4679.625629949596, 4699.483056863743, 4756.476787553366, 94752.05247947571, 140593.0, 142591.86112208324, 146749.0, 147583.35874885655, 125897.0, 127740.0, 129608.0, 129827.35086324697, 132286.0, 75156.0, 93902.0, 95363.52318238365, 97586.51206743921, 104394.95605043853, 9840.55717492729, 9877.567752675965, 10037.247206459399, 10044.698659040241, 10046.54790534413, 49681.0, 50617.49382506275, 54320.18519722286, 56762.432375675715, 60944.695276523336, 80243.59878200572, 80743.59628201822, 80914.59542702249, 84415.64254701238, 87993.0, 10462.581496740046, 12370.257784532956, 12664.6833829155, 12672.429740661508, 12907.0, 14919.776203356741, 14949.177795221367, 15043.247837608295, 15172.24138793078, 15529.45646902353, 12837.037222208559, 13891.791623125458, 13931.164130152223, 14095.0, 14292.499762508261, 13714.108582942077, 13877.444902203799, 14049.297535122998, 14161.787573186617, 14729.852701473277, 13680.179189248676, 13730.65673358174, 13877.0, 14201.43194272259, 14332.0, 11105.278156919774, 11313.943430282796, 11448.656540303804, 11785.764284714429, 12207.206531575419, 11393.145514086642, 11443.25618834773, 11686.357250350971, 11696.70758231017, 11723.941380293385], "value": 7887.4597619312635, "unit": "score"}], "name": "dromaeo_css", "value": 7128.35113844493, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 111.0, 110.0, 110.0, 111.0, 110.0, 110.0, 111.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 115.0, 173.0, 117.0, 118.0, 140.0, 117.0, 150.0, 118.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 186.0, 183.0, 194.0, 269.0, 184.0, 179.0, 184.0, 185.0, 181.0], "value": 192.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 62.0, 118.0, 87.0, 82.0, 71.0, 60.0, 82.0, 54.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 92.0, 69.0, 71.0, 68.0, 95.0, 69.0, 67.0, 98.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 82.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 84.0, 85.0, 100.0, 139.0, 85.0, 89.0, 87.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 55.0, 56.0, 52.0, 56.0, 57.0, 57.0, 53.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 77.0, 78.0, 74.0, 71.0, 71.0, 72.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 85.0, 86.0, 86.0, 86.0, 83.0, 87.0, 122.0, 80.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 127.0, 144.0, 142.0, 144.0, 135.0, 163.0, 154.0, 165.0, 144.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 165.0, 166.0, 167.0, 213.0, 186.0, 164.0, 166.0, 161.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 64.0, 65.0, 67.0, 66.0, 67.0, 66.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1459.5, "alertThreshold": 2.0}]} 05:47:38 INFO - Return code: 0 05:47:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:47:38 ERROR - # TBPL SUCCESS # 05:47:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:47:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:47:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:47:38 INFO - 2016-06-02 05:47:38.856 screenresolution[1974:21182] starting screenresolution argv=screenresolution get 05:47:38 INFO - 2016-06-02 05:47:38.859 screenresolution[1974:21182] Display 0: 1600x1200x32@60 05:47:38 INFO - 2016-06-02 05:47:38.865 screenresolution[1975:21186] starting screenresolution argv=screenresolution list 05:47:38 INFO - Available Modes on Display 0 05:47:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:47:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:47:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:47:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:47:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:47:39 INFO - Intel Iris: 05:47:39 INFO - Chipset Model: Intel Iris 05:47:39 INFO - Type: GPU 05:47:39 INFO - Bus: Built-In 05:47:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:47:39 INFO - Vendor: Intel (0x8086) 05:47:39 INFO - Device ID: 0x0a2e 05:47:39 INFO - Revision ID: 0x0009 05:47:39 INFO - Displays: 05:47:39 INFO - Display: 05:47:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:47:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:47:39 INFO - Display Serial Number: 1600x1200 60 05:47:39 INFO - Main Display: Yes 05:47:39 INFO - Mirror: Off 05:47:39 INFO - Online: Yes 05:47:39 INFO - Rotation: Supported 05:47:39 INFO - Return code: 0 05:47:39 INFO - Running post-action listener: _resource_record_post_action 05:47:39 INFO - ##### 05:47:39 INFO - ##### Finished run-tests step (success) 05:47:39 INFO - ##### 05:47:39 INFO - Running post-run listener: _resource_record_post_run 05:47:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 40960; Write bytes: 695731712; Read time: 1; Write time: 1855 05:47:39 INFO - TinderboxPrint: CPU usage
53.8% 05:47:39 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 1 05:47:39 INFO - TinderboxPrint: I/O write bytes / time
695,731,712 / 1,855 05:47:39 INFO - TinderboxPrint: CPU idle
2,235.1 (46.2%) 05:47:39 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 05:47:39 INFO - TinderboxPrint: CPU user
1,642.7 (33.9%) 05:47:39 INFO - TinderboxPrint: Swap in / out
399,081,472 / 0 05:47:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400579072; Write bytes: 402347520; Read time: 29306; Write time: 749 05:47:39 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 40960; Write bytes: 292536320; Read time: 1; Write time: 1103 05:47:39 INFO - Running post-run listener: _upload_blobber_files 05:47:39 INFO - Blob upload gear active. 05:47:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:47:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:47:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:47:41 INFO - (blobuploader) - INFO - Done attempting. 05:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:47:41 INFO - (blobuploader) - INFO - Done attempting. 05:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:47:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:47:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:47:44 INFO - (blobuploader) - INFO - Done attempting. 05:47:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:47:44 INFO - Return code: 0 05:47:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:47:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:47:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14cd7352062550bc886ccdaf710958083b3c04ca97fa00a1e12d85c5e67f72b04cd27b9afc73e899a6b81c5e794d9fbea953879ea417728217f5e9333828b065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3eb0a2bad52c8a28bab682bbc530a862f68f8050029622afc6e93ba6905c354bfb82079a1a88a0dc32cfddfb9d64eb1fef72f21f1336a4752c9ecf5a6bde588d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9837157677007d0c9d8d26319a2bd023acfc0d6a7301854ca8d33d08db9472cf888417f14fbcda75d5994c63ac280c6ef577a168597f4b57da1f1c8349f5e75e"} 05:47:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:47:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:47:44 INFO - Contents: 05:47:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14cd7352062550bc886ccdaf710958083b3c04ca97fa00a1e12d85c5e67f72b04cd27b9afc73e899a6b81c5e794d9fbea953879ea417728217f5e9333828b065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3eb0a2bad52c8a28bab682bbc530a862f68f8050029622afc6e93ba6905c354bfb82079a1a88a0dc32cfddfb9d64eb1fef72f21f1336a4752c9ecf5a6bde588d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9837157677007d0c9d8d26319a2bd023acfc0d6a7301854ca8d33d08db9472cf888417f14fbcda75d5994c63ac280c6ef577a168597f4b57da1f1c8349f5e75e"} 05:47:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:47:44 INFO - Copying logs to upload dir... 05:47:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:47:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.541015 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-06-02 05:47:44.448209) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 05:47:44.451538) ========= 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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14cd7352062550bc886ccdaf710958083b3c04ca97fa00a1e12d85c5e67f72b04cd27b9afc73e899a6b81c5e794d9fbea953879ea417728217f5e9333828b065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3eb0a2bad52c8a28bab682bbc530a862f68f8050029622afc6e93ba6905c354bfb82079a1a88a0dc32cfddfb9d64eb1fef72f21f1336a4752c9ecf5a6bde588d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9837157677007d0c9d8d26319a2bd023acfc0d6a7301854ca8d33d08db9472cf888417f14fbcda75d5994c63ac280c6ef577a168597f4b57da1f1c8349f5e75e"} build_url:https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011519 build_url: 'https://queue.taskcluster.net/v1/task/Wu2jFzmSQBCetguT19P3mA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14cd7352062550bc886ccdaf710958083b3c04ca97fa00a1e12d85c5e67f72b04cd27b9afc73e899a6b81c5e794d9fbea953879ea417728217f5e9333828b065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3eb0a2bad52c8a28bab682bbc530a862f68f8050029622afc6e93ba6905c354bfb82079a1a88a0dc32cfddfb9d64eb1fef72f21f1336a4752c9ecf5a6bde588d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9837157677007d0c9d8d26319a2bd023acfc0d6a7301854ca8d33d08db9472cf888417f14fbcda75d5994c63ac280c6ef577a168597f4b57da1f1c8349f5e75e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 05:47:44.484916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:47:44.485270) ========= 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.RDCSgY6SxT/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.OUDl9BqNTq/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005360 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:47:44.533979) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 05:47:44.534298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 05:47:44.534623) ========= ========= Total master_lag: 0.17 =========