builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1464438789.85 results: success (0) buildid: 20160528033932 builduid: a578fa7b871d4f22a35bf020ea4372fe revision: 199230f44725b8e4919a5014b9649a9951383355 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:09.855491) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:09.856019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:09.856406) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:10.065907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:10.066287) ========= 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.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-28 05:33:10-- 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% 18.7M=0.001s 2016-05-28 05:33:10 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.597957 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:10.723839) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:10.724122) ========= 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.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.026846 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:10.787202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 05:33:10.787522) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 199230f44725b8e4919a5014b9649a9951383355 --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 199230f44725b8e4919a5014b9649a9951383355 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-28 05:33:10,857 truncating revision to first 12 chars 2016-05-28 05:33:10,857 Setting DEBUG logging. 2016-05-28 05:33:10,857 attempt 1/10 2016-05-28 05:33:10,857 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/199230f44725?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 05:33:11,657 unpacking tar archive at: fx-team-199230f44725/testing/mozharness/ program finished with exit code 0 elapsedTime=1.079571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 05:33:11.886851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:11.887187) ========= script_repo_revision: 199230f44725b8e4919a5014b9649a9951383355 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:11.890597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:11.890939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 05:33:11.907049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-28 05:33:11.907393) ========= /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.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:33:12 INFO - MultiFileLogger online at 20160528 05:33:12 in /builds/slave/test 05:33:12 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:33:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:33:12 INFO - {'append_to_log': False, 05:33:12 INFO - 'base_work_dir': '/builds/slave/test', 05:33:12 INFO - 'blob_upload_branch': 'Fx-Team', 05:33:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:33:12 INFO - 'branch': 'Fx-Team', 05:33:12 INFO - 'buildbot_json_path': 'buildprops.json', 05:33:12 INFO - 'config_files': ('talos/mac_config.py',), 05:33:12 INFO - 'default_actions': ('clobber', 05:33:12 INFO - 'read-buildbot-config', 05:33:12 INFO - 'download-and-extract', 05:33:12 INFO - 'populate-webroot', 05:33:12 INFO - 'create-virtualenv', 05:33:12 INFO - 'install', 05:33:12 INFO - 'run-tests'), 05:33:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:33:12 INFO - 'download_minidump_stackwalk': True, 05:33:12 INFO - 'download_symbols': 'ondemand', 05:33:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:33:12 INFO - 'tooltool.py': '/tools/tooltool.py', 05:33:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:33:12 INFO - '/tools/misc-python/virtualenv.py')}, 05:33:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:33:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:33:12 INFO - 'installer_path': 'installer.exe', 05:33:12 INFO - 'log_level': 'info', 05:33:12 INFO - 'log_name': 'talos', 05:33:12 INFO - 'log_to_console': True, 05:33:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:33:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:33:12 INFO - 'opt_config_files': (), 05:33:12 INFO - 'pip_index': False, 05:33:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:33:12 INFO - 'cmd': ('bash', 05:33:12 INFO - '-c', 05:33:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:33:12 INFO - 'enabled': True, 05:33:12 INFO - 'halt_on_failure': False, 05:33:12 INFO - 'name': 'check_screen_resolution'},), 05:33:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:33:12 INFO - 'cmd': ('bash', 05:33:12 INFO - '-c', 05:33:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:33:12 INFO - 'enabled': True, 05:33:12 INFO - 'halt_on_failure': False, 05:33:12 INFO - 'name': 'check_screen_resolution'},), 05:33:12 INFO - 'run_cmd_checks_enabled': True, 05:33:12 INFO - 'sps_profile': False, 05:33:12 INFO - 'sps_profile_interval': 0, 05:33:12 INFO - 'suite': 'dromaeojs', 05:33:12 INFO - 'system_bits': '32', 05:33:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:33:12 INFO - 'title': 't-yosemite-r7-0296', 05:33:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:33:12 INFO - 'use_talos_json': True, 05:33:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:33:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:33:12 INFO - 'work_dir': 'build'} 05:33:12 INFO - ##### 05:33:12 INFO - ##### Running clobber step. 05:33:12 INFO - ##### 05:33:12 INFO - Running pre-action listener: _resource_record_pre_action 05:33:12 INFO - Running main action method: clobber 05:33:12 INFO - rmtree: /builds/slave/test/build 05:33:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:33:13 INFO - Running post-action listener: _resource_record_post_action 05:33:13 INFO - ##### 05:33:13 INFO - ##### Finished clobber step (success) 05:33:13 INFO - ##### 05:33:13 INFO - ##### 05:33:13 INFO - ##### Running read-buildbot-config step. 05:33:13 INFO - ##### 05:33:13 INFO - Running pre-action listener: _resource_record_pre_action 05:33:13 INFO - Running main action method: read_buildbot_config 05:33:13 INFO - Using buildbot properties: 05:33:13 INFO - { 05:33:13 INFO - "project": "", 05:33:13 INFO - "product": "firefox", 05:33:13 INFO - "script_repo_revision": "production", 05:33:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:33:13 INFO - "repository": "", 05:33:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:33:13 INFO - "buildid": "20160528033932", 05:33:13 INFO - "pgo_build": "False", 05:33:13 INFO - "basedir": "/builds/slave/test", 05:33:13 INFO - "buildnumber": 29, 05:33:13 INFO - "slavename": "t-yosemite-r7-0296", 05:33:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:33:13 INFO - "platform": "yosemite_r7", 05:33:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:33:13 INFO - "branch": "fx-team", 05:33:13 INFO - "repo_path": "integration/fx-team", 05:33:13 INFO - "revision": "199230f44725b8e4919a5014b9649a9951383355", 05:33:13 INFO - "stage_platform": "macosx64", 05:33:13 INFO - "builduid": "a578fa7b871d4f22a35bf020ea4372fe", 05:33:13 INFO - "slavebuilddir": "test" 05:33:13 INFO - } 05:33:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:33:13 INFO - Running post-action listener: _resource_record_post_action 05:33:13 INFO - ##### 05:33:13 INFO - ##### Finished read-buildbot-config step (success) 05:33:13 INFO - ##### 05:33:13 INFO - ##### 05:33:13 INFO - ##### Running download-and-extract step. 05:33:13 INFO - ##### 05:33:13 INFO - Running pre-action listener: _resource_record_pre_action 05:33:13 INFO - Running main action method: download_and_extract 05:33:13 INFO - mkdir: /builds/slave/test/build/tests 05:33: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:33:13 INFO - https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:33:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:33:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:33:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:26 INFO - Downloaded 1199 bytes. 05:33:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:33:26 INFO - Using the following test package requirements: 05:33:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:33:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:33:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:33:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'jsshell-mac.zip'], 05:33:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:33:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:33:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:33:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:33:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:33:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:33:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:33:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:26 INFO - https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:33:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:33:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:33:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:29 INFO - Downloaded 18684798 bytes. 05:33:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:33:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:33:32 INFO - Return code: 0 05:33:32 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:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:32 INFO - https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:33:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:33:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:33:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:36 INFO - Downloaded 18684798 bytes. 05:33:36 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:33:36 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:33:40 INFO - Return code: 0 05:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:40 INFO - https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:33:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:33:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:33:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:41 INFO - Downloaded 10834034 bytes. 05:33:41 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:33:41 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:33:41 INFO - Return code: 0 05:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:41 INFO - https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:33:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:33:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:33:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:33:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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:33:49 INFO - Downloaded 110488054 bytes. 05:33:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:33:49 INFO - mkdir: /builds/slave/test/properties 05:33:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:33:49 INFO - Writing to file /builds/slave/test/properties/build_url 05:33:49 INFO - Contents: 05:33:49 INFO - build_url:https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:33:51 INFO - Running post-action listener: _resource_record_post_action 05:33:51 INFO - Running post-action listener: set_extra_try_arguments 05:33:51 INFO - ##### 05:33:51 INFO - ##### Finished download-and-extract step (success) 05:33:51 INFO - ##### 05:33:51 INFO - ##### 05:33:51 INFO - ##### Running populate-webroot step. 05:33:51 INFO - ##### 05:33:51 INFO - Running pre-action listener: _resource_record_pre_action 05:33:51 INFO - Running main action method: populate_webroot 05:33:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:33:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:33:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:33:51 INFO - '--tppagecycles', 05:33:51 INFO - '7'], 05:33:51 INFO - 'tests': ['tsvgm']}}, 05:33:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:33:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:33:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:33:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:33:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['damp', 'tps']}, 05:33:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'tests': ['damp', 'tps']}, 05:33:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['dromaeo_dom']}, 05:33:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:33:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['a11yr', 05:33:51 INFO - 'ts_paint', 05:33:51 INFO - 'tpaint', 05:33:51 INFO - 'sessionrestore', 05:33:51 INFO - 'sessionrestore_no_auto_restore', 05:33:51 INFO - 'tabpaint']}, 05:33:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['tsvgx', 05:33:51 INFO - 'tsvgr_opacity', 05:33:51 INFO - 'tart', 05:33:51 INFO - 'tscrollx', 05:33:51 INFO - 'cart']}, 05:33:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:33:51 INFO - 'tsvgr_opacity', 05:33:51 INFO - 'tart', 05:33:51 INFO - 'tscrollx', 05:33:51 INFO - 'cart']}, 05:33:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'talos_options': ['--disable-e10s'], 05:33:51 INFO - 'tests': ['tp5o']}, 05:33:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'tests': ['tp5o']}, 05:33:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'talos_options': ['--disable-e10s', 05:33:51 INFO - '--xperf_path', 05:33:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:51 INFO - 'tests': ['tp5n']}, 05:33:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:51 INFO - 'talos_options': ['--xperf_path', 05:33:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:51 INFO - 'tests': ['tp5n']}}, 05:33:51 INFO - 'talos.zip': {'path': '', 05:33:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:33:51 INFO - Running post-action listener: _resource_record_post_action 05:33:51 INFO - ##### 05:33:51 INFO - ##### Finished populate-webroot step (success) 05:33:51 INFO - ##### 05:33:51 INFO - ##### 05:33:51 INFO - ##### Running create-virtualenv step. 05:33:51 INFO - ##### 05:33:51 INFO - Running pre-action listener: _resource_record_pre_action 05:33:51 INFO - Running main action method: create_virtualenv 05:33:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:33:51 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:33:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:33:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:33:51 INFO - Using real prefix '/tools/python27' 05:33:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:33:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:33:54 INFO - Installing pip.................done. 05:33:54 INFO - Return code: 0 05:33:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:54 INFO - 'HOME': '/Users/cltbld', 05:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:54 INFO - 'LOGNAME': 'cltbld', 05:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:54 INFO - 'MOZ_NO_REMOTE': '1', 05:33:54 INFO - 'NO_EM_RESTART': '1', 05:33:54 INFO - 'PAGER': '/bin/cat', 05:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:54 INFO - 'PWD': '/builds/slave/test', 05:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:54 INFO - 'SHELL': '/bin/bash', 05:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:33:54 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:54 INFO - 'USER': 'cltbld', 05:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:54 INFO - 'XPC_FLAGS': '0x0', 05:33:54 INFO - 'XPC_SERVICE_NAME': '0', 05:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:55 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:33:55 INFO - Cleaning up... 05:33:55 INFO - Return code: 0 05:33:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:33:55 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:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:55 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:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:55 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:33:55 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:33:55 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:33:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:33:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:55 INFO - 'HOME': '/Users/cltbld', 05:33:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:55 INFO - 'LOGNAME': 'cltbld', 05:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:55 INFO - 'MOZ_NO_REMOTE': '1', 05:33:55 INFO - 'NO_EM_RESTART': '1', 05:33:55 INFO - 'PAGER': '/bin/cat', 05:33:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:55 INFO - 'PWD': '/builds/slave/test', 05:33:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:55 INFO - 'SHELL': '/bin/bash', 05:33:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:33:55 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:33:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:55 INFO - 'USER': 'cltbld', 05:33:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:55 INFO - 'XPC_FLAGS': '0x0', 05:33:55 INFO - 'XPC_SERVICE_NAME': '0', 05:33:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:55 INFO - Downloading/unpacking psutil>=3.1.1 05:33:55 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:33:55 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:33:55 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:33:55 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:33:55 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:33:55 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:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:58 INFO - Installing collected packages: psutil 05:33:58 INFO - Running setup.py install for psutil 05:33:58 INFO - building 'psutil._psutil_osx' extension 05:33:58 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:33:59 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:33:59 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:33:59 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:33:59 INFO - building 'psutil._psutil_posix' extension 05:33:59 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:33:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:33:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:33:59 INFO - ^ 05:33:59 INFO - 1 warning generated. 05:33:59 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:33:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:59 INFO - Successfully installed psutil 05:33:59 INFO - Cleaning up... 05:33:59 INFO - Return code: 0 05:33:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:59 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:33:59 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:33:59 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:33:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:33:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:59 INFO - 'HOME': '/Users/cltbld', 05:33:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:59 INFO - 'LOGNAME': 'cltbld', 05:33:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:59 INFO - 'MOZ_NO_REMOTE': '1', 05:33:59 INFO - 'NO_EM_RESTART': '1', 05:33:59 INFO - 'PAGER': '/bin/cat', 05:33:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:59 INFO - 'PWD': '/builds/slave/test', 05:33:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:59 INFO - 'SHELL': '/bin/bash', 05:33:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:33:59 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:33:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:59 INFO - 'USER': 'cltbld', 05:33:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:59 INFO - 'XPC_FLAGS': '0x0', 05:33:59 INFO - 'XPC_SERVICE_NAME': '0', 05:33:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:33:59 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:33:59 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:33:59 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:33:59 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:33:59 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:33:59 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:34:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:34:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:34:02 INFO - Installing collected packages: mozsystemmonitor 05:34:02 INFO - Running setup.py install for mozsystemmonitor 05:34:02 INFO - Successfully installed mozsystemmonitor 05:34:02 INFO - Cleaning up... 05:34:02 INFO - Return code: 0 05:34:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:34: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:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34: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:34:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:02 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:02 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:34:02 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:34:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:02 INFO - 'HOME': '/Users/cltbld', 05:34:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:02 INFO - 'LOGNAME': 'cltbld', 05:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:02 INFO - 'MOZ_NO_REMOTE': '1', 05:34:02 INFO - 'NO_EM_RESTART': '1', 05:34:02 INFO - 'PAGER': '/bin/cat', 05:34:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:02 INFO - 'PWD': '/builds/slave/test', 05:34:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:02 INFO - 'SHELL': '/bin/bash', 05:34:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:02 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:02 INFO - 'USER': 'cltbld', 05:34:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:02 INFO - 'XPC_FLAGS': '0x0', 05:34:02 INFO - 'XPC_SERVICE_NAME': '0', 05:34:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:34:02 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:34:02 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:34:02 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:34:02 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:34:02 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:34:02 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:34:05 INFO - Downloading blobuploader-1.2.4.tar.gz 05:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:34:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:34:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:06 INFO - Downloading docopt-0.6.1.tar.gz 05:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:34:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:34:06 INFO - Running setup.py install for blobuploader 05:34:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:34:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:34:06 INFO - Running setup.py install for requests 05:34:07 INFO - Running setup.py install for docopt 05:34:07 INFO - Successfully installed blobuploader requests docopt 05:34:07 INFO - Cleaning up... 05:34:07 INFO - Return code: 0 05:34:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:07 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:07 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:34:07 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:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:07 INFO - 'HOME': '/Users/cltbld', 05:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:07 INFO - 'LOGNAME': 'cltbld', 05:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:07 INFO - 'MOZ_NO_REMOTE': '1', 05:34:07 INFO - 'NO_EM_RESTART': '1', 05:34:07 INFO - 'PAGER': '/bin/cat', 05:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:07 INFO - 'PWD': '/builds/slave/test', 05:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:07 INFO - 'SHELL': '/bin/bash', 05:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:07 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:07 INFO - 'USER': 'cltbld', 05:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:07 INFO - 'XPC_FLAGS': '0x0', 05:34:07 INFO - 'XPC_SERVICE_NAME': '0', 05:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:34:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-1lR9hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:34:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-EuIL_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-50Q4VC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-t47YET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-HsRHgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-mIyM9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Ji9ikK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-OpdyPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-oBHX9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8_7ojd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-67dtcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:34:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KUG48k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:34:09 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ZChUmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:34:09 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-0GFx1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:09 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nQkpZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:34:09 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-7KLvhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:34:09 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-fD5TY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:34:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:34:09 INFO - Running setup.py install for manifestparser 05:34:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:34:09 INFO - Running setup.py install for mozcrash 05:34:09 INFO - Running setup.py install for mozdebug 05:34:10 INFO - Running setup.py install for mozdevice 05:34:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Running setup.py install for mozfile 05:34:10 INFO - Running setup.py install for mozhttpd 05:34:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Running setup.py install for mozinfo 05:34:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Running setup.py install for mozInstall 05:34:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:34:10 INFO - Running setup.py install for mozleak 05:34:10 INFO - Running setup.py install for mozlog 05:34:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for moznetwork 05:34:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozprocess 05:34:11 INFO - Running setup.py install for mozprofile 05:34:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozrunner 05:34:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:34:11 INFO - Running setup.py install for mozscreenshot 05:34:11 INFO - Running setup.py install for moztest 05:34:12 INFO - Running setup.py install for mozversion 05:34:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:34:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:34:12 INFO - Cleaning up... 05:34:12 INFO - Return code: 0 05:34:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:12 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:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:12 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:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:12 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:12 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:34:12 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:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:12 INFO - 'HOME': '/Users/cltbld', 05:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:12 INFO - 'LOGNAME': 'cltbld', 05:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:12 INFO - 'MOZ_NO_REMOTE': '1', 05:34:12 INFO - 'NO_EM_RESTART': '1', 05:34:12 INFO - 'PAGER': '/bin/cat', 05:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:12 INFO - 'PWD': '/builds/slave/test', 05:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:12 INFO - 'SHELL': '/bin/bash', 05:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:12 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:12 INFO - 'USER': 'cltbld', 05:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:12 INFO - 'XPC_FLAGS': '0x0', 05:34:12 INFO - 'XPC_SERVICE_NAME': '0', 05:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-k6YpgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DLp3ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-fQJyEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-58d4oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-cOJeRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:34:12 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-YBkYlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-hpmUNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-z67nGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Jh09RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-3_DOJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-94V9bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-eydNvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-2kYQyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DsD80M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:13 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nqwUeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:34:14 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-YCe1Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:34:14 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:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:34:14 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-V7ssSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:17 INFO - Downloading blessings-1.6.tar.gz 05:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:34:17 INFO - Installing collected packages: blessings 05:34:17 INFO - Running setup.py install for blessings 05:34:17 INFO - Successfully installed blessings 05:34:17 INFO - Cleaning up... 05:34:17 INFO - Return code: 0 05:34:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:34:17 INFO - Reading from file tmpfile_stdout 05:34:17 INFO - Current package versions: 05:34:17 INFO - blessings == 1.6 05:34:17 INFO - blobuploader == 1.2.4 05:34:17 INFO - docopt == 0.6.1 05:34:17 INFO - manifestparser == 1.1 05:34:17 INFO - mozInstall == 1.12 05:34:17 INFO - mozcrash == 0.17 05:34:17 INFO - mozdebug == 0.1 05:34:17 INFO - mozdevice == 0.48 05:34:17 INFO - mozfile == 1.2 05:34:17 INFO - mozhttpd == 0.7 05:34:17 INFO - mozinfo == 0.9 05:34:17 INFO - mozleak == 0.1 05:34:17 INFO - mozlog == 3.2 05:34:17 INFO - moznetwork == 0.27 05:34:17 INFO - mozprocess == 0.22 05:34:17 INFO - mozprofile == 0.28 05:34:17 INFO - mozrunner == 6.11 05:34:17 INFO - mozscreenshot == 0.1 05:34:17 INFO - mozsystemmonitor == 0.3 05:34:17 INFO - moztest == 0.7 05:34:17 INFO - mozversion == 1.4 05:34:17 INFO - psutil == 3.1.1 05:34:17 INFO - requests == 1.2.3 05:34:17 INFO - wsgiref == 0.1.2 05:34:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:17 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 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:17 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:34:17 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:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:17 INFO - 'HOME': '/Users/cltbld', 05:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:17 INFO - 'LOGNAME': 'cltbld', 05:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:17 INFO - 'MOZ_NO_REMOTE': '1', 05:34:17 INFO - 'NO_EM_RESTART': '1', 05:34:17 INFO - 'PAGER': '/bin/cat', 05:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:17 INFO - 'PWD': '/builds/slave/test', 05:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:17 INFO - 'SHELL': '/bin/bash', 05:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:17 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:17 INFO - 'USER': 'cltbld', 05:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:17 INFO - 'XPC_FLAGS': '0x0', 05:34:17 INFO - 'XPC_SERVICE_NAME': '0', 05:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 INFO - Cleaning up... 05:34:18 INFO - Return code: 0 05:34:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:34: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:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34: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:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:18 INFO - 'HOME': '/Users/cltbld', 05:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:18 INFO - 'LOGNAME': 'cltbld', 05:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:18 INFO - 'MOZ_NO_REMOTE': '1', 05:34:18 INFO - 'NO_EM_RESTART': '1', 05:34:18 INFO - 'PAGER': '/bin/cat', 05:34:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:18 INFO - 'PWD': '/builds/slave/test', 05:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:18 INFO - 'SHELL': '/bin/bash', 05:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:18 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:18 INFO - 'USER': 'cltbld', 05:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:18 INFO - 'XPC_FLAGS': '0x0', 05:34:18 INFO - 'XPC_SERVICE_NAME': '0', 05:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:18 INFO - Downloading/unpacking jsonschema 05:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:34:20 INFO - Downloading/unpacking functools32 (from jsonschema) 05:34: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:34: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:34: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:34: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:34: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:34: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:34:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:34:21 INFO - warning: no files found matching '*.txt' 05:34:21 INFO - no previously-included directories found matching 'build' 05:34:21 INFO - no previously-included directories found matching 'dist' 05:34:21 INFO - no previously-included directories found matching '.git*' 05:34:21 INFO - Installing collected packages: jsonschema, functools32 05:34:21 INFO - Running setup.py install for functools32 05:34:21 INFO - warning: no files found matching '*.txt' 05:34:21 INFO - no previously-included directories found matching 'build' 05:34:21 INFO - no previously-included directories found matching 'dist' 05:34:21 INFO - no previously-included directories found matching '.git*' 05:34:21 INFO - Successfully installed jsonschema functools32 05:34:21 INFO - Cleaning up... 05:34:21 INFO - Return code: 0 05:34:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c992570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff709d9f890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, '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.NPEIJtDHfJ/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.9693I2TCHU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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:34:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:34:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:34:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:21 INFO - 'HOME': '/Users/cltbld', 05:34:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:21 INFO - 'LOGNAME': 'cltbld', 05:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:21 INFO - 'MOZ_NO_REMOTE': '1', 05:34:21 INFO - 'NO_EM_RESTART': '1', 05:34:21 INFO - 'PAGER': '/bin/cat', 05:34:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:21 INFO - 'PWD': '/builds/slave/test', 05:34:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:21 INFO - 'SHELL': '/bin/bash', 05:34:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:34:21 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:34:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:21 INFO - 'USER': 'cltbld', 05:34:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:21 INFO - 'XPC_FLAGS': '0x0', 05:34:21 INFO - 'XPC_SERVICE_NAME': '0', 05:34:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:21 INFO - Downloading/unpacking flake8 05:34:21 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:34:21 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:34:21 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:34:21 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:34:21 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:34:21 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:34:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:34:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:34:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:34:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:34:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:34:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:34:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:34:25 INFO - no previously-included directories found matching 'docs/_build' 05:34:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:34:25 INFO - Running setup.py install for pep8 05:34:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:34:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:34:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:34:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:34:25 INFO - no previously-included directories found matching 'docs/_build' 05:34:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:34:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:34:25 INFO - Cleaning up... 05:34:25 INFO - Return code: 0 05:34:25 INFO - Running post-action listener: _resource_record_post_action 05:34:25 INFO - Running post-action listener: _start_resource_monitoring 05:34:25 INFO - Starting resource monitoring. 05:34:25 INFO - ##### 05:34:25 INFO - ##### Finished create-virtualenv step (success) 05:34:25 INFO - ##### 05:34:25 INFO - ##### 05:34:25 INFO - ##### Running install step. 05:34:25 INFO - ##### 05:34:25 INFO - Running pre-action listener: _resource_record_pre_action 05:34:25 INFO - Running main action method: install 05:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:34:26 INFO - Reading from file tmpfile_stdout 05:34:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:34:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:34:26 INFO - Reading from file tmpfile_stdout 05:34:26 INFO - Output received: 05:34:26 INFO - Usage: mozinstall [options] installer 05:34:26 INFO - Options: 05:34:26 INFO - -h, --help show this help message and exit 05:34:26 INFO - -d DEST, --destination=DEST 05:34:26 INFO - Directory to install application into. [default: 05:34:26 INFO - "/builds/slave/test"] 05:34:26 INFO - --app=APP Application being installed. [default: firefox] 05:34:26 INFO - mkdir: /builds/slave/test/build/application 05:34:26 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:34:26 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:34:59 INFO - Reading from file tmpfile_stdout 05:34:59 INFO - Output received: 05:34:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:34:59 INFO - Running post-action listener: _resource_record_post_action 05:34:59 INFO - ##### 05:34:59 INFO - ##### Finished install step (success) 05:34:59 INFO - ##### 05:34:59 INFO - ##### 05:34:59 INFO - ##### Running run-tests step. 05:34:59 INFO - ##### 05:34:59 INFO - Running pre-action listener: _resource_record_pre_action 05:34:59 INFO - Running main action method: run_tests 05:34:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:59 INFO - 2016-05-28 05:34:59.889 screenresolution[1655:8697] starting screenresolution argv=screenresolution get 05:34:59 INFO - 2016-05-28 05:34:59.891 screenresolution[1655:8697] Display 0: 1600x1200x32@60 05:34:59 INFO - 2016-05-28 05:34:59.897 screenresolution[1656:8699] starting screenresolution argv=screenresolution list 05:34:59 INFO - Available Modes on Display 0 05:34:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:00 INFO - Intel Iris: 05:35:00 INFO - Chipset Model: Intel Iris 05:35:00 INFO - Type: GPU 05:35:00 INFO - Bus: Built-In 05:35:00 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:00 INFO - Vendor: Intel (0x8086) 05:35:00 INFO - Device ID: 0x0a2e 05:35:00 INFO - Revision ID: 0x0009 05:35:00 INFO - Displays: 05:35:00 INFO - Display: 05:35:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:00 INFO - Display Serial Number: 1600x1200 60 05:35:00 INFO - Main Display: Yes 05:35:00 INFO - Mirror: Off 05:35:00 INFO - Online: Yes 05:35:00 INFO - Rotation: Supported 05:35:00 INFO - Return code: 0 05:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:35:00 INFO - Python 2.7.3 05:35:00 INFO - Return code: 0 05:35:00 INFO - grabbing minidump binary from tooltool 05:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:00 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 0x10c830d30>, '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 0x10c654db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c827b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:35:00 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:35:00 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:35:00 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:35:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:35:00 INFO - Return code: 0 05:35:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:35:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:35:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:35:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:35:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:35:01 INFO - Return code: 0 05:35:01 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/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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:35:01 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/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPEIJtDHfJ/Render', 05:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:01 INFO - 'HOME': '/Users/cltbld', 05:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:01 INFO - 'LOGNAME': 'cltbld', 05:35:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:35:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:01 INFO - 'MOZ_NO_REMOTE': '1', 05:35:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:35:01 INFO - 'NO_EM_RESTART': '1', 05:35:01 INFO - 'PAGER': '/bin/cat', 05:35:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:01 INFO - 'PWD': '/builds/slave/test', 05:35:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:01 INFO - 'SHELL': '/bin/bash', 05:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9693I2TCHU/Listeners', 05:35:01 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:01 INFO - 'USER': 'cltbld', 05:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:01 INFO - 'XPC_FLAGS': '0x0', 05:35:01 INFO - 'XPC_SERVICE_NAME': '0', 05:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:01 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/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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:35:01 INFO - mozversion application_buildid: 20160528033932 05:35:01 INFO - mozversion application_changeset: 199230f44725b8e4919a5014b9649a9951383355 05:35:01 INFO - mozversion application_display_name: Nightly 05:35:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:35:01 INFO - mozversion application_name: Firefox 05:35:01 INFO - mozversion application_remotingname: firefox 05:35:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:35:01 INFO - mozversion application_vendor: Mozilla 05:35:01 INFO - mozversion application_version: 49.0a1 05:35:01 INFO - mozversion platform_buildid: 20160528033932 05:35:01 INFO - mozversion platform_changeset: 199230f44725b8e4919a5014b9649a9951383355 05:35:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:35:01 INFO - mozversion platform_version: 49.0a1 05:35:01 INFO - using testdate: 1464438901 05:35:01 INFO - actual date: 1464438901 05:35:01 INFO - starting webserver on 'localhost:49282' 05:35:01 INFO - SUITE-START | Running 2 tests 05:35:01 INFO - TEST-START | dromaeo_css 05:35:01 INFO - operating with platform_type : mac_ 05:35:01 INFO - Initialising browser for dromaeo_css test... 05:35:01 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpdln38T/profile http://localhost:49282/getInfo.html) 05:35:03 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:35:03 INFO - PROCESS | 1674 | colorDepth:24 05:35:03 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:35:03 INFO - PROCESS | 1674 | __metrics 05:35:03 INFO - PROCESS | 1674 | 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:35:03 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:35:04 INFO - TEST-INFO | 1674: exit 0 05:35:04 INFO - Browser initialized. 05:35:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:35:04 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpdln38T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:35:16 INFO - PROCESS | 1677 | RSS: Main: 241913856 05:35:16 INFO - PROCESS | 1677 | 05:36:07 INFO - PROCESS | 1677 | 1464438967402 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:38:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 05:38:19 INFO - PROCESS | 1677 | RSS: Main: 343040000 05:38:19 INFO - PROCESS | 1677 | 05:41:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 05:41:23 INFO - PROCESS | 1677 | RSS: Main: 373264384 05:41:23 INFO - PROCESS | 1677 | 05:44:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 05:44:26 INFO - PROCESS | 1677 | RSS: Main: 372961280 05:44:26 INFO - PROCESS | 1677 | 05:47:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 05:47:29 INFO - PROCESS | 1677 | RSS: Main: 372379648 05:47:29 INFO - PROCESS | 1677 | 05:50:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 05:50:33 INFO - PROCESS | 1677 | RSS: Main: 337453056 05:50:33 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 05:53:36 INFO - PROCESS | 1677 | RSS: Main: 392011776 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | __start_tp_report 05:53:36 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:53:36 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:53:36 INFO - PROCESS | 1677 | |i|pagename|runs| 05:53:36 INFO - PROCESS | 1677 | |0;dojo.html;3440.4323286657705;3447.6724711152447;3448.8275586220684;3468.7398445116614;3517.34929038128;5539.473749993755;5570.331560212775;5663.603547751659;5719.971400143004;5749.108888122338;5598.272224610795;5621.971890140544;5774.220480235167;5777.855553611162;5782.624129431584;3745.4381842723674;5024.422191447994;5082.08522465956;5134.537891589756;5241;5247.737613119348;5253;5270.341207349082;5299.125644268691;5313.442088580682;5114.309568208281;5166.276721259027;5168.741562921877;5214.165733482643;5238.947610523884;5194.168932970744;5229.320188375486;5234.398044224887;5240.633155679066;5264.5525130363585;5148.61385396093;5202.739863006835;5208.713520756357;5225.503577160163;5248.947510524884;5071.757419432226;5613.382527921925;5628.015097357946;5638.605297629168;5659;5124.5131712487255;5860.150278209679;6094.207752992127;6135.478484328837;6151.89265932132;6188.350223226586;6215.782447614311;6217;6232.781852635181;6235.158253635746;5791.160281759206;5826.708664566755;5840.007198776176;5877.676727780014;5889.734961926723;6146.815595532141;6232.594881332699;6247.687615619201;6268.529860260499;6274.05889116627;6121.387861213843;6138.969305153535;6145.0782382642965;6164.137020817089;6180.876382472416;6254.718537665725;6275.560710750204;6275.654838983808;6287.874242515124;6291.622502649856;6064.939350606525;6104;6111.694415279218;6135.1224528509065;6142.314611233224;6017.914343338499;6102.0348389851015;6115.602485838406;6130.979101201984;6187.876242475125;4881.658283920163;4920.21276595743;4933.777979990909;4944.085344211332;4970.304157417964;4460.219461594208;4487.483939346952;4503.617192538605;4506.909861802746;4507.053518761031;5110.97444512775;5647.887042259132;5672;5724.599278050579;5731.426857314235;6190.907136392958;6262.624242545462;6275.403836635513;6285.748570057147;6313.621182729051;5712.771489140471;5737.139429085671;5742.511886489611;5750.942490575125;5770.682612456271;5181.3782346118705;5272.551833094153;5343.572514198855;5362.705051222142;5404.378496472956;6268.185135932391;6296.087067375296;6306.810795676137;6322.0200868865395;6355;6309.842253943688;6315.5894866833505;6317.115603815378;6341.968290158519;6349.523785716182;6242.968785156045;6243.313235544178;6261.24865016201;6304.779332723333;6305.653189074645;6174.969125154345;6179.474744646755;6196.318404975539;6207.348228435949;6224.533160013108;6123.938760612515;6195.4424101831055;6231.594946328474;6254.437100660963;6257.5932564383165;5904.9704751475965;5916.733746981482;6025.758969641341;6060.515158787198;6062.818115456544;5896.380880007538;5988.580799344179;5998.25021872266;5999;6049.879002420015;5771.653700777967;5826.30084389859;5926.525877929842;5945.405459454193;5953.374895636069;5068.493150684902;5993.160957466045;6063.575549711478;6084.847878802888;6126.578745377076;5874.647521148745;5888.587798854039;5896.469317761422;5904.380040095728;5914.112883067574 05:53:36 INFO - PROCESS | 1677 | |1;ext.html;507.51278772378515;517.4101524262342;518.6810111781253;524.4886235919978;557.7350758389763;30873;31122.84438577807;42290.99999999996;42691.786541067326;42791.786041069754;44301.55698443014;44614.33078503825;44684.329735054;44757;44833.7758311208;9485.905140948606;9694.951525242364;9704.805903881917;9708.466034368106;9709.417434953926;8610.354223433236;8801.295896328278;11439.142064345166;11627.127965402586;11627.709307267343;11744.471498782532;11839.467223974898;11846.933775960162;11856.288622682623;11859;8268.669253229864;11467.08263338931;11486.827697584544;11587.304761714282;11602.477888494954;11482;11781;11810.586629467927;11846.644600661994;11850.46672899713;40888;41321;41378.58621413777;41431.58568414307;41506.54727084568;3454.4472884338384;3501.9649803501893;3502.1069627244888;3515.82420878955;3657.1040095176586;26641.86679066592;31916.36167276665;32166;32417.351652967045;33582;3317.270200555874;3333.3499967506345;3351.6815902489225;3394.439917413624;3401.48977653354;511869;530131.3493432547;530211;530815;532259;35807;68058.3194168052;69388.65305673439;69472.6526367375;69581;36608.81695591558;37295.81352093277;37353.066173345884;37361.62638373636;37436.06409839776;14841.406343746345;15065.397384104732;15099.698006039818;15102.565256300633;15126.319315630823;11453.255538389978;11585.015273701829;12579.685507862194;12711.936440317739;12827.743445131231;7979;8034.317083048007;8047.155048719917;8114;8127.918720812834;3400.7449441292;3411.1301618087227;3418.1796368871787;3418.572678415198;3452.2750222453064;1044.0186224948525;1051.952662130206;1059.2267644619317;1084.2952081147316;1118.9608363707232;30324.24189395283;30601.69398306033;30722;31324.530132048036;31485.37029259402;1122.6014764758556;1144.811106167473;1172.8416663750284;1172.9237599556002;1211.9879900283363;13107.934460327637;13344.866551334362;16411.76911731601;16447.424340148038;16449.09529975863;705991;710653;713479;716093;717148;61363;62044;62152;62175.6891215541;62224.37775622186;1289.3553223388305;1390.958271251864;1418.439716312051;1427.6787722762294;1437.116173553257;31193.8440307797;31557.00208480191;31872.681273186972;32060.51909221317;32429.18927026843;51542.74228628983;51626;52032;52178.21732674089;52235;552473;557839;559642;560819;560858;24620;27516.312092197855;27931;27958.3010424741;28053.859730701217;42031.89840508023;48705.75647121741;48903.0219395603;49326.26010609772;50027.24959125543;39971.60028399679;40227.59772402239;40290.395644064774;41187.794061029505;41343.7932810334;32456.83771581127;36434;41769.95575110525;42629.786851065546;43291.78354108209;40502.392464112476;40866.773996780146;41341;42217;42533.149337012466;41098.4517090189;42396.364054538586;43108.70673879789;43302.350464743686;43415;29589.408211836075;32429.351412971137;32614.02157935266;32822.34355312928;33436.83281583576 05:53:36 INFO - PROCESS | 1677 | |2;jquery.html;353.6622525488159;360.8773017174161;383.85221689649495;398.96010398960124;403.9394090886367;18512.537186570342;18924.810751892477;20673.793262067375;21114.15543378265;21160.89419552904;20247.291344802936;20733.792662073374;21169.788302116973;21332.253371132014;21338;5263.473652634725;5693.629914055584;5913.438223368773;6538.771143009995;6539.607623542579;6276.807406592733;6431.389018043278;6431.48548116152;6461.709223084949;6469.320721324264;5337.599680023995;6466.902996455057;6470.935290647104;6476.773312934049;6489.286178520359;5436.646617969819;5809.157672137559;6448.742050317982;6460.257070436889;6467.029945508164;5427.647202931837;5628.859278518029;5640.74616642248;5658.801941932013;5666.979943610148;24903;25190.874045629655;25833.612495812577;26511.602325965127;26547.336316592045;2072.0779253232317;2093.1627349060345;2106.747190337154;2128.244473212003;2162.10272736814;9520.073101448126;9989.300748947577;10129.341592796518;12654;14728.926355368154;1837.6416598763246;1916.1664675866043;1925.2972664977322;1928.6914093745074;1958.608278344337;116015;116959.83040169574;119082;122605;123831;11254.705708843425;11501.712457188638;11545.711357216136;11734.765304693858;12055;14701.338439770023;14796.556103316918;14799.038062525902;14812.333444995002;14835.703285934222;8438.324934005264;8484.36367272457;8518.829623407497;8519.659213631508;8674.6096425661;8408.747737567883;8409.831803363899;8421.115782842835;8556.315494760403;8572.014218364968;4507.143642707875;4509.661775366861;4523.5024147343765;4548.363229147922;4580.931286030712;1868.3367404571454;1931.8454523638075;1933;1945.5719741657097;1953.658109830774;787.9133295337508;978.7602037500858;1006.4162785584343;1022.3967858963248;1055.5830446973557;19998.800071995727;20401.79598203999;20446.38660840177;20711.792882070986;20771.58456830855;879.349281531662;974.668612671695;976.4775844922932;978.8923218445965;1058.941758203306;10425.706344604176;10799.622013229498;10883.891161088446;10908.945455272673;10973.396463194442;513197;602667;611662;619016;622495;101554;122298.38850805689;141760.99999999793;142501;144960.27519862333;993.0516356879164;1000.7848312612823;1007.6624330849247;1042.702829693549;1046.3251202974038;18201.726974095134;18578.44264672062;18642;18753.437396878115;19264.229430822612;33310.333793323516;47123.52876471329;50126.74429650747;51560;51602;54421.18368224559;72053.639731801;72082.27917720756;72203.63898180476;72287.63856180686;7996.520208787491;8057.637406316847;8184.754457366289;8241.670333186605;8369.95815020921;12106.607740109648;12750.362481876055;12853.614391568268;12890.29103399322;13161;5347.144456886879;7597.544147351176;9543.665971690958;10852.186086043735;11249.268797528135;10668.62659806903;10885.292455990335;11005;11079.390633515233;11137.220394572301;11224.551017959517;11354.829677554679;11428.25716328436;11540.596079137189;11703.180777345504;6592.648507055946;6714.127163468718;7017.894731578928;8052.677892884219;8349.666013359398;10903.781924361627;11440.656780296604;11452.198346115692;11522.539098436304;11543.884561154617 05:53:36 INFO - PROCESS | 1677 | |3;mootools.html;1401.2783416540476;1531.2114261155507;1572.4968010236723;1789.6241789224262;1942.5240816000073;7897.723579674713;8594.871076933843;8836.77908052299;8839.337049721273;9005.189532942033;7209;8639.52482613456;8827.29381649468;8830.911690883106;8834.46993180408;2018.5861898310811;2348.8473249238746;2382.4639456122322;2386.9283921482297;2387.7254115776645;2334.6264597664376;2366.3374255208482;2379.631711765733;2379.6668466414717;2390.247072172269;2228.6211344071467;2247.045005872506;2247.8426510144295;2271.4889149941296;2287.942801429969;2315.2591170825344;2318.362450326157;2350.1304517328613;2358.3750306168795;2359.929202123939;2317.3627252505526;2356.7996720278798;2362.126013375062;2363.61000434928;2365.0066971871856;8658.523781192032;8741;8846.115388461167;8852.291816654651;8872.689455869013;4859.465458799529;5107.438181799999;5226.189940559219;5447.645903016291;5456.427075157131;3488.494168345601;3625.23869987303;3690.907727306812;3705.5182826232417;3712.090537818252;1061.1828891753385;1094.042712626452;1097.253867370188;1099.5052226498108;1120.675004248767;11557.826632600518;11653.592124275694;11660;11680.948714615726;11694.239874408137;4502.414686090788;4518.277075667943;4654.2785868190485;4659.906801864012;4662.930056049162;8931.017588065308;9152.81694366109;9164.404313719588;9168.083191680778;9171.312151588658;4604.953950460453;5242.895142097137;5250.921236181384;5255.947440525622;5256.185291279858;4435.445569303837;4578.793954272066;4774.259989701602;4911.705297682079;4929.186684197139;3761.2101458693433;3795.1650636859845;3852;3886.553046399644;3908.085038331197;982.40564458503;1162.0761494611636;1163.3717792392013;1164.342146687119;1164.5807509296646;663.7046514301089;787.9921200788034;916.5417291354323;955.4792638012161;985.7042887133974;5728.169415434739;5758.913616295759;5926.7925622603;6001.4298641628975;6107.267127944675;919.1589695428742;931.4271722890373;975.6243696388059;994.0953732103751;995.2088089968493;3955.960440395617;4021.7586944783407;4024.2957482440456;4069.7965101744794;4077.245709543744;10228.488575571191;10361.844572331422;10787.137029037658;10789.62236321725;10799.460026998775;10630.840537391945;10677.092447141924;10839.620613278497;10851.294665846695;10867.510962006729;8013.19868013194;8117.79705507367;8275.131111233233;8282.378821588405;8329.750107496784;5969.820905372846;6025.638461692312;6044.84887877789;6088.4215999479975;6125.9693701531205;9842.360246583949;10039.29724919278;10134.087932086148;10134.341267817568;10142.594296228068;9723.270754693567;9836.163926066365;10348.120409764953;10359.964003599882;10375.377477351383;7426.962865185639;7611;7693.422993275415;7694.4229182810395;7700.114486834086;8168.7957801055445;8286.171382861665;8321.6671333146;8347.749567512985;8357;7483.027206463125;7518.173000969999;7551.942728018192;7580.431467639839;7666.846663066819;7325.926740732524;7355.2277010913085;7365.668544915385;7480.887786683313;7491.550506969599;6879.243283238739;7002.859942801014;7057.117860267466;7062.869940809446;7064.293570642894;4843.564079232886;5002.749862506933;5012.548870601663;5013.824516141917;5148.30497882796;4387.934180987224;4412.933805992849;5048.268001139888;5077.669951453144;5215.765290561858 05:53:36 INFO - PROCESS | 1677 | |4;prototype.html;299.3145696355346;309.5681524273638;312.2085513223978;318.46656849776616;318.5365293497959;4426.889327766803;5104.897902041962;5407.675539467625;5418.701971391577;5459.590530710193;4542.9097016716;4648.558946726517;5054.924176137351;5274.367075950891;5371.462853714626;3789.545254569449;3817.9118951098944;3872.9806350968206;3917.784521851297;4064.5732198119217;3374.3420033093557;4003.65968892643;4033.636972672459;4051.2653958577844;4056.533498647649;3311.304626028537;4002.299597570428;4018.0713257636207;4048.57489963554;4056.837726490937;3150.3384289299274;3554.3246783111126;3946.1910308386714;4037.5154981402125;4069.674426045909;3290.9835450822834;3507.105688049531;3507.9824600877087;3517.4547945068557;3518.612952575215;4198.958010419918;5097.3118628984985;5101.566366858821;5142.177251639757;5144.228365745131;4225.429567008476;4265.338872474771;4622.653301002439;4626.236670949289;4634.281686338589;5609.887802243932;5627.465390787868;5634.605577609569;5646.57650676201;5663.773449062032;3519.6832285094465;3838.654521093115;3845.9615403845883;3855.768653880776;3873.1479074603544;4802.495737947535;5437.8368648940195;5561.304836895388;5568.661162311839;5638;3376.5104059911178;3836.693064554829;3858.8456461741216;3884.1843212925037;3912.882613521599;3534.6818786309354;3752.5309336332957;3881.728279020498;3889.8833035008997;3960.9009774755864;3904.1606054698377;4082.7346222495444;4203.687971615599;4651.674382793172;4670.229412147026;5060.912843165666;5184.377874655065;5644.78637093027;5754.625949313281;5827.426594819375;1217.2027322104036;1252.7870262055471;1300.486307908371;1347.0368686389083;1347.7574036673298;789.4434423731255;809.3242142810819;828.2234606768448;831.0967883546731;838.674893668003;4488.259437192826;5007.173816320266;5025.296458495814;5200.427952925175;5247.344081989751;5142.280080788692;5208.427073021964;5602.607817452739;5708.286464191976;5835.533157347402;4135.97932010338;4950.796895381593;5155.38135423744;5240.292560504283;5246.658967167122;2917.810342327742;3035.9241018974703;3040.130319853274;3048.481758101128;3071.0191926207917;4848.666616680371;5342.9465705342445;6006.639601623917;6046.516278697693;6058.909116363258;5605.402460298796;5953.672548009901;5955.52355811534;5980.192673989043;6000.639961602318;5270.2054520745005;5595.4748508439425;6005.6997150142315;6011.6994150293185;6028.216331876828;4859.00390419956;5407.188921661782;5543.805966791143;5623.409541998195;5795.884082318245;5230.607704422259;5247.714309994076;5253.082624841982;5627.986962346653;5745.816589695889;6026.819195424145;6037.1021909482815;6043.54673399488;6047.274327080779;6076;3569.2861427714042;3823.827927743314;3826.1199924017073;3826.349520581452;3879.631435013669;5128.8461346159675;5782.5952183348445;5820.592558520862;5831.7375718093635;5834.620749651259;5632.295963004624;5653.689047102284;5654.095344744821;5656.717164141694;5673.602847800615;4693.319468677091;5321.494458026481;5429.782808687608;5599.468050535355;5628.60599758013;4656.790444430077;4851.611871050237;5225.529702326809;5528.530074943675;5547.389787123494;5388.488093631256;5510.6969116698965;5533.280673512418;5635.295588051494;5682.5453963681975;4708.788104535236;4829.420469543677;5524.14375771756;5534.944650553443;5551.7779288828 05:53:36 INFO - PROCESS | 1677 | |5;yui.html;712.2307907459943;825.8472182646215;838.9286910612598;839.6683310092516;866.5840396609631;14765.18791466469;15136.848631513682;15317.84682153178;15384.999975001645;15385.999910005856;13101.934490327534;13108.27904465254;13110.147840390367;13131.540396086139;13137.67155821105;714.4820005496026;742.0094174277347;884.1821315283337;907.7594437474074;907.9137481939205;884.2130503851578;895.3866601378045;899.5637115998735;901.9774505637379;902.4495058014605;952.6284748947916;954.0125969621444;954.3605784124618;954.5799848066863;954.9188318992894;753.2655660730799;780.3912947900646;782.6908371193404;786.2766255045372;945.9006804285555;577.7082573300498;687.9862402751967;708.6421357214625;708.8617719544691;709.3367701199395;14349.713005739932;14401.20793356365;14412.927935360362;14415;14419.495317663936;11509.884901150965;13392.86607133926;13504.324783760772;13582.388792504362;13867.4453021879;10924.890751092467;11381.2033157679;11556.479958401893;11774.764504709858;12011.219270747373;4463.330500424962;4522.6155776759015;4536.500984891659;4585.105904348653;4762.761861906891;79721.60139199324;80010.59994700048;80423.66945039225;81360.9999999994;81772.36455270862;10602.681919542427;10672.626458074086;10687.198460115522;10821.296615719953;11149.498272577754;19936.800631993785;20103.497412564804;20143.496412589804;20150.39548813538;20340.389788306373;7631.54210747357;7719.11230208522;7830.921690783133;7872.645730942121;7916.208379162038;4325.329573916048;4362.607365337071;4447.888802779957;4535.591796738309;4673.579377856009;3106.2389714520086;3209.3902158589794;3250.382427338798;3251.7398608111293;3258.218027673389;685.5783693028851;689.3175756001523;703.714995426853;706.2831226305304;707.2149913595905;651.8924377477663;681.9965900170467;682.6382017530717;687.8864987277143;708.5005071424633;7792.727254546064;8958;9146.451212927246;9338.533073346305;9642.469664168399;624.2602516018533;638.9680515974183;665.2914645902099;675.587891386254;708.3129364516514;4355.564443555619;4376.978115109404;4395.142947125344;4450.864172934177;4473.083017981306;80842;81974.59012704898;87869;88018.55990720133;88308.23383532422;70274.99999999898;71823.8452846405;72863.90704139443;73853;74183.88724169142;69890.3010969894;70384;70621.64689176521;71242;71725.28274717394;8441.493510389397;8975;9229.630814767334;9439.433633981984;9504.23966082698;33295.66704332926;37267.06832329214;38015.809920951324;38094.57225753954;38772;46302.536974629824;60729.69635151796;61224.693876530335;63502;65421;11548.82676759866;13005.349732513525;13344.733105337644;13479.932600336935;13484.662883427993;14217.431302747775;14402.42390304376;14671.119732816065;15028.248587570797;15049.322780475124;13346.19922804635;13418.32908354598;13522.79715804244;13607.86392136066;13658.59024229235;11418.714532136431;13149.868501314864;13372.264525450804;14022.439102435788;14197.574072777834;12511.749765004466;12625.431855566338;12795.80806287925;12944.482220711443;13066.803997939849;10643.361398316125;10771.730706732395;10805.51375188134;11084.501197445972;11203.103751700008;10554.577816887238;10832.891671083189;11107.888921110685;11122.721931951766;11220.326780393203 05:53:36 INFO - PROCESS | 1677 | __end_tp_report 05:53:36 INFO - PROCESS | 1677 | __start_cc_report 05:53:36 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1050 05:53:36 INFO - PROCESS | 1677 | __end_cc_report 05:53:36 INFO - PROCESS | 1677 | __startTimestamp1464440016696__endTimestamp 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:53:36 INFO - PROCESS | 1677 | Number of tests: 6 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#0] dojo.html Cycles:165 Average:5702.28 Median:5896.38 stddev:637.63 (10.8%) stddev-sans-first:614.40 05:53:36 INFO - PROCESS | 1677 | Values: 3440.4 3447.7 3448.8 3468.7 3517.3 5539.5 5570.3 5663.6 5720.0 5749.1 5598.3 5622.0 5774.2 5777.9 5782.6 3745.4 5024.4 5082.1 5134.5 5241.0 5247.7 5253.0 5270.3 5299.1 5313.4 5114.3 5166.3 5168.7 5214.2 5238.9 5194.2 5229.3 5234.4 5240.6 5264.6 5148.6 5202.7 5208.7 5225.5 5248.9 5071.8 5613.4 5628.0 5638.6 5659.0 5124.5 5860.2 6094.2 6135.5 6151.9 6188.4 6215.8 6217.0 6232.8 6235.2 5791.2 5826.7 5840.0 5877.7 5889.7 6146.8 6232.6 6247.7 6268.5 6274.1 6121.4 6139.0 6145.1 6164.1 6180.9 6254.7 6275.6 6275.7 6287.9 6291.6 6064.9 6104.0 6111.7 6135.1 6142.3 6017.9 6102.0 6115.6 6131.0 6187.9 4881.7 4920.2 4933.8 4944.1 4970.3 4460.2 4487.5 4503.6 4506.9 4507.1 5111.0 5647.9 5672.0 5724.6 5731.4 6190.9 6262.6 6275.4 6285.7 6313.6 5712.8 5737.1 5742.5 5750.9 5770.7 5181.4 5272.6 5343.6 5362.7 5404.4 6268.2 6296.1 6306.8 6322.0 6355.0 6309.8 6315.6 6317.1 6342.0 6349.5 6243.0 6243.3 6261.2 6304.8 6305.7 6175.0 6179.5 6196.3 6207.3 6224.5 6123.9 6195.4 6231.6 6254.4 6257.6 5905.0 5916.7 6025.8 6060.5 6062.8 5896.4 5988.6 5998.3 5999.0 6049.9 5771.7 5826.3 5926.5 5945.4 5953.4 5068.5 5993.2 6063.6 6084.8 6126.6 5874.6 5888.6 5896.5 5904.4 5914.1 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#1] ext.html Cycles:180 Average:72788.04 Median:20045.55 stddev:161957.93 (807.9%) stddev-sans-first:162321.34 05:53:36 INFO - PROCESS | 1677 | Values: 507.5 517.4 518.7 524.5 557.7 30873.0 31122.8 42291.0 42691.8 42791.8 44301.6 44614.3 44684.3 44757.0 44833.8 9485.9 9695.0 9704.8 9708.5 9709.4 8610.4 8801.3 11439.1 11627.1 11627.7 11744.5 11839.5 11846.9 11856.3 11859.0 8268.7 11467.1 11486.8 11587.3 11602.5 11482.0 11781.0 11810.6 11846.6 11850.5 40888.0 41321.0 41378.6 41431.6 41506.5 3454.4 3502.0 3502.1 3515.8 3657.1 26641.9 31916.4 32166.0 32417.4 33582.0 3317.3 3333.3 3351.7 3394.4 3401.5 511869.0 530131.3 530211.0 530815.0 532259.0 35807.0 68058.3 69388.7 69472.7 69581.0 36608.8 37295.8 37353.1 37361.6 37436.1 14841.4 15065.4 15099.7 15102.6 15126.3 11453.3 11585.0 12579.7 12711.9 12827.7 7979.0 8034.3 8047.2 8114.0 8127.9 3400.7 3411.1 3418.2 3418.6 3452.3 1044.0 1052.0 1059.2 1084.3 1119.0 30324.2 30601.7 30722.0 31324.5 31485.4 1122.6 1144.8 1172.8 1172.9 1212.0 13107.9 13344.9 16411.8 16447.4 16449.1 705991.0 710653.0 713479.0 716093.0 717148.0 61363.0 62044.0 62152.0 62175.7 62224.4 1289.4 1391.0 1418.4 1427.7 1437.1 31193.8 31557.0 31872.7 32060.5 32429.2 51542.7 51626.0 52032.0 52178.2 52235.0 552473.0 557839.0 559642.0 560819.0 560858.0 24620.0 27516.3 27931.0 27958.3 28053.9 42031.9 48705.8 48903.0 49326.3 50027.2 39971.6 40227.6 40290.4 41187.8 41343.8 32456.8 36434.0 41770.0 42629.8 43291.8 40502.4 40866.8 41341.0 42217.0 42533.1 41098.5 42396.4 43108.7 43302.4 43415.0 29589.4 32429.4 32614.0 32822.3 33436.8 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#2] jquery.html Cycles:180 Average:34618.11 Median:21335.13 stddev:99411.28 (466.0%) stddev-sans-first:99656.86 05:53:36 INFO - PROCESS | 1677 | Values: 353.7 360.9 383.9 399.0 403.9 18512.5 18924.8 20673.8 21114.2 21160.9 20247.3 20733.8 21169.8 21332.3 21338.0 5263.5 5693.6 5913.4 6538.8 6539.6 6276.8 6431.4 6431.5 6461.7 6469.3 5337.6 6466.9 6470.9 6476.8 6489.3 5436.6 5809.2 6448.7 6460.3 6467.0 5427.6 5628.9 5640.7 5658.8 5667.0 24903.0 25190.9 25833.6 26511.6 26547.3 2072.1 2093.2 2106.7 2128.2 2162.1 9520.1 9989.3 10129.3 12654.0 14728.9 1837.6 1916.2 1925.3 1928.7 1958.6 116015.0 116959.8 119082.0 122605.0 123831.0 11254.7 11501.7 11545.7 11734.8 12055.0 14701.3 14796.6 14799.0 14812.3 14835.7 8438.3 8484.4 8518.8 8519.7 8674.6 8408.7 8409.8 8421.1 8556.3 8572.0 4507.1 4509.7 4523.5 4548.4 4580.9 1868.3 1931.8 1933.0 1945.6 1953.7 787.9 978.8 1006.4 1022.4 1055.6 19998.8 20401.8 20446.4 20711.8 20771.6 879.3 974.7 976.5 978.9 1058.9 10425.7 10799.6 10883.9 10908.9 10973.4 513197.0 602667.0 611662.0 619016.0 622495.0 101554.0 122298.4 141761.0 142501.0 144960.3 993.1 1000.8 1007.7 1042.7 1046.3 18201.7 18578.4 18642.0 18753.4 19264.2 33310.3 47123.5 50126.7 51560.0 51602.0 54421.2 72053.6 72082.3 72203.6 72287.6 7996.5 8057.6 8184.8 8241.7 8370.0 12106.6 12750.4 12853.6 12890.3 13161.0 5347.1 7597.5 9543.7 10852.2 11249.3 10668.6 10885.3 11005.0 11079.4 11137.2 11224.6 11354.8 11428.3 11540.6 11703.2 6592.6 6714.1 7017.9 8052.7 8349.7 10903.8 11440.7 11452.2 11522.5 11543.9 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#3] mootools.html Cycles:180 Average:5683.59 Median:4965.97 stddev:3184.97 (64.1%) stddev-sans-first:3177.64 05:53:36 INFO - PROCESS | 1677 | Values: 1401.3 1531.2 1572.5 1789.6 1942.5 7897.7 8594.9 8836.8 8839.3 9005.2 7209.0 8639.5 8827.3 8830.9 8834.5 2018.6 2348.8 2382.5 2386.9 2387.7 2334.6 2366.3 2379.6 2379.7 2390.2 2228.6 2247.0 2247.8 2271.5 2287.9 2315.3 2318.4 2350.1 2358.4 2359.9 2317.4 2356.8 2362.1 2363.6 2365.0 8658.5 8741.0 8846.1 8852.3 8872.7 4859.5 5107.4 5226.2 5447.6 5456.4 3488.5 3625.2 3690.9 3705.5 3712.1 1061.2 1094.0 1097.3 1099.5 1120.7 11557.8 11653.6 11660.0 11680.9 11694.2 4502.4 4518.3 4654.3 4659.9 4662.9 8931.0 9152.8 9164.4 9168.1 9171.3 4605.0 5242.9 5250.9 5255.9 5256.2 4435.4 4578.8 4774.3 4911.7 4929.2 3761.2 3795.2 3852.0 3886.6 3908.1 982.4 1162.1 1163.4 1164.3 1164.6 663.7 788.0 916.5 955.5 985.7 5728.2 5758.9 5926.8 6001.4 6107.3 919.2 931.4 975.6 994.1 995.2 3956.0 4021.8 4024.3 4069.8 4077.2 10228.5 10361.8 10787.1 10789.6 10799.5 10630.8 10677.1 10839.6 10851.3 10867.5 8013.2 8117.8 8275.1 8282.4 8329.8 5969.8 6025.6 6044.8 6088.4 6126.0 9842.4 10039.3 10134.1 10134.3 10142.6 9723.3 9836.2 10348.1 10360.0 10375.4 7427.0 7611.0 7693.4 7694.4 7700.1 8168.8 8286.2 8321.7 8347.7 8357.0 7483.0 7518.2 7551.9 7580.4 7666.8 7325.9 7355.2 7365.7 7480.9 7491.6 6879.2 7002.9 7057.1 7062.9 7064.3 4843.6 5002.7 5012.5 5013.8 5148.3 4387.9 4412.9 5048.3 5077.7 5215.8 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#4] prototype.html Cycles:180 Average:4505.36 Median:5103.23 stddev:1405.63 (27.5%) stddev-sans-first:1373.67 05:53:36 INFO - PROCESS | 1677 | Values: 299.3 309.6 312.2 318.5 318.5 4426.9 5104.9 5407.7 5418.7 5459.6 4542.9 4648.6 5054.9 5274.4 5371.5 3789.5 3817.9 3873.0 3917.8 4064.6 3374.3 4003.7 4033.6 4051.3 4056.5 3311.3 4002.3 4018.1 4048.6 4056.8 3150.3 3554.3 3946.2 4037.5 4069.7 3291.0 3507.1 3508.0 3517.5 3518.6 4199.0 5097.3 5101.6 5142.2 5144.2 4225.4 4265.3 4622.7 4626.2 4634.3 5609.9 5627.5 5634.6 5646.6 5663.8 3519.7 3838.7 3846.0 3855.8 3873.1 4802.5 5437.8 5561.3 5568.7 5638.0 3376.5 3836.7 3858.8 3884.2 3912.9 3534.7 3752.5 3881.7 3889.9 3960.9 3904.2 4082.7 4203.7 4651.7 4670.2 5060.9 5184.4 5644.8 5754.6 5827.4 1217.2 1252.8 1300.5 1347.0 1347.8 789.4 809.3 828.2 831.1 838.7 4488.3 5007.2 5025.3 5200.4 5247.3 5142.3 5208.4 5602.6 5708.3 5835.5 4136.0 4950.8 5155.4 5240.3 5246.7 2917.8 3035.9 3040.1 3048.5 3071.0 4848.7 5342.9 6006.6 6046.5 6058.9 5605.4 5953.7 5955.5 5980.2 6000.6 5270.2 5595.5 6005.7 6011.7 6028.2 4859.0 5407.2 5543.8 5623.4 5795.9 5230.6 5247.7 5253.1 5628.0 5745.8 6026.8 6037.1 6043.5 6047.3 6076.0 3569.3 3823.8 3826.1 3826.3 3879.6 5128.8 5782.6 5820.6 5831.7 5834.6 5632.3 5653.7 5654.1 5656.7 5673.6 4693.3 5321.5 5429.8 5599.5 5628.6 4656.8 4851.6 5225.5 5528.5 5547.4 5388.5 5510.7 5533.3 5635.3 5682.5 4708.8 4829.4 5524.1 5534.9 5551.8 05:53:36 INFO - PROCESS | 1677 | 05:53:36 INFO - PROCESS | 1677 | [#5] yui.html Cycles:180 Average:17859.18 Median:4536.05 stddev:23903.33 (527.0%) stddev-sans-first:23935.71 05:53:36 INFO - PROCESS | 1677 | Values: 712.2 825.8 838.9 839.7 866.6 14765.2 15136.8 15317.8 15385.0 15386.0 13101.9 13108.3 13110.1 13131.5 13137.7 714.5 742.0 884.2 907.8 907.9 884.2 895.4 899.6 902.0 902.4 952.6 954.0 954.4 954.6 954.9 753.3 780.4 782.7 786.3 945.9 577.7 688.0 708.6 708.9 709.3 14349.7 14401.2 14412.9 14415.0 14419.5 11509.9 13392.9 13504.3 13582.4 13867.4 10924.9 11381.2 11556.5 11774.8 12011.2 4463.3 4522.6 4536.5 4585.1 4762.8 79721.6 80010.6 80423.7 81361.0 81772.4 10602.7 10672.6 10687.2 10821.3 11149.5 19936.8 20103.5 20143.5 20150.4 20340.4 7631.5 7719.1 7830.9 7872.6 7916.2 4325.3 4362.6 4447.9 4535.6 4673.6 3106.2 3209.4 3250.4 3251.7 3258.2 685.6 689.3 703.7 706.3 707.2 651.9 682.0 682.6 687.9 708.5 7792.7 8958.0 9146.5 9338.5 9642.5 624.3 639.0 665.3 675.6 708.3 4355.6 4377.0 4395.1 4450.9 4473.1 80842.0 81974.6 87869.0 88018.6 88308.2 70275.0 71823.8 72863.9 73853.0 74183.9 69890.3 70384.0 70621.6 71242.0 71725.3 8441.5 8975.0 9229.6 9439.4 9504.2 33295.7 37267.1 38015.8 38094.6 38772.0 46302.5 60729.7 61224.7 63502.0 65421.0 11548.8 13005.3 13344.7 13479.9 13484.7 14217.4 14402.4 14671.1 15028.2 15049.3 13346.2 13418.3 13522.8 13607.9 13658.6 11418.7 13149.9 13372.3 14022.4 14197.6 12511.7 12625.4 12795.8 12944.5 13066.8 10643.4 10771.7 10805.5 11084.5 11203.1 10554.6 10832.9 11107.9 11122.7 11220.3 05:53:36 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:53:36 INFO - PROCESS | 1677 | 05:53:37 INFO - TEST-INFO | 1677: exit 0 05:53:37 INFO - TEST-OK | dromaeo_css | took 1115708ms 05:53:37 INFO - TEST-START | kraken 05:53:37 INFO - operating with platform_type : mac_ 05:53:37 INFO - Initialising browser for kraken test... 05:53:37 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpDu9a8k/profile http://localhost:49282/getInfo.html) 05:53:39 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 05:53:39 INFO - PROCESS | 1684 | colorDepth:24 05:53:39 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 05:53:39 INFO - PROCESS | 1684 | __metrics 05:53:39 INFO - PROCESS | 1684 | 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:53:39 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:53:40 INFO - TEST-INFO | 1684: exit 0 05:53:40 INFO - Browser initialized. 05:53:40 INFO - Running cycle 1/1 for kraken test... 05:53:40 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpDu9a8k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:53:52 INFO - PROCESS | 1685 | RSS: Main: 241664000 05:53:52 INFO - PROCESS | 1685 | 05:53:55 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 05:53:55 INFO - PROCESS | 1685 | RSS: Main: 292626432 05:53:55 INFO - PROCESS | 1685 | 05:53:58 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 05:53:59 INFO - PROCESS | 1685 | RSS: Main: 301629440 05:53:59 INFO - PROCESS | 1685 | 05:54:02 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 05:54:03 INFO - PROCESS | 1685 | RSS: Main: 387403776 05:54:03 INFO - PROCESS | 1685 | 05:54:05 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 05:54:05 INFO - PROCESS | 1685 | RSS: Main: 288505856 05:54:05 INFO - PROCESS | 1685 | 05:54:08 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:54:08 INFO - PROCESS | 1685 | RSS: Main: 292253696 05:54:08 INFO - PROCESS | 1685 | 05:54:11 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 05:54:12 INFO - PROCESS | 1685 | RSS: Main: 331612160 05:54:12 INFO - PROCESS | 1685 | 05:54:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 05:54:15 INFO - PROCESS | 1685 | RSS: Main: 331640832 05:54:15 INFO - PROCESS | 1685 | 05:54:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 05:54:18 INFO - PROCESS | 1685 | RSS: Main: 330579968 05:54:18 INFO - PROCESS | 1685 | 05:54:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:54:21 INFO - PROCESS | 1685 | RSS: Main: 309579776 05:54:21 INFO - PROCESS | 1685 | 05:54:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:54:23 INFO - PROCESS | 1685 | RSS: Main: 316055552 05:54:23 INFO - PROCESS | 1685 | 05:54:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:54:26 INFO - PROCESS | 1685 | RSS: Main: 321138688 05:54:26 INFO - PROCESS | 1685 | 05:54:29 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:54:30 INFO - PROCESS | 1685 | RSS: Main: 323694592 05:54:30 INFO - PROCESS | 1685 | 05:54:33 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:54:33 INFO - PROCESS | 1685 | RSS: Main: 323178496 05:54:33 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 05:54:35 INFO - PROCESS | 1685 | RSS: Main: 366665728 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | __start_tp_report 05:54:35 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 05:54:35 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 05:54:35 INFO - PROCESS | 1685 | |i|pagename|runs| 05:54:35 INFO - PROCESS | 1685 | |0;ai-astar;110;108;113;112;112;112;111;112;112;111 05:54:35 INFO - PROCESS | 1685 | |1;audio-beat-detection;156;124;116;176;140;141;141;117;173;119 05:54:35 INFO - PROCESS | 1685 | |2;audio-dft;185;181;185;180;182;183;181;176;184;184 05:54:35 INFO - PROCESS | 1685 | |3;audio-fft;74;77;57;104;57;57;60;64;130;72 05:54:35 INFO - PROCESS | 1685 | |4;audio-oscillator;82;86;94;70;71;68;95;70;67;99 05:54:35 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;88;85;87;87;120;84;87;84;87;84 05:54:35 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;96;96;95;94;94;96;99;94;94 05:54:35 INFO - PROCESS | 1685 | |7;imaging-desaturate;84;84;83;84;83;85;85;84;85;87 05:54:35 INFO - PROCESS | 1685 | |8;json-parse-financial;57;57;56;58;54;60;75;84;79;54 05:54:35 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;69;79;67;72;73;70;70;69;70;70 05:54:35 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;87;86;75;80;99;105;106;85;150;103 05:54:35 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;138;139;139;135;137;136;136;140;149;145 05:54:35 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;164;165;180;167;162;164;165;162;214;174 05:54:35 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;68;67;66;67;66;64;67;65;65;66 05:54:35 INFO - PROCESS | 1685 | __end_tp_report 05:54:35 INFO - PROCESS | 1685 | __start_cc_report 05:54:35 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2080 05:54:35 INFO - PROCESS | 1685 | __end_cc_report 05:54:35 INFO - PROCESS | 1685 | __startTimestamp1464440075744__endTimestamp 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | ------- Summary: start ------- 05:54:35 INFO - PROCESS | 1685 | Number of tests: 14 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:1.42 (1.3%) stddev-sans-first:1.42 05:54:35 INFO - PROCESS | 1685 | Values: 110.0 108.0 113.0 112.0 112.0 112.0 111.0 112.0 112.0 111.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:141.00 stddev:22.22 (15.8%) stddev-sans-first:22.83 05:54:35 INFO - PROCESS | 1685 | Values: 156.0 124.0 116.0 176.0 140.0 141.0 141.0 117.0 173.0 119.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:182.10 Median:183.50 stddev:2.77 (1.5%) stddev-sans-first:2.73 05:54:35 INFO - PROCESS | 1685 | Values: 185.0 181.0 185.0 180.0 182.0 183.0 181.0 176.0 184.0 184.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:75.20 Median:62.00 stddev:24.03 (38.8%) stddev-sans-first:25.49 05:54:35 INFO - PROCESS | 1685 | Values: 74.0 77.0 57.0 104.0 57.0 57.0 60.0 64.0 130.0 72.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:84.00 stddev:12.54 (14.9%) stddev-sans-first:13.29 05:54:35 INFO - PROCESS | 1685 | Values: 82.0 86.0 94.0 70.0 71.0 68.0 95.0 70.0 67.0 99.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:87.00 stddev:10.89 (12.5%) stddev-sans-first:11.54 05:54:35 INFO - PROCESS | 1685 | Values: 88.0 85.0 87.0 87.0 120.0 84.0 87.0 84.0 87.0 84.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.50 stddev:1.57 (1.6%) stddev-sans-first:1.66 05:54:35 INFO - PROCESS | 1685 | Values: 95.0 96.0 96.0 95.0 94.0 94.0 96.0 99.0 94.0 94.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.24 05:54:35 INFO - PROCESS | 1685 | Values: 84.0 84.0 83.0 84.0 83.0 85.0 85.0 84.0 85.0 87.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:59.00 stddev:11.34 (19.2%) stddev-sans-first:11.78 05:54:35 INFO - PROCESS | 1685 | Values: 57.0 57.0 56.0 58.0 54.0 60.0 75.0 84.0 79.0 54.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.00 stddev:3.28 (4.7%) stddev-sans-first:3.41 05:54:35 INFO - PROCESS | 1685 | Values: 69.0 79.0 67.0 72.0 73.0 70.0 70.0 69.0 70.0 70.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:97.60 Median:82.50 stddev:21.42 (26.0%) stddev-sans-first:22.37 05:54:35 INFO - PROCESS | 1685 | Values: 87.0 86.0 75.0 80.0 99.0 105.0 106.0 85.0 150.0 103.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:139.00 stddev:4.40 (3.2%) stddev-sans-first:4.64 05:54:35 INFO - PROCESS | 1685 | Values: 138.0 139.0 139.0 135.0 137.0 136.0 136.0 140.0 149.0 145.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:166.00 stddev:15.92 (9.6%) stddev-sans-first:16.64 05:54:35 INFO - PROCESS | 1685 | Values: 164.0 165.0 180.0 167.0 162.0 164.0 165.0 162.0 214.0 174.0 05:54:35 INFO - PROCESS | 1685 | 05:54:35 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.20 (1.8%) stddev-sans-first:1.05 05:54:35 INFO - PROCESS | 1685 | Values: 68.0 67.0 66.0 67.0 66.0 64.0 67.0 65.0 65.0 66.0 05:54:35 INFO - PROCESS | 1685 | -------- Summary: end -------- 05:54:35 INFO - PROCESS | 1685 | 05:54:36 INFO - TEST-INFO | 1685: exit 0 05:54:36 INFO - TEST-OK | kraken | took 59263ms 05:54:36 INFO - SUITE-END | took 1174s 05:54:36 INFO - Completed test suite (00:19:35) 05:54:36 INFO - javascript benchmark 05:54:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3440.4323286657705, 3447.6724711152447, 3448.8275586220684, 3468.7398445116614, 3517.34929038128, 5539.473749993755, 5570.331560212775, 5663.603547751659, 5719.971400143004, 5749.108888122338, 5598.272224610795, 5621.971890140544, 5774.220480235167, 5777.855553611162, 5782.624129431584, 3745.4381842723674, 5024.422191447994, 5082.08522465956, 5134.537891589756, 5241.0, 5247.737613119348, 5253.0, 5270.341207349082, 5299.125644268691, 5313.442088580682, 5114.309568208281, 5166.276721259027, 5168.741562921877, 5214.165733482643, 5238.947610523884, 5194.168932970744, 5229.320188375486, 5234.398044224887, 5240.633155679066, 5264.5525130363585, 5148.61385396093, 5202.739863006835, 5208.713520756357, 5225.503577160163, 5248.947510524884, 5071.757419432226, 5613.382527921925, 5628.015097357946, 5638.605297629168, 5659.0, 5124.5131712487255, 5860.150278209679, 6094.207752992127, 6135.478484328837, 6151.89265932132, 6188.350223226586, 6215.782447614311, 6217.0, 6232.781852635181, 6235.158253635746, 5791.160281759206, 5826.708664566755, 5840.007198776176, 5877.676727780014, 5889.734961926723, 6146.815595532141, 6232.594881332699, 6247.687615619201, 6268.529860260499, 6274.05889116627, 6121.387861213843, 6138.969305153535, 6145.0782382642965, 6164.137020817089, 6180.876382472416, 6254.718537665725, 6275.560710750204, 6275.654838983808, 6287.874242515124, 6291.622502649856, 6064.939350606525, 6104.0, 6111.694415279218, 6135.1224528509065, 6142.314611233224, 6017.914343338499, 6102.0348389851015, 6115.602485838406, 6130.979101201984, 6187.876242475125, 4881.658283920163, 4920.21276595743, 4933.777979990909, 4944.085344211332, 4970.304157417964, 4460.219461594208, 4487.483939346952, 4503.617192538605, 4506.909861802746, 4507.053518761031, 5110.97444512775, 5647.887042259132, 5672.0, 5724.599278050579, 5731.426857314235, 6190.907136392958, 6262.624242545462, 6275.403836635513, 6285.748570057147, 6313.621182729051, 5712.771489140471, 5737.139429085671, 5742.511886489611, 5750.942490575125, 5770.682612456271, 5181.3782346118705, 5272.551833094153, 5343.572514198855, 5362.705051222142, 5404.378496472956, 6268.185135932391, 6296.087067375296, 6306.810795676137, 6322.0200868865395, 6355.0, 6309.842253943688, 6315.5894866833505, 6317.115603815378, 6341.968290158519, 6349.523785716182, 6242.968785156045, 6243.313235544178, 6261.24865016201, 6304.779332723333, 6305.653189074645, 6174.969125154345, 6179.474744646755, 6196.318404975539, 6207.348228435949, 6224.533160013108, 6123.938760612515, 6195.4424101831055, 6231.594946328474, 6254.437100660963, 6257.5932564383165, 5904.9704751475965, 5916.733746981482, 6025.758969641341, 6060.515158787198, 6062.818115456544, 5896.380880007538, 5988.580799344179, 5998.25021872266, 5999.0, 6049.879002420015, 5771.653700777967, 5826.30084389859, 5926.525877929842, 5945.405459454193, 5953.374895636069, 5068.493150684902, 5993.160957466045, 6063.575549711478, 6084.847878802888, 6126.578745377076, 5874.647521148745, 5888.587798854039, 5896.469317761422, 5904.380040095728, 5914.112883067574], "value": 5663.414314248356, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.51278772378515, 517.4101524262342, 518.6810111781253, 524.4886235919978, 557.7350758389763, 30873.0, 31122.84438577807, 42290.99999999996, 42691.786541067326, 42791.786041069754, 44301.55698443014, 44614.33078503825, 44684.329735054, 44757.0, 44833.7758311208, 9485.905140948606, 9694.951525242364, 9704.805903881917, 9708.466034368106, 9709.417434953926, 8610.354223433236, 8801.295896328278, 11439.142064345166, 11627.127965402586, 11627.709307267343, 11744.471498782532, 11839.467223974898, 11846.933775960162, 11856.288622682623, 11859.0, 8268.669253229864, 11467.08263338931, 11486.827697584544, 11587.304761714282, 11602.477888494954, 11482.0, 11781.0, 11810.586629467927, 11846.644600661994, 11850.46672899713, 40888.0, 41321.0, 41378.58621413777, 41431.58568414307, 41506.54727084568, 3454.4472884338384, 3501.9649803501893, 3502.1069627244888, 3515.82420878955, 3657.1040095176586, 26641.86679066592, 31916.36167276665, 32166.0, 32417.351652967045, 33582.0, 3317.270200555874, 3333.3499967506345, 3351.6815902489225, 3394.439917413624, 3401.48977653354, 511869.0, 530131.3493432547, 530211.0, 530815.0, 532259.0, 35807.0, 68058.3194168052, 69388.65305673439, 69472.6526367375, 69581.0, 36608.81695591558, 37295.81352093277, 37353.066173345884, 37361.62638373636, 37436.06409839776, 14841.406343746345, 15065.397384104732, 15099.698006039818, 15102.565256300633, 15126.319315630823, 11453.255538389978, 11585.015273701829, 12579.685507862194, 12711.936440317739, 12827.743445131231, 7979.0, 8034.317083048007, 8047.155048719917, 8114.0, 8127.918720812834, 3400.7449441292, 3411.1301618087227, 3418.1796368871787, 3418.572678415198, 3452.2750222453064, 1044.0186224948525, 1051.952662130206, 1059.2267644619317, 1084.2952081147316, 1118.9608363707232, 30324.24189395283, 30601.69398306033, 30722.0, 31324.530132048036, 31485.37029259402, 1122.6014764758556, 1144.811106167473, 1172.8416663750284, 1172.9237599556002, 1211.9879900283363, 13107.934460327637, 13344.866551334362, 16411.76911731601, 16447.424340148038, 16449.09529975863, 705991.0, 710653.0, 713479.0, 716093.0, 717148.0, 61363.0, 62044.0, 62152.0, 62175.6891215541, 62224.37775622186, 1289.3553223388305, 1390.958271251864, 1418.439716312051, 1427.6787722762294, 1437.116173553257, 31193.8440307797, 31557.00208480191, 31872.681273186972, 32060.51909221317, 32429.18927026843, 51542.74228628983, 51626.0, 52032.0, 52178.21732674089, 52235.0, 552473.0, 557839.0, 559642.0, 560819.0, 560858.0, 24620.0, 27516.312092197855, 27931.0, 27958.3010424741, 28053.859730701217, 42031.89840508023, 48705.75647121741, 48903.0219395603, 49326.26010609772, 50027.24959125543, 39971.60028399679, 40227.59772402239, 40290.395644064774, 41187.794061029505, 41343.7932810334, 32456.83771581127, 36434.0, 41769.95575110525, 42629.786851065546, 43291.78354108209, 40502.392464112476, 40866.773996780146, 41341.0, 42217.0, 42533.149337012466, 41098.4517090189, 42396.364054538586, 43108.70673879789, 43302.350464743686, 43415.0, 29589.408211836075, 32429.351412971137, 32614.02157935266, 32822.34355312928, 33436.83281583576], "value": 19834.467052129447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.6622525488159, 360.8773017174161, 383.85221689649495, 398.96010398960124, 403.9394090886367, 18512.537186570342, 18924.810751892477, 20673.793262067375, 21114.15543378265, 21160.89419552904, 20247.291344802936, 20733.792662073374, 21169.788302116973, 21332.253371132014, 21338.0, 5263.473652634725, 5693.629914055584, 5913.438223368773, 6538.771143009995, 6539.607623542579, 6276.807406592733, 6431.389018043278, 6431.48548116152, 6461.709223084949, 6469.320721324264, 5337.599680023995, 6466.902996455057, 6470.935290647104, 6476.773312934049, 6489.286178520359, 5436.646617969819, 5809.157672137559, 6448.742050317982, 6460.257070436889, 6467.029945508164, 5427.647202931837, 5628.859278518029, 5640.74616642248, 5658.801941932013, 5666.979943610148, 24903.0, 25190.874045629655, 25833.612495812577, 26511.602325965127, 26547.336316592045, 2072.0779253232317, 2093.1627349060345, 2106.747190337154, 2128.244473212003, 2162.10272736814, 9520.073101448126, 9989.300748947577, 10129.341592796518, 12654.0, 14728.926355368154, 1837.6416598763246, 1916.1664675866043, 1925.2972664977322, 1928.6914093745074, 1958.608278344337, 116015.0, 116959.83040169574, 119082.0, 122605.0, 123831.0, 11254.705708843425, 11501.712457188638, 11545.711357216136, 11734.765304693858, 12055.0, 14701.338439770023, 14796.556103316918, 14799.038062525902, 14812.333444995002, 14835.703285934222, 8438.324934005264, 8484.36367272457, 8518.829623407497, 8519.659213631508, 8674.6096425661, 8408.747737567883, 8409.831803363899, 8421.115782842835, 8556.315494760403, 8572.014218364968, 4507.143642707875, 4509.661775366861, 4523.5024147343765, 4548.363229147922, 4580.931286030712, 1868.3367404571454, 1931.8454523638075, 1933.0, 1945.5719741657097, 1953.658109830774, 787.9133295337508, 978.7602037500858, 1006.4162785584343, 1022.3967858963248, 1055.5830446973557, 19998.800071995727, 20401.79598203999, 20446.38660840177, 20711.792882070986, 20771.58456830855, 879.349281531662, 974.668612671695, 976.4775844922932, 978.8923218445965, 1058.941758203306, 10425.706344604176, 10799.622013229498, 10883.891161088446, 10908.945455272673, 10973.396463194442, 513197.0, 602667.0, 611662.0, 619016.0, 622495.0, 101554.0, 122298.38850805689, 141760.99999999793, 142501.0, 144960.27519862333, 993.0516356879164, 1000.7848312612823, 1007.6624330849247, 1042.702829693549, 1046.3251202974038, 18201.726974095134, 18578.44264672062, 18642.0, 18753.437396878115, 19264.229430822612, 33310.333793323516, 47123.52876471329, 50126.74429650747, 51560.0, 51602.0, 54421.18368224559, 72053.639731801, 72082.27917720756, 72203.63898180476, 72287.63856180686, 7996.520208787491, 8057.637406316847, 8184.754457366289, 8241.670333186605, 8369.95815020921, 12106.607740109648, 12750.362481876055, 12853.614391568268, 12890.29103399322, 13161.0, 5347.144456886879, 7597.544147351176, 9543.665971690958, 10852.186086043735, 11249.268797528135, 10668.62659806903, 10885.292455990335, 11005.0, 11079.390633515233, 11137.220394572301, 11224.551017959517, 11354.829677554679, 11428.25716328436, 11540.596079137189, 11703.180777345504, 6592.648507055946, 6714.127163468718, 7017.894731578928, 8052.677892884219, 8349.666013359398, 10903.781924361627, 11440.656780296604, 11452.198346115692, 11522.539098436304, 11543.884561154617], "value": 9558.767270645472, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.2783416540476, 1531.2114261155507, 1572.4968010236723, 1789.6241789224262, 1942.5240816000073, 7897.723579674713, 8594.871076933843, 8836.77908052299, 8839.337049721273, 9005.189532942033, 7209.0, 8639.52482613456, 8827.29381649468, 8830.911690883106, 8834.46993180408, 2018.5861898310811, 2348.8473249238746, 2382.4639456122322, 2386.9283921482297, 2387.7254115776645, 2334.6264597664376, 2366.3374255208482, 2379.631711765733, 2379.6668466414717, 2390.247072172269, 2228.6211344071467, 2247.045005872506, 2247.8426510144295, 2271.4889149941296, 2287.942801429969, 2315.2591170825344, 2318.362450326157, 2350.1304517328613, 2358.3750306168795, 2359.929202123939, 2317.3627252505526, 2356.7996720278798, 2362.126013375062, 2363.61000434928, 2365.0066971871856, 8658.523781192032, 8741.0, 8846.115388461167, 8852.291816654651, 8872.689455869013, 4859.465458799529, 5107.438181799999, 5226.189940559219, 5447.645903016291, 5456.427075157131, 3488.494168345601, 3625.23869987303, 3690.907727306812, 3705.5182826232417, 3712.090537818252, 1061.1828891753385, 1094.042712626452, 1097.253867370188, 1099.5052226498108, 1120.675004248767, 11557.826632600518, 11653.592124275694, 11660.0, 11680.948714615726, 11694.239874408137, 4502.414686090788, 4518.277075667943, 4654.2785868190485, 4659.906801864012, 4662.930056049162, 8931.017588065308, 9152.81694366109, 9164.404313719588, 9168.083191680778, 9171.312151588658, 4604.953950460453, 5242.895142097137, 5250.921236181384, 5255.947440525622, 5256.185291279858, 4435.445569303837, 4578.793954272066, 4774.259989701602, 4911.705297682079, 4929.186684197139, 3761.2101458693433, 3795.1650636859845, 3852.0, 3886.553046399644, 3908.085038331197, 982.40564458503, 1162.0761494611636, 1163.3717792392013, 1164.342146687119, 1164.5807509296646, 663.7046514301089, 787.9921200788034, 916.5417291354323, 955.4792638012161, 985.7042887133974, 5728.169415434739, 5758.913616295759, 5926.7925622603, 6001.4298641628975, 6107.267127944675, 919.1589695428742, 931.4271722890373, 975.6243696388059, 994.0953732103751, 995.2088089968493, 3955.960440395617, 4021.7586944783407, 4024.2957482440456, 4069.7965101744794, 4077.245709543744, 10228.488575571191, 10361.844572331422, 10787.137029037658, 10789.62236321725, 10799.460026998775, 10630.840537391945, 10677.092447141924, 10839.620613278497, 10851.294665846695, 10867.510962006729, 8013.19868013194, 8117.79705507367, 8275.131111233233, 8282.378821588405, 8329.750107496784, 5969.820905372846, 6025.638461692312, 6044.84887877789, 6088.4215999479975, 6125.9693701531205, 9842.360246583949, 10039.29724919278, 10134.087932086148, 10134.341267817568, 10142.594296228068, 9723.270754693567, 9836.163926066365, 10348.120409764953, 10359.964003599882, 10375.377477351383, 7426.962865185639, 7611.0, 7693.422993275415, 7694.4229182810395, 7700.114486834086, 8168.7957801055445, 8286.171382861665, 8321.6671333146, 8347.749567512985, 8357.0, 7483.027206463125, 7518.173000969999, 7551.942728018192, 7580.431467639839, 7666.846663066819, 7325.926740732524, 7355.2277010913085, 7365.668544915385, 7480.887786683313, 7491.550506969599, 6879.243283238739, 7002.859942801014, 7057.117860267466, 7062.869940809446, 7064.293570642894, 4843.564079232886, 5002.749862506933, 5012.548870601663, 5013.824516141917, 5148.30497882796, 4387.934180987224, 4412.933805992849, 5048.268001139888, 5077.669951453144, 5215.765290561858], "value": 4559.475985255392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.3145696355346, 309.5681524273638, 312.2085513223978, 318.46656849776616, 318.5365293497959, 4426.889327766803, 5104.897902041962, 5407.675539467625, 5418.701971391577, 5459.590530710193, 4542.9097016716, 4648.558946726517, 5054.924176137351, 5274.367075950891, 5371.462853714626, 3789.545254569449, 3817.9118951098944, 3872.9806350968206, 3917.784521851297, 4064.5732198119217, 3374.3420033093557, 4003.65968892643, 4033.636972672459, 4051.2653958577844, 4056.533498647649, 3311.304626028537, 4002.299597570428, 4018.0713257636207, 4048.57489963554, 4056.837726490937, 3150.3384289299274, 3554.3246783111126, 3946.1910308386714, 4037.5154981402125, 4069.674426045909, 3290.9835450822834, 3507.105688049531, 3507.9824600877087, 3517.4547945068557, 3518.612952575215, 4198.958010419918, 5097.3118628984985, 5101.566366858821, 5142.177251639757, 5144.228365745131, 4225.429567008476, 4265.338872474771, 4622.653301002439, 4626.236670949289, 4634.281686338589, 5609.887802243932, 5627.465390787868, 5634.605577609569, 5646.57650676201, 5663.773449062032, 3519.6832285094465, 3838.654521093115, 3845.9615403845883, 3855.768653880776, 3873.1479074603544, 4802.495737947535, 5437.8368648940195, 5561.304836895388, 5568.661162311839, 5638.0, 3376.5104059911178, 3836.693064554829, 3858.8456461741216, 3884.1843212925037, 3912.882613521599, 3534.6818786309354, 3752.5309336332957, 3881.728279020498, 3889.8833035008997, 3960.9009774755864, 3904.1606054698377, 4082.7346222495444, 4203.687971615599, 4651.674382793172, 4670.229412147026, 5060.912843165666, 5184.377874655065, 5644.78637093027, 5754.625949313281, 5827.426594819375, 1217.2027322104036, 1252.7870262055471, 1300.486307908371, 1347.0368686389083, 1347.7574036673298, 789.4434423731255, 809.3242142810819, 828.2234606768448, 831.0967883546731, 838.674893668003, 4488.259437192826, 5007.173816320266, 5025.296458495814, 5200.427952925175, 5247.344081989751, 5142.280080788692, 5208.427073021964, 5602.607817452739, 5708.286464191976, 5835.533157347402, 4135.97932010338, 4950.796895381593, 5155.38135423744, 5240.292560504283, 5246.658967167122, 2917.810342327742, 3035.9241018974703, 3040.130319853274, 3048.481758101128, 3071.0191926207917, 4848.666616680371, 5342.9465705342445, 6006.639601623917, 6046.516278697693, 6058.909116363258, 5605.402460298796, 5953.672548009901, 5955.52355811534, 5980.192673989043, 6000.639961602318, 5270.2054520745005, 5595.4748508439425, 6005.6997150142315, 6011.6994150293185, 6028.216331876828, 4859.00390419956, 5407.188921661782, 5543.805966791143, 5623.409541998195, 5795.884082318245, 5230.607704422259, 5247.714309994076, 5253.082624841982, 5627.986962346653, 5745.816589695889, 6026.819195424145, 6037.1021909482815, 6043.54673399488, 6047.274327080779, 6076.0, 3569.2861427714042, 3823.827927743314, 3826.1199924017073, 3826.349520581452, 3879.631435013669, 5128.8461346159675, 5782.5952183348445, 5820.592558520862, 5831.7375718093635, 5834.620749651259, 5632.295963004624, 5653.689047102284, 5654.095344744821, 5656.717164141694, 5673.602847800615, 4693.319468677091, 5321.494458026481, 5429.782808687608, 5599.468050535355, 5628.60599758013, 4656.790444430077, 4851.611871050237, 5225.529702326809, 5528.530074943675, 5547.389787123494, 5388.488093631256, 5510.6969116698965, 5533.280673512418, 5635.295588051494, 5682.5453963681975, 4708.788104535236, 4829.420469543677, 5524.14375771756, 5534.944650553443, 5551.7779288828], "value": 4055.8211434650993, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.2307907459943, 825.8472182646215, 838.9286910612598, 839.6683310092516, 866.5840396609631, 14765.18791466469, 15136.848631513682, 15317.84682153178, 15384.999975001645, 15385.999910005856, 13101.934490327534, 13108.27904465254, 13110.147840390367, 13131.540396086139, 13137.67155821105, 714.4820005496026, 742.0094174277347, 884.1821315283337, 907.7594437474074, 907.9137481939205, 884.2130503851578, 895.3866601378045, 899.5637115998735, 901.9774505637379, 902.4495058014605, 952.6284748947916, 954.0125969621444, 954.3605784124618, 954.5799848066863, 954.9188318992894, 753.2655660730799, 780.3912947900646, 782.6908371193404, 786.2766255045372, 945.9006804285555, 577.7082573300498, 687.9862402751967, 708.6421357214625, 708.8617719544691, 709.3367701199395, 14349.713005739932, 14401.20793356365, 14412.927935360362, 14415.0, 14419.495317663936, 11509.884901150965, 13392.86607133926, 13504.324783760772, 13582.388792504362, 13867.4453021879, 10924.890751092467, 11381.2033157679, 11556.479958401893, 11774.764504709858, 12011.219270747373, 4463.330500424962, 4522.6155776759015, 4536.500984891659, 4585.105904348653, 4762.761861906891, 79721.60139199324, 80010.59994700048, 80423.66945039225, 81360.9999999994, 81772.36455270862, 10602.681919542427, 10672.626458074086, 10687.198460115522, 10821.296615719953, 11149.498272577754, 19936.800631993785, 20103.497412564804, 20143.496412589804, 20150.39548813538, 20340.389788306373, 7631.54210747357, 7719.11230208522, 7830.921690783133, 7872.645730942121, 7916.208379162038, 4325.329573916048, 4362.607365337071, 4447.888802779957, 4535.591796738309, 4673.579377856009, 3106.2389714520086, 3209.3902158589794, 3250.382427338798, 3251.7398608111293, 3258.218027673389, 685.5783693028851, 689.3175756001523, 703.714995426853, 706.2831226305304, 707.2149913595905, 651.8924377477663, 681.9965900170467, 682.6382017530717, 687.8864987277143, 708.5005071424633, 7792.727254546064, 8958.0, 9146.451212927246, 9338.533073346305, 9642.469664168399, 624.2602516018533, 638.9680515974183, 665.2914645902099, 675.587891386254, 708.3129364516514, 4355.564443555619, 4376.978115109404, 4395.142947125344, 4450.864172934177, 4473.083017981306, 80842.0, 81974.59012704898, 87869.0, 88018.55990720133, 88308.23383532422, 70274.99999999898, 71823.8452846405, 72863.90704139443, 73853.0, 74183.88724169142, 69890.3010969894, 70384.0, 70621.64689176521, 71242.0, 71725.28274717394, 8441.493510389397, 8975.0, 9229.630814767334, 9439.433633981984, 9504.23966082698, 33295.66704332926, 37267.06832329214, 38015.809920951324, 38094.57225753954, 38772.0, 46302.536974629824, 60729.69635151796, 61224.693876530335, 63502.0, 65421.0, 11548.82676759866, 13005.349732513525, 13344.733105337644, 13479.932600336935, 13484.662883427993, 14217.431302747775, 14402.42390304376, 14671.119732816065, 15028.248587570797, 15049.322780475124, 13346.19922804635, 13418.32908354598, 13522.79715804244, 13607.86392136066, 13658.59024229235, 11418.714532136431, 13149.868501314864, 13372.264525450804, 14022.439102435788, 14197.574072777834, 12511.749765004466, 12625.431855566338, 12795.80806287925, 12944.482220711443, 13066.803997939849, 10643.361398316125, 10771.730706732395, 10805.51375188134, 11084.501197445972, 11203.103751700008, 10554.577816887238, 10832.891671083189, 11107.888921110685, 11122.721931951766, 11220.326780393203], "value": 7197.756991207013, "unit": "score"}], "name": "dromaeo_css", "value": 7230.855140018434, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 113.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 116.0, 176.0, 140.0, 141.0, 141.0, 117.0, 173.0, 119.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 185.0, 180.0, 182.0, 183.0, 181.0, 176.0, 184.0, 184.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 57.0, 104.0, 57.0, 57.0, 60.0, 64.0, 130.0, 72.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 94.0, 70.0, 71.0, 68.0, 95.0, 70.0, 67.0, 99.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 87.0, 120.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 94.0, 94.0, 96.0, 99.0, 94.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 83.0, 85.0, 85.0, 84.0, 85.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 58.0, 54.0, 60.0, 75.0, 84.0, 79.0, 54.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 67.0, 72.0, 73.0, 70.0, 70.0, 69.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 75.0, 80.0, 99.0, 105.0, 106.0, 85.0, 150.0, 103.0], "value": 97.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 139.0, 135.0, 137.0, 136.0, 136.0, 140.0, 149.0, 145.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 180.0, 167.0, 162.0, 164.0, 165.0, 162.0, 214.0, 174.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 66.0, 64.0, 67.0, 65.0, 65.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1467.1999999999998, "alertThreshold": 2.0}]} 05:54:36 INFO - Return code: 0 05:54:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:54:37 ERROR - # TBPL SUCCESS # 05:54:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:54:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:54:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:54:37 INFO - 2016-05-28 05:54:37.068 screenresolution[1689:10875] starting screenresolution argv=screenresolution get 05:54:37 INFO - 2016-05-28 05:54:37.071 screenresolution[1689:10875] Display 0: 1600x1200x32@60 05:54:37 INFO - 2016-05-28 05:54:37.077 screenresolution[1690:10878] starting screenresolution argv=screenresolution list 05:54:37 INFO - Available Modes on Display 0 05:54:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:54:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:54:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:54:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:54:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:54:37 INFO - Intel Iris: 05:54:37 INFO - Chipset Model: Intel Iris 05:54:37 INFO - Type: GPU 05:54:37 INFO - Bus: Built-In 05:54:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:54:37 INFO - Vendor: Intel (0x8086) 05:54:37 INFO - Device ID: 0x0a2e 05:54:37 INFO - Revision ID: 0x0009 05:54:37 INFO - Displays: 05:54:37 INFO - Display: 05:54:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:54:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:54:37 INFO - Display Serial Number: 1600x1200 60 05:54:37 INFO - Main Display: Yes 05:54:37 INFO - Mirror: Off 05:54:37 INFO - Online: Yes 05:54:37 INFO - Rotation: Supported 05:54:37 INFO - Return code: 0 05:54:37 INFO - Running post-action listener: _resource_record_post_action 05:54:37 INFO - ##### 05:54:37 INFO - ##### Finished run-tests step (success) 05:54:37 INFO - ##### 05:54:37 INFO - Running post-run listener: _resource_record_post_run 05:54:37 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31669760; Write bytes: 694396416; Read time: 199; Write time: 1929 05:54:37 INFO - TinderboxPrint: CPU usage
53.6% 05:54:37 INFO - TinderboxPrint: I/O read bytes / time
31,669,760 / 199 05:54:37 INFO - TinderboxPrint: I/O write bytes / time
694,396,416 / 1,929 05:54:37 INFO - TinderboxPrint: CPU idle
2,248.6 (46.4%) 05:54:37 INFO - TinderboxPrint: CPU system
960.0 (19.8%) 05:54:37 INFO - TinderboxPrint: CPU user
1,633.1 (33.7%) 05:54:37 INFO - TinderboxPrint: Swap in / out
426,020,864 / 0 05:54:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377113088; Write bytes: 371242496; Read time: 28292; Write time: 780 05:54:37 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30748160; Write bytes: 293998592; Read time: 188; Write time: 1079 05:54:37 INFO - Running post-run listener: _upload_blobber_files 05:54:37 INFO - Blob upload gear active. 05:54:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:54:37 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:54:37 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:54:37 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:54:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:54:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:54:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:54:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:39 INFO - (blobuploader) - INFO - Done attempting. 05:54:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:54:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:54:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:40 INFO - (blobuploader) - INFO - Done attempting. 05:54:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:54:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:54:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:54:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:54:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:54:42 INFO - (blobuploader) - INFO - Done attempting. 05:54:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:54:42 INFO - Return code: 0 05:54:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:54:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:54:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09e4c070657150f44232b3597ddb9290af8c87abe1ff0300d2c3812bdc95213cd00e589a68811b8f03b8de576dac77fded1ab72bcc9b06151a61f7c3d8a04a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed9ce33ee4fcc9649729094d573acf14b93df36e6e1e5fba605fe09d27daa2958a868b8a7ea427ec6b03f22d3f8bbec597ce2a25586fc275cd4027ad33052179", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c50fbbda406c5da78bfc4becd792428e5d4bf982c9b87195712c61e4186d68acd50d87fc01992252c01c906eda9fd5c75196c643d82e7b67efa95aab5cd556d"} 05:54:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:54:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:54:42 INFO - Contents: 05:54:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09e4c070657150f44232b3597ddb9290af8c87abe1ff0300d2c3812bdc95213cd00e589a68811b8f03b8de576dac77fded1ab72bcc9b06151a61f7c3d8a04a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed9ce33ee4fcc9649729094d573acf14b93df36e6e1e5fba605fe09d27daa2958a868b8a7ea427ec6b03f22d3f8bbec597ce2a25586fc275cd4027ad33052179", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c50fbbda406c5da78bfc4becd792428e5d4bf982c9b87195712c61e4186d68acd50d87fc01992252c01c906eda9fd5c75196c643d82e7b67efa95aab5cd556d"} 05:54:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:54:42 INFO - Copying logs to upload dir... 05:54:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:54:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.837995 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-28 05:54:42.795209) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 05:54:42.798880) ========= 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.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/09e4c070657150f44232b3597ddb9290af8c87abe1ff0300d2c3812bdc95213cd00e589a68811b8f03b8de576dac77fded1ab72bcc9b06151a61f7c3d8a04a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed9ce33ee4fcc9649729094d573acf14b93df36e6e1e5fba605fe09d27daa2958a868b8a7ea427ec6b03f22d3f8bbec597ce2a25586fc275cd4027ad33052179", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c50fbbda406c5da78bfc4becd792428e5d4bf982c9b87195712c61e4186d68acd50d87fc01992252c01c906eda9fd5c75196c643d82e7b67efa95aab5cd556d"} build_url:https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/eYj2REvAQQeyS2Ff3U0M8A/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/09e4c070657150f44232b3597ddb9290af8c87abe1ff0300d2c3812bdc95213cd00e589a68811b8f03b8de576dac77fded1ab72bcc9b06151a61f7c3d8a04a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed9ce33ee4fcc9649729094d573acf14b93df36e6e1e5fba605fe09d27daa2958a868b8a7ea427ec6b03f22d3f8bbec597ce2a25586fc275cd4027ad33052179", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c50fbbda406c5da78bfc4becd792428e5d4bf982c9b87195712c61e4186d68acd50d87fc01992252c01c906eda9fd5c75196c643d82e7b67efa95aab5cd556d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 05:54:42.830385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:54:42.830783) ========= 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.NPEIJtDHfJ/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.9693I2TCHU/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005218 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 05:54:42.883472) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 05:54:42.884220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 05:54:42.885002) ========= ========= Total master_lag: 0.23 =========