builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0308 starttime: 1463140179.43 results: success (0) buildid: 20160513030046 builduid: f13e940a58724e47b6786411ede947d8 revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.430713) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.431124) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.431392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.457699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.457992) ========= 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.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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-13 04:49:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 141M=0s 2016-05-13 04:49:39 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.639005) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.639575) ========= 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.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.024935 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.692842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:39.695242) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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-13 04:49:39,771 truncating revision to first 12 chars 2016-05-13 04:49:39,771 Setting DEBUG logging. 2016-05-13 04:49:39,771 attempt 1/10 2016-05-13 04:49:39,771 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a2deb5a9b09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 04:49:40,021 unpacking tar archive at: mozilla-central-5a2deb5a9b09/testing/mozharness/ program finished with exit code 0 elapsedTime=0.503269 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:40.215170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:40.215472) ========= script_repo_revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:40.215859) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:40.216138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:49:40.229283) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-13 04:49:40.229554) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:49:40 INFO - MultiFileLogger online at 20160513 04:49:40 in /builds/slave/test 04:49:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:49:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:49:40 INFO - {'append_to_log': False, 04:49:40 INFO - 'base_work_dir': '/builds/slave/test', 04:49:40 INFO - 'blob_upload_branch': 'Firefox', 04:49:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:49:40 INFO - 'branch': 'Firefox', 04:49:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:40 INFO - 'config_files': ('talos/mac_config.py',), 04:49:40 INFO - 'default_actions': ('clobber', 04:49:40 INFO - 'read-buildbot-config', 04:49:40 INFO - 'download-and-extract', 04:49:40 INFO - 'populate-webroot', 04:49:40 INFO - 'create-virtualenv', 04:49:40 INFO - 'install', 04:49:40 INFO - 'run-tests'), 04:49:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:40 INFO - 'download_minidump_stackwalk': True, 04:49:40 INFO - 'download_symbols': 'ondemand', 04:49:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:40 INFO - 'installer_path': 'installer.exe', 04:49:40 INFO - 'log_level': 'info', 04:49:40 INFO - 'log_name': 'talos', 04:49:40 INFO - 'log_to_console': True, 04:49:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:49:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:49:40 INFO - 'opt_config_files': (), 04:49:40 INFO - 'pip_index': False, 04:49:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:40 INFO - 'cmd': ('bash', 04:49:40 INFO - '-c', 04:49:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:40 INFO - 'enabled': True, 04:49:40 INFO - 'halt_on_failure': False, 04:49:40 INFO - 'name': 'check_screen_resolution'},), 04:49:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:40 INFO - 'cmd': ('bash', 04:49:40 INFO - '-c', 04:49:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:40 INFO - 'enabled': True, 04:49:40 INFO - 'halt_on_failure': False, 04:49:40 INFO - 'name': 'check_screen_resolution'},), 04:49:40 INFO - 'run_cmd_checks_enabled': True, 04:49:40 INFO - 'sps_profile': False, 04:49:40 INFO - 'sps_profile_interval': 0, 04:49:40 INFO - 'suite': 'dromaeojs', 04:49:40 INFO - 'system_bits': '32', 04:49:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:40 INFO - 'title': 't-yosemite-r7-0308', 04:49:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:40 INFO - 'use_talos_json': True, 04:49:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:49:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:40 INFO - 'work_dir': 'build'} 04:49:40 INFO - ##### 04:49:40 INFO - ##### Running clobber step. 04:49:40 INFO - ##### 04:49:40 INFO - Running pre-action listener: _resource_record_pre_action 04:49:40 INFO - Running main action method: clobber 04:49:40 INFO - rmtree: /builds/slave/test/build 04:49:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:49:42 INFO - Running post-action listener: _resource_record_post_action 04:49:42 INFO - ##### 04:49:42 INFO - ##### Finished clobber step (success) 04:49:42 INFO - ##### 04:49:42 INFO - ##### 04:49:42 INFO - ##### Running read-buildbot-config step. 04:49:42 INFO - ##### 04:49:42 INFO - Running pre-action listener: _resource_record_pre_action 04:49:42 INFO - Running main action method: read_buildbot_config 04:49:42 INFO - Using buildbot properties: 04:49:42 INFO - { 04:49:42 INFO - "project": "", 04:49:42 INFO - "product": "firefox", 04:49:42 INFO - "script_repo_revision": "production", 04:49:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:49:42 INFO - "repository": "", 04:49:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:49:42 INFO - "buildid": "20160513030046", 04:49:42 INFO - "pgo_build": "False", 04:49:42 INFO - "basedir": "/builds/slave/test", 04:49:42 INFO - "buildnumber": 6, 04:49:42 INFO - "slavename": "t-yosemite-r7-0308", 04:49:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:49:42 INFO - "platform": "yosemite_r7", 04:49:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:49:42 INFO - "branch": "mozilla-central", 04:49:42 INFO - "repo_path": "mozilla-central", 04:49:42 INFO - "revision": "5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4", 04:49:42 INFO - "stage_platform": "macosx64", 04:49:42 INFO - "builduid": "f13e940a58724e47b6786411ede947d8", 04:49:42 INFO - "slavebuilddir": "test" 04:49:42 INFO - } 04:49:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:49:42 INFO - Running post-action listener: _resource_record_post_action 04:49:42 INFO - ##### 04:49:42 INFO - ##### Finished read-buildbot-config step (success) 04:49:42 INFO - ##### 04:49:42 INFO - ##### 04:49:42 INFO - ##### Running download-and-extract step. 04:49:42 INFO - ##### 04:49:42 INFO - Running pre-action listener: _resource_record_pre_action 04:49:42 INFO - Running main action method: download_and_extract 04:49:42 INFO - mkdir: /builds/slave/test/build/tests 04:49:42 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')]} 04:49:42 INFO - https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:49:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:49:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:49:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:43 INFO - Downloaded 1199 bytes. 04:49:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:49:43 INFO - Using the following test package requirements: 04:49:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:49:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:49:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:49:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'jsshell-mac.zip'], 04:49:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:49:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:49:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:49:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:49:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:49:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:49:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:49:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:43 INFO - https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:49:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:49:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:49:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:46 INFO - Downloaded 18153870 bytes. 04:49:46 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'] 04:49:46 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 04:49:49 INFO - Return code: 0 04:49:49 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 04:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:49 INFO - https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:49:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:49:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:49:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:50 INFO - Downloaded 18153870 bytes. 04:49:50 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'] 04:49:50 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 04:49:53 INFO - Return code: 0 04:49:53 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')]} 04:49:53 INFO - https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:49:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:49:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:49:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:54 INFO - Downloaded 10840239 bytes. 04:49:54 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'] 04:49:54 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 04:49:55 INFO - Return code: 0 04:49: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')]} 04:49:55 INFO - https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:49:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:49:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:49:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:49:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/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 04:49:59 INFO - Downloaded 110077139 bytes. 04:49:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:49:59 INFO - mkdir: /builds/slave/test/properties 04:49:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:49:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:49:59 INFO - Contents: 04:49:59 INFO - build_url:https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:50:00 INFO - Running post-action listener: _resource_record_post_action 04:50:00 INFO - Running post-action listener: set_extra_try_arguments 04:50:00 INFO - ##### 04:50:00 INFO - ##### Finished download-and-extract step (success) 04:50:00 INFO - ##### 04:50:00 INFO - ##### 04:50:00 INFO - ##### Running populate-webroot step. 04:50:00 INFO - ##### 04:50:00 INFO - Running pre-action listener: _resource_record_pre_action 04:50:00 INFO - Running main action method: populate_webroot 04:50:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:50:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:50:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:50:00 INFO - '--tppagecycles', 04:50:00 INFO - '7'], 04:50:00 INFO - 'tests': ['tsvgm']}}, 04:50:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:50:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:50:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:50:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:50:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['damp', 'tps']}, 04:50:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'tests': ['damp', 'tps']}, 04:50:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['dromaeo_dom']}, 04:50:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:50:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['a11yr', 04:50:00 INFO - 'ts_paint', 04:50:00 INFO - 'tpaint', 04:50:00 INFO - 'sessionrestore', 04:50:00 INFO - 'sessionrestore_no_auto_restore', 04:50:00 INFO - 'tabpaint']}, 04:50:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['tsvgx', 04:50:00 INFO - 'tsvgr_opacity', 04:50:00 INFO - 'tart', 04:50:00 INFO - 'tscrollx', 04:50:00 INFO - 'cart']}, 04:50:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:50:00 INFO - 'tsvgr_opacity', 04:50:00 INFO - 'tart', 04:50:00 INFO - 'tscrollx', 04:50:00 INFO - 'cart']}, 04:50:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'talos_options': ['--disable-e10s'], 04:50:00 INFO - 'tests': ['tp5o']}, 04:50:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'tests': ['tp5o']}, 04:50:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'talos_options': ['--disable-e10s', 04:50:00 INFO - '--xperf_path', 04:50:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:00 INFO - 'tests': ['tp5n']}, 04:50:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:00 INFO - 'talos_options': ['--xperf_path', 04:50:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:00 INFO - 'tests': ['tp5n']}}, 04:50:00 INFO - 'talos.zip': {'path': '', 04:50:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:50:00 INFO - Running post-action listener: _resource_record_post_action 04:50:00 INFO - ##### 04:50:00 INFO - ##### Finished populate-webroot step (success) 04:50:00 INFO - ##### 04:50:00 INFO - ##### 04:50:00 INFO - ##### Running create-virtualenv step. 04:50:00 INFO - ##### 04:50:00 INFO - Running pre-action listener: _resource_record_pre_action 04:50:00 INFO - Running main action method: create_virtualenv 04:50:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:50:00 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 04:50:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:50:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:50:00 INFO - Using real prefix '/tools/python27' 04:50:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:50:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:50:03 INFO - Installing pip.................done. 04:50:03 INFO - Return code: 0 04:50:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:50:03 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')]} 04:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:03 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')]} 04:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:03 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:03 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 04:50:03 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 04:50:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:03 INFO - 'HOME': '/Users/cltbld', 04:50:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:03 INFO - 'LOGNAME': 'cltbld', 04:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:03 INFO - 'MOZ_NO_REMOTE': '1', 04:50:03 INFO - 'NO_EM_RESTART': '1', 04:50:03 INFO - 'PAGER': '/bin/cat', 04:50:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:03 INFO - 'PWD': '/builds/slave/test', 04:50:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:03 INFO - 'SHELL': '/bin/bash', 04:50:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:03 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:03 INFO - 'USER': 'cltbld', 04:50:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:03 INFO - 'XPC_FLAGS': '0x0', 04:50:03 INFO - 'XPC_SERVICE_NAME': '0', 04:50:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:04 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 04:50:04 INFO - Cleaning up... 04:50:04 INFO - Return code: 0 04:50:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:04 INFO - 'HOME': '/Users/cltbld', 04:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:04 INFO - 'LOGNAME': 'cltbld', 04:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:04 INFO - 'MOZ_NO_REMOTE': '1', 04:50:04 INFO - 'NO_EM_RESTART': '1', 04:50:04 INFO - 'PAGER': '/bin/cat', 04:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:04 INFO - 'PWD': '/builds/slave/test', 04:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:04 INFO - 'SHELL': '/bin/bash', 04:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:04 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:04 INFO - 'USER': 'cltbld', 04:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:04 INFO - 'XPC_FLAGS': '0x0', 04:50:04 INFO - 'XPC_SERVICE_NAME': '0', 04:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:04 INFO - Downloading/unpacking psutil>=3.1.1 04:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:50:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:07 INFO - Installing collected packages: psutil 04:50:07 INFO - Running setup.py install for psutil 04:50:07 INFO - building 'psutil._psutil_osx' extension 04:50:07 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 04:50:07 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 04:50:08 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 04:50:08 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 04:50:08 INFO - building 'psutil._psutil_posix' extension 04:50:08 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 04:50:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:50:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:50:08 INFO - ^ 04:50:08 INFO - 1 warning generated. 04:50:08 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 04:50:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:08 INFO - Successfully installed psutil 04:50:08 INFO - Cleaning up... 04:50:08 INFO - Return code: 0 04:50:08 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 04:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 04:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 04:50:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:08 INFO - 'HOME': '/Users/cltbld', 04:50:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:08 INFO - 'LOGNAME': 'cltbld', 04:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:08 INFO - 'MOZ_NO_REMOTE': '1', 04:50:08 INFO - 'NO_EM_RESTART': '1', 04:50:08 INFO - 'PAGER': '/bin/cat', 04:50:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:08 INFO - 'PWD': '/builds/slave/test', 04:50:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:08 INFO - 'SHELL': '/bin/bash', 04:50:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:08 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:08 INFO - 'USER': 'cltbld', 04:50:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:08 INFO - 'XPC_FLAGS': '0x0', 04:50:08 INFO - 'XPC_SERVICE_NAME': '0', 04:50:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:08 INFO - Downloading/unpacking mozsystemmonitor==0.1 04:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:11 INFO - Downloading mozsystemmonitor-0.1.tar.gz 04:50:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:50:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 04:50:11 INFO - Installing collected packages: mozsystemmonitor 04:50:11 INFO - Running setup.py install for mozsystemmonitor 04:50:11 INFO - Successfully installed mozsystemmonitor 04:50:11 INFO - Cleaning up... 04:50:11 INFO - Return code: 0 04:50:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:50:11 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')]} 04:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:11 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')]} 04:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:11 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:11 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 04:50:11 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 04:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:11 INFO - 'HOME': '/Users/cltbld', 04:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:11 INFO - 'LOGNAME': 'cltbld', 04:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:11 INFO - 'MOZ_NO_REMOTE': '1', 04:50:11 INFO - 'NO_EM_RESTART': '1', 04:50:11 INFO - 'PAGER': '/bin/cat', 04:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:11 INFO - 'PWD': '/builds/slave/test', 04:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:11 INFO - 'SHELL': '/bin/bash', 04:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:11 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:11 INFO - 'USER': 'cltbld', 04:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:11 INFO - 'XPC_FLAGS': '0x0', 04:50:11 INFO - 'XPC_SERVICE_NAME': '0', 04:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:50:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:50:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:15 INFO - Downloading docopt-0.6.1.tar.gz 04:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:50:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:50:15 INFO - Running setup.py install for blobuploader 04:50:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:50:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:50:15 INFO - Running setup.py install for requests 04:50:16 INFO - Running setup.py install for docopt 04:50:16 INFO - Successfully installed blobuploader requests docopt 04:50:16 INFO - Cleaning up... 04:50:16 INFO - Return code: 0 04:50:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:16 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')]} 04:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 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')]} 04:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:16 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 04:50:16 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 04:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:16 INFO - 'HOME': '/Users/cltbld', 04:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:16 INFO - 'LOGNAME': 'cltbld', 04:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:16 INFO - 'MOZ_NO_REMOTE': '1', 04:50:16 INFO - 'NO_EM_RESTART': '1', 04:50:16 INFO - 'PAGER': '/bin/cat', 04:50:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:16 INFO - 'PWD': '/builds/slave/test', 04:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:16 INFO - 'SHELL': '/bin/bash', 04:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:16 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:16 INFO - 'USER': 'cltbld', 04:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:16 INFO - 'XPC_FLAGS': '0x0', 04:50:16 INFO - 'XPC_SERVICE_NAME': '0', 04:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:16 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NgXJ8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:16 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ruSPok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:16 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-het7ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:16 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-G63jsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-PVRmUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-An7Prp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-vfCUnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-R7ceWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tnKPUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cK_Io0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-mCgeZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-l40cKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:17 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-2XGs3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:18 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-HS0Z47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:18 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UjIkxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:18 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-FQBLB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:18 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tPogA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:50:18 INFO - Running setup.py install for manifestparser 04:50:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:50:18 INFO - Running setup.py install for mozcrash 04:50:18 INFO - Running setup.py install for mozdebug 04:50:18 INFO - Running setup.py install for mozdevice 04:50:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Running setup.py install for mozfile 04:50:19 INFO - Running setup.py install for mozhttpd 04:50:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Running setup.py install for mozinfo 04:50:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Running setup.py install for mozInstall 04:50:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Running setup.py install for mozleak 04:50:19 INFO - Running setup.py install for mozlog 04:50:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:19 INFO - Running setup.py install for moznetwork 04:50:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Running setup.py install for mozprocess 04:50:20 INFO - Running setup.py install for mozprofile 04:50:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Running setup.py install for mozrunner 04:50:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Running setup.py install for mozscreenshot 04:50:20 INFO - Running setup.py install for moztest 04:50:20 INFO - Running setup.py install for mozversion 04:50:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:20 INFO - Cleaning up... 04:50:20 INFO - Return code: 0 04:50:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:20 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:20 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 04:50:20 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 04:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:20 INFO - 'HOME': '/Users/cltbld', 04:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:20 INFO - 'LOGNAME': 'cltbld', 04:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:20 INFO - 'MOZ_NO_REMOTE': '1', 04:50:20 INFO - 'NO_EM_RESTART': '1', 04:50:20 INFO - 'PAGER': '/bin/cat', 04:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:20 INFO - 'PWD': '/builds/slave/test', 04:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:20 INFO - 'SHELL': '/bin/bash', 04:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:20 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:20 INFO - 'USER': 'cltbld', 04:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:20 INFO - 'XPC_FLAGS': '0x0', 04:50:20 INFO - 'XPC_SERVICE_NAME': '0', 04:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-m6SLo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-vBFCR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-WuJuhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tSr37f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ULwL8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-0vgCT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-eOcNCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:21 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)) 04:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:21 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-N7BJCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Xqzzs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-gkiO3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cuc3Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-i3hiOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-bpOo_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-xg8zou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-1dlfcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-kcpaZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:22 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)) 04:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:22 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-H4Q4q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:23 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)) 04:50:23 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)) 04:50:23 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)) 04:50:23 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)) 04:50:23 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)) 04:50:23 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)) 04:50:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:25 INFO - Downloading blessings-1.6.tar.gz 04:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:25 INFO - Installing collected packages: blessings 04:50:25 INFO - Running setup.py install for blessings 04:50:25 INFO - Successfully installed blessings 04:50:25 INFO - Cleaning up... 04:50:25 INFO - Return code: 0 04:50:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:26 INFO - Reading from file tmpfile_stdout 04:50:26 INFO - Current package versions: 04:50:26 INFO - blessings == 1.6 04:50:26 INFO - blobuploader == 1.2.4 04:50:26 INFO - docopt == 0.6.1 04:50:26 INFO - manifestparser == 1.1 04:50:26 INFO - mozInstall == 1.12 04:50:26 INFO - mozcrash == 0.17 04:50:26 INFO - mozdebug == 0.1 04:50:26 INFO - mozdevice == 0.48 04:50:26 INFO - mozfile == 1.2 04:50:26 INFO - mozhttpd == 0.7 04:50:26 INFO - mozinfo == 0.9 04:50:26 INFO - mozleak == 0.1 04:50:26 INFO - mozlog == 3.2 04:50:26 INFO - moznetwork == 0.27 04:50:26 INFO - mozprocess == 0.22 04:50:26 INFO - mozprofile == 0.28 04:50:26 INFO - mozrunner == 6.11 04:50:26 INFO - mozscreenshot == 0.1 04:50:26 INFO - mozsystemmonitor == 0.1 04:50:26 INFO - moztest == 0.7 04:50:26 INFO - mozversion == 1.4 04:50:26 INFO - psutil == 3.1.1 04:50:26 INFO - requests == 1.2.3 04:50:26 INFO - wsgiref == 0.1.2 04:50:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:26 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 04:50:26 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 04:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:26 INFO - 'HOME': '/Users/cltbld', 04:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:26 INFO - 'LOGNAME': 'cltbld', 04:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:26 INFO - 'MOZ_NO_REMOTE': '1', 04:50:26 INFO - 'NO_EM_RESTART': '1', 04:50:26 INFO - 'PAGER': '/bin/cat', 04:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:26 INFO - 'PWD': '/builds/slave/test', 04:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:26 INFO - 'SHELL': '/bin/bash', 04:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:26 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:26 INFO - 'USER': 'cltbld', 04:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:26 INFO - 'XPC_FLAGS': '0x0', 04:50:26 INFO - 'XPC_SERVICE_NAME': '0', 04:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 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)) 04:50:26 INFO - Cleaning up... 04:50:26 INFO - Return code: 0 04:50:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:26 INFO - 'HOME': '/Users/cltbld', 04:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:26 INFO - 'LOGNAME': 'cltbld', 04:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:26 INFO - 'MOZ_NO_REMOTE': '1', 04:50:26 INFO - 'NO_EM_RESTART': '1', 04:50:26 INFO - 'PAGER': '/bin/cat', 04:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:26 INFO - 'PWD': '/builds/slave/test', 04:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:26 INFO - 'SHELL': '/bin/bash', 04:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:26 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:26 INFO - 'USER': 'cltbld', 04:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:26 INFO - 'XPC_FLAGS': '0x0', 04:50:26 INFO - 'XPC_SERVICE_NAME': '0', 04:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:26 INFO - Downloading/unpacking jsonschema 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:50:29 INFO - Downloading/unpacking functools32 (from jsonschema) 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:50:29 INFO - warning: no files found matching '*.txt' 04:50:29 INFO - no previously-included directories found matching 'build' 04:50:29 INFO - no previously-included directories found matching 'dist' 04:50:29 INFO - no previously-included directories found matching '.git*' 04:50:29 INFO - Installing collected packages: jsonschema, functools32 04:50:29 INFO - Running setup.py install for functools32 04:50:29 INFO - warning: no files found matching '*.txt' 04:50:29 INFO - no previously-included directories found matching 'build' 04:50:29 INFO - no previously-included directories found matching 'dist' 04:50:29 INFO - no previously-included directories found matching '.git*' 04:50:29 INFO - Successfully installed jsonschema functools32 04:50:29 INFO - Cleaning up... 04:50:29 INFO - Return code: 0 04:50:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:50:29 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')]} 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:29 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')]} 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:29 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 0x103090a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103096570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf1d77cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, '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.ksLky2mV6T/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.VIrhWH5yqq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 04:50:29 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 04:50:29 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 04:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:29 INFO - 'HOME': '/Users/cltbld', 04:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:29 INFO - 'LOGNAME': 'cltbld', 04:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:29 INFO - 'MOZ_NO_REMOTE': '1', 04:50:29 INFO - 'NO_EM_RESTART': '1', 04:50:29 INFO - 'PAGER': '/bin/cat', 04:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:29 INFO - 'PWD': '/builds/slave/test', 04:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:29 INFO - 'SHELL': '/bin/bash', 04:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:50:29 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:29 INFO - 'USER': 'cltbld', 04:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:29 INFO - 'XPC_FLAGS': '0x0', 04:50:29 INFO - 'XPC_SERVICE_NAME': '0', 04:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:30 INFO - Downloading/unpacking flake8 04:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:50:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:50:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:32 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 04:50:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:50:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:50:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:50:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:50:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:50:33 INFO - no previously-included directories found matching 'docs/_build' 04:50:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:50:33 INFO - Running setup.py install for pep8 04:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:50:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:50:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:50:34 INFO - no previously-included directories found matching 'docs/_build' 04:50:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:50:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:50:34 INFO - Cleaning up... 04:50:34 INFO - Return code: 0 04:50:34 INFO - Running post-action listener: _resource_record_post_action 04:50:34 INFO - Running post-action listener: _start_resource_monitoring 04:50:34 INFO - Starting resource monitoring. 04:50:34 INFO - ##### 04:50:34 INFO - ##### Finished create-virtualenv step (success) 04:50:34 INFO - ##### 04:50:34 INFO - ##### 04:50:34 INFO - ##### Running install step. 04:50:34 INFO - ##### 04:50:34 INFO - Running pre-action listener: _resource_record_pre_action 04:50:34 INFO - Running main action method: install 04:50:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:34 INFO - Reading from file tmpfile_stdout 04:50:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:34 INFO - Reading from file tmpfile_stdout 04:50:34 INFO - Output received: 04:50:34 INFO - Usage: mozinstall [options] installer 04:50:34 INFO - Options: 04:50:34 INFO - -h, --help show this help message and exit 04:50:34 INFO - -d DEST, --destination=DEST 04:50:34 INFO - Directory to install application into. [default: 04:50:34 INFO - "/builds/slave/test"] 04:50:34 INFO - --app=APP Application being installed. [default: firefox] 04:50:34 INFO - mkdir: /builds/slave/test/build/application 04:50:34 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'] 04:50:34 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 04:51:08 INFO - Reading from file tmpfile_stdout 04:51:08 INFO - Output received: 04:51:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:51:08 INFO - Running post-action listener: _resource_record_post_action 04:51:08 INFO - ##### 04:51:08 INFO - ##### Finished install step (success) 04:51:08 INFO - ##### 04:51:08 INFO - ##### 04:51:08 INFO - ##### Running run-tests step. 04:51:08 INFO - ##### 04:51:08 INFO - Running pre-action listener: _resource_record_pre_action 04:51:08 INFO - Running main action method: run_tests 04:51:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:51:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:51:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:51:08 INFO - 2016-05-13 04:51:08.019 screenresolution[1641:7595] starting screenresolution argv=screenresolution get 04:51:08 INFO - 2016-05-13 04:51:08.022 screenresolution[1641:7595] Display 0: 1600x1200x32@60 04:51:08 INFO - 2016-05-13 04:51:08.029 screenresolution[1642:7598] starting screenresolution argv=screenresolution list 04:51:08 INFO - Available Modes on Display 0 04:51:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:51:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:51:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:51:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:51:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:51:08 INFO - Intel Iris: 04:51:08 INFO - Chipset Model: Intel Iris 04:51:08 INFO - Type: GPU 04:51:08 INFO - Bus: Built-In 04:51:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:51:08 INFO - Vendor: Intel (0x8086) 04:51:08 INFO - Device ID: 0x0a2e 04:51:08 INFO - Revision ID: 0x0009 04:51:08 INFO - Displays: 04:51:08 INFO - Display: 04:51:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:51:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:51:08 INFO - Display Serial Number: 1600x1200 60 04:51:08 INFO - Main Display: Yes 04:51:08 INFO - Mirror: Off 04:51:08 INFO - Online: Yes 04:51:08 INFO - Rotation: Supported 04:51:08 INFO - Return code: 0 04:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:51:08 INFO - Python 2.7.3 04:51:08 INFO - Return code: 0 04:51:08 INFO - grabbing minidump binary from tooltool 04:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:08 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 0x102f34d30>, '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 0x102d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:51:08 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 04:51:08 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 04:51:08 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 04:51:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:51:08 INFO - Return code: 0 04:51:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:51:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:51:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:51:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:51:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:51:09 INFO - Return code: 0 04:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 04:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0308 --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 04:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksLky2mV6T/Render', 04:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:09 INFO - 'HOME': '/Users/cltbld', 04:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:09 INFO - 'LOGNAME': 'cltbld', 04:51:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:51:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:09 INFO - 'MOZ_NO_REMOTE': '1', 04:51:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:51:09 INFO - 'NO_EM_RESTART': '1', 04:51:09 INFO - 'PAGER': '/bin/cat', 04:51:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:09 INFO - 'PWD': '/builds/slave/test', 04:51:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:09 INFO - 'SHELL': '/bin/bash', 04:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VIrhWH5yqq/Listeners', 04:51:09 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 04:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:09 INFO - 'USER': 'cltbld', 04:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:09 INFO - 'XPC_FLAGS': '0x0', 04:51:09 INFO - 'XPC_SERVICE_NAME': '0', 04:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 04:51:09 INFO - mozversion application_buildid: 20160513030046 04:51:09 INFO - mozversion application_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 04:51:09 INFO - mozversion application_display_name: Nightly 04:51:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:51:09 INFO - mozversion application_name: Firefox 04:51:09 INFO - mozversion application_remotingname: firefox 04:51:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:51:09 INFO - mozversion application_vendor: Mozilla 04:51:09 INFO - mozversion application_version: 49.0a1 04:51:09 INFO - mozversion platform_buildid: 20160513030046 04:51:09 INFO - mozversion platform_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 04:51:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:51:09 INFO - mozversion platform_version: 49.0a1 04:51:09 INFO - using testdate: 1463140269 04:51:09 INFO - actual date: 1463140269 04:51:09 INFO - starting webserver on 'localhost:49275' 04:51:09 INFO - SUITE-START | Running 2 tests 04:51:09 INFO - TEST-START | dromaeo_css 04:51:09 INFO - operating with platform_type : mac_ 04:51:09 INFO - Initialising browser for dromaeo_css test... 04:51:09 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpTXeU1v/profile http://localhost:49275/getInfo.html) 04:51:11 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:51:11 INFO - PROCESS | 1660 | colorDepth:24 04:51:11 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:51:11 INFO - PROCESS | 1660 | __metrics 04:51:12 INFO - PROCESS | 1660 | 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" 04:51:12 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:51:12 INFO - TEST-INFO | 1660: exit 0 04:51:12 INFO - Browser initialized. 04:51:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:51:12 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpTXeU1v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:24 INFO - PROCESS | 1662 | RSS: Main: 238964736 04:51:24 INFO - PROCESS | 1662 | 04:52:15 INFO - PROCESS | 1662 | 1463140335816 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] 04:54:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 04:54:27 INFO - PROCESS | 1662 | RSS: Main: 367915008 04:54:27 INFO - PROCESS | 1662 | 04:57:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 04:57:31 INFO - PROCESS | 1662 | RSS: Main: 373276672 04:57:31 INFO - PROCESS | 1662 | 05:00:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:00:34 INFO - PROCESS | 1662 | RSS: Main: 370331648 05:00:34 INFO - PROCESS | 1662 | 05:03:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 05:03:36 INFO - PROCESS | 1662 | RSS: Main: 367435776 05:03:36 INFO - PROCESS | 1662 | 05:06:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 05:06:40 INFO - PROCESS | 1662 | RSS: Main: 333008896 05:06:40 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 05:09:43 INFO - PROCESS | 1662 | RSS: Main: 378232832 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | __start_tp_report 05:09:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:09:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:09:43 INFO - PROCESS | 1662 | |i|pagename|runs| 05:09:43 INFO - PROCESS | 1662 | |0;dojo.html;2963.466576016317;3475.5829300483947;3488.5464889564355;3503.1592417819697;3551.4140166872467;5143.485651434855;5209.218617207416;5640.351359593648;5682.573806964473;5764.798232061884;5707.086866101425;5712.571557133211;5721.199032135504;5804.187413762077;5837.474627283544;5116.641835071547;5143.794248230085;5210.5310522053005;5220.190870415092;5309.761060752276;5268.762905669254;5283.260343551906;5305.549028332577;5327.440618735035;5371.5165635092835;5124.743762811844;5126.307948426952;5158.716270605115;5182.300389447414;5195.40252870919;5138.126518491828;5166.5866730661455;5191.169412893919;5196.818111366122;5224.320838291036;5177.326947496839;5192.948070519285;5207.297014902978;5210.791568337262;5227.3465816772905;5530.6128571000045;5602.271704678406;5667.149927510865;5695.857603559944;5725.6278341908055;6187.876242475125;6203.2556093269095;6203.348648391899;6210.347913469066;6215.782447614357;5436.966976274534;5746.712664366807;6109.511239100906;6176.660283684396;6211.906821397637;5770.79802206925;5845.2401187845735;5858.179854820329;5924.081767325985;5942.64344139353;6304.968475157595;6333.619982801047;6353.904691429632;6354.872902541923;6361.4592759615025;6061.575689701766;6067.74288113449;6199.101130336076;6244.812655620339;6257.186565746424;6185.597936134137;6241.594296370721;6242.65665388408;6287.245530536274;6290.21372328459;6176.073588961692;6187.84530386735;6221.253449586078;6243.7814676486105;6259.561830671809;6166.445019948226;6236.064590311399;6250.218722659668;6268.122462855204;6290.119383286344;4888.120138375056;4917.139500587383;4946.3817022872145;4965.379327584052;5005.099082165245;4426.269665505156;4430.911381772411;4438.866833994985;4441.933370999438;4447.777611119431;5824.126381042793;5843.0066888629035;5843.795467158629;5862.091375836752;5869.970650146722;5218.739063046833;6186;6189.319174890758;6301.684915754286;6338.366163383625;5767.884642307215;5814.59297849155;5823.155642431823;5836.358000619928;5868.823935281863;5396;5416.404195538487;5423.132298832168;5452.754626041838;5469.371022332403;5181.92227116586;5695.800646977253;6286;6327.493800496041;6416.935830641633;6318.368163183645;6339.397757213057;6349.555531112777;6358.523110766619;6361.904571431525;6182.783602573888;6359.523035772243;6445.12990746262;6468.223813142265;6494.967525162344;6113.476306051762;6176.536759742948;6183.288921774054;6195.256569211723;6218.098375735583;6154.415330543591;6188.4739797117745;6215.440610345091;6221;6226.906596401148;4918.852434426973;5921.496672782861;5926.555508336805;6115.4190351916495;6156.661383623944;6127.111568822585;6211.937880621317;6213.937860621336;6222.28443728977;6225.408586184305;5967.671778052075;5989.724242349167;5999.880002400015;6046.274447066205;6049;6012.609180403259;6075.301340345917;6125.693715314127;6211.3167551570195;6225.62646241227;5827.329857066492;5890.970545147418;5892.381299963614;5941.197938278277;5942.881142377215 05:09:43 INFO - PROCESS | 1662 | |1;ext.html;517.0770175237202;519.2678323563774;523.1707743226985;527.5832092646809;550.6365798572942;27643.447131057394;31400;43203.13593728127;44606.776966115125;44711.32933005999;36585.268294634094;44539.109217815625;44732.329015064715;44840.551594484044;44929;9707.02929707029;9893.75265618358;9901.207903367713;9904.900950990506;9906.504674766269;8729.694460693878;8914.153155450222;11704.882951170464;11918.52325906967;11923.940380298087;11683.299002059903;11767.352795596238;11774;11785.941070294637;11794.351310677952;8783.472991620523;11527.711807204803;11603.129765267639;11623.302601843832;11654.417279136094;9664.516774161335;11651.76696466066;11654.533818647244;11656.650300491;11673.70815729605;41462.37806432906;41898.37152442716;41924.951876203035;41938.58061419377;41997.530086447136;3210.4060748761553;3211.1811488070393;3218.452863013293;3267.9019629411155;3427.8114703691285;25645.46127232372;29479.263018424495;29490.557641635394;30298.242543936358;30827.229319266975;2714.3214196450886;2751.55975043992;2764.0514232876194;2839.9716002839914;2881.1644623059287;514503;520613;521403;521957.3902130503;523329;36135.638643613755;63806.04290935547;64617.67691161608;64772.67613661902;64841.86739458656;36843.447348289796;38185.80907095502;38201.23597528034;38204.0448988772;38281.425778613346;15493.225338733018;15748.212589370485;15756.212189390484;15759.606009849616;15775.447859324868;12038.759224815454;12216.511339546496;12974.80537791934;13118.212907225598;13190.274534900673;8210.794730131674;8312.376571757142;8327.167283271745;8382.87425688615;8388.161183881562;3417.128632198773;3444.7588668793446;3449.81026043565;3460.619331873492;3483.059573915029;957.9042095790365;973.8441849304078;992.692265397729;1009.7273736091214;1114.8494953181216;30470.69529304723;30574.54138187929;30857.84448293231;31109.68890311068;31468.842655786575;1105.5135740274254;1122.4387806096952;1131.8358837968449;1157.5948418053613;1161.7269941563725;14272.85727142736;14276.857851371866;15737.921310393374;15954.702548728643;16062.678746425006;677603;681335;682874;688028.3978701517;691051;52338;62812.05781913275;63342;63510.6824465884;63541.682291588244;1311.2525860259561;1352.154903185509;1484.6140003599141;1511.1764088571754;1539.538138558428;30014.399712006074;31404.371912562077;31417.371652566362;32332;32376.676233238308;54217;54962.450375497334;54980;54989;55133;534451;544586;546086;546419;547232;24373.26880193597;26985.86507067452;27236.046738364854;27704.722952770215;28913.7108628911;47609;47918;48014.279785802544;48588.5141148584;49739.75130124326;38978.2204355917;39503.60496395114;39678.404823927085;39967.2006559873;41029.794851026745;38924.80537597294;39332.410013850385;39749.60250397459;40314.395284070175;40790.388144178454;32722.83638581792;39212.2157556853;39583.40624890687;40339.596604033584;40349.79825100856;33430.66569334276;33923.83038084794;34747.82626086853;35052.773152939524;35160.29679406449;24428.26715198547;25282.36794080089;25614.23157305284;26030.86984565065;26391.34021649474 05:09:43 INFO - PROCESS | 1662 | |2;jquery.html;399.43280541630884;408.61998341542375;408.8814243869278;412.09133859839056;428.29545397820584;17791.199396027176;20479.590408191845;20870.165193392248;21003.159873605036;21766.238181663644;20630.48423789406;20739.792602073976;20899.791002089976;20929.476763080893;21327.57344853106;5682.460166284196;6191;6198.2252218472695;6237.688115594225;6256.781012664557;6114;6120.326764055951;6177.104319873616;6182.752689892399;6240;5911.556633252501;6136.447719705226;6138.478229350489;6153.138560601519;6154.138420621117;5991.790287339944;6031.125486804411;6060.393960603904;6066.150738896514;6095.268567771852;5182.766775495112;5313.256144139785;5342.904704535561;5354.223637572569;5355.169948657965;26250.606240906403;26654.46691066187;26899;27366;27462.17613471579;2136.59404713104;2162.794534519234;2239.9776002240096;2327.3599760066118;2804.3690169711854;9581.856272155923;9983.950080249624;10111.949440252753;10297.124744396735;10417.010384013507;1578.5690037342479;1756.1833747307435;1759.81521940197;1821.8815776974454;1837.5130590393555;102403.48798255961;124805;130176.04735928969;131401.99999999904;134871.65128348788;11577.942110289563;11730;11732.237404568676;11748.35384053868;11872.821907671392;15205.923970380078;15232.923835380752;15262.694746105239;15265.923670381577;15305.846941530664;8789.824203515895;8838.29293656506;8844.823103537894;8859.557022148867;8939.016708162097;8762.780930476789;8787.956060219658;8801.51591662452;8865.734027979172;8906.421082629608;4687.953120468752;4689.484156742756;4706.905861882743;4717.268823332389;4773.427188737373;1638.6394993101499;1917.9712304315447;1959.8726082804571;1982.5638359560874;1982.682770756672;823.3495538524618;836.1847198980945;893.8793262909554;980.294188184506;1000.1798525209258;20572;20755.481112972295;20800.167993280098;21097.578048438947;21157.25949591757;802.4423025996877;928.8746019287445;945.9858102128474;945.9952700236455;960.1934375124929;10219.335743176669;11518.8374219792;11889.405529723479;11978.221415607957;12006.279623222634;636891;641879;647528;650432;655321;133927;141659;142275;159259;163044;802.3380710913519;1072.7693545887828;1088.9128869690405;1091.3233795046963;1095.2990086344746;15528;15892.52322430329;19131.85208887471;19559.902200488905;19660.50848728793;45158.41945531891;53124.4687553135;55115.724421377636;56255.718721406134;57693.42306577048;55866.72066639641;86039.56980215058;86483;87068.5646571763;89605;6945.062416573698;7486.663100160397;7542.660580273791;7606.20134885829;7703.653335600024;12426.254424734545;12599.307038112991;12862;13160.868391315964;13188.802167967297;8602;10998.670039898816;11234.269772464764;11527.481263343001;11577.247478913843;10840.024397804236;11004.229703920648;11021.283616564897;11175.050120739828;11361.886381136082;11387.772244554897;11468.025217856575;11569;11601.477933492844;11846.170768046499;7410.333070023724;7687.231276872491;7691.538507689556;7803.648835802516;7840.921590784247;9159.679411220575;10970.396628185526;11144.728696103779;11385.772284554429;11452.885471145515 05:09:43 INFO - PROCESS | 1662 | |3;mootools.html;1356.7896975968729;1432.8793385789031;1657.68503984243;1875.3436297295948;2022.1405902491438;8082.71710490133;8547.316214702825;8551.871721924168;8561;8872.136851261226;8859.291256699466;8922.776930576723;9067.319951003668;9072.364934454574;9082.046385129566;2417.492326611409;2452.362385779701;2453.3130723397476;2454.460018795862;2455.5702752018415;2402.1112188490283;2425.745296743843;2433.8904749286235;2443.59680652693;2443.767842055002;2401.399650087478;2414.843035202715;2427.890744916483;2442.780149786519;2446.217210492643;2384.523095380931;2404.0504000919627;2418.1536462238164;2418.3954011497126;2426.8665223412704;2383.4637206628545;2398.8440751351104;2403.242978461779;2404.4349577849193;2408.5182963407215;8978.102189781035;9075.319351048698;9076.22852057576;9080.273578113734;9097.863532047024;5279.70961597112;5352.91970620441;5517.420670829545;5527.778888844441;5533.806316778893;3079.691131269202;3606.8376923038527;3655.817209139532;3705.925881482355;3753.4557489163917;1028.9588416463332;1047.9685609431729;1049.8792638846478;1071.3411252079993;1072.3297938788257;12193.634190974197;12352.814707779391;12360.381980900918;12368.628941131781;12382.504699811996;4247.830086796555;4729.337892695025;4928.852134435972;5098.770555325019;5162.716050617177;9564;9565.952170239105;9665;9669.90330096704;9678.225741940769;5461.590380721462;5472.699001554953;5480.177973304037;5483.945160548423;5492.340919089735;4084.8366065357054;4432.180046691373;5046.318746969186;5090.032893750175;5131.846044618667;3253.853576589012;3563.127033876665;3930.862419815293;3992.5807790182193;4015.5181378234797;1134.2967360236705;1220.5422966387605;1230.6185082624415;1235.3144005077088;1251.2742609286593;780.4848799792223;844.5566077809076;971.0580736685545;974.3715303629119;982.7150126463246;5942.37605051472;6138.693065346715;6158.753649854045;6159.476444502267;6173.228346456693;946.4179529589253;978.1392374710209;980.8577756225308;1009.6769033909079;1012.1902478017556;3786.905327366838;3884.2425726982965;4001.899477643671;4102.159057393227;4157.708960372805;10591.89408105909;10942.288751231144;11250.774984500427;11268.71828204285;11276.661700148845;11073.723156921142;11110.666679999613;11240.325580465034;11256.380899050468;11265.662030139109;6726.293739157416;8113.188681131957;8546.572671366406;8553.358498112666;8559.657613695506;6085.786997455068;6201.81394558164;6241.563090583797;6276.74893004293;6305.936940630719;9994.400335979864;10212.13196878244;10519.105876000625;10532.05211530966;10532.789344213226;10405.271630985759;10611.840822387825;10655.680329590125;10659.09397701204;10724.302920310154;7408.592527411044;7759.262870027338;7770.844583108419;7858.882116768139;7918.643661035382;8686;8745.956270218863;8764.035956044958;8768.868466972874;8780.78048048804;7547.207543207884;7705;7767.456038217706;7821.530708157528;7854.2145785421;7479.700811967678;7600.50596711212;7603.353714934293;7767.990161278997;7799.259070388304;7239.384652304613;7281.890771638324;7302;7318;7321.63391830417;4887.657863949489;5006.899862002812;5137.588992880485;5153.587712982878;5170.844874653766;5085.160948443466;5105.412877519133;5125.8718532037;5199.74001299941;5275.472452754694 05:09:43 INFO - PROCESS | 1662 | |4;prototype.html;253.410819843863;307.8414616472517;322.6708757067791;324.49378968808674;326.4124575763625;3768.5289338832645;5156.278121063054;5164.509371609701;5259.631825772198;5282.656627319227;4920.729359885214;5066.807911016054;5087.949120508793;5152.124138896387;5257.921131183026;3290.424175769243;3928.664254153587;3966.2662407454714;3968.424578436125;3987.9800600997028;3355;3785.507883975093;4002.6197511236533;4015.3976903464422;4042.383046781282;3229.806211627298;3894.9805250973845;3898.766074035567;3916.4516967624554;3930.862419815307;3879.282332768447;3892.2215556888596;3933.4886464759684;3939.4484772131923;3941.2905676978135;2809.8454584997817;3374.4432168692138;3386.8306584670668;3420.435628121357;3426.7258619310396;4736.384270044907;4775.42694876617;4955.306257124005;4973.825916092956;4988.476209997971;3637.4361973893824;4422.469303683546;4444.844430444951;4463.24124898768;4499.865004049884;4479.02922714008;5372.623916325859;5382.6232163748555;5386;5415.593830462731;2974.323109201714;3216.5175223716396;3497.2306092659574;3725.9813700931595;3737.2338670572467;4896.60827133832;5219.217117432416;5711.371749107595;5774.535278832709;5805.680687562182;3139.4505961456653;3216.951745723816;3437.914052148716;3774.7735135891935;3857.614238576176;3323.2688808462585;3613.855445782192;3791.938257287964;3928.0769019280447;3929.351656976624;3596.2627661329366;4176.206520761046;4543.977280113579;4557.589816916494;4558.2251017327135;5234.738263086831;5284.199374331611;5519.668819870821;5544.8355845272945;5560.916586251209;1184.5321098166175;1196.6469891382062;1249.0320001998343;1306.7255876265901;1324.788033914569;712.982175445618;713.9000539924415;796.8127490039836;806.7257132574953;821.5276216175723;3743.47591337213;3895.6299151580556;4088.8977775555254;4264.635316698626;4276.434139516327;4325.718828276152;4392;4472.377374326741;4722.906496244965;4767.37909110904;3610.7472476926887;4135.462389889295;4250.489941207075;4254.808533615995;4286;2336.9532609348057;2386.5823480890776;2416.299273210749;2512.459821138464;2946.7053294670363;5455.072637651609;5620.185073164368;5783.467381144;5906.291245050621;5928.7332070056955;5212.374515058217;5535.861603459945;5648.943510564924;5767.8558036049435;5803.448672376117;5305.814296499604;5442.265294185314;5749.511291540266;5942.910856337158;6012.2564456308055;4338.9349159762;5695.772169113355;5719.256496655408;5747.195392645117;5756.424357564378;4443.866683999485;4749.35883655702;5176.534111929945;5614.17539299721;5678.602497825113;5457.536109430743;5510.0632892409185;5692.975264452439;5900.1149827526215;5918.171455996078;3325.7006869380903;4001.579834117376;4014.6386825185873;4061.025353915098;4084.244414783275;4780.808767649255;4869.829555965524;5040.445550989315;5648.802291919763;5749.97125014389;4632.884177895579;4884.340614017205;5212.50481204285;5242.16125419931;5288.286081378965;4584.633229341712;4991.101601711582;5310.468953104659;5458.263134476953;5466.480684334982;4930.285108659296;5079.593632509316;5266.78932842682;5374.6506477078865;5406.189071639285;4868.853934381974;5458.590605704509;5460.399356070909;5468.125099983983;5482.342118945752;4234.1108367241995;4687.62499000086;4928.383952005999;5352.143657014859;5386.676799392049 05:09:43 INFO - PROCESS | 1662 | |5;yui.html;661.7319985405909;745.5079647432693;747.5925620536807;754.5849782619558;759.9810004749877;15181.924090379547;15488;15657.138857362841;16018.839811601909;16036.83963160368;14620.926895365536;14683.559493215194;14732;14757.704845903076;14761.335739891703;738.2580506590896;937.6343226141793;955.5365647660878;955.5604421965869;956.7082039977839;756.1455555222591;903.5482258870564;931.0689310689311;934.4813628436215;940.7930255343848;969.8593703912928;977.8288799460101;979.8089372572351;983.7737320416309;985.8274801909673;765.479473957714;952.7046615549202;972.9902700972971;975.1516180922607;977.6480467031898;736.3189050128609;740.0268646729534;740.85182963407;740.922203168665;740.988885166723;15454.227288635524;15529.145896975662;15542.300596473073;15545.378184872705;15555.066695998163;12422;12453.315067671274;12485;12728.23630582168;12768.744625107445;10614.097801686867;10782.622608208674;10947.781044379148;11203.103751699926;11352.829707554394;3093.767967402454;3378.2398960233995;3703.500027496307;3997.2205419943125;4367.672424568138;87792.56103719505;88330.55834720786;88860;89062;89542.99999999935;9143.13140251675;9353.485558294224;9363.578638961262;9408.905910940803;9674.177694895872;20942.057607407703;20952.47618809542;20967.056482458327;20985.580288394147;20988;7426.517276377018;7465.70137194517;7515.774526764206;7536.811579710551;7695.115061767857;3863.74885632433;3998.620131087542;4004.799760012046;4105.404716316118;4167.708260421802;3583.390823559949;3678.70570354371;3686.299603075407;3688.8708895189075;3704.2776658551325;643.4755674125572;660.3891400454656;661.0447902780436;672.7947975867407;682.4335801284922;537.5304344376497;547.9150731636603;556.5325126893348;589.6344266554763;605.1286147946943;8079.515229086273;8118;8151.184881511801;8154.633041513146;8357.707480238285;493.96295277854307;502.5250888641848;523.9685618862881;569.2400645138704;604.5435696049454;3516.9472457913153;3779.0174554615996;3847.942280865789;3865.555461121951;3873.4383514390256;89751.55124224337;91041;93509;95123;95199;80538;82201.58899205585;82730.58634706908;83259.58370208231;84459.15540844636;67372.98940515798;68392;68431.65784171048;68914;73814.26185738182;7469.178390377163;7616.8476630465975;7710.614469276402;7907.48601340911;7957.124716281089;44796;45362;45989.310160348294;46504.7674761624;47793.28310075282;63035.42411439751;63106.684466577375;63430.048549270876;63430.048549270876;63515.6824215876;10017.949910250401;10249.077583017564;10403.479826008821;10446.947765261126;10468.162546996375;10970.945145274223;10999.670009899715;11108.578224887451;11116.444177790916;11410.885891141314;10514.947425262824;10584.259101862795;10622.8937710625;10793.02862742357;10846.728831779268;11127.221094523306;11418;11651.12616553745;11822.11334149925;12038.277703337828;11316.434178290889;11364.602238921598;11410.429478526206;11423;11531.942340288246;9675.951620242135;9687.17658998993;9772;9813.509324533887;9894.802103958025;9420.764480888032;9734.742204445529;9749.415035097916;9754.609815607297;9945.453000085065 05:09:43 INFO - PROCESS | 1662 | __end_tp_report 05:09:43 INFO - PROCESS | 1662 | __start_cc_report 05:09:43 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1433 05:09:43 INFO - PROCESS | 1662 | __end_cc_report 05:09:43 INFO - PROCESS | 1662 | __startTimestamp1463141383942__endTimestamp 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:09:43 INFO - PROCESS | 1662 | Number of tests: 6 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5734.57 Median:5921.50 stddev:645.31 (10.9%) stddev-sans-first:609.58 05:09:43 INFO - PROCESS | 1662 | Values: 2963.5 3475.6 3488.5 3503.2 3551.4 5143.5 5209.2 5640.4 5682.6 5764.8 5707.1 5712.6 5721.2 5804.2 5837.5 5116.6 5143.8 5210.5 5220.2 5309.8 5268.8 5283.3 5305.5 5327.4 5371.5 5124.7 5126.3 5158.7 5182.3 5195.4 5138.1 5166.6 5191.2 5196.8 5224.3 5177.3 5192.9 5207.3 5210.8 5227.3 5530.6 5602.3 5667.1 5695.9 5725.6 6187.9 6203.3 6203.3 6210.3 6215.8 5437.0 5746.7 6109.5 6176.7 6211.9 5770.8 5845.2 5858.2 5924.1 5942.6 6305.0 6333.6 6353.9 6354.9 6361.5 6061.6 6067.7 6199.1 6244.8 6257.2 6185.6 6241.6 6242.7 6287.2 6290.2 6176.1 6187.8 6221.3 6243.8 6259.6 6166.4 6236.1 6250.2 6268.1 6290.1 4888.1 4917.1 4946.4 4965.4 5005.1 4426.3 4430.9 4438.9 4441.9 4447.8 5824.1 5843.0 5843.8 5862.1 5870.0 5218.7 6186.0 6189.3 6301.7 6338.4 5767.9 5814.6 5823.2 5836.4 5868.8 5396.0 5416.4 5423.1 5452.8 5469.4 5181.9 5695.8 6286.0 6327.5 6416.9 6318.4 6339.4 6349.6 6358.5 6361.9 6182.8 6359.5 6445.1 6468.2 6495.0 6113.5 6176.5 6183.3 6195.3 6218.1 6154.4 6188.5 6215.4 6221.0 6226.9 4918.9 5921.5 5926.6 6115.4 6156.7 6127.1 6211.9 6213.9 6222.3 6225.4 5967.7 5989.7 5999.9 6046.3 6049.0 6012.6 6075.3 6125.7 6211.3 6225.6 5827.3 5891.0 5892.4 5941.2 5942.9 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:70821.64 Median:35647.97 stddev:157200.91 (441.0%) stddev-sans-first:157553.28 05:09:43 INFO - PROCESS | 1662 | Values: 517.1 519.3 523.2 527.6 550.6 27643.4 31400.0 43203.1 44606.8 44711.3 36585.3 44539.1 44732.3 44840.6 44929.0 9707.0 9893.8 9901.2 9904.9 9906.5 8729.7 8914.2 11704.9 11918.5 11923.9 11683.3 11767.4 11774.0 11785.9 11794.4 8783.5 11527.7 11603.1 11623.3 11654.4 9664.5 11651.8 11654.5 11656.7 11673.7 41462.4 41898.4 41925.0 41938.6 41997.5 3210.4 3211.2 3218.5 3267.9 3427.8 25645.5 29479.3 29490.6 30298.2 30827.2 2714.3 2751.6 2764.1 2840.0 2881.2 514503.0 520613.0 521403.0 521957.4 523329.0 36135.6 63806.0 64617.7 64772.7 64841.9 36843.4 38185.8 38201.2 38204.0 38281.4 15493.2 15748.2 15756.2 15759.6 15775.4 12038.8 12216.5 12974.8 13118.2 13190.3 8210.8 8312.4 8327.2 8382.9 8388.2 3417.1 3444.8 3449.8 3460.6 3483.1 957.9 973.8 992.7 1009.7 1114.8 30470.7 30574.5 30857.8 31109.7 31468.8 1105.5 1122.4 1131.8 1157.6 1161.7 14272.9 14276.9 15737.9 15954.7 16062.7 677603.0 681335.0 682874.0 688028.4 691051.0 52338.0 62812.1 63342.0 63510.7 63541.7 1311.3 1352.2 1484.6 1511.2 1539.5 30014.4 31404.4 31417.4 32332.0 32376.7 54217.0 54962.5 54980.0 54989.0 55133.0 534451.0 544586.0 546086.0 546419.0 547232.0 24373.3 26985.9 27236.0 27704.7 28913.7 47609.0 47918.0 48014.3 48588.5 49739.8 38978.2 39503.6 39678.4 39967.2 41029.8 38924.8 39332.4 39749.6 40314.4 40790.4 32722.8 39212.2 39583.4 40339.6 40349.8 33430.7 33923.8 34747.8 35052.8 35160.3 24428.3 25282.4 25614.2 26030.9 26391.3 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:37303.45 Median:26452.54 stddev:108212.31 (409.1%) stddev-sans-first:108480.39 05:09:43 INFO - PROCESS | 1662 | Values: 399.4 408.6 408.9 412.1 428.3 17791.2 20479.6 20870.2 21003.2 21766.2 20630.5 20739.8 20899.8 20929.5 21327.6 5682.5 6191.0 6198.2 6237.7 6256.8 6114.0 6120.3 6177.1 6182.8 6240.0 5911.6 6136.4 6138.5 6153.1 6154.1 5991.8 6031.1 6060.4 6066.2 6095.3 5182.8 5313.3 5342.9 5354.2 5355.2 26250.6 26654.5 26899.0 27366.0 27462.2 2136.6 2162.8 2240.0 2327.4 2804.4 9581.9 9984.0 10111.9 10297.1 10417.0 1578.6 1756.2 1759.8 1821.9 1837.5 102403.5 124805.0 130176.0 131402.0 134871.7 11577.9 11730.0 11732.2 11748.4 11872.8 15205.9 15232.9 15262.7 15265.9 15305.8 8789.8 8838.3 8844.8 8859.6 8939.0 8762.8 8788.0 8801.5 8865.7 8906.4 4688.0 4689.5 4706.9 4717.3 4773.4 1638.6 1918.0 1959.9 1982.6 1982.7 823.3 836.2 893.9 980.3 1000.2 20572.0 20755.5 20800.2 21097.6 21157.3 802.4 928.9 946.0 946.0 960.2 10219.3 11518.8 11889.4 11978.2 12006.3 636891.0 641879.0 647528.0 650432.0 655321.0 133927.0 141659.0 142275.0 159259.0 163044.0 802.3 1072.8 1088.9 1091.3 1095.3 15528.0 15892.5 19131.9 19559.9 19660.5 45158.4 53124.5 55115.7 56255.7 57693.4 55866.7 86039.6 86483.0 87068.6 89605.0 6945.1 7486.7 7542.7 7606.2 7703.7 12426.3 12599.3 12862.0 13160.9 13188.8 8602.0 10998.7 11234.3 11527.5 11577.2 10840.0 11004.2 11021.3 11175.1 11361.9 11387.8 11468.0 11569.0 11601.5 11846.2 7410.3 7687.2 7691.5 7803.6 7840.9 9159.7 10970.4 11144.7 11385.8 11452.9 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5871.30 Median:5094.40 stddev:3323.23 (65.2%) stddev-sans-first:3315.24 05:09:43 INFO - PROCESS | 1662 | Values: 1356.8 1432.9 1657.7 1875.3 2022.1 8082.7 8547.3 8551.9 8561.0 8872.1 8859.3 8922.8 9067.3 9072.4 9082.0 2417.5 2452.4 2453.3 2454.5 2455.6 2402.1 2425.7 2433.9 2443.6 2443.8 2401.4 2414.8 2427.9 2442.8 2446.2 2384.5 2404.1 2418.2 2418.4 2426.9 2383.5 2398.8 2403.2 2404.4 2408.5 8978.1 9075.3 9076.2 9080.3 9097.9 5279.7 5352.9 5517.4 5527.8 5533.8 3079.7 3606.8 3655.8 3705.9 3753.5 1029.0 1048.0 1049.9 1071.3 1072.3 12193.6 12352.8 12360.4 12368.6 12382.5 4247.8 4729.3 4928.9 5098.8 5162.7 9564.0 9566.0 9665.0 9669.9 9678.2 5461.6 5472.7 5480.2 5483.9 5492.3 4084.8 4432.2 5046.3 5090.0 5131.8 3253.9 3563.1 3930.9 3992.6 4015.5 1134.3 1220.5 1230.6 1235.3 1251.3 780.5 844.6 971.1 974.4 982.7 5942.4 6138.7 6158.8 6159.5 6173.2 946.4 978.1 980.9 1009.7 1012.2 3786.9 3884.2 4001.9 4102.2 4157.7 10591.9 10942.3 11250.8 11268.7 11276.7 11073.7 11110.7 11240.3 11256.4 11265.7 6726.3 8113.2 8546.6 8553.4 8559.7 6085.8 6201.8 6241.6 6276.7 6305.9 9994.4 10212.1 10519.1 10532.1 10532.8 10405.3 10611.8 10655.7 10659.1 10724.3 7408.6 7759.3 7770.8 7858.9 7918.6 8686.0 8746.0 8764.0 8768.9 8780.8 7547.2 7705.0 7767.5 7821.5 7854.2 7479.7 7600.5 7603.4 7768.0 7799.3 7239.4 7281.9 7302.0 7318.0 7321.6 4887.7 5006.9 5137.6 5153.6 5170.8 5085.2 5105.4 5125.9 5199.7 5275.5 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4292.77 Median:4705.27 stddev:1366.04 (29.0%) stddev-sans-first:1335.80 05:09:43 INFO - PROCESS | 1662 | Values: 253.4 307.8 322.7 324.5 326.4 3768.5 5156.3 5164.5 5259.6 5282.7 4920.7 5066.8 5087.9 5152.1 5257.9 3290.4 3928.7 3966.3 3968.4 3988.0 3355.0 3785.5 4002.6 4015.4 4042.4 3229.8 3895.0 3898.8 3916.5 3930.9 3879.3 3892.2 3933.5 3939.4 3941.3 2809.8 3374.4 3386.8 3420.4 3426.7 4736.4 4775.4 4955.3 4973.8 4988.5 3637.4 4422.5 4444.8 4463.2 4499.9 4479.0 5372.6 5382.6 5386.0 5415.6 2974.3 3216.5 3497.2 3726.0 3737.2 4896.6 5219.2 5711.4 5774.5 5805.7 3139.5 3217.0 3437.9 3774.8 3857.6 3323.3 3613.9 3791.9 3928.1 3929.4 3596.3 4176.2 4544.0 4557.6 4558.2 5234.7 5284.2 5519.7 5544.8 5560.9 1184.5 1196.6 1249.0 1306.7 1324.8 713.0 713.9 796.8 806.7 821.5 3743.5 3895.6 4088.9 4264.6 4276.4 4325.7 4392.0 4472.4 4722.9 4767.4 3610.7 4135.5 4250.5 4254.8 4286.0 2337.0 2386.6 2416.3 2512.5 2946.7 5455.1 5620.2 5783.5 5906.3 5928.7 5212.4 5535.9 5648.9 5767.9 5803.4 5305.8 5442.3 5749.5 5942.9 6012.3 4338.9 5695.8 5719.3 5747.2 5756.4 4443.9 4749.4 5176.5 5614.2 5678.6 5457.5 5510.1 5693.0 5900.1 5918.2 3325.7 4001.6 4014.6 4061.0 4084.2 4780.8 4869.8 5040.4 5648.8 5750.0 4632.9 4884.3 5212.5 5242.2 5288.3 4584.6 4991.1 5310.5 5458.3 5466.5 4930.3 5079.6 5266.8 5374.7 5406.2 4868.9 5458.6 5460.4 5468.1 5482.3 4234.1 4687.6 4928.4 5352.1 5386.7 05:09:43 INFO - PROCESS | 1662 | 05:09:43 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18418.51 Median:63071.05 stddev:26233.16 (41.6%) stddev-sans-first:26272.87 05:09:43 INFO - PROCESS | 1662 | Values: 661.7 745.5 747.6 754.6 760.0 15181.9 15488.0 15657.1 16018.8 16036.8 14620.9 14683.6 14732.0 14757.7 14761.3 738.3 937.6 955.5 955.6 956.7 756.1 903.5 931.1 934.5 940.8 969.9 977.8 979.8 983.8 985.8 765.5 952.7 973.0 975.2 977.6 736.3 740.0 740.9 740.9 741.0 15454.2 15529.1 15542.3 15545.4 15555.1 12422.0 12453.3 12485.0 12728.2 12768.7 10614.1 10782.6 10947.8 11203.1 11352.8 3093.8 3378.2 3703.5 3997.2 4367.7 87792.6 88330.6 88860.0 89062.0 89543.0 9143.1 9353.5 9363.6 9408.9 9674.2 20942.1 20952.5 20967.1 20985.6 20988.0 7426.5 7465.7 7515.8 7536.8 7695.1 3863.7 3998.6 4004.8 4105.4 4167.7 3583.4 3678.7 3686.3 3688.9 3704.3 643.5 660.4 661.0 672.8 682.4 537.5 547.9 556.5 589.6 605.1 8079.5 8118.0 8151.2 8154.6 8357.7 494.0 502.5 524.0 569.2 604.5 3516.9 3779.0 3847.9 3865.6 3873.4 89751.6 91041.0 93509.0 95123.0 95199.0 80538.0 82201.6 82730.6 83259.6 84459.2 67373.0 68392.0 68431.7 68914.0 73814.3 7469.2 7616.8 7710.6 7907.5 7957.1 44796.0 45362.0 45989.3 46504.8 47793.3 63035.4 63106.7 63430.0 63430.0 63515.7 10017.9 10249.1 10403.5 10446.9 10468.2 10970.9 10999.7 11108.6 11116.4 11410.9 10514.9 10584.3 10622.9 10793.0 10846.7 11127.2 11418.0 11651.1 11822.1 12038.3 11316.4 11364.6 11410.4 11423.0 11531.9 9676.0 9687.2 9772.0 9813.5 9894.8 9420.8 9734.7 9749.4 9754.6 9945.5 05:09:43 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:09:43 INFO - PROCESS | 1662 | 05:09:44 INFO - TEST-INFO | 1662: exit 0 05:09:44 INFO - TEST-OK | dromaeo_css | took 1114860ms 05:09:44 INFO - TEST-START | kraken 05:09:44 INFO - operating with platform_type : mac_ 05:09:44 INFO - Initialising browser for kraken test... 05:09:44 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpcrV22P/profile http://localhost:49275/getInfo.html) 05:09:46 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:09:46 INFO - PROCESS | 1668 | colorDepth:24 05:09:46 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:09:46 INFO - PROCESS | 1668 | __metrics 05:09:46 INFO - PROCESS | 1668 | 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:09:46 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:09:47 INFO - TEST-INFO | 1668: exit 0 05:09:47 INFO - Browser initialized. 05:09:47 INFO - Running cycle 1/1 for kraken test... 05:09:47 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpcrV22P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:59 INFO - PROCESS | 1670 | RSS: Main: 239022080 05:09:59 INFO - PROCESS | 1670 | 05:10:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 05:10:03 INFO - PROCESS | 1670 | RSS: Main: 287432704 05:10:03 INFO - PROCESS | 1670 | 05:10:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 05:10:06 INFO - PROCESS | 1670 | RSS: Main: 296083456 05:10:06 INFO - PROCESS | 1670 | 05:10:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 05:10:10 INFO - PROCESS | 1670 | RSS: Main: 384704512 05:10:10 INFO - PROCESS | 1670 | 05:10:12 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 05:10:13 INFO - PROCESS | 1670 | RSS: Main: 282124288 05:10:13 INFO - PROCESS | 1670 | 05:10:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:10:16 INFO - PROCESS | 1670 | RSS: Main: 286789632 05:10:16 INFO - PROCESS | 1670 | 05:10:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 05:10:19 INFO - PROCESS | 1670 | RSS: Main: 324747264 05:10:19 INFO - PROCESS | 1670 | 05:10:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 05:10:23 INFO - PROCESS | 1670 | RSS: Main: 328585216 05:10:23 INFO - PROCESS | 1670 | 05:10:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 05:10:27 INFO - PROCESS | 1670 | RSS: Main: 325767168 05:10:27 INFO - PROCESS | 1670 | 05:10:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:10:29 INFO - PROCESS | 1670 | RSS: Main: 316227584 05:10:29 INFO - PROCESS | 1670 | 05:10:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:10:32 INFO - PROCESS | 1670 | RSS: Main: 322965504 05:10:32 INFO - PROCESS | 1670 | 05:10:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:10:35 INFO - PROCESS | 1670 | RSS: Main: 324853760 05:10:35 INFO - PROCESS | 1670 | 05:10:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:38 INFO - PROCESS | 1670 | RSS: Main: 329547776 05:10:38 INFO - PROCESS | 1670 | 05:10:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:42 INFO - PROCESS | 1670 | RSS: Main: 327364608 05:10:42 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 05:10:44 INFO - PROCESS | 1670 | RSS: Main: 374173696 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | __start_tp_report 05:10:44 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:10:44 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:10:44 INFO - PROCESS | 1670 | |i|pagename|runs| 05:10:44 INFO - PROCESS | 1670 | |0;ai-astar;116;118;113;111;111;111;111;111;111;112 05:10:44 INFO - PROCESS | 1670 | |1;audio-beat-detection;151;123;169;209;120;136;133;119;193;138 05:10:44 INFO - PROCESS | 1670 | |2;audio-dft;184;185;185;183;181;182;183;182;183;179 05:10:44 INFO - PROCESS | 1670 | |3;audio-fft;78;72;57;141;80;141;56;59;109;55 05:10:44 INFO - PROCESS | 1670 | |4;audio-oscillator;91;99;134;79;78;76;100;79;83;101 05:10:44 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;88;84;87;85;86;84;87;84;87;84 05:10:44 INFO - PROCESS | 1670 | |6;imaging-darkroom;94;93;93;120;150;93;93;94;92;92 05:10:44 INFO - PROCESS | 1670 | |7;imaging-desaturate;84;84;86;86;85;85;85;83;83;86 05:10:44 INFO - PROCESS | 1670 | |8;json-parse-financial;57;59;56;55;56;57;56;116;54;55 05:10:44 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;63;63;80;73;75;68;67;68;68;69 05:10:44 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;99;98;89;83;84;86;84;86;88;85 05:10:44 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;140;137;137;135;138;142;142;146;210;139 05:10:44 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;171;164;163;221;207;164;166;161;163;163 05:10:44 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;64;65;67;66;68;64;65;64;65;64 05:10:44 INFO - PROCESS | 1670 | __end_tp_report 05:10:44 INFO - PROCESS | 1670 | __start_cc_report 05:10:44 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2249 05:10:44 INFO - PROCESS | 1670 | __end_cc_report 05:10:44 INFO - PROCESS | 1670 | __startTimestamp1463141444391__endTimestamp 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:10:44 INFO - PROCESS | 1670 | Number of tests: 14 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:112.50 Median:111.50 stddev:2.51 (2.2%) stddev-sans-first:2.32 05:10:44 INFO - PROCESS | 1670 | Values: 116.0 118.0 113.0 111.0 111.0 111.0 111.0 111.0 111.0 112.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:144.50 stddev:31.45 (21.8%) stddev-sans-first:33.35 05:10:44 INFO - PROCESS | 1670 | Values: 151.0 123.0 169.0 209.0 120.0 136.0 133.0 119.0 193.0 138.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.83 (1.0%) stddev-sans-first:1.88 05:10:44 INFO - PROCESS | 1670 | Values: 184.0 185.0 185.0 183.0 181.0 182.0 183.0 182.0 183.0 179.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:84.80 Median:58.00 stddev:33.82 (58.3%) stddev-sans-first:35.78 05:10:44 INFO - PROCESS | 1670 | Values: 78.0 72.0 57.0 141.0 80.0 141.0 56.0 59.0 109.0 55.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:79.00 stddev:17.73 (22.4%) stddev-sans-first:18.80 05:10:44 INFO - PROCESS | 1670 | Values: 91.0 99.0 134.0 79.0 78.0 76.0 100.0 79.0 83.0 101.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.58 (1.8%) stddev-sans-first:1.41 05:10:44 INFO - PROCESS | 1670 | Values: 88.0 84.0 87.0 85.0 86.0 84.0 87.0 84.0 87.0 84.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:93.00 stddev:19.08 (20.5%) stddev-sans-first:20.05 05:10:44 INFO - PROCESS | 1670 | Values: 94.0 93.0 93.0 120.0 150.0 93.0 93.0 94.0 92.0 92.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 05:10:44 INFO - PROCESS | 1670 | Values: 84.0 84.0 86.0 86.0 85.0 85.0 85.0 83.0 83.0 86.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:56.00 stddev:18.99 (33.9%) stddev-sans-first:20.05 05:10:44 INFO - PROCESS | 1670 | Values: 57.0 59.0 56.0 55.0 56.0 57.0 56.0 116.0 54.0 55.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:68.50 stddev:5.27 (7.7%) stddev-sans-first:5.06 05:10:44 INFO - PROCESS | 1670 | Values: 63.0 63.0 80.0 73.0 75.0 68.0 67.0 68.0 68.0 69.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:87.00 stddev:5.73 (6.6%) stddev-sans-first:4.56 05:10:44 INFO - PROCESS | 1670 | Values: 99.0 98.0 89.0 83.0 84.0 86.0 84.0 86.0 88.0 85.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:141.00 stddev:22.50 (16.0%) stddev-sans-first:23.74 05:10:44 INFO - PROCESS | 1670 | Values: 140.0 137.0 137.0 135.0 138.0 142.0 142.0 146.0 210.0 139.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:165.00 stddev:21.35 (12.9%) stddev-sans-first:22.61 05:10:44 INFO - PROCESS | 1670 | Values: 171.0 164.0 163.0 221.0 207.0 164.0 166.0 161.0 163.0 163.0 05:10:44 INFO - PROCESS | 1670 | 05:10:44 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.40 (2.2%) stddev-sans-first:1.41 05:10:44 INFO - PROCESS | 1670 | Values: 64.0 65.0 67.0 66.0 68.0 64.0 65.0 64.0 65.0 64.0 05:10:44 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:10:44 INFO - PROCESS | 1670 | 05:10:45 INFO - TEST-INFO | 1670: exit 0 05:10:45 INFO - TEST-OK | kraken | took 60685ms 05:10:45 INFO - SUITE-END | took 1175s 05:10:45 INFO - Completed test suite (00:19:35) 05:10:45 INFO - javascript benchmark 05:10:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.466576016317, 3475.5829300483947, 3488.5464889564355, 3503.1592417819697, 3551.4140166872467, 5143.485651434855, 5209.218617207416, 5640.351359593648, 5682.573806964473, 5764.798232061884, 5707.086866101425, 5712.571557133211, 5721.199032135504, 5804.187413762077, 5837.474627283544, 5116.641835071547, 5143.794248230085, 5210.5310522053005, 5220.190870415092, 5309.761060752276, 5268.762905669254, 5283.260343551906, 5305.549028332577, 5327.440618735035, 5371.5165635092835, 5124.743762811844, 5126.307948426952, 5158.716270605115, 5182.300389447414, 5195.40252870919, 5138.126518491828, 5166.5866730661455, 5191.169412893919, 5196.818111366122, 5224.320838291036, 5177.326947496839, 5192.948070519285, 5207.297014902978, 5210.791568337262, 5227.3465816772905, 5530.6128571000045, 5602.271704678406, 5667.149927510865, 5695.857603559944, 5725.6278341908055, 6187.876242475125, 6203.2556093269095, 6203.348648391899, 6210.347913469066, 6215.782447614357, 5436.966976274534, 5746.712664366807, 6109.511239100906, 6176.660283684396, 6211.906821397637, 5770.79802206925, 5845.2401187845735, 5858.179854820329, 5924.081767325985, 5942.64344139353, 6304.968475157595, 6333.619982801047, 6353.904691429632, 6354.872902541923, 6361.4592759615025, 6061.575689701766, 6067.74288113449, 6199.101130336076, 6244.812655620339, 6257.186565746424, 6185.597936134137, 6241.594296370721, 6242.65665388408, 6287.245530536274, 6290.21372328459, 6176.073588961692, 6187.84530386735, 6221.253449586078, 6243.7814676486105, 6259.561830671809, 6166.445019948226, 6236.064590311399, 6250.218722659668, 6268.122462855204, 6290.119383286344, 4888.120138375056, 4917.139500587383, 4946.3817022872145, 4965.379327584052, 5005.099082165245, 4426.269665505156, 4430.911381772411, 4438.866833994985, 4441.933370999438, 4447.777611119431, 5824.126381042793, 5843.0066888629035, 5843.795467158629, 5862.091375836752, 5869.970650146722, 5218.739063046833, 6186.0, 6189.319174890758, 6301.684915754286, 6338.366163383625, 5767.884642307215, 5814.59297849155, 5823.155642431823, 5836.358000619928, 5868.823935281863, 5396.0, 5416.404195538487, 5423.132298832168, 5452.754626041838, 5469.371022332403, 5181.92227116586, 5695.800646977253, 6286.0, 6327.493800496041, 6416.935830641633, 6318.368163183645, 6339.397757213057, 6349.555531112777, 6358.523110766619, 6361.904571431525, 6182.783602573888, 6359.523035772243, 6445.12990746262, 6468.223813142265, 6494.967525162344, 6113.476306051762, 6176.536759742948, 6183.288921774054, 6195.256569211723, 6218.098375735583, 6154.415330543591, 6188.4739797117745, 6215.440610345091, 6221.0, 6226.906596401148, 4918.852434426973, 5921.496672782861, 5926.555508336805, 6115.4190351916495, 6156.661383623944, 6127.111568822585, 6211.937880621317, 6213.937860621336, 6222.28443728977, 6225.408586184305, 5967.671778052075, 5989.724242349167, 5999.880002400015, 6046.274447066205, 6049.0, 6012.609180403259, 6075.301340345917, 6125.693715314127, 6211.3167551570195, 6225.62646241227, 5827.329857066492, 5890.970545147418, 5892.381299963614, 5941.197938278277, 5942.881142377215], "value": 5694.469810477003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.0770175237202, 519.2678323563774, 523.1707743226985, 527.5832092646809, 550.6365798572942, 27643.447131057394, 31400.0, 43203.13593728127, 44606.776966115125, 44711.32933005999, 36585.268294634094, 44539.109217815625, 44732.329015064715, 44840.551594484044, 44929.0, 9707.02929707029, 9893.75265618358, 9901.207903367713, 9904.900950990506, 9906.504674766269, 8729.694460693878, 8914.153155450222, 11704.882951170464, 11918.52325906967, 11923.940380298087, 11683.299002059903, 11767.352795596238, 11774.0, 11785.941070294637, 11794.351310677952, 8783.472991620523, 11527.711807204803, 11603.129765267639, 11623.302601843832, 11654.417279136094, 9664.516774161335, 11651.76696466066, 11654.533818647244, 11656.650300491, 11673.70815729605, 41462.37806432906, 41898.37152442716, 41924.951876203035, 41938.58061419377, 41997.530086447136, 3210.4060748761553, 3211.1811488070393, 3218.452863013293, 3267.9019629411155, 3427.8114703691285, 25645.46127232372, 29479.263018424495, 29490.557641635394, 30298.242543936358, 30827.229319266975, 2714.3214196450886, 2751.55975043992, 2764.0514232876194, 2839.9716002839914, 2881.1644623059287, 514503.0, 520613.0, 521403.0, 521957.3902130503, 523329.0, 36135.638643613755, 63806.04290935547, 64617.67691161608, 64772.67613661902, 64841.86739458656, 36843.447348289796, 38185.80907095502, 38201.23597528034, 38204.0448988772, 38281.425778613346, 15493.225338733018, 15748.212589370485, 15756.212189390484, 15759.606009849616, 15775.447859324868, 12038.759224815454, 12216.511339546496, 12974.80537791934, 13118.212907225598, 13190.274534900673, 8210.794730131674, 8312.376571757142, 8327.167283271745, 8382.87425688615, 8388.161183881562, 3417.128632198773, 3444.7588668793446, 3449.81026043565, 3460.619331873492, 3483.059573915029, 957.9042095790365, 973.8441849304078, 992.692265397729, 1009.7273736091214, 1114.8494953181216, 30470.69529304723, 30574.54138187929, 30857.84448293231, 31109.68890311068, 31468.842655786575, 1105.5135740274254, 1122.4387806096952, 1131.8358837968449, 1157.5948418053613, 1161.7269941563725, 14272.85727142736, 14276.857851371866, 15737.921310393374, 15954.702548728643, 16062.678746425006, 677603.0, 681335.0, 682874.0, 688028.3978701517, 691051.0, 52338.0, 62812.05781913275, 63342.0, 63510.6824465884, 63541.682291588244, 1311.2525860259561, 1352.154903185509, 1484.6140003599141, 1511.1764088571754, 1539.538138558428, 30014.399712006074, 31404.371912562077, 31417.371652566362, 32332.0, 32376.676233238308, 54217.0, 54962.450375497334, 54980.0, 54989.0, 55133.0, 534451.0, 544586.0, 546086.0, 546419.0, 547232.0, 24373.26880193597, 26985.86507067452, 27236.046738364854, 27704.722952770215, 28913.7108628911, 47609.0, 47918.0, 48014.279785802544, 48588.5141148584, 49739.75130124326, 38978.2204355917, 39503.60496395114, 39678.404823927085, 39967.2006559873, 41029.794851026745, 38924.80537597294, 39332.410013850385, 39749.60250397459, 40314.395284070175, 40790.388144178454, 32722.83638581792, 39212.2157556853, 39583.40624890687, 40339.596604033584, 40349.79825100856, 33430.66569334276, 33923.83038084794, 34747.82626086853, 35052.773152939524, 35160.29679406449, 24428.26715198547, 25282.36794080089, 25614.23157305284, 26030.86984565065, 26391.34021649474], "value": 19361.707126520912, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.43280541630884, 408.61998341542375, 408.8814243869278, 412.09133859839056, 428.29545397820584, 17791.199396027176, 20479.590408191845, 20870.165193392248, 21003.159873605036, 21766.238181663644, 20630.48423789406, 20739.792602073976, 20899.791002089976, 20929.476763080893, 21327.57344853106, 5682.460166284196, 6191.0, 6198.2252218472695, 6237.688115594225, 6256.781012664557, 6114.0, 6120.326764055951, 6177.104319873616, 6182.752689892399, 6240.0, 5911.556633252501, 6136.447719705226, 6138.478229350489, 6153.138560601519, 6154.138420621117, 5991.790287339944, 6031.125486804411, 6060.393960603904, 6066.150738896514, 6095.268567771852, 5182.766775495112, 5313.256144139785, 5342.904704535561, 5354.223637572569, 5355.169948657965, 26250.606240906403, 26654.46691066187, 26899.0, 27366.0, 27462.17613471579, 2136.59404713104, 2162.794534519234, 2239.9776002240096, 2327.3599760066118, 2804.3690169711854, 9581.856272155923, 9983.950080249624, 10111.949440252753, 10297.124744396735, 10417.010384013507, 1578.5690037342479, 1756.1833747307435, 1759.81521940197, 1821.8815776974454, 1837.5130590393555, 102403.48798255961, 124805.0, 130176.04735928969, 131401.99999999904, 134871.65128348788, 11577.942110289563, 11730.0, 11732.237404568676, 11748.35384053868, 11872.821907671392, 15205.923970380078, 15232.923835380752, 15262.694746105239, 15265.923670381577, 15305.846941530664, 8789.824203515895, 8838.29293656506, 8844.823103537894, 8859.557022148867, 8939.016708162097, 8762.780930476789, 8787.956060219658, 8801.51591662452, 8865.734027979172, 8906.421082629608, 4687.953120468752, 4689.484156742756, 4706.905861882743, 4717.268823332389, 4773.427188737373, 1638.6394993101499, 1917.9712304315447, 1959.8726082804571, 1982.5638359560874, 1982.682770756672, 823.3495538524618, 836.1847198980945, 893.8793262909554, 980.294188184506, 1000.1798525209258, 20572.0, 20755.481112972295, 20800.167993280098, 21097.578048438947, 21157.25949591757, 802.4423025996877, 928.8746019287445, 945.9858102128474, 945.9952700236455, 960.1934375124929, 10219.335743176669, 11518.8374219792, 11889.405529723479, 11978.221415607957, 12006.279623222634, 636891.0, 641879.0, 647528.0, 650432.0, 655321.0, 133927.0, 141659.0, 142275.0, 159259.0, 163044.0, 802.3380710913519, 1072.7693545887828, 1088.9128869690405, 1091.3233795046963, 1095.2990086344746, 15528.0, 15892.52322430329, 19131.85208887471, 19559.902200488905, 19660.50848728793, 45158.41945531891, 53124.4687553135, 55115.724421377636, 56255.718721406134, 57693.42306577048, 55866.72066639641, 86039.56980215058, 86483.0, 87068.5646571763, 89605.0, 6945.062416573698, 7486.663100160397, 7542.660580273791, 7606.20134885829, 7703.653335600024, 12426.254424734545, 12599.307038112991, 12862.0, 13160.868391315964, 13188.802167967297, 8602.0, 10998.670039898816, 11234.269772464764, 11527.481263343001, 11577.247478913843, 10840.024397804236, 11004.229703920648, 11021.283616564897, 11175.050120739828, 11361.886381136082, 11387.772244554897, 11468.025217856575, 11569.0, 11601.477933492844, 11846.170768046499, 7410.333070023724, 7687.231276872491, 7691.538507689556, 7803.648835802516, 7840.921590784247, 9159.679411220575, 10970.396628185526, 11144.728696103779, 11385.772284554429, 11452.885471145515], "value": 9735.387145614504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.7896975968729, 1432.8793385789031, 1657.68503984243, 1875.3436297295948, 2022.1405902491438, 8082.71710490133, 8547.316214702825, 8551.871721924168, 8561.0, 8872.136851261226, 8859.291256699466, 8922.776930576723, 9067.319951003668, 9072.364934454574, 9082.046385129566, 2417.492326611409, 2452.362385779701, 2453.3130723397476, 2454.460018795862, 2455.5702752018415, 2402.1112188490283, 2425.745296743843, 2433.8904749286235, 2443.59680652693, 2443.767842055002, 2401.399650087478, 2414.843035202715, 2427.890744916483, 2442.780149786519, 2446.217210492643, 2384.523095380931, 2404.0504000919627, 2418.1536462238164, 2418.3954011497126, 2426.8665223412704, 2383.4637206628545, 2398.8440751351104, 2403.242978461779, 2404.4349577849193, 2408.5182963407215, 8978.102189781035, 9075.319351048698, 9076.22852057576, 9080.273578113734, 9097.863532047024, 5279.70961597112, 5352.91970620441, 5517.420670829545, 5527.778888844441, 5533.806316778893, 3079.691131269202, 3606.8376923038527, 3655.817209139532, 3705.925881482355, 3753.4557489163917, 1028.9588416463332, 1047.9685609431729, 1049.8792638846478, 1071.3411252079993, 1072.3297938788257, 12193.634190974197, 12352.814707779391, 12360.381980900918, 12368.628941131781, 12382.504699811996, 4247.830086796555, 4729.337892695025, 4928.852134435972, 5098.770555325019, 5162.716050617177, 9564.0, 9565.952170239105, 9665.0, 9669.90330096704, 9678.225741940769, 5461.590380721462, 5472.699001554953, 5480.177973304037, 5483.945160548423, 5492.340919089735, 4084.8366065357054, 4432.180046691373, 5046.318746969186, 5090.032893750175, 5131.846044618667, 3253.853576589012, 3563.127033876665, 3930.862419815293, 3992.5807790182193, 4015.5181378234797, 1134.2967360236705, 1220.5422966387605, 1230.6185082624415, 1235.3144005077088, 1251.2742609286593, 780.4848799792223, 844.5566077809076, 971.0580736685545, 974.3715303629119, 982.7150126463246, 5942.37605051472, 6138.693065346715, 6158.753649854045, 6159.476444502267, 6173.228346456693, 946.4179529589253, 978.1392374710209, 980.8577756225308, 1009.6769033909079, 1012.1902478017556, 3786.905327366838, 3884.2425726982965, 4001.899477643671, 4102.159057393227, 4157.708960372805, 10591.89408105909, 10942.288751231144, 11250.774984500427, 11268.71828204285, 11276.661700148845, 11073.723156921142, 11110.666679999613, 11240.325580465034, 11256.380899050468, 11265.662030139109, 6726.293739157416, 8113.188681131957, 8546.572671366406, 8553.358498112666, 8559.657613695506, 6085.786997455068, 6201.81394558164, 6241.563090583797, 6276.74893004293, 6305.936940630719, 9994.400335979864, 10212.13196878244, 10519.105876000625, 10532.05211530966, 10532.789344213226, 10405.271630985759, 10611.840822387825, 10655.680329590125, 10659.09397701204, 10724.302920310154, 7408.592527411044, 7759.262870027338, 7770.844583108419, 7858.882116768139, 7918.643661035382, 8686.0, 8745.956270218863, 8764.035956044958, 8768.868466972874, 8780.78048048804, 7547.207543207884, 7705.0, 7767.456038217706, 7821.530708157528, 7854.2145785421, 7479.700811967678, 7600.50596711212, 7603.353714934293, 7767.990161278997, 7799.259070388304, 7239.384652304613, 7281.890771638324, 7302.0, 7318.0, 7321.63391830417, 4887.657863949489, 5006.899862002812, 5137.588992880485, 5153.587712982878, 5170.844874653766, 5085.160948443466, 5105.412877519133, 5125.8718532037, 5199.74001299941, 5275.472452754694], "value": 4698.53085802901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.410819843863, 307.8414616472517, 322.6708757067791, 324.49378968808674, 326.4124575763625, 3768.5289338832645, 5156.278121063054, 5164.509371609701, 5259.631825772198, 5282.656627319227, 4920.729359885214, 5066.807911016054, 5087.949120508793, 5152.124138896387, 5257.921131183026, 3290.424175769243, 3928.664254153587, 3966.2662407454714, 3968.424578436125, 3987.9800600997028, 3355.0, 3785.507883975093, 4002.6197511236533, 4015.3976903464422, 4042.383046781282, 3229.806211627298, 3894.9805250973845, 3898.766074035567, 3916.4516967624554, 3930.862419815307, 3879.282332768447, 3892.2215556888596, 3933.4886464759684, 3939.4484772131923, 3941.2905676978135, 2809.8454584997817, 3374.4432168692138, 3386.8306584670668, 3420.435628121357, 3426.7258619310396, 4736.384270044907, 4775.42694876617, 4955.306257124005, 4973.825916092956, 4988.476209997971, 3637.4361973893824, 4422.469303683546, 4444.844430444951, 4463.24124898768, 4499.865004049884, 4479.02922714008, 5372.623916325859, 5382.6232163748555, 5386.0, 5415.593830462731, 2974.323109201714, 3216.5175223716396, 3497.2306092659574, 3725.9813700931595, 3737.2338670572467, 4896.60827133832, 5219.217117432416, 5711.371749107595, 5774.535278832709, 5805.680687562182, 3139.4505961456653, 3216.951745723816, 3437.914052148716, 3774.7735135891935, 3857.614238576176, 3323.2688808462585, 3613.855445782192, 3791.938257287964, 3928.0769019280447, 3929.351656976624, 3596.2627661329366, 4176.206520761046, 4543.977280113579, 4557.589816916494, 4558.2251017327135, 5234.738263086831, 5284.199374331611, 5519.668819870821, 5544.8355845272945, 5560.916586251209, 1184.5321098166175, 1196.6469891382062, 1249.0320001998343, 1306.7255876265901, 1324.788033914569, 712.982175445618, 713.9000539924415, 796.8127490039836, 806.7257132574953, 821.5276216175723, 3743.47591337213, 3895.6299151580556, 4088.8977775555254, 4264.635316698626, 4276.434139516327, 4325.718828276152, 4392.0, 4472.377374326741, 4722.906496244965, 4767.37909110904, 3610.7472476926887, 4135.462389889295, 4250.489941207075, 4254.808533615995, 4286.0, 2336.9532609348057, 2386.5823480890776, 2416.299273210749, 2512.459821138464, 2946.7053294670363, 5455.072637651609, 5620.185073164368, 5783.467381144, 5906.291245050621, 5928.7332070056955, 5212.374515058217, 5535.861603459945, 5648.943510564924, 5767.8558036049435, 5803.448672376117, 5305.814296499604, 5442.265294185314, 5749.511291540266, 5942.910856337158, 6012.2564456308055, 4338.9349159762, 5695.772169113355, 5719.256496655408, 5747.195392645117, 5756.424357564378, 4443.866683999485, 4749.35883655702, 5176.534111929945, 5614.17539299721, 5678.602497825113, 5457.536109430743, 5510.0632892409185, 5692.975264452439, 5900.1149827526215, 5918.171455996078, 3325.7006869380903, 4001.579834117376, 4014.6386825185873, 4061.025353915098, 4084.244414783275, 4780.808767649255, 4869.829555965524, 5040.445550989315, 5648.802291919763, 5749.97125014389, 4632.884177895579, 4884.340614017205, 5212.50481204285, 5242.16125419931, 5288.286081378965, 4584.633229341712, 4991.101601711582, 5310.468953104659, 5458.263134476953, 5466.480684334982, 4930.285108659296, 5079.593632509316, 5266.78932842682, 5374.6506477078865, 5406.189071639285, 4868.853934381974, 5458.590605704509, 5460.399356070909, 5468.125099983983, 5482.342118945752, 4234.1108367241995, 4687.62499000086, 4928.383952005999, 5352.143657014859, 5386.676799392049], "value": 3861.5182989240616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.7319985405909, 745.5079647432693, 747.5925620536807, 754.5849782619558, 759.9810004749877, 15181.924090379547, 15488.0, 15657.138857362841, 16018.839811601909, 16036.83963160368, 14620.926895365536, 14683.559493215194, 14732.0, 14757.704845903076, 14761.335739891703, 738.2580506590896, 937.6343226141793, 955.5365647660878, 955.5604421965869, 956.7082039977839, 756.1455555222591, 903.5482258870564, 931.0689310689311, 934.4813628436215, 940.7930255343848, 969.8593703912928, 977.8288799460101, 979.8089372572351, 983.7737320416309, 985.8274801909673, 765.479473957714, 952.7046615549202, 972.9902700972971, 975.1516180922607, 977.6480467031898, 736.3189050128609, 740.0268646729534, 740.85182963407, 740.922203168665, 740.988885166723, 15454.227288635524, 15529.145896975662, 15542.300596473073, 15545.378184872705, 15555.066695998163, 12422.0, 12453.315067671274, 12485.0, 12728.23630582168, 12768.744625107445, 10614.097801686867, 10782.622608208674, 10947.781044379148, 11203.103751699926, 11352.829707554394, 3093.767967402454, 3378.2398960233995, 3703.500027496307, 3997.2205419943125, 4367.672424568138, 87792.56103719505, 88330.55834720786, 88860.0, 89062.0, 89542.99999999935, 9143.13140251675, 9353.485558294224, 9363.578638961262, 9408.905910940803, 9674.177694895872, 20942.057607407703, 20952.47618809542, 20967.056482458327, 20985.580288394147, 20988.0, 7426.517276377018, 7465.70137194517, 7515.774526764206, 7536.811579710551, 7695.115061767857, 3863.74885632433, 3998.620131087542, 4004.799760012046, 4105.404716316118, 4167.708260421802, 3583.390823559949, 3678.70570354371, 3686.299603075407, 3688.8708895189075, 3704.2776658551325, 643.4755674125572, 660.3891400454656, 661.0447902780436, 672.7947975867407, 682.4335801284922, 537.5304344376497, 547.9150731636603, 556.5325126893348, 589.6344266554763, 605.1286147946943, 8079.515229086273, 8118.0, 8151.184881511801, 8154.633041513146, 8357.707480238285, 493.96295277854307, 502.5250888641848, 523.9685618862881, 569.2400645138704, 604.5435696049454, 3516.9472457913153, 3779.0174554615996, 3847.942280865789, 3865.555461121951, 3873.4383514390256, 89751.55124224337, 91041.0, 93509.0, 95123.0, 95199.0, 80538.0, 82201.58899205585, 82730.58634706908, 83259.58370208231, 84459.15540844636, 67372.98940515798, 68392.0, 68431.65784171048, 68914.0, 73814.26185738182, 7469.178390377163, 7616.8476630465975, 7710.614469276402, 7907.48601340911, 7957.124716281089, 44796.0, 45362.0, 45989.310160348294, 46504.7674761624, 47793.28310075282, 63035.42411439751, 63106.684466577375, 63430.048549270876, 63430.048549270876, 63515.6824215876, 10017.949910250401, 10249.077583017564, 10403.479826008821, 10446.947765261126, 10468.162546996375, 10970.945145274223, 10999.670009899715, 11108.578224887451, 11116.444177790916, 11410.885891141314, 10514.947425262824, 10584.259101862795, 10622.8937710625, 10793.02862742357, 10846.728831779268, 11127.221094523306, 11418.0, 11651.12616553745, 11822.11334149925, 12038.277703337828, 11316.434178290889, 11364.602238921598, 11410.429478526206, 11423.0, 11531.942340288246, 9675.951620242135, 9687.17658998993, 9772.0, 9813.509324533887, 9894.802103958025, 9420.764480888032, 9734.742204445529, 9749.415035097916, 9754.609815607297, 9945.453000085065], "value": 6910.878294028383, "unit": "score"}], "name": "dromaeo_css", "value": 7158.825434947628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 113.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 169.0, 209.0, 120.0, 136.0, 133.0, 119.0, 193.0, 138.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 183.0, 181.0, 182.0, 183.0, 182.0, 183.0, 179.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 57.0, 141.0, 80.0, 141.0, 56.0, 59.0, 109.0, 55.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 134.0, 79.0, 78.0, 76.0, 100.0, 79.0, 83.0, 101.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 86.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 120.0, 150.0, 93.0, 93.0, 94.0, 92.0, 92.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 85.0, 85.0, 85.0, 83.0, 83.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 56.0, 55.0, 56.0, 57.0, 56.0, 116.0, 54.0, 55.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 80.0, 73.0, 75.0, 68.0, 67.0, 68.0, 68.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 89.0, 83.0, 84.0, 86.0, 84.0, 86.0, 88.0, 85.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 137.0, 135.0, 138.0, 142.0, 142.0, 146.0, 210.0, 139.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 164.0, 163.0, 221.0, 207.0, 164.0, 166.0, 161.0, 163.0, 163.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 66.0, 68.0, 64.0, 65.0, 64.0, 65.0, 64.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1498.6000000000001, "alertThreshold": 2.0}]} 05:10:45 INFO - Return code: 0 05:10:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:10:45 ERROR - # TBPL SUCCESS # 05:10:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:45 INFO - 2016-05-13 05:10:45.717 screenresolution[1674:9702] starting screenresolution argv=screenresolution get 05:10:45 INFO - 2016-05-13 05:10:45.719 screenresolution[1674:9702] Display 0: 1600x1200x32@60 05:10:45 INFO - 2016-05-13 05:10:45.725 screenresolution[1675:9705] starting screenresolution argv=screenresolution list 05:10:45 INFO - Available Modes on Display 0 05:10:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:45 INFO - Intel Iris: 05:10:45 INFO - Chipset Model: Intel Iris 05:10:45 INFO - Type: GPU 05:10:45 INFO - Bus: Built-In 05:10:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:45 INFO - Vendor: Intel (0x8086) 05:10:45 INFO - Device ID: 0x0a2e 05:10:45 INFO - Revision ID: 0x0009 05:10:45 INFO - Displays: 05:10:45 INFO - Display: 05:10:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:45 INFO - Display Serial Number: 1600x1200 60 05:10:45 INFO - Main Display: Yes 05:10:45 INFO - Mirror: Off 05:10:45 INFO - Online: Yes 05:10:45 INFO - Rotation: Supported 05:10:45 INFO - Return code: 0 05:10:45 INFO - Running post-action listener: _resource_record_post_action 05:10:45 INFO - ##### 05:10:45 INFO - ##### Finished run-tests step (success) 05:10:45 INFO - ##### 05:10:45 INFO - Running post-run listener: _resource_record_post_run 05:10:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36607488; Write bytes: 692018176; Read time: 244; Write time: 1840 05:10:46 INFO - TinderboxPrint: CPU usage
53.5% 05:10:46 INFO - TinderboxPrint: I/O read bytes / time
36,607,488 / 244 05:10:46 INFO - TinderboxPrint: I/O write bytes / time
692,018,176 / 1,840 05:10:46 INFO - TinderboxPrint: CPU idle
2,252.9 (46.5%) 05:10:46 INFO - TinderboxPrint: CPU system
961.5 (19.8%) 05:10:46 INFO - TinderboxPrint: CPU user
1,631.3 (33.7%) 05:10:46 INFO - TinderboxPrint: Swap in / out
430,960,640 / 0 05:10:46 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383535616; Write bytes: 372594688; Read time: 28159; Write time: 713 05:10:46 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30428672; Write bytes: 294564864; Read time: 183; Write time: 1066 05:10:46 INFO - Running post-run listener: _upload_blobber_files 05:10:46 INFO - Blob upload gear active. 05:10:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:46 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:10:46 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:10:46 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:10:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:10:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:48 INFO - (blobuploader) - INFO - Done attempting. 05:10:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:10:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:10:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:48 INFO - (blobuploader) - INFO - Done attempting. 05:10:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:10:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:10:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:51 INFO - (blobuploader) - INFO - Done attempting. 05:10:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:51 INFO - Return code: 0 05:10:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c6e5354edf27386af2312fbf38df8d3ceca193bbf0d29ef44d85747c88efc1491fa8a06cbf9337522f39820b8eaf72128a6dc85bbdbec446606e540423b1612", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43dba1a539a9cf4fd1dc015a07bf584a24cff6f9f0319d1c5e8d89aaf01cd4c0d73b6c632e9b950b8216b76af8e3af7110814711c7e747ecf7784e0595cfe5c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da9f6ebf72ff60802f6a669d347eea776c02b6fc512dbe0b54e8f7be5212e0453daa4e238cc1a2f282d0799853033e8b1647696cb5c24df88592bea4c11172e"} 05:10:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:51 INFO - Contents: 05:10:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c6e5354edf27386af2312fbf38df8d3ceca193bbf0d29ef44d85747c88efc1491fa8a06cbf9337522f39820b8eaf72128a6dc85bbdbec446606e540423b1612", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43dba1a539a9cf4fd1dc015a07bf584a24cff6f9f0319d1c5e8d89aaf01cd4c0d73b6c632e9b950b8216b76af8e3af7110814711c7e747ecf7784e0595cfe5c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da9f6ebf72ff60802f6a669d347eea776c02b6fc512dbe0b54e8f7be5212e0453daa4e238cc1a2f282d0799853033e8b1647696cb5c24df88592bea4c11172e"} 05:10:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:51 INFO - Copying logs to upload dir... 05:10:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.040249 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-13 05:10:51.300944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:10:51.304463) ========= 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.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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/Firefox/sha512/4c6e5354edf27386af2312fbf38df8d3ceca193bbf0d29ef44d85747c88efc1491fa8a06cbf9337522f39820b8eaf72128a6dc85bbdbec446606e540423b1612", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43dba1a539a9cf4fd1dc015a07bf584a24cff6f9f0319d1c5e8d89aaf01cd4c0d73b6c632e9b950b8216b76af8e3af7110814711c7e747ecf7784e0595cfe5c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da9f6ebf72ff60802f6a669d347eea776c02b6fc512dbe0b54e8f7be5212e0453daa4e238cc1a2f282d0799853033e8b1647696cb5c24df88592bea4c11172e"} build_url:https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009477 build_url: 'https://queue.taskcluster.net/v1/task/cy1J9mdqQgOhYf3Y4L9dgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c6e5354edf27386af2312fbf38df8d3ceca193bbf0d29ef44d85747c88efc1491fa8a06cbf9337522f39820b8eaf72128a6dc85bbdbec446606e540423b1612", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43dba1a539a9cf4fd1dc015a07bf584a24cff6f9f0319d1c5e8d89aaf01cd4c0d73b6c632e9b950b8216b76af8e3af7110814711c7e747ecf7784e0595cfe5c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da9f6ebf72ff60802f6a669d347eea776c02b6fc512dbe0b54e8f7be5212e0453daa4e238cc1a2f282d0799853033e8b1647696cb5c24df88592bea4c11172e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:10:51.328926) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:10:51.329273) ========= 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.ksLky2mV6T/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.VIrhWH5yqq/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.004597 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:10:51.377944) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:10:51.378274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:10:51.378627) ========= ========= Total master_lag: 0.16 =========