builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0230 starttime: 1464042127.85 results: success (0) buildid: 20160523133445 builduid: 8df0a5616cbe466693bb27ebdc52d775 revision: 3aa793fa2d0b078e07bfe780f0a8859dedafc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:07.851509) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:07.851986) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:07.852267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:07.908130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:07.908512) ========= 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.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-23 15:22:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.8M=0s 2016-05-23 15:22:08 (24.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.504494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:08.431908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:08.432248) ========= 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.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.019453 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:08.479767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:08.480105) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3aa793fa2d0b078e07bfe780f0a8859dedafc850 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3aa793fa2d0b078e07bfe780f0a8859dedafc850 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-23 15:22:08,538 truncating revision to first 12 chars 2016-05-23 15:22:08,538 Setting DEBUG logging. 2016-05-23 15:22:08,538 attempt 1/10 2016-05-23 15:22:08,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3aa793fa2d0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 15:22:09,218 unpacking tar archive at: fx-team-3aa793fa2d0b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.935801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:09.434275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:09.434596) ========= script_repo_revision: 3aa793fa2d0b078e07bfe780f0a8859dedafc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:09.434997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:09.435262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:22:09.450093) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-23 15:22:09.450485) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:09 INFO - MultiFileLogger online at 20160523 15:22:09 in /builds/slave/test 15:22:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:22:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:22:09 INFO - {'append_to_log': False, 15:22:09 INFO - 'base_work_dir': '/builds/slave/test', 15:22:09 INFO - 'blob_upload_branch': 'Fx-Team', 15:22:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:22:09 INFO - 'branch': 'Fx-Team', 15:22:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:22:09 INFO - 'config_files': ('talos/mac_config.py',), 15:22:09 INFO - 'default_actions': ('clobber', 15:22:09 INFO - 'read-buildbot-config', 15:22:09 INFO - 'download-and-extract', 15:22:09 INFO - 'populate-webroot', 15:22:09 INFO - 'create-virtualenv', 15:22:09 INFO - 'install', 15:22:09 INFO - 'run-tests'), 15:22:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:22:09 INFO - 'download_minidump_stackwalk': True, 15:22:09 INFO - 'download_symbols': 'ondemand', 15:22:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:22:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:22:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:22:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:22:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:22:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:22:09 INFO - 'installer_path': 'installer.exe', 15:22:09 INFO - 'log_level': 'info', 15:22:09 INFO - 'log_name': 'talos', 15:22:09 INFO - 'log_to_console': True, 15:22:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:22:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:22:09 INFO - 'opt_config_files': (), 15:22:09 INFO - 'pip_index': False, 15:22:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:22:09 INFO - 'cmd': ('bash', 15:22:09 INFO - '-c', 15:22:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:22:09 INFO - 'enabled': True, 15:22:09 INFO - 'halt_on_failure': False, 15:22:09 INFO - 'name': 'check_screen_resolution'},), 15:22:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:22:09 INFO - 'cmd': ('bash', 15:22:09 INFO - '-c', 15:22:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:22:09 INFO - 'enabled': True, 15:22:09 INFO - 'halt_on_failure': False, 15:22:09 INFO - 'name': 'check_screen_resolution'},), 15:22:09 INFO - 'run_cmd_checks_enabled': True, 15:22:09 INFO - 'sps_profile': False, 15:22:09 INFO - 'sps_profile_interval': 0, 15:22:09 INFO - 'suite': 'dromaeojs', 15:22:09 INFO - 'system_bits': '32', 15:22:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:22:09 INFO - 'title': 't-yosemite-r7-0230', 15:22:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:22:09 INFO - 'use_talos_json': True, 15:22:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:22:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:22:09 INFO - 'work_dir': 'build'} 15:22:09 INFO - ##### 15:22:09 INFO - ##### Running clobber step. 15:22:09 INFO - ##### 15:22:09 INFO - Running pre-action listener: _resource_record_pre_action 15:22:09 INFO - Running main action method: clobber 15:22:09 INFO - rmtree: /builds/slave/test/build 15:22:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:22:11 INFO - Running post-action listener: _resource_record_post_action 15:22:11 INFO - ##### 15:22:11 INFO - ##### Finished clobber step (success) 15:22:11 INFO - ##### 15:22:11 INFO - ##### 15:22:11 INFO - ##### Running read-buildbot-config step. 15:22:11 INFO - ##### 15:22:11 INFO - Running pre-action listener: _resource_record_pre_action 15:22:11 INFO - Running main action method: read_buildbot_config 15:22:11 INFO - Using buildbot properties: 15:22:11 INFO - { 15:22:11 INFO - "project": "", 15:22:11 INFO - "product": "firefox", 15:22:11 INFO - "script_repo_revision": "production", 15:22:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:22:11 INFO - "repository": "", 15:22:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:22:11 INFO - "buildid": "20160523133445", 15:22:11 INFO - "pgo_build": "False", 15:22:11 INFO - "basedir": "/builds/slave/test", 15:22:11 INFO - "buildnumber": 785, 15:22:11 INFO - "slavename": "t-yosemite-r7-0230", 15:22:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:22:11 INFO - "platform": "yosemite_r7", 15:22:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:22:11 INFO - "branch": "fx-team", 15:22:11 INFO - "repo_path": "integration/fx-team", 15:22:11 INFO - "revision": "3aa793fa2d0b078e07bfe780f0a8859dedafc850", 15:22:11 INFO - "stage_platform": "macosx64", 15:22:11 INFO - "builduid": "8df0a5616cbe466693bb27ebdc52d775", 15:22:11 INFO - "slavebuilddir": "test" 15:22:11 INFO - } 15:22:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:22:11 INFO - Running post-action listener: _resource_record_post_action 15:22:11 INFO - ##### 15:22:11 INFO - ##### Finished read-buildbot-config step (success) 15:22:11 INFO - ##### 15:22:11 INFO - ##### 15:22:11 INFO - ##### Running download-and-extract step. 15:22:11 INFO - ##### 15:22:11 INFO - Running pre-action listener: _resource_record_pre_action 15:22:11 INFO - Running main action method: download_and_extract 15:22:11 INFO - mkdir: /builds/slave/test/build/tests 15:22: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')]} 15:22:11 INFO - https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:22:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:22:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:22:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:12 INFO - Downloaded 1199 bytes. 15:22:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:22:12 INFO - Using the following test package requirements: 15:22:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:22:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:22:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:22:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'jsshell-mac.zip'], 15:22:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:22:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:22:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:22:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:22:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:22:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:22:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:22:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:12 INFO - https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:22:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:22:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:22:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:13 INFO - Downloaded 18630446 bytes. 15:22:13 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'] 15:22:13 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 15:22:16 INFO - Return code: 0 15:22:16 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 15:22: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')]} 15:22:16 INFO - https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:22:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:22:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:22:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:18 INFO - Downloaded 18630446 bytes. 15:22:18 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'] 15:22:18 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 15:22:22 INFO - Return code: 0 15:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:22 INFO - https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:22:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:22:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:22:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:23 INFO - Downloaded 10840359 bytes. 15:22:23 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'] 15:22:23 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 15:22:24 INFO - Return code: 0 15:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:24 INFO - https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:22:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:22:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:22:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/enXjvDRsQcmnd4G4-udd9Q/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 15:22:27 INFO - Downloaded 110171823 bytes. 15:22:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:22:27 INFO - mkdir: /builds/slave/test/properties 15:22:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:22:27 INFO - Writing to file /builds/slave/test/properties/build_url 15:22:27 INFO - Contents: 15:22:27 INFO - build_url:https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:22:29 INFO - Running post-action listener: _resource_record_post_action 15:22:29 INFO - Running post-action listener: set_extra_try_arguments 15:22:29 INFO - ##### 15:22:29 INFO - ##### Finished download-and-extract step (success) 15:22:29 INFO - ##### 15:22:29 INFO - ##### 15:22:29 INFO - ##### Running populate-webroot step. 15:22:29 INFO - ##### 15:22:29 INFO - Running pre-action listener: _resource_record_pre_action 15:22:29 INFO - Running main action method: populate_webroot 15:22:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:22:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:22:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:22:29 INFO - '--tppagecycles', 15:22:29 INFO - '7'], 15:22:29 INFO - 'tests': ['tsvgm']}}, 15:22:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:22:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:22:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:22:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:22:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['damp', 'tps']}, 15:22:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'tests': ['damp', 'tps']}, 15:22:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['dromaeo_dom']}, 15:22:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:22:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'other-e10s': {'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['a11yr', 15:22:29 INFO - 'ts_paint', 15:22:29 INFO - 'tpaint', 15:22:29 INFO - 'sessionrestore', 15:22:29 INFO - 'sessionrestore_no_auto_restore', 15:22:29 INFO - 'tabpaint']}, 15:22:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['tsvgx', 15:22:29 INFO - 'tsvgr_opacity', 15:22:29 INFO - 'tart', 15:22:29 INFO - 'tscrollx', 15:22:29 INFO - 'cart']}, 15:22:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:22:29 INFO - 'tsvgr_opacity', 15:22:29 INFO - 'tart', 15:22:29 INFO - 'tscrollx', 15:22:29 INFO - 'cart']}, 15:22:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'talos_options': ['--disable-e10s'], 15:22:29 INFO - 'tests': ['tp5o']}, 15:22:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'tests': ['tp5o']}, 15:22:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'talos_options': ['--disable-e10s', 15:22:29 INFO - '--xperf_path', 15:22:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:29 INFO - 'tests': ['tp5n']}, 15:22:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:29 INFO - 'talos_options': ['--xperf_path', 15:22:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:29 INFO - 'tests': ['tp5n']}}, 15:22:29 INFO - 'talos.zip': {'path': '', 15:22:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:22:29 INFO - Running post-action listener: _resource_record_post_action 15:22:29 INFO - ##### 15:22:29 INFO - ##### Finished populate-webroot step (success) 15:22:29 INFO - ##### 15:22:29 INFO - ##### 15:22:29 INFO - ##### Running create-virtualenv step. 15:22:29 INFO - ##### 15:22:29 INFO - Running pre-action listener: _resource_record_pre_action 15:22:29 INFO - Running main action method: create_virtualenv 15:22:29 INFO - Creating virtualenv /builds/slave/test/build/venv 15:22:29 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 15:22:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:22:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:22:29 INFO - Using real prefix '/tools/python27' 15:22:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:22:30 INFO - Installing distribute.............................................................................................................................................................................................done. 15:22:32 INFO - Installing pip.................done. 15:22:32 INFO - Return code: 0 15:22:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:32 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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:32 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 15:22:32 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 15:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:32 INFO - 'HOME': '/Users/cltbld', 15:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:32 INFO - 'LOGNAME': 'cltbld', 15:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:32 INFO - 'MOZ_NO_REMOTE': '1', 15:22:32 INFO - 'NO_EM_RESTART': '1', 15:22:32 INFO - 'PAGER': '/bin/cat', 15:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:32 INFO - 'PWD': '/builds/slave/test', 15:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:32 INFO - 'SHELL': '/bin/bash', 15:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:32 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:32 INFO - 'USER': 'cltbld', 15:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:32 INFO - 'XPC_FLAGS': '0x0', 15:22:32 INFO - 'XPC_SERVICE_NAME': '0', 15:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:33 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 15:22:33 INFO - Cleaning up... 15:22:33 INFO - Return code: 0 15:22:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:22:33 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')]} 15:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 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')]} 15:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:33 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 15:22:33 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 15:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:33 INFO - 'HOME': '/Users/cltbld', 15:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:33 INFO - 'LOGNAME': 'cltbld', 15:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:33 INFO - 'MOZ_NO_REMOTE': '1', 15:22:33 INFO - 'NO_EM_RESTART': '1', 15:22:33 INFO - 'PAGER': '/bin/cat', 15:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:33 INFO - 'PWD': '/builds/slave/test', 15:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:33 INFO - 'SHELL': '/bin/bash', 15:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:33 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:33 INFO - 'USER': 'cltbld', 15:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:33 INFO - 'XPC_FLAGS': '0x0', 15:22:33 INFO - 'XPC_SERVICE_NAME': '0', 15:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:33 INFO - Downloading/unpacking psutil>=3.1.1 15:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:22:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:36 INFO - Installing collected packages: psutil 15:22:36 INFO - Running setup.py install for psutil 15:22:36 INFO - building 'psutil._psutil_osx' extension 15:22:36 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 15:22:37 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 15:22:37 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 15:22:37 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 15:22:37 INFO - building 'psutil._psutil_posix' extension 15:22:37 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 15:22:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:22:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:22:37 INFO - ^ 15:22:37 INFO - 1 warning generated. 15:22:37 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 15:22:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:37 INFO - Successfully installed psutil 15:22:37 INFO - Cleaning up... 15:22:37 INFO - Return code: 0 15:22:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:22:37 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')]} 15:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:37 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')]} 15:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:37 INFO - 'HOME': '/Users/cltbld', 15:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:37 INFO - 'LOGNAME': 'cltbld', 15:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:37 INFO - 'MOZ_NO_REMOTE': '1', 15:22:37 INFO - 'NO_EM_RESTART': '1', 15:22:37 INFO - 'PAGER': '/bin/cat', 15:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:37 INFO - 'PWD': '/builds/slave/test', 15:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:37 INFO - 'SHELL': '/bin/bash', 15:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:37 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:37 INFO - 'USER': 'cltbld', 15:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:37 INFO - 'XPC_FLAGS': '0x0', 15:22:37 INFO - 'XPC_SERVICE_NAME': '0', 15:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:22:40 INFO - Installing collected packages: mozsystemmonitor 15:22:40 INFO - Running setup.py install for mozsystemmonitor 15:22:40 INFO - Successfully installed mozsystemmonitor 15:22:40 INFO - Cleaning up... 15:22:40 INFO - Return code: 0 15:22:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:40 INFO - 'HOME': '/Users/cltbld', 15:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:40 INFO - 'LOGNAME': 'cltbld', 15:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:40 INFO - 'MOZ_NO_REMOTE': '1', 15:22:40 INFO - 'NO_EM_RESTART': '1', 15:22:40 INFO - 'PAGER': '/bin/cat', 15:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:40 INFO - 'PWD': '/builds/slave/test', 15:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:40 INFO - 'SHELL': '/bin/bash', 15:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:40 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:40 INFO - 'USER': 'cltbld', 15:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:40 INFO - 'XPC_FLAGS': '0x0', 15:22:40 INFO - 'XPC_SERVICE_NAME': '0', 15:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:41 INFO - Downloading/unpacking blobuploader==1.2.4 15:22:41 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 15:22:41 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 15:22:41 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 15:22:41 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 15:22:41 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 15:22:41 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 15:22:43 INFO - Downloading blobuploader-1.2.4.tar.gz 15:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:22:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:22:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:22:44 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 15:22:44 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 15:22:44 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 15:22:44 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 15:22:44 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 15:22:44 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 15:22:44 INFO - Downloading docopt-0.6.1.tar.gz 15:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:22:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:22:44 INFO - Running setup.py install for blobuploader 15:22:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:22:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:22:44 INFO - Running setup.py install for requests 15:22:45 INFO - Running setup.py install for docopt 15:22:45 INFO - Successfully installed blobuploader requests docopt 15:22:45 INFO - Cleaning up... 15:22:45 INFO - Return code: 0 15:22:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:45 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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:45 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 15:22:45 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 15:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:45 INFO - 'HOME': '/Users/cltbld', 15:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:45 INFO - 'LOGNAME': 'cltbld', 15:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:45 INFO - 'MOZ_NO_REMOTE': '1', 15:22:45 INFO - 'NO_EM_RESTART': '1', 15:22:45 INFO - 'PAGER': '/bin/cat', 15:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:45 INFO - 'PWD': '/builds/slave/test', 15:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:45 INFO - 'SHELL': '/bin/bash', 15:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:45 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:45 INFO - 'USER': 'cltbld', 15:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:45 INFO - 'XPC_FLAGS': '0x0', 15:22:45 INFO - 'XPC_SERVICE_NAME': '0', 15:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:45 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-A1jSDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:45 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-VviMnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-q85RC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-P2nPec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Bb_FR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Zex86w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-KCruqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9kZeMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jLwdGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5xQe2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-JWQUh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:46 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-4hqyYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:47 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-DoAzGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:47 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-GZKiu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:47 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Vechi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:47 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-dktOGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:47 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-T6Fu3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:22:47 INFO - Running setup.py install for manifestparser 15:22:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:22:47 INFO - Running setup.py install for mozcrash 15:22:47 INFO - Running setup.py install for mozdebug 15:22:48 INFO - Running setup.py install for mozdevice 15:22:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Running setup.py install for mozfile 15:22:48 INFO - Running setup.py install for mozhttpd 15:22:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Running setup.py install for mozinfo 15:22:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Running setup.py install for mozInstall 15:22:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:22:48 INFO - Running setup.py install for mozleak 15:22:48 INFO - Running setup.py install for mozlog 15:22:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Running setup.py install for moznetwork 15:22:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Running setup.py install for mozprocess 15:22:49 INFO - Running setup.py install for mozprofile 15:22:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Running setup.py install for mozrunner 15:22:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:22:49 INFO - Running setup.py install for mozscreenshot 15:22:49 INFO - Running setup.py install for moztest 15:22:50 INFO - Running setup.py install for mozversion 15:22:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:22:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:22:50 INFO - Cleaning up... 15:22:50 INFO - Return code: 0 15:22:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:50 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')]} 15:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:50 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')]} 15:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:50 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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:50 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 15:22:50 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 15:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:50 INFO - 'HOME': '/Users/cltbld', 15:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:50 INFO - 'LOGNAME': 'cltbld', 15:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:50 INFO - 'MOZ_NO_REMOTE': '1', 15:22:50 INFO - 'NO_EM_RESTART': '1', 15:22:50 INFO - 'PAGER': '/bin/cat', 15:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:50 INFO - 'PWD': '/builds/slave/test', 15:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:50 INFO - 'SHELL': '/bin/bash', 15:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:50 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:50 INFO - 'USER': 'cltbld', 15:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:50 INFO - 'XPC_FLAGS': '0x0', 15:22:50 INFO - 'XPC_SERVICE_NAME': '0', 15:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:50 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-A0YsGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:50 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)) 15:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:50 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-P3VySF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:50 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)) 15:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:50 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-b_ZI5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:50 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)) 15:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:50 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jqp7rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:50 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)) 15:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:50 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-C9wxiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-KqM62J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-tA226G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-SP3TND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-MeRWbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ZFJPs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-SLLFaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_cshjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-u4LVOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-rpBEGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:51 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)) 15:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-TDzT4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:52 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)) 15:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-SV3ep7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:52 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)) 15:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9kG_jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:52 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)) 15:22:52 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)) 15:22:52 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)) 15:22:52 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)) 15:22:52 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)) 15:22:52 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)) 15:22:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:22:52 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 15:22:52 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 15:22:52 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 15:22:52 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 15:22:52 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 15:22:52 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 15:22:54 INFO - Downloading blessings-1.6.tar.gz 15:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:22:54 INFO - Installing collected packages: blessings 15:22:54 INFO - Running setup.py install for blessings 15:22:54 INFO - Successfully installed blessings 15:22:54 INFO - Cleaning up... 15:22:55 INFO - Return code: 0 15:22:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:22:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:22:55 INFO - Reading from file tmpfile_stdout 15:22:55 INFO - Current package versions: 15:22:55 INFO - blessings == 1.6 15:22:55 INFO - blobuploader == 1.2.4 15:22:55 INFO - docopt == 0.6.1 15:22:55 INFO - manifestparser == 1.1 15:22:55 INFO - mozInstall == 1.12 15:22:55 INFO - mozcrash == 0.17 15:22:55 INFO - mozdebug == 0.1 15:22:55 INFO - mozdevice == 0.48 15:22:55 INFO - mozfile == 1.2 15:22:55 INFO - mozhttpd == 0.7 15:22:55 INFO - mozinfo == 0.9 15:22:55 INFO - mozleak == 0.1 15:22:55 INFO - mozlog == 3.2 15:22:55 INFO - moznetwork == 0.27 15:22:55 INFO - mozprocess == 0.22 15:22:55 INFO - mozprofile == 0.28 15:22:55 INFO - mozrunner == 6.11 15:22:55 INFO - mozscreenshot == 0.1 15:22:55 INFO - mozsystemmonitor == 0.3 15:22:55 INFO - moztest == 0.7 15:22:55 INFO - mozversion == 1.4 15:22:55 INFO - psutil == 3.1.1 15:22:55 INFO - requests == 1.2.3 15:22:55 INFO - wsgiref == 0.1.2 15:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:55 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 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:55 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 15:22:55 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 15:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:55 INFO - 'HOME': '/Users/cltbld', 15:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:55 INFO - 'LOGNAME': 'cltbld', 15:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:55 INFO - 'MOZ_NO_REMOTE': '1', 15:22:55 INFO - 'NO_EM_RESTART': '1', 15:22:55 INFO - 'PAGER': '/bin/cat', 15:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:55 INFO - 'PWD': '/builds/slave/test', 15:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:55 INFO - 'SHELL': '/bin/bash', 15:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:55 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:55 INFO - 'USER': 'cltbld', 15:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:55 INFO - 'XPC_FLAGS': '0x0', 15:22:55 INFO - 'XPC_SERVICE_NAME': '0', 15:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 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)) 15:22:55 INFO - Cleaning up... 15:22:55 INFO - Return code: 0 15:22:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:55 INFO - 'HOME': '/Users/cltbld', 15:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:55 INFO - 'LOGNAME': 'cltbld', 15:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:55 INFO - 'MOZ_NO_REMOTE': '1', 15:22:55 INFO - 'NO_EM_RESTART': '1', 15:22:55 INFO - 'PAGER': '/bin/cat', 15:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:55 INFO - 'PWD': '/builds/slave/test', 15:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:55 INFO - 'SHELL': '/bin/bash', 15:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:55 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:55 INFO - 'USER': 'cltbld', 15:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:55 INFO - 'XPC_FLAGS': '0x0', 15:22:55 INFO - 'XPC_SERVICE_NAME': '0', 15:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:55 INFO - Downloading/unpacking jsonschema 15:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:22:58 INFO - Downloading/unpacking functools32 (from jsonschema) 15:22:58 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 15:22:58 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 15:22:58 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 15:22:58 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 15:22:58 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 15:22:58 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 15:22:58 INFO - Downloading functools32-3.2.3-2.tar.gz 15:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:22:58 INFO - warning: no files found matching '*.txt' 15:22:58 INFO - no previously-included directories found matching 'build' 15:22:58 INFO - no previously-included directories found matching 'dist' 15:22:58 INFO - no previously-included directories found matching '.git*' 15:22:58 INFO - Installing collected packages: jsonschema, functools32 15:22:58 INFO - Running setup.py install for functools32 15:22:58 INFO - warning: no files found matching '*.txt' 15:22:58 INFO - no previously-included directories found matching 'build' 15:22:58 INFO - no previously-included directories found matching 'dist' 15:22:58 INFO - no previously-included directories found matching '.git*' 15:22:58 INFO - Successfully installed jsonschema functools32 15:22:58 INFO - Cleaning up... 15:22:59 INFO - Return code: 0 15:22:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028d7f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, '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.b5tKPQfxms/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.6HwiSwKaqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 15:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:59 INFO - 'HOME': '/Users/cltbld', 15:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:59 INFO - 'LOGNAME': 'cltbld', 15:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:59 INFO - 'MOZ_NO_REMOTE': '1', 15:22:59 INFO - 'NO_EM_RESTART': '1', 15:22:59 INFO - 'PAGER': '/bin/cat', 15:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:59 INFO - 'PWD': '/builds/slave/test', 15:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:59 INFO - 'SHELL': '/bin/bash', 15:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:22:59 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:59 INFO - 'USER': 'cltbld', 15:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:59 INFO - 'XPC_FLAGS': '0x0', 15:22:59 INFO - 'XPC_SERVICE_NAME': '0', 15:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:59 INFO - Downloading/unpacking flake8 15:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:23:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:23:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:23:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:23:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:23:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:23:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:23:03 INFO - no previously-included directories found matching 'docs/_build' 15:23:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:23:03 INFO - Running setup.py install for pep8 15:23:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:23:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:23:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:23:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:23:03 INFO - no previously-included directories found matching 'docs/_build' 15:23:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:23:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:23:03 INFO - Cleaning up... 15:23:03 INFO - Return code: 0 15:23:03 INFO - Running post-action listener: _resource_record_post_action 15:23:03 INFO - Running post-action listener: _start_resource_monitoring 15:23:03 INFO - Starting resource monitoring. 15:23:03 INFO - ##### 15:23:03 INFO - ##### Finished create-virtualenv step (success) 15:23:03 INFO - ##### 15:23:03 INFO - ##### 15:23:03 INFO - ##### Running install step. 15:23:03 INFO - ##### 15:23:03 INFO - Running pre-action listener: _resource_record_pre_action 15:23:03 INFO - Running main action method: install 15:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:23:03 INFO - Reading from file tmpfile_stdout 15:23:03 INFO - Detecting whether we're running mozinstall >=1.0... 15:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:23:03 INFO - Reading from file tmpfile_stdout 15:23:03 INFO - Output received: 15:23:03 INFO - Usage: mozinstall [options] installer 15:23:03 INFO - Options: 15:23:03 INFO - -h, --help show this help message and exit 15:23:03 INFO - -d DEST, --destination=DEST 15:23:03 INFO - Directory to install application into. [default: 15:23:03 INFO - "/builds/slave/test"] 15:23:03 INFO - --app=APP Application being installed. [default: firefox] 15:23:03 INFO - mkdir: /builds/slave/test/build/application 15:23:03 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'] 15:23:03 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 15:23:37 INFO - Reading from file tmpfile_stdout 15:23:37 INFO - Output received: 15:23:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:23:37 INFO - Running post-action listener: _resource_record_post_action 15:23:37 INFO - ##### 15:23:37 INFO - ##### Finished install step (success) 15:23:37 INFO - ##### 15:23:37 INFO - ##### 15:23:37 INFO - ##### Running run-tests step. 15:23:37 INFO - ##### 15:23:37 INFO - Running pre-action listener: _resource_record_pre_action 15:23:37 INFO - Running main action method: run_tests 15:23:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:23:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:23:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:23:37 INFO - 2016-05-23 15:23:37.543 screenresolution[1925:22067] starting screenresolution argv=screenresolution get 15:23:37 INFO - 2016-05-23 15:23:37.545 screenresolution[1925:22067] Display 0: 1600x1200x32@60 15:23:37 INFO - 2016-05-23 15:23:37.551 screenresolution[1926:22069] starting screenresolution argv=screenresolution list 15:23:37 INFO - Available Modes on Display 0 15:23:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:23:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:23:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:23:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:23:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:23:37 INFO - Intel Iris: 15:23:37 INFO - Chipset Model: Intel Iris 15:23:37 INFO - Type: GPU 15:23:37 INFO - Bus: Built-In 15:23:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:23:37 INFO - Vendor: Intel (0x8086) 15:23:37 INFO - Device ID: 0x0a2e 15:23:37 INFO - Revision ID: 0x0009 15:23:37 INFO - Displays: 15:23:37 INFO - Display: 15:23:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:23:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:23:37 INFO - Display Serial Number: 1600x1200 60 15:23:37 INFO - Main Display: Yes 15:23:37 INFO - Mirror: Off 15:23:37 INFO - Online: Yes 15:23:37 INFO - Rotation: Supported 15:23:37 INFO - Return code: 0 15:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:23:37 INFO - Python 2.7.3 15:23:37 INFO - Return code: 0 15:23:37 INFO - grabbing minidump binary from tooltool 15:23:37 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')]} 15:23:37 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 0x109849d30>, '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 0x10966ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109840b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:23:37 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 15:23:37 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 15:23:37 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 15:23:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:23:37 INFO - Return code: 0 15:23:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:23:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:23:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:23:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:23:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:23:38 INFO - Return code: 0 15:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 15:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 15:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b5tKPQfxms/Render', 15:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:39 INFO - 'HOME': '/Users/cltbld', 15:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:39 INFO - 'LOGNAME': 'cltbld', 15:23:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:23:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:39 INFO - 'MOZ_NO_REMOTE': '1', 15:23:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:23:39 INFO - 'NO_EM_RESTART': '1', 15:23:39 INFO - 'PAGER': '/bin/cat', 15:23:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:39 INFO - 'PWD': '/builds/slave/test', 15:23:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:39 INFO - 'SHELL': '/bin/bash', 15:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6HwiSwKaqP/Listeners', 15:23:39 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 15:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:39 INFO - 'USER': 'cltbld', 15:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:39 INFO - 'XPC_FLAGS': '0x0', 15:23:39 INFO - 'XPC_SERVICE_NAME': '0', 15:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 15:23:39 INFO - mozversion application_buildid: 20160523133445 15:23:39 INFO - mozversion application_changeset: 3aa793fa2d0b078e07bfe780f0a8859dedafc850 15:23:39 INFO - mozversion application_display_name: Nightly 15:23:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:23:39 INFO - mozversion application_name: Firefox 15:23:39 INFO - mozversion application_remotingname: firefox 15:23:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:23:39 INFO - mozversion application_vendor: Mozilla 15:23:39 INFO - mozversion application_version: 49.0a1 15:23:39 INFO - mozversion platform_buildid: 20160523133445 15:23:39 INFO - mozversion platform_changeset: 3aa793fa2d0b078e07bfe780f0a8859dedafc850 15:23:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:23:39 INFO - mozversion platform_version: 49.0a1 15:23:39 INFO - using testdate: 1464042219 15:23:39 INFO - actual date: 1464042219 15:23:39 INFO - starting webserver on 'localhost:55684' 15:23:39 INFO - SUITE-START | Running 2 tests 15:23:39 INFO - TEST-START | dromaeo_css 15:23:39 INFO - operating with platform_type : mac_ 15:23:39 INFO - Initialising browser for dromaeo_css test... 15:23:39 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpJA3HxW/profile http://localhost:55684/getInfo.html) 15:23:41 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 15:23:41 INFO - PROCESS | 1944 | colorDepth:24 15:23:41 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 15:23:41 INFO - PROCESS | 1944 | __metrics 15:23:41 INFO - PROCESS | 1944 | 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" 15:23:41 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:23:42 INFO - TEST-INFO | 1944: exit 0 15:23:42 INFO - Browser initialized. 15:23:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:23:42 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpJA3HxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:23:54 INFO - PROCESS | 1946 | RSS: Main: 238960640 15:23:54 INFO - PROCESS | 1946 | 15:24:45 INFO - PROCESS | 1946 | 1464042285617 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] 15:26:56 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55684/tests/dromaeo/cssquery-ext.html) 15:26:57 INFO - PROCESS | 1946 | RSS: Main: 355254272 15:26:57 INFO - PROCESS | 1946 | 15:30:00 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-ext.html (next: http://localhost:55684/tests/dromaeo/cssquery-jquery.html) 15:30:01 INFO - PROCESS | 1946 | RSS: Main: 373010432 15:30:01 INFO - PROCESS | 1946 | 15:33:03 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55684/tests/dromaeo/cssquery-mootools.html) 15:33:03 INFO - PROCESS | 1946 | RSS: Main: 368136192 15:33:03 INFO - PROCESS | 1946 | 15:36:06 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55684/tests/dromaeo/cssquery-prototype.html) 15:36:06 INFO - PROCESS | 1946 | RSS: Main: 369668096 15:36:06 INFO - PROCESS | 1946 | 15:39:10 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55684/tests/dromaeo/cssquery-yui.html) 15:39:10 INFO - PROCESS | 1946 | RSS: Main: 343199744 15:39:10 INFO - PROCESS | 1946 | 15:42:13 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55684/tests/dromaeo/cssquery-yui.html (next: http://localhost:55684/tests/dromaeo/cssquery-dojo.html) 15:42:14 INFO - PROCESS | 1946 | RSS: Main: 375988224 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | __start_tp_report 15:42:14 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 15:42:14 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 15:42:14 INFO - PROCESS | 1946 | |i|pagename|runs| 15:42:14 INFO - PROCESS | 1946 | |0;dojo.html;3428.6399928007563;3450.344434557432;3453.084932492888;3464.9307013859725;3523.2953409318125;5562.360328562217;5584.91622625661;5607.943920560793;5618.578606604506;5667.574931880115;5609.635373700707;5615.7472913718875;5683.943160568393;5697.6866272355;5734.741936612852;5092.312537807404;5133.948660513384;5191.792328306863;5199.818006369778;5305.991861546313;5263.789448422078;5298.92051619226;5301.469853014687;5316.707581083039;5337.973310133463;5085.771140298677;5153.613478989053;5155.40712818027;5169.121249387613;5219.71291578963;5117.82087626935;5128.435872054109;5176.378834539879;5270.262163297141;5301.7614207360375;5117.795288188468;5176.741162941876;5196.53231209193;5199.038177937094;5238.528532432061;5294.735263236861;5606.187102870061;5664.688442135681;5673.489385955284;5691.146328050825;5342.358916929955;6082.908756368658;6112.412294442025;6123.265208175003;6166.229221347332;6182.69086545671;6199.380061993809;6226.470749986257;6235;6247.468965137969;5642.943570564242;5750.396208398141;5775.27809023872;5779.566532510079;5810.983077961382;6219.067139929047;6273.560850740404;6275.748970041147;6291.433770960635;6301.905471417932;5993.160957465871;6074.969625151934;6083.452489275986;6085.665288409092;6093;5027.547520723079;6097.1952901664845;6154.846128846814;6160;6171.506279497629;6184.041473571604;6186.659733714689;6195.132681424604;6208.782692605737;6209.627422354582;6203.31763506014;6208.348123447064;6209.06863970399;6214.751409943642;6233.3143354231;4818.349522814376;4856.8300109495995;4876.341693871282;4900.803967841246;4968.552830245295;4327.870163895088;4340.739555626673;4446.577575130422;4448.866534003921;4472.217361961643;5618.157276408489;5641.210230567724;5660.518855897295;5698.31620205578;5762.683052432157;6238.251409830849;6249.875002499924;6253.1245625612455;6253.6873156342;6269.749210031548;5639.774409023676;5703.429657034347;5711.742971566289;5721.256236689203;5749;5245.291885595472;5282.022825777243;5298.894022119536;5362;5376.892462150735;6250.593711408744;6252.374762523893;6253.6560489173535;6253.6873156342;6260.968695156586;5256.868578285574;6012.789552365646;6141.731496609813;6243.8126856194385;6272.874542509033;6259.749610015549;6270.0908368287255;6275.435210831047;6279.466245369012;6294.622322660655;6073.99779036454;6154.569180157346;6171.660558669317;6173.722182501709;6183.196184495987;5383.300170977748;6072.969635151796;6118.143459915705;6124.350421260821;6169.784057558143;4494.70784399013;6067.969660151671;6087.178230938766;6091.543134264859;6100.176476175659;6056.90914636272;6074.3014553325165;6075;6089.817305480843;6114.57197996136;5342.492463215988;5550.69471179089;5718.142278658068;5879.853003674772;5910.320313164041;6037.18498002764;6044.788432404845;6073.787417440545;6115.877682446415;6122.387761223842;5296.523312901857;5553.305836770403;5893.292804863444;5920.467157955804;5925.40745925404 15:42:14 INFO - PROCESS | 1946 | |1;ext.html;496.6498618473975;510.3642824746686;510.48696060459235;532.3744600094888;545.912653975364;30222.697773022264;30469.238269043286;31742.84128579357;40580.39129413061;41898.58101418993;42384.57615423845;42828.78585607067;42876;42981;43021.92445188864;9316.860247096281;9577.090176433228;9582.52087395631;9593.136617704406;9603.231741460666;8509.914900850974;9390.812183756321;11357.432128393588;11547.249428787101;11548.076153907667;10201.387916724983;11644.82532762005;11653.70865728355;11663.300201987866;11681.474333655005;8493.575321233913;11407.315561066363;11487.25332853362;11530.423478826067;11592.47833847479;11173.10615150786;11356.772864542745;11487.310761354345;11673.883261167364;11746.706332341675;39589;39852.20295594072;39992.60007399918;40100.398494022615;40122.59877401218;3189.0751682012187;3280.8687652493873;3710.6474884885893;3718.665320121202;4010.8796736097966;26999.46001079987;28099.297517562223;28701.995430159845;29101;29341.26646833846;2677.598360245959;2709.7967652426146;2731.984917373879;2770.432061427423;2908.2147820088458;560240;569216;572595;722226.3888680575;722577.3871130716;62259.6887015562;62288.688556556925;63050.369496305364;63171.68283171646;63215.6839215801;34675.826620866734;35419.4687079694;35579.28841423157;35609.64390356115;36144.38556144365;16514.917425412797;16841.32634694623;17091.230894609773;17093;17094.829051709323;11530.827037594443;11908.940455297668;12976.35118244084;13056.608301750963;13080.084394092513;19292.4212273632;19527.90236048839;19584.804151958582;19902.303419380543;19956.401307960783;7811.843763124706;7962.6416811243635;7964.1637628049375;7973.2824045835;8055.798605034921;925.8981512033671;925.8981512033671;1058.8147074262126;1062.5909025025421;1129.209553312668;27969.88120475199;28559.857200713865;28794.712052879204;28894.566581501294;29252;998.681740103057;1021.8453147942777;1062.617457715222;1078.7249251440833;1105.4914739220048;11911.868372504598;12244.571439999558;12513.249205047545;13081;14756.335964881606;679779;685808;685957;686579;687214;43004.78497607554;59003.70498147482;59081.40918590845;59133;59336;1299.52567312932;1339.4709089909627;1441.0921119694735;1447.949321773733;1456.5848733111013;26806.727284478482;28844.711552884204;29185.270368240963;29633.814647413863;29763.553546696385;49804.75097624489;49957.75021124871;50715.74642126766;50736.985260295325;50796.49203507917;626285;641143.7942810256;641157;642136.7893160505;643932.7803360954;35708.64291357053;41268.587314127675;41909.79045104755;42321.788391057846;42874.356884646135;35547;45052.549474506144;45259;45491.31763023491;45730;36717.816410917774;36892.262154756216;37229.06927326838;37609.43585846269;38025.85922422332;35861;35880;36236.81881590575;36435.27129457449;36706;36127;36339.81830090833;37113.814430927676;37456;37962.62037379591;32430.83784581062;32623;33056.83471582723;33365.49951750774;33435.16412089717;20014.29949951745;24280.271591852274;24298.878505607358;24403;24617.138400156626 15:42:14 INFO - PROCESS | 1946 | |2;jquery.html;372.39857629927667;378.4739212494632;378.91474418255876;388.94360317753944;393.43345582361417;14713.632159196026;15468.767968480483;19426.90286548567;20705.378838634828;20755.377338679846;19095.713564296548;20521.4869628259;20575.074121664522;20606.38180854573;20851.687224691643;5544.417836127209;5834.6790926499025;5880.911786323209;6089.543284253698;6125.173101631267;5978.312494063195;5978.372270911557;6017.224918649009;6017.48851347636;6058.575899687024;5560.471755183251;5880.26496687914;5890.852728681774;5970.552208584352;5985.371535988724;5861.443162899539;5868;5870.647761134324;5914.704264786744;5924.437178468082;4872.9756351218375;5045.697258164522;5108.4125325587665;5169.948300516984;5389.973050134724;10876;12657.556985505464;13005;13069.477220911152;13322;2131.0729832522748;2172.847900646955;2264.479169790941;2605.335639411938;2631.67104111986;9541.61833526665;9641.71074867748;10132.898671013269;12237.510499580007;14648.571157409942;1589.8171710253353;1656.2546853915665;1688.274042161882;1701.4895531340549;1743.579553921807;96486.51756741242;108916.82166356708;121372;128215.35892320478;129604;11292;11384.373859437645;11506.252093613888;11560.913198192768;11683.890030447094;14197.858021419861;14213.928930355489;14644.19456929879;14667.853321466862;14753.114813111248;8424.705135320235;8464.238218560358;8472.44929079608;8631.223189912938;8662.350323725746;8540.871886921701;8541.487510749375;8596.570171491401;8656.61045252965;8690.26132778721;4418.33724941255;4539.7074477388705;4540.636749060133;4551.112533056023;4598.931016034762;1588.6425554250432;1609.967800644004;1626.6746650669913;1917.4439412570478;1941.4369832748346;869.5652173913044;931.4690626343055;964.9565769540387;969.3360048366891;993.1111655068673;19241.4227573173;20224.89887550553;20331.695024574645;20399.59200816005;20508;862.0259107208814;901.6213190460022;922.898481167071;948.9715308540755;975.9996004095717;9147.77130571741;10398.948005259925;10843;10857.51141198648;10898.455077246106;591346;602524;603825.9808700929;607964;610636;103105.76371034233;122713;122950.77049229572;125516.37241813914;128672;858.2661824140395;975.8243516167016;1019.189744153385;1019.3628981886321;1019.9439030853226;18801.435956921316;18902.52743681419;18918.52703682419;18934.810651893305;18968.715469267696;50676.49323506865;51600;52267;52283.47716522786;53340.73329633327;64380.67809660922;74257.25742742504;74716.62641686974;75412.11469713302;75912;7759.534427934342;7949.8410031800195;7951.522908625501;8021.759347219593;8290.295324897452;8910.777230569029;11670.183087184156;12344.259344439362;12396.752064958831;12842.422091006114;7282.490225684363;10741;10872.456377180952;11037.889621103686;11329.773404531697;10857.891421085687;10969.780604388026;10990.725231869268;11096.5561377544;11189.104871610414;6387.297397286202;7427.517211381243;9434.29242806806;11108.111351091731;11238.7752244953;6284.811455656338;7264.309890560599;7272.818179545554;7283.2352602975925;8346;8126.5936703163425;10258.28192026551;10724.195685323475;11105;11193.216474847008 15:42:14 INFO - PROCESS | 1946 | |3;mootools.html;1400.4328247059932;1501.4594745891482;1591.7930669013026;1643.8931469454485;1955.4133759872043;7825.843483130341;8552.315814734822;8788.384813063089;8799.604017819205;8898.77753056174;8522.744317670464;8671.91328086719;8783.341249406289;8790.64837406503;8795.076516965722;1852.4905650945964;2099.3492017474555;2296.3225848374686;2338.53229354129;2370.8933098010543;2350.459394339303;2379.1553998330605;2414.3239892830034;2416.903323867043;2422.6002709552904;1924.4130540185238;2246.764089770575;2296.230762694491;2321.8606883586954;2325.2791634593245;2074.7199128117736;2255.052877791315;2314.826388020897;2329.429289823987;2335.2410466598253;2357.210334537932;2377.976220237793;2383.3445802404476;2384.1655420602824;2385.7375688674233;8713;8730.86903696445;8760.605772740242;8776.429532080394;8832.69085582008;5217.50433708797;5267.3679158500845;5268.894622107537;5272.261883336336;5366.570674346082;3366.646502117267;3376.206591451007;3551.0646697549896;3556.270964452281;3576.624454432273;1013.2350075692879;1014.2241185493103;1016.8678071850686;1017.5421060522795;1017.6133069433641;11481.598144065001;11611.709707257301;11620.360880151673;11678.007369373614;11715.7071073223;4613.515580864028;4724.291356296583;4791.185498465269;4812.807487700453;4820.421549414023;9895.218860605017;9947.751306217258;9960.203183745283;9960.651377201762;9973.451460169616;5374.4625537445945;5376.435474275145;5379.327584051994;5387.24578559002;5398.78404863801;3966.900827479278;4412.161689279027;4572.51988541205;4930.1865192243595;4946.35697359341;3919.941200881932;3980.044789250559;4023.657989070903;4057.959420405817;4111.362738775495;1249.6001279590625;1250.1186663402461;1267.271318991565;1275.4069357748506;1299.9415026323838;837.7821766340795;896.7982204004047;914.3964983111115;932.9673461428953;938.8591711243231;5634.6337488063145;5689.459501347365;5760.683162426023;5796.536277097822;6020.307664618537;440.1614923570603;445.2475316714742;447.6508323507669;970.5098925042809;996.8454889492141;3866.3427217373114;3915.7063220258597;3951.9012024699614;3966.6033396660105;3995.880123596297;10606.893931060591;10629;10669.679909602724;10785.56857725698;10926.453677316262;10665.733356666145;10671.89328106709;10853.348799071922;10856.78286434267;10860.348379097122;7018.75434359795;8127.2279133482225;8159.877601835978;8301.543415112106;8359.874601880976;5747.540196784331;5752.07966725322;5922.230110085661;5990.2212712348855;6046.365131661289;9491.857622135536;9865.506724663595;10039.04629060238;10052.94973525157;10071;9852.753681158028;10139.493025348556;10139.9860013998;10271.229657775548;10273.486325683836;6650.235222949423;6881.759138430131;7022.648867556704;7171.067761191011;7291.08861392326;8180.181981801772;8333.791655208668;8368.958155209186;8405.369597280107;8412.242898139197;7297.270272972661;7397.815054623462;7402.074740657418;7476.327130558276;7578.924210758042;7184;7259.673314700956;7279.563226206445;7345.22875098107;7423.665935032828;6885.4835887309655;6898.517103802686;6938.653067346511;6951.339622735832;6988;4764.103404278602;4857.53853383928;4896.216605343128;4929.088118698052;5089.465606111305;4785.760711964457;4835.032993401404;4880.6095512358215;4938.827141050046;5094.261332106749 15:42:14 INFO - PROCESS | 1946 | |4;prototype.html;279.96780370257426;293.70189257903235;303.5037713338691;312.20232306457;316.928691044515;4271.885416718725;5089.262057001742;5220.6606570572885;5282.012080878941;5297.973510132449;4546.4771551271615;4901.877453063676;5192.350956130484;5195.714235717034;5212.400573933999;4015.9397609035886;4039.8384064637453;4051.554329023805;4055.4119652650347;4066.2883995300854;3260.4620237660756;3969.729686500321;4078.9184216315803;4080.3063479208604;4094.2016306820183;3847.365184744514;4033.6773058155277;4041.2725709372303;4053.858259848626;4066.9186616267657;3878.3794592865006;3962.6235507626875;4035.5964403559556;4039.313316736162;4050.372192310167;3095.5356696495483;3549.804760738158;3552.2185119273977;3570.428731402963;3576.195356044895;4733.715977041388;5061.670991385548;5077.796888124471;5090.592752579785;5126.461721519261;3929.626685464876;4622.08183603804;4637.628989680851;4645.372874661911;4660.04469083837;4708.411448568929;5530.695811730353;5591.580631452657;5621.80323688673;5638.971805140989;3662.9194387655707;3941.8423263069444;3948.7828169450668;3964.464797252363;3967.0677390813134;5206.739663016834;5208.198015448744;5598.552115830724;5858.502027327682;5892.499137573312;3377.341418423433;3852.749571277858;3979.6617287530307;4010.538788039413;4031.4355990161403;4052.9797351013644;4077.796110194538;4079.4084857695466;4086;4102.466679331727;4027.35562310029;4652.767361631905;4657.930131048037;4659.464161621457;4663.696859704109;4840.635758148123;5249.763760630781;5527.474889885454;5561.499465048165;5620.353659329229;1300.78537041389;1364.4883168811696;1391.1358779235873;1391.3530208452914;1395.965100872466;738.674983007475;745.6271864067966;746.1829296919864;836.6653338664461;844.8648216285364;3838.5393752749846;3843.4057011517116;3860.536735591691;4307.332363483665;4334.869953901388;4210.473690788652;4255.0426154115075;4801.423829140525;4855.830045948375;4879.633377811759;3956.3274243378696;3974.7018973577096;4110.383442483591;4184.330507118846;4381.7151885128005;2564.871456559108;2728.577070554067;3030.787844850883;3031.227037105568;3043.178341847689;5081.60255929615;6030.608010479393;6037.45662890342;6046.395360464006;6050.183225264533;5578.63738856973;5923.496502797224;5956.45132265614;5958.731857066442;5965.850853728692;4851.029794041136;5959.314678811906;5963.970180149072;5983.055506960255;6009.836458874802;5247.661846229187;5282.6038047147385;5282.9471705283995;5746.597738158456;5783.94216057834;4457.04173602664;5296.364436267672;5648.406917273627;5652.406497317723;5710.428957104256;5197.558207552401;5656.943430565641;5737.713114344349;5865.387018569927;5946.910796337972;3792.544894612664;3793.336166170854;3802.4486449465226;3818.16000479894;3819;4982.975085124696;5018;5339.932013597218;5689.715514224189;5710.2862142232225;4196.832126714898;5458.727063646881;5466.562674986071;5468.28912241406;5472.671639701472;4625.560571745679;4928.225838697994;5207.843764687065;5462.289902312674;5485.6434331768305;4543.772811359485;5176.482351764794;5377.24718539397;5382.623216374816;5390.946090539202;4664.696794708469;4726.1020406122725;4993.400791904995;5172.836111874949;5231.136862417658;5170.43125256214;5338.386085600206;5385.465142434387;5410.918836217381;5414.75633596497 15:42:14 INFO - PROCESS | 1946 | |5;yui.html;670.8126615889875;689.4243306838789;698.6332175607805;733.5645656420686;742.8662840688673;16049.19754012299;16626.33494660212;16633.83366166338;16634.916825415872;16639.750403743954;15438.22808859558;15933.96429232099;16037.037777733314;16111.194440277997;16142.677146457094;721.6427868205245;744.681913013965;872.8865247517814;913.4108500017485;921.1939552891204;900.0189793125492;911.7583840282298;912.0104686415215;912.4160537256162;913.5021413329717;930.5207817973749;943.377370935183;945.0974319524837;946.6355453150552;947.2422062350126;748.9513181643204;936.5036530638772;973.3186769261474;976.501983988164;979.4417182206146;705.14677240539;706.1032488739323;710.8009757267973;712.8966299886539;713.2653367031913;14606.19665918374;14749.631259218499;14767.040142390817;14941.925290373478;14980.176090315028;12578.056645751605;12796.872031279661;12829.984953535912;12926.87073129266;12932.87067129326;9745.463999480025;10189.388636681822;10216.642417515426;10230.641927532575;10509.159267258601;3408.625051244361;3532.7703699259723;3678.8160591970295;3737.9626203738158;3762.5861155272896;72640.27359726442;78621.60689196517;78637;78669.99999999943;79685.20314796836;9698.321117521706;9698.854517182248;9789.804203915883;9984.400935943866;10097.444640544694;18499.075046247635;22726.545469090524;22729.772702273098;22750.886245568665;22826.65760013601;5829.15477255804;7252.782416527512;7324;7486.887696684554;7522.996057818738;3853.0560012797046;3882.5923278055398;3902.434147048662;3906.121122747359;3913.1977944521304;3322.1528510229737;3453.7582369233915;3548.6451354864826;3551.6093229744706;3558.09268636496;596.4304089594427;597.172915512019;631.135344577922;648.3840351665937;658.2495954611746;561.7107189797258;581.604508933929;585.6573904265966;590.7282649981057;594.9167116603679;6828.419584335387;8160.3471722262075;8318.334533237326;8406.285465735358;8413.326933845277;548.8353493951798;562.445990699161;569.8429215598815;588.1736160694231;624.572168064877;3785.136657792154;3836.8848934532007;3928.64642182205;3950.644442000234;4037.515498140242;80849.3830123406;81989.59005204936;83193.58403208066;83284.75072873912;84011;64088.39779005562;65117.348826512076;66865;67065.66467167731;74741.25258747452;63142.05286920608;63238.68380658067;65163.67418162784;65327.673361632886;65439.01841472382;8181.427300089174;8211.425200235926;8257.587120643824;8292.543910085004;8393.632416787412;40056.3991540133;41796.79101604473;42762.57237427586;43868.464603738714;44659.10681786411;53768.731156343965;60702.696486517285;60705.08942365956;61012.38987610067;61027.38972610217;10746.570137194425;10759.89240107589;10773.407462589634;10858.2399232053;11213.327200368005;11657.591984280509;11717.88282117168;11776.941115294369;12007.639770806889;12354.444050017592;11026.669199924016;11057.22599418033;11071.778564428827;11448.313101213955;11579.536818527502;11178.552857885594;11241.100711943189;11343.092552595608;11378.772424551627;11700.941495292469;11048.392338421463;11105.960231889234;11208.49561769706;11249.71875703114;11356.772864542829;9370.437773733598;9442.371424454548;9574.569144388659;9642.035796420301;9953.452560108974;9256.676016339396;9370.718878433658;9404.606865561711;9551.952240238754;9599.376040557341 15:42:14 INFO - PROCESS | 1946 | __end_tp_report 15:42:14 INFO - PROCESS | 1946 | __start_cc_report 15:42:14 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1146 15:42:14 INFO - PROCESS | 1946 | __end_cc_report 15:42:14 INFO - PROCESS | 1946 | __startTimestamp1464043334137__endTimestamp 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | ------- Summary: start ------- 15:42:14 INFO - PROCESS | 1946 | Number of tests: 6 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5671.82 Median:5775.28 stddev:624.33 (10.8%) stddev-sans-first:600.93 15:42:14 INFO - PROCESS | 1946 | Values: 3428.6 3450.3 3453.1 3464.9 3523.3 5562.4 5584.9 5607.9 5618.6 5667.6 5609.6 5615.7 5683.9 5697.7 5734.7 5092.3 5133.9 5191.8 5199.8 5306.0 5263.8 5298.9 5301.5 5316.7 5338.0 5085.8 5153.6 5155.4 5169.1 5219.7 5117.8 5128.4 5176.4 5270.3 5301.8 5117.8 5176.7 5196.5 5199.0 5238.5 5294.7 5606.2 5664.7 5673.5 5691.1 5342.4 6082.9 6112.4 6123.3 6166.2 6182.7 6199.4 6226.5 6235.0 6247.5 5642.9 5750.4 5775.3 5779.6 5811.0 6219.1 6273.6 6275.7 6291.4 6301.9 5993.2 6075.0 6083.5 6085.7 6093.0 5027.5 6097.2 6154.8 6160.0 6171.5 6184.0 6186.7 6195.1 6208.8 6209.6 6203.3 6208.3 6209.1 6214.8 6233.3 4818.3 4856.8 4876.3 4900.8 4968.6 4327.9 4340.7 4446.6 4448.9 4472.2 5618.2 5641.2 5660.5 5698.3 5762.7 6238.3 6249.9 6253.1 6253.7 6269.7 5639.8 5703.4 5711.7 5721.3 5749.0 5245.3 5282.0 5298.9 5362.0 5376.9 6250.6 6252.4 6253.7 6253.7 6261.0 5256.9 6012.8 6141.7 6243.8 6272.9 6259.7 6270.1 6275.4 6279.5 6294.6 6074.0 6154.6 6171.7 6173.7 6183.2 5383.3 6073.0 6118.1 6124.4 6169.8 4494.7 6068.0 6087.2 6091.5 6100.2 6056.9 6074.3 6075.0 6089.8 6114.6 5342.5 5550.7 5718.1 5879.9 5910.3 6037.2 6044.8 6073.8 6115.9 6122.4 5296.5 5553.3 5893.3 5920.5 5925.4 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:76305.16 Median:35870.50 stddev:175201.41 (488.4%) stddev-sans-first:175600.44 15:42:14 INFO - PROCESS | 1946 | Values: 496.6 510.4 510.5 532.4 545.9 30222.7 30469.2 31742.8 40580.4 41898.6 42384.6 42828.8 42876.0 42981.0 43021.9 9316.9 9577.1 9582.5 9593.1 9603.2 8509.9 9390.8 11357.4 11547.2 11548.1 10201.4 11644.8 11653.7 11663.3 11681.5 8493.6 11407.3 11487.3 11530.4 11592.5 11173.1 11356.8 11487.3 11673.9 11746.7 39589.0 39852.2 39992.6 40100.4 40122.6 3189.1 3280.9 3710.6 3718.7 4010.9 26999.5 28099.3 28702.0 29101.0 29341.3 2677.6 2709.8 2732.0 2770.4 2908.2 560240.0 569216.0 572595.0 722226.4 722577.4 62259.7 62288.7 63050.4 63171.7 63215.7 34675.8 35419.5 35579.3 35609.6 36144.4 16514.9 16841.3 17091.2 17093.0 17094.8 11530.8 11908.9 12976.4 13056.6 13080.1 19292.4 19527.9 19584.8 19902.3 19956.4 7811.8 7962.6 7964.2 7973.3 8055.8 925.9 925.9 1058.8 1062.6 1129.2 27969.9 28559.9 28794.7 28894.6 29252.0 998.7 1021.8 1062.6 1078.7 1105.5 11911.9 12244.6 12513.2 13081.0 14756.3 679779.0 685808.0 685957.0 686579.0 687214.0 43004.8 59003.7 59081.4 59133.0 59336.0 1299.5 1339.5 1441.1 1447.9 1456.6 26806.7 28844.7 29185.3 29633.8 29763.6 49804.8 49957.8 50715.7 50737.0 50796.5 626285.0 641143.8 641157.0 642136.8 643932.8 35708.6 41268.6 41909.8 42321.8 42874.4 35547.0 45052.5 45259.0 45491.3 45730.0 36717.8 36892.3 37229.1 37609.4 38025.9 35861.0 35880.0 36236.8 36435.3 36706.0 36127.0 36339.8 37113.8 37456.0 37962.6 32430.8 32623.0 33056.8 33365.5 33435.2 20014.3 24280.3 24298.9 24403.0 24617.1 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:34183.17 Median:383.93 stddev:100512.58 (26180.0%) stddev-sans-first:100762.48 15:42:14 INFO - PROCESS | 1946 | Values: 372.4 378.5 378.9 388.9 393.4 14713.6 15468.8 19426.9 20705.4 20755.4 19095.7 20521.5 20575.1 20606.4 20851.7 5544.4 5834.7 5880.9 6089.5 6125.2 5978.3 5978.4 6017.2 6017.5 6058.6 5560.5 5880.3 5890.9 5970.6 5985.4 5861.4 5868.0 5870.6 5914.7 5924.4 4873.0 5045.7 5108.4 5169.9 5390.0 10876.0 12657.6 13005.0 13069.5 13322.0 2131.1 2172.8 2264.5 2605.3 2631.7 9541.6 9641.7 10132.9 12237.5 14648.6 1589.8 1656.3 1688.3 1701.5 1743.6 96486.5 108916.8 121372.0 128215.4 129604.0 11292.0 11384.4 11506.3 11560.9 11683.9 14197.9 14213.9 14644.2 14667.9 14753.1 8424.7 8464.2 8472.4 8631.2 8662.4 8540.9 8541.5 8596.6 8656.6 8690.3 4418.3 4539.7 4540.6 4551.1 4598.9 1588.6 1610.0 1626.7 1917.4 1941.4 869.6 931.5 965.0 969.3 993.1 19241.4 20224.9 20331.7 20399.6 20508.0 862.0 901.6 922.9 949.0 976.0 9147.8 10398.9 10843.0 10857.5 10898.5 591346.0 602524.0 603826.0 607964.0 610636.0 103105.8 122713.0 122950.8 125516.4 128672.0 858.3 975.8 1019.2 1019.4 1019.9 18801.4 18902.5 18918.5 18934.8 18968.7 50676.5 51600.0 52267.0 52283.5 53340.7 64380.7 74257.3 74716.6 75412.1 75912.0 7759.5 7949.8 7951.5 8021.8 8290.3 8910.8 11670.2 12344.3 12396.8 12842.4 7282.5 10741.0 10872.5 11037.9 11329.8 10857.9 10969.8 10990.7 11096.6 11189.1 6387.3 7427.5 9434.3 11108.1 11238.8 6284.8 7264.3 7272.8 7283.2 8346.0 8126.6 10258.3 10724.2 11105.0 11193.2 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5666.32 Median:4846.29 stddev:3212.90 (66.3%) stddev-sans-first:3205.92 15:42:14 INFO - PROCESS | 1946 | Values: 1400.4 1501.5 1591.8 1643.9 1955.4 7825.8 8552.3 8788.4 8799.6 8898.8 8522.7 8671.9 8783.3 8790.6 8795.1 1852.5 2099.3 2296.3 2338.5 2370.9 2350.5 2379.2 2414.3 2416.9 2422.6 1924.4 2246.8 2296.2 2321.9 2325.3 2074.7 2255.1 2314.8 2329.4 2335.2 2357.2 2378.0 2383.3 2384.2 2385.7 8713.0 8730.9 8760.6 8776.4 8832.7 5217.5 5267.4 5268.9 5272.3 5366.6 3366.6 3376.2 3551.1 3556.3 3576.6 1013.2 1014.2 1016.9 1017.5 1017.6 11481.6 11611.7 11620.4 11678.0 11715.7 4613.5 4724.3 4791.2 4812.8 4820.4 9895.2 9947.8 9960.2 9960.7 9973.5 5374.5 5376.4 5379.3 5387.2 5398.8 3966.9 4412.2 4572.5 4930.2 4946.4 3919.9 3980.0 4023.7 4058.0 4111.4 1249.6 1250.1 1267.3 1275.4 1299.9 837.8 896.8 914.4 933.0 938.9 5634.6 5689.5 5760.7 5796.5 6020.3 440.2 445.2 447.7 970.5 996.8 3866.3 3915.7 3951.9 3966.6 3995.9 10606.9 10629.0 10669.7 10785.6 10926.5 10665.7 10671.9 10853.3 10856.8 10860.3 7018.8 8127.2 8159.9 8301.5 8359.9 5747.5 5752.1 5922.2 5990.2 6046.4 9491.9 9865.5 10039.0 10052.9 10071.0 9852.8 10139.5 10140.0 10271.2 10273.5 6650.2 6881.8 7022.6 7171.1 7291.1 8180.2 8333.8 8369.0 8405.4 8412.2 7297.3 7397.8 7402.1 7476.3 7578.9 7184.0 7259.7 7279.6 7345.2 7423.7 6885.5 6898.5 6938.7 6951.3 6988.0 4764.1 4857.5 4896.2 4929.1 5089.5 4785.8 4835.0 4880.6 4938.8 5094.3 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:4408.87 Median:4729.91 stddev:1346.05 (28.5%) stddev-sans-first:1313.67 15:42:14 INFO - PROCESS | 1946 | Values: 280.0 293.7 303.5 312.2 316.9 4271.9 5089.3 5220.7 5282.0 5298.0 4546.5 4901.9 5192.4 5195.7 5212.4 4015.9 4039.8 4051.6 4055.4 4066.3 3260.5 3969.7 4078.9 4080.3 4094.2 3847.4 4033.7 4041.3 4053.9 4066.9 3878.4 3962.6 4035.6 4039.3 4050.4 3095.5 3549.8 3552.2 3570.4 3576.2 4733.7 5061.7 5077.8 5090.6 5126.5 3929.6 4622.1 4637.6 4645.4 4660.0 4708.4 5530.7 5591.6 5621.8 5639.0 3662.9 3941.8 3948.8 3964.5 3967.1 5206.7 5208.2 5598.6 5858.5 5892.5 3377.3 3852.7 3979.7 4010.5 4031.4 4053.0 4077.8 4079.4 4086.0 4102.5 4027.4 4652.8 4657.9 4659.5 4663.7 4840.6 5249.8 5527.5 5561.5 5620.4 1300.8 1364.5 1391.1 1391.4 1396.0 738.7 745.6 746.2 836.7 844.9 3838.5 3843.4 3860.5 4307.3 4334.9 4210.5 4255.0 4801.4 4855.8 4879.6 3956.3 3974.7 4110.4 4184.3 4381.7 2564.9 2728.6 3030.8 3031.2 3043.2 5081.6 6030.6 6037.5 6046.4 6050.2 5578.6 5923.5 5956.5 5958.7 5965.9 4851.0 5959.3 5964.0 5983.1 6009.8 5247.7 5282.6 5282.9 5746.6 5783.9 4457.0 5296.4 5648.4 5652.4 5710.4 5197.6 5656.9 5737.7 5865.4 5946.9 3792.5 3793.3 3802.4 3818.2 3819.0 4983.0 5018.0 5339.9 5689.7 5710.3 4196.8 5458.7 5466.6 5468.3 5472.7 4625.6 4928.2 5207.8 5462.3 5485.6 4543.8 5176.5 5377.2 5382.6 5390.9 4664.7 4726.1 4993.4 5172.8 5231.1 5170.4 5338.4 5385.5 5410.9 5414.8 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:17142.85 Median:595.67 stddev:23068.05 (3872.6%) stddev-sans-first:23099.60 15:42:14 INFO - PROCESS | 1946 | Values: 670.8 689.4 698.6 733.6 742.9 16049.2 16626.3 16633.8 16634.9 16639.8 15438.2 15934.0 16037.0 16111.2 16142.7 721.6 744.7 872.9 913.4 921.2 900.0 911.8 912.0 912.4 913.5 930.5 943.4 945.1 946.6 947.2 749.0 936.5 973.3 976.5 979.4 705.1 706.1 710.8 712.9 713.3 14606.2 14749.6 14767.0 14941.9 14980.2 12578.1 12796.9 12830.0 12926.9 12932.9 9745.5 10189.4 10216.6 10230.6 10509.2 3408.6 3532.8 3678.8 3738.0 3762.6 72640.3 78621.6 78637.0 78670.0 79685.2 9698.3 9698.9 9789.8 9984.4 10097.4 18499.1 22726.5 22729.8 22750.9 22826.7 5829.2 7252.8 7324.0 7486.9 7523.0 3853.1 3882.6 3902.4 3906.1 3913.2 3322.2 3453.8 3548.6 3551.6 3558.1 596.4 597.2 631.1 648.4 658.2 561.7 581.6 585.7 590.7 594.9 6828.4 8160.3 8318.3 8406.3 8413.3 548.8 562.4 569.8 588.2 624.6 3785.1 3836.9 3928.6 3950.6 4037.5 80849.4 81989.6 83193.6 83284.8 84011.0 64088.4 65117.3 66865.0 67065.7 74741.3 63142.1 63238.7 65163.7 65327.7 65439.0 8181.4 8211.4 8257.6 8292.5 8393.6 40056.4 41796.8 42762.6 43868.5 44659.1 53768.7 60702.7 60705.1 61012.4 61027.4 10746.6 10759.9 10773.4 10858.2 11213.3 11657.6 11717.9 11776.9 12007.6 12354.4 11026.7 11057.2 11071.8 11448.3 11579.5 11178.6 11241.1 11343.1 11378.8 11700.9 11048.4 11106.0 11208.5 11249.7 11356.8 9370.4 9442.4 9574.6 9642.0 9953.5 9256.7 9370.7 9404.6 9552.0 9599.4 15:42:14 INFO - PROCESS | 1946 | -------- Summary: end -------- 15:42:14 INFO - PROCESS | 1946 | 15:42:14 INFO - TEST-INFO | 1946: exit 0 15:42:14 INFO - TEST-OK | dromaeo_css | took 1115499ms 15:42:14 INFO - TEST-START | kraken 15:42:14 INFO - operating with platform_type : mac_ 15:42:14 INFO - Initialising browser for kraken test... 15:42:14 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpv8nH0k/profile http://localhost:55684/getInfo.html) 15:42:17 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 15:42:17 INFO - PROCESS | 1951 | colorDepth:24 15:42:17 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 15:42:17 INFO - PROCESS | 1951 | __metrics 15:42:17 INFO - PROCESS | 1951 | 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" 15:42:17 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:42:17 INFO - TEST-INFO | 1951: exit 0 15:42:17 INFO - Browser initialized. 15:42:17 INFO - Running cycle 1/1 for kraken test... 15:42:17 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpv8nH0k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:29 INFO - PROCESS | 1953 | RSS: Main: 238821376 15:42:29 INFO - PROCESS | 1953 | 15:42:32 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55684/tests/kraken/driver.html?testName=audio-beat-detection) 15:42:33 INFO - PROCESS | 1953 | RSS: Main: 286900224 15:42:33 INFO - PROCESS | 1953 | 15:42:36 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55684/tests/kraken/driver.html?testName=audio-dft) 15:42:36 INFO - PROCESS | 1953 | RSS: Main: 295813120 15:42:36 INFO - PROCESS | 1953 | 15:42:40 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55684/tests/kraken/driver.html?testName=audio-fft) 15:42:41 INFO - PROCESS | 1953 | RSS: Main: 384434176 15:42:41 INFO - PROCESS | 1953 | 15:42:43 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55684/tests/kraken/driver.html?testName=audio-oscillator) 15:42:44 INFO - PROCESS | 1953 | RSS: Main: 285347840 15:42:44 INFO - PROCESS | 1953 | 15:42:46 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55684/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:42:46 INFO - PROCESS | 1953 | RSS: Main: 289517568 15:42:46 INFO - PROCESS | 1953 | 15:42:50 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55684/tests/kraken/driver.html?testName=imaging-darkroom) 15:42:50 INFO - PROCESS | 1953 | RSS: Main: 329945088 15:42:50 INFO - PROCESS | 1953 | 15:42:53 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55684/tests/kraken/driver.html?testName=imaging-desaturate) 15:42:54 INFO - PROCESS | 1953 | RSS: Main: 330911744 15:42:54 INFO - PROCESS | 1953 | 15:42:57 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55684/tests/kraken/driver.html?testName=json-parse-financial) 15:42:58 INFO - PROCESS | 1953 | RSS: Main: 328450048 15:42:58 INFO - PROCESS | 1953 | 15:42:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55684/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:43:00 INFO - PROCESS | 1953 | RSS: Main: 316284928 15:43:00 INFO - PROCESS | 1953 | 15:43:02 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:43:02 INFO - PROCESS | 1953 | RSS: Main: 323366912 15:43:03 INFO - PROCESS | 1953 | 15:43:05 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:43:06 INFO - PROCESS | 1953 | RSS: Main: 326213632 15:43:06 INFO - PROCESS | 1953 | 15:43:09 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:43:09 INFO - PROCESS | 1953 | RSS: Main: 330706944 15:43:09 INFO - PROCESS | 1953 | 15:43:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:43:13 INFO - PROCESS | 1953 | RSS: Main: 328257536 15:43:13 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:55684/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55684/tests/kraken/driver.html?testName=ai-astar) 15:43:15 INFO - PROCESS | 1953 | RSS: Main: 375844864 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | __start_tp_report 15:43:15 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 15:43:15 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 15:43:15 INFO - PROCESS | 1953 | |i|pagename|runs| 15:43:15 INFO - PROCESS | 1953 | |0;ai-astar;113;107;112;112;113;113;113;112;112;113 15:43:15 INFO - PROCESS | 1953 | |1;audio-beat-detection;157;118;137;187;119;147;138;121;168;119 15:43:15 INFO - PROCESS | 1953 | |2;audio-dft;183;185;184;186;235;185;185;180;183;185 15:43:15 INFO - PROCESS | 1953 | |3;audio-fft;80;76;82;163;67;57;65;59;116;78 15:43:15 INFO - PROCESS | 1953 | |4;audio-oscillator;96;77;140;79;79;76;103;79;77;108 15:43:15 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;89;86;90;87;87;85;89;88;108;80 15:43:15 INFO - PROCESS | 1953 | |6;imaging-darkroom;94;94;95;94;95;94;94;95;94;93 15:43:15 INFO - PROCESS | 1953 | |7;imaging-desaturate;83;83;84;85;83;84;84;85;84;84 15:43:15 INFO - PROCESS | 1953 | |8;json-parse-financial;58;55;58;56;56;57;58;122;55;57 15:43:15 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;66;72;71;76;73;71;71;70;71;72 15:43:15 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;86;85;86;86;87;94;106;107;83;86 15:43:15 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;146;152;143;134;139;140;141;149;218;156 15:43:15 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;220;221;167;165;168;167;166;164;164;228 15:43:15 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;68;68;67;65;66;65;67;65;64;66 15:43:15 INFO - PROCESS | 1953 | __end_tp_report 15:43:15 INFO - PROCESS | 1953 | __start_cc_report 15:43:15 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2378 15:43:15 INFO - PROCESS | 1953 | __end_cc_report 15:43:15 INFO - PROCESS | 1953 | __startTimestamp1464043395506__endTimestamp 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | ------- Summary: start ------- 15:43:15 INFO - PROCESS | 1953 | Number of tests: 14 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:1.83 (1.6%) stddev-sans-first:1.90 15:43:15 INFO - PROCESS | 1953 | Values: 113.0 107.0 112.0 112.0 113.0 113.0 113.0 112.0 112.0 113.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:142.50 stddev:23.71 (16.6%) stddev-sans-first:24.44 15:43:15 INFO - PROCESS | 1953 | Values: 157.0 118.0 137.0 187.0 119.0 147.0 138.0 121.0 168.0 119.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:189.10 Median:185.00 stddev:16.22 (8.8%) stddev-sans-first:17.05 15:43:15 INFO - PROCESS | 1953 | Values: 183.0 185.0 184.0 186.0 235.0 185.0 185.0 180.0 183.0 185.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:84.30 Median:71.50 stddev:32.30 (45.2%) stddev-sans-first:34.22 15:43:15 INFO - PROCESS | 1953 | Values: 80.0 76.0 82.0 163.0 67.0 57.0 65.0 59.0 116.0 78.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:78.00 stddev:20.83 (26.7%) stddev-sans-first:22.03 15:43:15 INFO - PROCESS | 1953 | Values: 96.0 77.0 140.0 79.0 79.0 76.0 103.0 79.0 77.0 108.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:87.50 stddev:7.28 (8.3%) stddev-sans-first:7.72 15:43:15 INFO - PROCESS | 1953 | Values: 89.0 86.0 90.0 87.0 87.0 85.0 89.0 88.0 108.0 80.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 15:43:15 INFO - PROCESS | 1953 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 93.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 15:43:15 INFO - PROCESS | 1953 | Values: 83.0 83.0 84.0 85.0 83.0 84.0 84.0 85.0 84.0 84.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:57.00 stddev:20.69 (36.3%) stddev-sans-first:21.86 15:43:15 INFO - PROCESS | 1953 | Values: 58.0 55.0 58.0 56.0 56.0 57.0 58.0 122.0 55.0 57.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:2.50 (3.5%) stddev-sans-first:1.76 15:43:15 INFO - PROCESS | 1953 | Values: 66.0 72.0 71.0 76.0 73.0 71.0 71.0 70.0 71.0 72.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:86.00 stddev:8.85 (10.3%) stddev-sans-first:9.23 15:43:15 INFO - PROCESS | 1953 | Values: 86.0 85.0 86.0 86.0 87.0 94.0 106.0 107.0 83.0 86.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:147.50 stddev:24.17 (16.4%) stddev-sans-first:25.54 15:43:15 INFO - PROCESS | 1953 | Values: 146.0 152.0 143.0 134.0 139.0 140.0 141.0 149.0 218.0 156.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.00 Median:167.50 stddev:27.71 (16.5%) stddev-sans-first:25.95 15:43:15 INFO - PROCESS | 1953 | Values: 220.0 221.0 167.0 165.0 168.0 167.0 166.0 164.0 164.0 228.0 15:43:15 INFO - PROCESS | 1953 | 15:43:15 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.37 (2.1%) stddev-sans-first:1.27 15:43:15 INFO - PROCESS | 1953 | Values: 68.0 68.0 67.0 65.0 66.0 65.0 67.0 65.0 64.0 66.0 15:43:15 INFO - PROCESS | 1953 | -------- Summary: end -------- 15:43:15 INFO - PROCESS | 1953 | 15:43:16 INFO - TEST-INFO | 1953: exit 0 15:43:16 INFO - TEST-OK | kraken | took 61621ms 15:43:16 INFO - SUITE-END | took 1177s 15:43:16 INFO - Completed test suite (00:19:37) 15:43:16 INFO - javascript benchmark 15:43:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3428.6399928007563, 3450.344434557432, 3453.084932492888, 3464.9307013859725, 3523.2953409318125, 5562.360328562217, 5584.91622625661, 5607.943920560793, 5618.578606604506, 5667.574931880115, 5609.635373700707, 5615.7472913718875, 5683.943160568393, 5697.6866272355, 5734.741936612852, 5092.312537807404, 5133.948660513384, 5191.792328306863, 5199.818006369778, 5305.991861546313, 5263.789448422078, 5298.92051619226, 5301.469853014687, 5316.707581083039, 5337.973310133463, 5085.771140298677, 5153.613478989053, 5155.40712818027, 5169.121249387613, 5219.71291578963, 5117.82087626935, 5128.435872054109, 5176.378834539879, 5270.262163297141, 5301.7614207360375, 5117.795288188468, 5176.741162941876, 5196.53231209193, 5199.038177937094, 5238.528532432061, 5294.735263236861, 5606.187102870061, 5664.688442135681, 5673.489385955284, 5691.146328050825, 5342.358916929955, 6082.908756368658, 6112.412294442025, 6123.265208175003, 6166.229221347332, 6182.69086545671, 6199.380061993809, 6226.470749986257, 6235.0, 6247.468965137969, 5642.943570564242, 5750.396208398141, 5775.27809023872, 5779.566532510079, 5810.983077961382, 6219.067139929047, 6273.560850740404, 6275.748970041147, 6291.433770960635, 6301.905471417932, 5993.160957465871, 6074.969625151934, 6083.452489275986, 6085.665288409092, 6093.0, 5027.547520723079, 6097.1952901664845, 6154.846128846814, 6160.0, 6171.506279497629, 6184.041473571604, 6186.659733714689, 6195.132681424604, 6208.782692605737, 6209.627422354582, 6203.31763506014, 6208.348123447064, 6209.06863970399, 6214.751409943642, 6233.3143354231, 4818.349522814376, 4856.8300109495995, 4876.341693871282, 4900.803967841246, 4968.552830245295, 4327.870163895088, 4340.739555626673, 4446.577575130422, 4448.866534003921, 4472.217361961643, 5618.157276408489, 5641.210230567724, 5660.518855897295, 5698.31620205578, 5762.683052432157, 6238.251409830849, 6249.875002499924, 6253.1245625612455, 6253.6873156342, 6269.749210031548, 5639.774409023676, 5703.429657034347, 5711.742971566289, 5721.256236689203, 5749.0, 5245.291885595472, 5282.022825777243, 5298.894022119536, 5362.0, 5376.892462150735, 6250.593711408744, 6252.374762523893, 6253.6560489173535, 6253.6873156342, 6260.968695156586, 5256.868578285574, 6012.789552365646, 6141.731496609813, 6243.8126856194385, 6272.874542509033, 6259.749610015549, 6270.0908368287255, 6275.435210831047, 6279.466245369012, 6294.622322660655, 6073.99779036454, 6154.569180157346, 6171.660558669317, 6173.722182501709, 6183.196184495987, 5383.300170977748, 6072.969635151796, 6118.143459915705, 6124.350421260821, 6169.784057558143, 4494.70784399013, 6067.969660151671, 6087.178230938766, 6091.543134264859, 6100.176476175659, 6056.90914636272, 6074.3014553325165, 6075.0, 6089.817305480843, 6114.57197996136, 5342.492463215988, 5550.69471179089, 5718.142278658068, 5879.853003674772, 5910.320313164041, 6037.18498002764, 6044.788432404845, 6073.787417440545, 6115.877682446415, 6122.387761223842, 5296.523312901857, 5553.305836770403, 5893.292804863444, 5920.467157955804, 5925.40745925404], "value": 5635.302136912887, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.6498618473975, 510.3642824746686, 510.48696060459235, 532.3744600094888, 545.912653975364, 30222.697773022264, 30469.238269043286, 31742.84128579357, 40580.39129413061, 41898.58101418993, 42384.57615423845, 42828.78585607067, 42876.0, 42981.0, 43021.92445188864, 9316.860247096281, 9577.090176433228, 9582.52087395631, 9593.136617704406, 9603.231741460666, 8509.914900850974, 9390.812183756321, 11357.432128393588, 11547.249428787101, 11548.076153907667, 10201.387916724983, 11644.82532762005, 11653.70865728355, 11663.300201987866, 11681.474333655005, 8493.575321233913, 11407.315561066363, 11487.25332853362, 11530.423478826067, 11592.47833847479, 11173.10615150786, 11356.772864542745, 11487.310761354345, 11673.883261167364, 11746.706332341675, 39589.0, 39852.20295594072, 39992.60007399918, 40100.398494022615, 40122.59877401218, 3189.0751682012187, 3280.8687652493873, 3710.6474884885893, 3718.665320121202, 4010.8796736097966, 26999.46001079987, 28099.297517562223, 28701.995430159845, 29101.0, 29341.26646833846, 2677.598360245959, 2709.7967652426146, 2731.984917373879, 2770.432061427423, 2908.2147820088458, 560240.0, 569216.0, 572595.0, 722226.3888680575, 722577.3871130716, 62259.6887015562, 62288.688556556925, 63050.369496305364, 63171.68283171646, 63215.6839215801, 34675.826620866734, 35419.4687079694, 35579.28841423157, 35609.64390356115, 36144.38556144365, 16514.917425412797, 16841.32634694623, 17091.230894609773, 17093.0, 17094.829051709323, 11530.827037594443, 11908.940455297668, 12976.35118244084, 13056.608301750963, 13080.084394092513, 19292.4212273632, 19527.90236048839, 19584.804151958582, 19902.303419380543, 19956.401307960783, 7811.843763124706, 7962.6416811243635, 7964.1637628049375, 7973.2824045835, 8055.798605034921, 925.8981512033671, 925.8981512033671, 1058.8147074262126, 1062.5909025025421, 1129.209553312668, 27969.88120475199, 28559.857200713865, 28794.712052879204, 28894.566581501294, 29252.0, 998.681740103057, 1021.8453147942777, 1062.617457715222, 1078.7249251440833, 1105.4914739220048, 11911.868372504598, 12244.571439999558, 12513.249205047545, 13081.0, 14756.335964881606, 679779.0, 685808.0, 685957.0, 686579.0, 687214.0, 43004.78497607554, 59003.70498147482, 59081.40918590845, 59133.0, 59336.0, 1299.52567312932, 1339.4709089909627, 1441.0921119694735, 1447.949321773733, 1456.5848733111013, 26806.727284478482, 28844.711552884204, 29185.270368240963, 29633.814647413863, 29763.553546696385, 49804.75097624489, 49957.75021124871, 50715.74642126766, 50736.985260295325, 50796.49203507917, 626285.0, 641143.7942810256, 641157.0, 642136.7893160505, 643932.7803360954, 35708.64291357053, 41268.587314127675, 41909.79045104755, 42321.788391057846, 42874.356884646135, 35547.0, 45052.549474506144, 45259.0, 45491.31763023491, 45730.0, 36717.816410917774, 36892.262154756216, 37229.06927326838, 37609.43585846269, 38025.85922422332, 35861.0, 35880.0, 36236.81881590575, 36435.27129457449, 36706.0, 36127.0, 36339.81830090833, 37113.814430927676, 37456.0, 37962.62037379591, 32430.83784581062, 32623.0, 33056.83471582723, 33365.49951750774, 33435.16412089717, 20014.29949951745, 24280.271591852274, 24298.878505607358, 24403.0, 24617.138400156626], "value": 20075.576707256758, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.39857629927667, 378.4739212494632, 378.91474418255876, 388.94360317753944, 393.43345582361417, 14713.632159196026, 15468.767968480483, 19426.90286548567, 20705.378838634828, 20755.377338679846, 19095.713564296548, 20521.4869628259, 20575.074121664522, 20606.38180854573, 20851.687224691643, 5544.417836127209, 5834.6790926499025, 5880.911786323209, 6089.543284253698, 6125.173101631267, 5978.312494063195, 5978.372270911557, 6017.224918649009, 6017.48851347636, 6058.575899687024, 5560.471755183251, 5880.26496687914, 5890.852728681774, 5970.552208584352, 5985.371535988724, 5861.443162899539, 5868.0, 5870.647761134324, 5914.704264786744, 5924.437178468082, 4872.9756351218375, 5045.697258164522, 5108.4125325587665, 5169.948300516984, 5389.973050134724, 10876.0, 12657.556985505464, 13005.0, 13069.477220911152, 13322.0, 2131.0729832522748, 2172.847900646955, 2264.479169790941, 2605.335639411938, 2631.67104111986, 9541.61833526665, 9641.71074867748, 10132.898671013269, 12237.510499580007, 14648.571157409942, 1589.8171710253353, 1656.2546853915665, 1688.274042161882, 1701.4895531340549, 1743.579553921807, 96486.51756741242, 108916.82166356708, 121372.0, 128215.35892320478, 129604.0, 11292.0, 11384.373859437645, 11506.252093613888, 11560.913198192768, 11683.890030447094, 14197.858021419861, 14213.928930355489, 14644.19456929879, 14667.853321466862, 14753.114813111248, 8424.705135320235, 8464.238218560358, 8472.44929079608, 8631.223189912938, 8662.350323725746, 8540.871886921701, 8541.487510749375, 8596.570171491401, 8656.61045252965, 8690.26132778721, 4418.33724941255, 4539.7074477388705, 4540.636749060133, 4551.112533056023, 4598.931016034762, 1588.6425554250432, 1609.967800644004, 1626.6746650669913, 1917.4439412570478, 1941.4369832748346, 869.5652173913044, 931.4690626343055, 964.9565769540387, 969.3360048366891, 993.1111655068673, 19241.4227573173, 20224.89887550553, 20331.695024574645, 20399.59200816005, 20508.0, 862.0259107208814, 901.6213190460022, 922.898481167071, 948.9715308540755, 975.9996004095717, 9147.77130571741, 10398.948005259925, 10843.0, 10857.51141198648, 10898.455077246106, 591346.0, 602524.0, 603825.9808700929, 607964.0, 610636.0, 103105.76371034233, 122713.0, 122950.77049229572, 125516.37241813914, 128672.0, 858.2661824140395, 975.8243516167016, 1019.189744153385, 1019.3628981886321, 1019.9439030853226, 18801.435956921316, 18902.52743681419, 18918.52703682419, 18934.810651893305, 18968.715469267696, 50676.49323506865, 51600.0, 52267.0, 52283.47716522786, 53340.73329633327, 64380.67809660922, 74257.25742742504, 74716.62641686974, 75412.11469713302, 75912.0, 7759.534427934342, 7949.8410031800195, 7951.522908625501, 8021.759347219593, 8290.295324897452, 8910.777230569029, 11670.183087184156, 12344.259344439362, 12396.752064958831, 12842.422091006114, 7282.490225684363, 10741.0, 10872.456377180952, 11037.889621103686, 11329.773404531697, 10857.891421085687, 10969.780604388026, 10990.725231869268, 11096.5561377544, 11189.104871610414, 6387.297397286202, 7427.517211381243, 9434.29242806806, 11108.111351091731, 11238.7752244953, 6284.811455656338, 7264.309890560599, 7272.818179545554, 7283.2352602975925, 8346.0, 8126.5936703163425, 10258.28192026551, 10724.195685323475, 11105.0, 11193.216474847008], "value": 9129.546712285786, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.4328247059932, 1501.4594745891482, 1591.7930669013026, 1643.8931469454485, 1955.4133759872043, 7825.843483130341, 8552.315814734822, 8788.384813063089, 8799.604017819205, 8898.77753056174, 8522.744317670464, 8671.91328086719, 8783.341249406289, 8790.64837406503, 8795.076516965722, 1852.4905650945964, 2099.3492017474555, 2296.3225848374686, 2338.53229354129, 2370.8933098010543, 2350.459394339303, 2379.1553998330605, 2414.3239892830034, 2416.903323867043, 2422.6002709552904, 1924.4130540185238, 2246.764089770575, 2296.230762694491, 2321.8606883586954, 2325.2791634593245, 2074.7199128117736, 2255.052877791315, 2314.826388020897, 2329.429289823987, 2335.2410466598253, 2357.210334537932, 2377.976220237793, 2383.3445802404476, 2384.1655420602824, 2385.7375688674233, 8713.0, 8730.86903696445, 8760.605772740242, 8776.429532080394, 8832.69085582008, 5217.50433708797, 5267.3679158500845, 5268.894622107537, 5272.261883336336, 5366.570674346082, 3366.646502117267, 3376.206591451007, 3551.0646697549896, 3556.270964452281, 3576.624454432273, 1013.2350075692879, 1014.2241185493103, 1016.8678071850686, 1017.5421060522795, 1017.6133069433641, 11481.598144065001, 11611.709707257301, 11620.360880151673, 11678.007369373614, 11715.7071073223, 4613.515580864028, 4724.291356296583, 4791.185498465269, 4812.807487700453, 4820.421549414023, 9895.218860605017, 9947.751306217258, 9960.203183745283, 9960.651377201762, 9973.451460169616, 5374.4625537445945, 5376.435474275145, 5379.327584051994, 5387.24578559002, 5398.78404863801, 3966.900827479278, 4412.161689279027, 4572.51988541205, 4930.1865192243595, 4946.35697359341, 3919.941200881932, 3980.044789250559, 4023.657989070903, 4057.959420405817, 4111.362738775495, 1249.6001279590625, 1250.1186663402461, 1267.271318991565, 1275.4069357748506, 1299.9415026323838, 837.7821766340795, 896.7982204004047, 914.3964983111115, 932.9673461428953, 938.8591711243231, 5634.6337488063145, 5689.459501347365, 5760.683162426023, 5796.536277097822, 6020.307664618537, 440.1614923570603, 445.2475316714742, 447.6508323507669, 970.5098925042809, 996.8454889492141, 3866.3427217373114, 3915.7063220258597, 3951.9012024699614, 3966.6033396660105, 3995.880123596297, 10606.893931060591, 10629.0, 10669.679909602724, 10785.56857725698, 10926.453677316262, 10665.733356666145, 10671.89328106709, 10853.348799071922, 10856.78286434267, 10860.348379097122, 7018.75434359795, 8127.2279133482225, 8159.877601835978, 8301.543415112106, 8359.874601880976, 5747.540196784331, 5752.07966725322, 5922.230110085661, 5990.2212712348855, 6046.365131661289, 9491.857622135536, 9865.506724663595, 10039.04629060238, 10052.94973525157, 10071.0, 9852.753681158028, 10139.493025348556, 10139.9860013998, 10271.229657775548, 10273.486325683836, 6650.235222949423, 6881.759138430131, 7022.648867556704, 7171.067761191011, 7291.08861392326, 8180.181981801772, 8333.791655208668, 8368.958155209186, 8405.369597280107, 8412.242898139197, 7297.270272972661, 7397.815054623462, 7402.074740657418, 7476.327130558276, 7578.924210758042, 7184.0, 7259.673314700956, 7279.563226206445, 7345.22875098107, 7423.665935032828, 6885.4835887309655, 6898.517103802686, 6938.653067346511, 6951.339622735832, 6988.0, 4764.103404278602, 4857.53853383928, 4896.216605343128, 4929.088118698052, 5089.465606111305, 4785.760711964457, 4835.032993401404, 4880.6095512358215, 4938.827141050046, 5094.261332106749], "value": 4503.526984385168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.96780370257426, 293.70189257903235, 303.5037713338691, 312.20232306457, 316.928691044515, 4271.885416718725, 5089.262057001742, 5220.6606570572885, 5282.012080878941, 5297.973510132449, 4546.4771551271615, 4901.877453063676, 5192.350956130484, 5195.714235717034, 5212.400573933999, 4015.9397609035886, 4039.8384064637453, 4051.554329023805, 4055.4119652650347, 4066.2883995300854, 3260.4620237660756, 3969.729686500321, 4078.9184216315803, 4080.3063479208604, 4094.2016306820183, 3847.365184744514, 4033.6773058155277, 4041.2725709372303, 4053.858259848626, 4066.9186616267657, 3878.3794592865006, 3962.6235507626875, 4035.5964403559556, 4039.313316736162, 4050.372192310167, 3095.5356696495483, 3549.804760738158, 3552.2185119273977, 3570.428731402963, 3576.195356044895, 4733.715977041388, 5061.670991385548, 5077.796888124471, 5090.592752579785, 5126.461721519261, 3929.626685464876, 4622.08183603804, 4637.628989680851, 4645.372874661911, 4660.04469083837, 4708.411448568929, 5530.695811730353, 5591.580631452657, 5621.80323688673, 5638.971805140989, 3662.9194387655707, 3941.8423263069444, 3948.7828169450668, 3964.464797252363, 3967.0677390813134, 5206.739663016834, 5208.198015448744, 5598.552115830724, 5858.502027327682, 5892.499137573312, 3377.341418423433, 3852.749571277858, 3979.6617287530307, 4010.538788039413, 4031.4355990161403, 4052.9797351013644, 4077.796110194538, 4079.4084857695466, 4086.0, 4102.466679331727, 4027.35562310029, 4652.767361631905, 4657.930131048037, 4659.464161621457, 4663.696859704109, 4840.635758148123, 5249.763760630781, 5527.474889885454, 5561.499465048165, 5620.353659329229, 1300.78537041389, 1364.4883168811696, 1391.1358779235873, 1391.3530208452914, 1395.965100872466, 738.674983007475, 745.6271864067966, 746.1829296919864, 836.6653338664461, 844.8648216285364, 3838.5393752749846, 3843.4057011517116, 3860.536735591691, 4307.332363483665, 4334.869953901388, 4210.473690788652, 4255.0426154115075, 4801.423829140525, 4855.830045948375, 4879.633377811759, 3956.3274243378696, 3974.7018973577096, 4110.383442483591, 4184.330507118846, 4381.7151885128005, 2564.871456559108, 2728.577070554067, 3030.787844850883, 3031.227037105568, 3043.178341847689, 5081.60255929615, 6030.608010479393, 6037.45662890342, 6046.395360464006, 6050.183225264533, 5578.63738856973, 5923.496502797224, 5956.45132265614, 5958.731857066442, 5965.850853728692, 4851.029794041136, 5959.314678811906, 5963.970180149072, 5983.055506960255, 6009.836458874802, 5247.661846229187, 5282.6038047147385, 5282.9471705283995, 5746.597738158456, 5783.94216057834, 4457.04173602664, 5296.364436267672, 5648.406917273627, 5652.406497317723, 5710.428957104256, 5197.558207552401, 5656.943430565641, 5737.713114344349, 5865.387018569927, 5946.910796337972, 3792.544894612664, 3793.336166170854, 3802.4486449465226, 3818.16000479894, 3819.0, 4982.975085124696, 5018.0, 5339.932013597218, 5689.715514224189, 5710.2862142232225, 4196.832126714898, 5458.727063646881, 5466.562674986071, 5468.28912241406, 5472.671639701472, 4625.560571745679, 4928.225838697994, 5207.843764687065, 5462.289902312674, 5485.6434331768305, 4543.772811359485, 5176.482351764794, 5377.24718539397, 5382.623216374816, 5390.946090539202, 4664.696794708469, 4726.1020406122725, 4993.400791904995, 5172.836111874949, 5231.136862417658, 5170.43125256214, 5338.386085600206, 5385.465142434387, 5410.918836217381, 5414.75633596497], "value": 3979.1400280624907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.8126615889875, 689.4243306838789, 698.6332175607805, 733.5645656420686, 742.8662840688673, 16049.19754012299, 16626.33494660212, 16633.83366166338, 16634.916825415872, 16639.750403743954, 15438.22808859558, 15933.96429232099, 16037.037777733314, 16111.194440277997, 16142.677146457094, 721.6427868205245, 744.681913013965, 872.8865247517814, 913.4108500017485, 921.1939552891204, 900.0189793125492, 911.7583840282298, 912.0104686415215, 912.4160537256162, 913.5021413329717, 930.5207817973749, 943.377370935183, 945.0974319524837, 946.6355453150552, 947.2422062350126, 748.9513181643204, 936.5036530638772, 973.3186769261474, 976.501983988164, 979.4417182206146, 705.14677240539, 706.1032488739323, 710.8009757267973, 712.8966299886539, 713.2653367031913, 14606.19665918374, 14749.631259218499, 14767.040142390817, 14941.925290373478, 14980.176090315028, 12578.056645751605, 12796.872031279661, 12829.984953535912, 12926.87073129266, 12932.87067129326, 9745.463999480025, 10189.388636681822, 10216.642417515426, 10230.641927532575, 10509.159267258601, 3408.625051244361, 3532.7703699259723, 3678.8160591970295, 3737.9626203738158, 3762.5861155272896, 72640.27359726442, 78621.60689196517, 78637.0, 78669.99999999943, 79685.20314796836, 9698.321117521706, 9698.854517182248, 9789.804203915883, 9984.400935943866, 10097.444640544694, 18499.075046247635, 22726.545469090524, 22729.772702273098, 22750.886245568665, 22826.65760013601, 5829.15477255804, 7252.782416527512, 7324.0, 7486.887696684554, 7522.996057818738, 3853.0560012797046, 3882.5923278055398, 3902.434147048662, 3906.121122747359, 3913.1977944521304, 3322.1528510229737, 3453.7582369233915, 3548.6451354864826, 3551.6093229744706, 3558.09268636496, 596.4304089594427, 597.172915512019, 631.135344577922, 648.3840351665937, 658.2495954611746, 561.7107189797258, 581.604508933929, 585.6573904265966, 590.7282649981057, 594.9167116603679, 6828.419584335387, 8160.3471722262075, 8318.334533237326, 8406.285465735358, 8413.326933845277, 548.8353493951798, 562.445990699161, 569.8429215598815, 588.1736160694231, 624.572168064877, 3785.136657792154, 3836.8848934532007, 3928.64642182205, 3950.644442000234, 4037.515498140242, 80849.3830123406, 81989.59005204936, 83193.58403208066, 83284.75072873912, 84011.0, 64088.39779005562, 65117.348826512076, 66865.0, 67065.66467167731, 74741.25258747452, 63142.05286920608, 63238.68380658067, 65163.67418162784, 65327.673361632886, 65439.01841472382, 8181.427300089174, 8211.425200235926, 8257.587120643824, 8292.543910085004, 8393.632416787412, 40056.3991540133, 41796.79101604473, 42762.57237427586, 43868.464603738714, 44659.10681786411, 53768.731156343965, 60702.696486517285, 60705.08942365956, 61012.38987610067, 61027.38972610217, 10746.570137194425, 10759.89240107589, 10773.407462589634, 10858.2399232053, 11213.327200368005, 11657.591984280509, 11717.88282117168, 11776.941115294369, 12007.639770806889, 12354.444050017592, 11026.669199924016, 11057.22599418033, 11071.778564428827, 11448.313101213955, 11579.536818527502, 11178.552857885594, 11241.100711943189, 11343.092552595608, 11378.772424551627, 11700.941495292469, 11048.392338421463, 11105.960231889234, 11208.49561769706, 11249.71875703114, 11356.772864542829, 9370.437773733598, 9442.371424454548, 9574.569144388659, 9642.035796420301, 9953.452560108974, 9256.676016339396, 9370.718878433658, 9404.606865561711, 9551.952240238754, 9599.376040557341], "value": 6778.2198600147785, "unit": "score"}], "name": "dromaeo_css", "value": 7075.4916034792595, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 112.0, 112.0, 113.0, 113.0, 113.0, 112.0, 112.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 137.0, 187.0, 119.0, 147.0, 138.0, 121.0, 168.0, 119.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 186.0, 235.0, 185.0, 185.0, 180.0, 183.0, 185.0], "value": 189.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 82.0, 163.0, 67.0, 57.0, 65.0, 59.0, 116.0, 78.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 77.0, 140.0, 79.0, 79.0, 76.0, 103.0, 79.0, 77.0, 108.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 87.0, 85.0, 89.0, 88.0, 108.0, 80.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 85.0, 83.0, 84.0, 84.0, 85.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 58.0, 56.0, 56.0, 57.0, 58.0, 122.0, 55.0, 57.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 71.0, 76.0, 73.0, 71.0, 71.0, 70.0, 71.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 87.0, 94.0, 106.0, 107.0, 83.0, 86.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 152.0, 143.0, 134.0, 139.0, 140.0, 141.0, 149.0, 218.0, 156.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 221.0, 167.0, 165.0, 168.0, 167.0, 166.0, 164.0, 164.0, 228.0], "value": 183.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 65.0, 66.0, 65.0, 67.0, 65.0, 64.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1510.8999999999999, "alertThreshold": 2.0}]} 15:43:16 INFO - Return code: 0 15:43:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:43:16 ERROR - # TBPL SUCCESS # 15:43:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:43:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:43:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:43:16 INFO - 2016-05-23 15:43:16.819 screenresolution[1957:24273] starting screenresolution argv=screenresolution get 15:43:16 INFO - 2016-05-23 15:43:16.822 screenresolution[1957:24273] Display 0: 1600x1200x32@60 15:43:16 INFO - 2016-05-23 15:43:16.828 screenresolution[1958:24276] starting screenresolution argv=screenresolution list 15:43:16 INFO - Available Modes on Display 0 15:43:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:43:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:43:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:43:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:43:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:43:17 INFO - Intel Iris: 15:43:17 INFO - Chipset Model: Intel Iris 15:43:17 INFO - Type: GPU 15:43:17 INFO - Bus: Built-In 15:43:17 INFO - VRAM (Dynamic, Max): 1536 MB 15:43:17 INFO - Vendor: Intel (0x8086) 15:43:17 INFO - Device ID: 0x0a2e 15:43:17 INFO - Revision ID: 0x0009 15:43:17 INFO - Displays: 15:43:17 INFO - Display: 15:43:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:43:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:43:17 INFO - Display Serial Number: 1600x1200 60 15:43:17 INFO - Main Display: Yes 15:43:17 INFO - Mirror: Off 15:43:17 INFO - Online: Yes 15:43:17 INFO - Rotation: Supported 15:43:17 INFO - Return code: 0 15:43:17 INFO - Running post-action listener: _resource_record_post_action 15:43:17 INFO - ##### 15:43:17 INFO - ##### Finished run-tests step (success) 15:43:17 INFO - ##### 15:43:17 INFO - Running post-run listener: _resource_record_post_run 15:43:17 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 921600; Write bytes: 695736832; Read time: 6; Write time: 1885 15:43:17 INFO - TinderboxPrint: CPU usage
53.9% 15:43:17 INFO - TinderboxPrint: I/O read bytes / time
921,600 / 6 15:43:17 INFO - TinderboxPrint: I/O write bytes / time
695,736,832 / 1,885 15:43:17 INFO - TinderboxPrint: CPU idle
2,236.7 (46.1%) 15:43:17 INFO - TinderboxPrint: CPU system
973.1 (20.0%) 15:43:17 INFO - TinderboxPrint: CPU user
1,643.9 (33.9%) 15:43:17 INFO - TinderboxPrint: Swap in / out
396,820,480 / 0 15:43:17 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397017600; Write bytes: 398661120; Read time: 29758; Write time: 803 15:43:17 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 921600; Write bytes: 296227840; Read time: 6; Write time: 1078 15:43:17 INFO - Running post-run listener: _upload_blobber_files 15:43:17 INFO - Blob upload gear active. 15:43:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:43:17 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 15:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:43:17 INFO - (blobuploader) - INFO - Open directory for files ... 15:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:43:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:19 INFO - (blobuploader) - INFO - Done attempting. 15:43:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:43:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:43:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:20 INFO - (blobuploader) - INFO - Done attempting. 15:43:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:43:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:43:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:22 INFO - (blobuploader) - INFO - Done attempting. 15:43:22 INFO - (blobuploader) - INFO - Iteration through files over. 15:43:22 INFO - Return code: 0 15:43:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:43:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:43:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5c57b7d7d06b6fa1eabe1f7327e30bd6a33abfc55edf5fba3ee8cb5693f047444a2d4289a44366742f8bc22df604fc2a0b5b8b927783d2eebc578f19ab7d066", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4553f4fa84691bcaa3f772e990e0fbae355033400479f43c50c983dda09f85637b44c7856756713cfe68e5824bc92707ea9785ae5892869305ab5a3e5071ea11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/047e8425434e478b172d313552f202af5dbea889e57e212b6fecc9be358a02bf47500f5c1773b163aac7d405adddf2777a4c1ebdd3b48090a70022f8f0175762"} 15:43:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:43:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:43:22 INFO - Contents: 15:43:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5c57b7d7d06b6fa1eabe1f7327e30bd6a33abfc55edf5fba3ee8cb5693f047444a2d4289a44366742f8bc22df604fc2a0b5b8b927783d2eebc578f19ab7d066", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4553f4fa84691bcaa3f772e990e0fbae355033400479f43c50c983dda09f85637b44c7856756713cfe68e5824bc92707ea9785ae5892869305ab5a3e5071ea11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/047e8425434e478b172d313552f202af5dbea889e57e212b6fecc9be358a02bf47500f5c1773b163aac7d405adddf2777a4c1ebdd3b48090a70022f8f0175762"} 15:43:22 INFO - Running post-run listener: copy_logs_to_upload_dir 15:43:22 INFO - Copying logs to upload dir... 15:43:22 INFO - mkdir: /builds/slave/test/build/upload/logs 15:43:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.377550 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-23 15:43:22.874304) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 15:43:22.877832) ========= 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.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5c57b7d7d06b6fa1eabe1f7327e30bd6a33abfc55edf5fba3ee8cb5693f047444a2d4289a44366742f8bc22df604fc2a0b5b8b927783d2eebc578f19ab7d066", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4553f4fa84691bcaa3f772e990e0fbae355033400479f43c50c983dda09f85637b44c7856756713cfe68e5824bc92707ea9785ae5892869305ab5a3e5071ea11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/047e8425434e478b172d313552f202af5dbea889e57e212b6fecc9be358a02bf47500f5c1773b163aac7d405adddf2777a4c1ebdd3b48090a70022f8f0175762"} build_url:https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/enXjvDRsQcmnd4G4-udd9Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5c57b7d7d06b6fa1eabe1f7327e30bd6a33abfc55edf5fba3ee8cb5693f047444a2d4289a44366742f8bc22df604fc2a0b5b8b927783d2eebc578f19ab7d066", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4553f4fa84691bcaa3f772e990e0fbae355033400479f43c50c983dda09f85637b44c7856756713cfe68e5824bc92707ea9785ae5892869305ab5a3e5071ea11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/047e8425434e478b172d313552f202af5dbea889e57e212b6fecc9be358a02bf47500f5c1773b163aac7d405adddf2777a4c1ebdd3b48090a70022f8f0175762"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 15:43:22.907572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:43:22.907911) ========= 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.b5tKPQfxms/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.6HwiSwKaqP/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005188 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:43:22.956456) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:43:22.956775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:43:22.957123) ========= ========= Total master_lag: 0.17 =========