builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0308 starttime: 1474121881.52 results: success (0) buildid: 20160917071757 builduid: dbf6a46020f041379ff8cce3b019b986 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.523317) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.523758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.524052) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.580330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.580593) ========= 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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-17 07:18:01-- 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% 59.7M=0s 2016-09-17 07:18:02 (59.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.607220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.209908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.210196) ========= 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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021029 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.258807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:02.259123) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-17 07:18:02,343 truncating revision to first 12 chars 2016-09-17 07:18:02,343 Setting DEBUG logging. 2016-09-17 07:18:02,344 attempt 1/10 2016-09-17 07:18:02,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:18:03,552 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.473827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:03.757678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.758226) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.758803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.759083) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.777667) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-17 07:18:03.778006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:18:03 INFO - MultiFileLogger online at 20160917 07:18:03 in /builds/slave/test 07:18:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:18:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:03 INFO - {'append_to_log': False, 07:18:03 INFO - 'base_work_dir': '/builds/slave/test', 07:18:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:03 INFO - 'branch': 'Mozilla-Aurora', 07:18:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:03 INFO - 'config_files': ('talos/mac_config.py',), 07:18:03 INFO - 'default_actions': ('clobber', 07:18:03 INFO - 'read-buildbot-config', 07:18:03 INFO - 'download-and-extract', 07:18:03 INFO - 'populate-webroot', 07:18:03 INFO - 'create-virtualenv', 07:18:03 INFO - 'install', 07:18:03 INFO - 'run-tests'), 07:18:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:03 INFO - 'download_minidump_stackwalk': True, 07:18:03 INFO - 'download_symbols': 'ondemand', 07:18:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:03 INFO - 'installer_path': 'installer.exe', 07:18:03 INFO - 'log_level': 'info', 07:18:03 INFO - 'log_name': 'talos', 07:18:03 INFO - 'log_to_console': True, 07:18:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:03 INFO - 'opt_config_files': (), 07:18:03 INFO - 'pip_index': False, 07:18:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:03 INFO - 'cmd': ('bash', 07:18:03 INFO - '-c', 07:18:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:03 INFO - 'enabled': True, 07:18:03 INFO - 'halt_on_failure': False, 07:18:03 INFO - 'name': 'check_screen_resolution'},), 07:18:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:03 INFO - 'cmd': ('bash', 07:18:03 INFO - '-c', 07:18:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:03 INFO - 'enabled': True, 07:18:03 INFO - 'halt_on_failure': False, 07:18:03 INFO - 'name': 'check_screen_resolution'},), 07:18:03 INFO - 'run_cmd_checks_enabled': True, 07:18:03 INFO - 'sps_profile': False, 07:18:03 INFO - 'sps_profile_interval': 0, 07:18:03 INFO - 'suite': 'dromaeojs', 07:18:03 INFO - 'system_bits': '32', 07:18:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:03 INFO - 'title': 't-yosemite-r7-0308', 07:18:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:03 INFO - 'use_talos_json': True, 07:18:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:03 INFO - 'work_dir': 'build'} 07:18:03 INFO - [mozharness: 2016-09-17 14:18:03.964876Z] Running clobber step. 07:18:03 INFO - Running pre-action listener: _resource_record_pre_action 07:18:03 INFO - Running main action method: clobber 07:18:03 INFO - rmtree: /builds/slave/test/build 07:18:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:04 INFO - Running post-action listener: _resource_record_post_action 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.938415Z] Finished clobber step (success) 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.938655Z] Running read-buildbot-config step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: read_buildbot_config 07:18:04 INFO - Using buildbot properties: 07:18:04 INFO - { 07:18:04 INFO - "project": "", 07:18:04 INFO - "product": "firefox", 07:18:04 INFO - "script_repo_revision": "production", 07:18:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:04 INFO - "repository": "", 07:18:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:04 INFO - "buildid": "20160917071757", 07:18:04 INFO - "stage_platform": "macosx64", 07:18:04 INFO - "basedir": "/builds/slave/test", 07:18:04 INFO - "buildnumber": 44, 07:18:04 INFO - "platform": "yosemite_r7", 07:18:04 INFO - "mozci_request": { 07:18:04 INFO - "priority": -1, 07:18:04 INFO - "type": "trigger_all_talos_jobs", 07:18:04 INFO - "times": 6 07:18:04 INFO - }, 07:18:04 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:04 INFO - "slavebuilddir": "test", 07:18:04 INFO - "branch": "mozilla-aurora", 07:18:04 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:04 INFO - "slavename": "t-yosemite-r7-0308", 07:18:04 INFO - "builduid": "dbf6a46020f041379ff8cce3b019b986", 07:18:04 INFO - "repo_path": "releases/mozilla-aurora" 07:18:04 INFO - } 07:18:04 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg. 07:18:04 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json. 07:18:04 INFO - Running post-action listener: _resource_record_post_action 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.943859Z] Finished read-buildbot-config step (success) 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.943993Z] Running download-and-extract step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: download_and_extract 07:18:04 INFO - mkdir: /builds/slave/test/build/tests 07:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:04 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json 07:18:04 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #1 07:18:04 INFO - Downloaded 1199 bytes. 07:18:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:04 INFO - Using the following test package requirements: 07:18:04 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:04 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:04 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:04 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'jsshell-mac.zip'], 07:18:04 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:04 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:04 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:04 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:04 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:04 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:04 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:04 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:04 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:04 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:05 INFO - Downloaded 16309409 bytes. 07:18:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:08 INFO - Return code: 0 07:18:08 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip', u'firefox-50.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:08 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:08 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:08 INFO - Downloaded 16309409 bytes. 07:18:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:12 INFO - Return code: 0 07:18: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')]} 07:18:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:18:12 INFO - Downloaded 14413740 bytes. 07:18:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:12 INFO - Return code: 0 07:18: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')]} 07:18:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 07:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #1 07:18:16 INFO - Downloaded 115349660 bytes. 07:18:16 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:16 INFO - mkdir: /builds/slave/test/properties 07:18:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:16 INFO - Contents: 07:18:16 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:16 INFO - Running post-action listener: _resource_record_post_action 07:18:16 INFO - Running post-action listener: set_extra_try_arguments 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.640641Z] Finished download-and-extract step (success) 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.640797Z] Running populate-webroot step. 07:18:16 INFO - Running pre-action listener: _resource_record_pre_action 07:18:16 INFO - Running main action method: populate_webroot 07:18:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:16 INFO - '--tppagecycles', 07:18:16 INFO - '7'], 07:18:16 INFO - 'tests': ['tsvgm']}}, 07:18:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['damp', 'tps']}, 07:18:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['damp', 'tps']}, 07:18:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['dromaeo_dom']}, 07:18:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['basic_compositor_video']}, 07:18:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['a11yr', 07:18:16 INFO - 'ts_paint', 07:18:16 INFO - 'tpaint', 07:18:16 INFO - 'sessionrestore', 07:18:16 INFO - 'sessionrestore_no_auto_restore', 07:18:16 INFO - 'tabpaint']}, 07:18:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tsvgx', 07:18:16 INFO - 'tsvgr_opacity', 07:18:16 INFO - 'tart', 07:18:16 INFO - 'tscrollx', 07:18:16 INFO - 'cart']}, 07:18:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:16 INFO - 'tsvgr_opacity', 07:18:16 INFO - 'tart', 07:18:16 INFO - 'tscrollx', 07:18:16 INFO - 'cart']}, 07:18:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s'], 07:18:16 INFO - 'tests': ['tp5o']}, 07:18:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'tests': ['tp5o']}, 07:18:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--disable-e10s', 07:18:16 INFO - '--xperf_path', 07:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:16 INFO - 'tests': ['tp5n']}, 07:18:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:16 INFO - 'talos_options': ['--xperf_path', 07:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:16 INFO - 'tests': ['tp5n']}}, 07:18:16 INFO - 'talos.zip': {'path': '', 07:18:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:16 INFO - Running post-action listener: _resource_record_post_action 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.659518Z] Finished populate-webroot step (success) 07:18:16 INFO - [mozharness: 2016-09-17 14:18:16.659650Z] Running create-virtualenv step. 07:18:16 INFO - Running pre-action listener: _resource_record_pre_action 07:18:16 INFO - Running main action method: create_virtualenv 07:18:16 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:16 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 07:18:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:16 INFO - Using real prefix '/tools/python27' 07:18:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:20 INFO - Installing pip.................done. 07:18:20 INFO - Return code: 0 07:18:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:20 INFO - 'HOME': '/Users/cltbld', 07:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:20 INFO - 'LOGNAME': 'cltbld', 07:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:20 INFO - 'MOZ_NO_REMOTE': '1', 07:18:20 INFO - 'NO_EM_RESTART': '1', 07:18:20 INFO - 'PAGER': '/bin/cat', 07:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:20 INFO - 'PWD': '/builds/slave/test', 07:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:20 INFO - 'SHELL': '/bin/bash', 07:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:20 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:20 INFO - 'USER': 'cltbld', 07:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:20 INFO - 'XPC_FLAGS': '0x0', 07:18:20 INFO - 'XPC_SERVICE_NAME': '0', 07:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:20 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 07:18:20 INFO - Cleaning up... 07:18:20 INFO - Return code: 0 07:18:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:20 INFO - 'HOME': '/Users/cltbld', 07:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:20 INFO - 'LOGNAME': 'cltbld', 07:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:20 INFO - 'MOZ_NO_REMOTE': '1', 07:18:20 INFO - 'NO_EM_RESTART': '1', 07:18:20 INFO - 'PAGER': '/bin/cat', 07:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:20 INFO - 'PWD': '/builds/slave/test', 07:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:20 INFO - 'SHELL': '/bin/bash', 07:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:20 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:20 INFO - 'USER': 'cltbld', 07:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:20 INFO - 'XPC_FLAGS': '0x0', 07:18:20 INFO - 'XPC_SERVICE_NAME': '0', 07:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:20 INFO - Downloading/unpacking psutil>=3.1.1 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:24 INFO - Installing collected packages: psutil 07:18:24 INFO - Running setup.py install for psutil 07:18:24 INFO - building 'psutil._psutil_osx' extension 07:18:24 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 07:18:25 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 07:18:25 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 07:18:25 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 07:18:25 INFO - building 'psutil._psutil_posix' extension 07:18:25 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 07:18:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:25 INFO - ^ 07:18:25 INFO - 1 warning generated. 07:18:25 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 07:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:25 INFO - Successfully installed psutil 07:18:25 INFO - Cleaning up... 07:18:25 INFO - Return code: 0 07:18:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:25 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')]} 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 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')]} 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:25 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 07:18:25 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 07:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:25 INFO - 'HOME': '/Users/cltbld', 07:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:25 INFO - 'LOGNAME': 'cltbld', 07:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:25 INFO - 'MOZ_NO_REMOTE': '1', 07:18:25 INFO - 'NO_EM_RESTART': '1', 07:18:25 INFO - 'PAGER': '/bin/cat', 07:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:25 INFO - 'PWD': '/builds/slave/test', 07:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:25 INFO - 'SHELL': '/bin/bash', 07:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:25 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:25 INFO - 'USER': 'cltbld', 07:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:25 INFO - 'XPC_FLAGS': '0x0', 07:18:25 INFO - 'XPC_SERVICE_NAME': '0', 07:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:18:28 INFO - Installing collected packages: mozsystemmonitor 07:18:28 INFO - Running setup.py install for mozsystemmonitor 07:18:28 INFO - Successfully installed mozsystemmonitor 07:18:28 INFO - Cleaning up... 07:18:28 INFO - Return code: 0 07:18:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:28 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')]} 07:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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')]} 07:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:28 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==2.5.1'] in /builds/slave/test/build 07:18:28 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==2.5.1 07:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:28 INFO - 'HOME': '/Users/cltbld', 07:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:28 INFO - 'LOGNAME': 'cltbld', 07:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:28 INFO - 'MOZ_NO_REMOTE': '1', 07:18:28 INFO - 'NO_EM_RESTART': '1', 07:18:28 INFO - 'PAGER': '/bin/cat', 07:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:28 INFO - 'PWD': '/builds/slave/test', 07:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:28 INFO - 'SHELL': '/bin/bash', 07:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:28 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:28 INFO - 'USER': 'cltbld', 07:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:28 INFO - 'XPC_FLAGS': '0x0', 07:18:28 INFO - 'XPC_SERVICE_NAME': '0', 07:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:28 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 07:18:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 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 07:18:31 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:31 INFO - warning: no files found matching '*.txt' 07:18:31 INFO - no previously-included directories found matching 'build' 07:18:31 INFO - no previously-included directories found matching 'dist' 07:18:31 INFO - no previously-included directories found matching '.git*' 07:18:31 INFO - Installing collected packages: jsonschema, functools32 07:18:31 INFO - Running setup.py install for functools32 07:18:31 INFO - warning: no files found matching '*.txt' 07:18:31 INFO - no previously-included directories found matching 'build' 07:18:31 INFO - no previously-included directories found matching 'dist' 07:18:31 INFO - no previously-included directories found matching '.git*' 07:18:31 INFO - Successfully installed jsonschema functools32 07:18:31 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18: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 functools32==3.2.3-2 07:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:32 INFO - 'HOME': '/Users/cltbld', 07:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:32 INFO - 'LOGNAME': 'cltbld', 07:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:32 INFO - 'MOZ_NO_REMOTE': '1', 07:18:32 INFO - 'NO_EM_RESTART': '1', 07:18:32 INFO - 'PAGER': '/bin/cat', 07:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:32 INFO - 'PWD': '/builds/slave/test', 07:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:32 INFO - 'SHELL': '/bin/bash', 07:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:32 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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', '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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18: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 blobuploader==1.2.4 07:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:32 INFO - 'HOME': '/Users/cltbld', 07:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:32 INFO - 'LOGNAME': 'cltbld', 07:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:32 INFO - 'MOZ_NO_REMOTE': '1', 07:18:32 INFO - 'NO_EM_RESTART': '1', 07:18:32 INFO - 'PAGER': '/bin/cat', 07:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:32 INFO - 'PWD': '/builds/slave/test', 07:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:32 INFO - 'SHELL': '/bin/bash', 07:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:35 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 07:18:36 INFO - Downloading docopt-0.6.1.tar.gz 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:36 INFO - Running setup.py install for blobuploader 07:18:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:36 INFO - Running setup.py install for requests 07:18:37 INFO - Running setup.py install for docopt 07:18:37 INFO - Successfully installed blobuploader requests docopt 07:18:37 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:37 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 07:18:37 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 07:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-aG7FaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-wR29pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-QvYbdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-eyGpqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-6NARfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ZVeQhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Ea17hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-k5k47a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-fNW2eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-dGbW85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-W9IoMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-SGvP4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-SdPRPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-rJYETx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-GAcdOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:39 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zhk0Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:39 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ujGxhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:39 INFO - Running setup.py install for manifestparser 07:18:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Running setup.py install for mozcrash 07:18:39 INFO - Running setup.py install for mozdebug 07:18:39 INFO - Running setup.py install for mozdevice 07:18:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:39 INFO - Running setup.py install for mozfile 07:18:40 INFO - Running setup.py install for mozhttpd 07:18:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozinfo 07:18:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozInstall 07:18:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozleak 07:18:40 INFO - Running setup.py install for mozlog 07:18:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for moznetwork 07:18:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozprocess 07:18:41 INFO - Running setup.py install for mozprofile 07:18:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozrunner 07:18:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozscreenshot 07:18:41 INFO - Running setup.py install for moztest 07:18:41 INFO - Running setup.py install for mozversion 07:18:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:41 INFO - Cleaning up... 07:18:41 INFO - Return code: 0 07:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:41 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 07:18:41 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 07:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:41 INFO - 'HOME': '/Users/cltbld', 07:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:41 INFO - 'LOGNAME': 'cltbld', 07:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:41 INFO - 'MOZ_NO_REMOTE': '1', 07:18:41 INFO - 'NO_EM_RESTART': '1', 07:18:41 INFO - 'PAGER': '/bin/cat', 07:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:41 INFO - 'PWD': '/builds/slave/test', 07:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:41 INFO - 'SHELL': '/bin/bash', 07:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:41 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:41 INFO - 'USER': 'cltbld', 07:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:41 INFO - 'XPC_FLAGS': '0x0', 07:18:41 INFO - 'XPC_SERVICE_NAME': '0', 07:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-1vmjU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-chnUjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-14uRtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-bhNySM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-8qvnZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-XxDydR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-0uI8HO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KpiMoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 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)) 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-n2GUCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-YsMH3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-GjrsSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zNz5Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-X1n0Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-B8hqTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-IEEjrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-qyk9kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:43 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)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:43 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zJJpb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 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)) 07:18:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:46 INFO - Downloading blessings-1.6.tar.gz 07:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:46 INFO - Installing collected packages: blessings 07:18:46 INFO - Running setup.py install for blessings 07:18:46 INFO - Successfully installed blessings 07:18:46 INFO - Cleaning up... 07:18:46 INFO - Return code: 0 07:18:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Current package versions: 07:18:47 INFO - blessings == 1.6 07:18:47 INFO - blobuploader == 1.2.4 07:18:47 INFO - docopt == 0.6.1 07:18:47 INFO - functools32 == 3.2.3-2 07:18:47 INFO - jsonschema == 2.5.1 07:18:47 INFO - manifestparser == 1.1 07:18:47 INFO - mozInstall == 1.12 07:18:47 INFO - mozcrash == 0.17 07:18:47 INFO - mozdebug == 0.1 07:18:47 INFO - mozdevice == 0.48 07:18:47 INFO - mozfile == 1.2 07:18:47 INFO - mozhttpd == 0.7 07:18:47 INFO - mozinfo == 0.9 07:18:47 INFO - mozleak == 0.1 07:18:47 INFO - mozlog == 3.3 07:18:47 INFO - moznetwork == 0.27 07:18:47 INFO - mozprocess == 0.23 07:18:47 INFO - mozprofile == 0.28 07:18:47 INFO - mozrunner == 6.12 07:18:47 INFO - mozscreenshot == 0.1 07:18:47 INFO - mozsystemmonitor == 0.3 07:18:47 INFO - moztest == 0.7 07:18:47 INFO - mozversion == 1.4 07:18:47 INFO - psutil == 3.1.1 07:18:47 INFO - requests == 1.2.3 07:18:47 INFO - wsgiref == 0.1.2 07:18:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:47 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')]} 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 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')]} 07:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:47 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 07:18:47 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 07:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:47 INFO - 'HOME': '/Users/cltbld', 07:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:47 INFO - 'LOGNAME': 'cltbld', 07:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:47 INFO - 'MOZ_NO_REMOTE': '1', 07:18:47 INFO - 'NO_EM_RESTART': '1', 07:18:47 INFO - 'PAGER': '/bin/cat', 07:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:47 INFO - 'PWD': '/builds/slave/test', 07:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:47 INFO - 'SHELL': '/bin/bash', 07:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:47 INFO - 'USER': 'cltbld', 07:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:47 INFO - 'XPC_FLAGS': '0x0', 07:18:47 INFO - 'XPC_SERVICE_NAME': '0', 07:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 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)) 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:47 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')]} 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 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')]} 07:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 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 0x10b9d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd08d79320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, '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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:47 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 07:18:47 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 07:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:47 INFO - 'HOME': '/Users/cltbld', 07:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:47 INFO - 'LOGNAME': 'cltbld', 07:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:47 INFO - 'MOZ_NO_REMOTE': '1', 07:18:47 INFO - 'NO_EM_RESTART': '1', 07:18:47 INFO - 'PAGER': '/bin/cat', 07:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:47 INFO - 'PWD': '/builds/slave/test', 07:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:47 INFO - 'SHELL': '/bin/bash', 07:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:47 INFO - 'USER': 'cltbld', 07:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:47 INFO - 'XPC_FLAGS': '0x0', 07:18:47 INFO - 'XPC_SERVICE_NAME': '0', 07:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Running post-action listener: _resource_record_post_action 07:18:47 INFO - Running post-action listener: _start_resource_monitoring 07:18:47 INFO - Starting resource monitoring. 07:18:47 INFO - [mozharness: 2016-09-17 14:18:47.639098Z] Finished create-virtualenv step (success) 07:18:47 INFO - [mozharness: 2016-09-17 14:18:47.640030Z] Running install step. 07:18:47 INFO - Running pre-action listener: _resource_record_pre_action 07:18:47 INFO - Running main action method: install 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Output received: 07:18:47 INFO - Usage: mozinstall [options] installer 07:18:47 INFO - Options: 07:18:47 INFO - -h, --help show this help message and exit 07:18:47 INFO - -d DEST, --destination=DEST 07:18:47 INFO - Directory to install application into. [default: 07:18:47 INFO - "/builds/slave/test"] 07:18:47 INFO - --app=APP Application being installed. [default: firefox] 07:18:47 INFO - mkdir: /builds/slave/test/build/application 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:19 INFO - Reading from file tmpfile_stdout 07:19:19 INFO - Output received: 07:19:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:19 INFO - Running post-action listener: _resource_record_post_action 07:19:19 INFO - [mozharness: 2016-09-17 14:19:19.963406Z] Finished install step (success) 07:19:19 INFO - [mozharness: 2016-09-17 14:19:19.963533Z] Running run-tests step. 07:19:19 INFO - Running pre-action listener: _resource_record_pre_action 07:19:19 INFO - Running main action method: run_tests 07:19:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:19 INFO - 2016-09-17 07:19:19.977 screenresolution[2044:19004] starting screenresolution argv=screenresolution get 07:19:19 INFO - 2016-09-17 07:19:19.980 screenresolution[2044:19004] Display 0: 1600x1200x32@60 07:19:19 INFO - 2016-09-17 07:19:19.986 screenresolution[2045:19006] starting screenresolution argv=screenresolution list 07:19:19 INFO - Available Modes on Display 0 07:19:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:20 INFO - Intel Iris: 07:19:20 INFO - Chipset Model: Intel Iris 07:19:20 INFO - Type: GPU 07:19:20 INFO - Bus: Built-In 07:19:20 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:20 INFO - Vendor: Intel (0x8086) 07:19:20 INFO - Device ID: 0x0a2e 07:19:20 INFO - Revision ID: 0x0009 07:19:20 INFO - Displays: 07:19:20 INFO - Display: 07:19:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:20 INFO - Display Serial Number: 1600x1200 60 07:19:20 INFO - Main Display: Yes 07:19:20 INFO - Mirror: Off 07:19:20 INFO - Online: Yes 07:19:20 INFO - Rotation: Supported 07:19:20 INFO - Return code: 0 07:19:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:20 INFO - Python 2.7.3 07:19:20 INFO - Return code: 0 07:19:20 INFO - grabbing minidump binary from tooltool 07:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:20 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 0x10b934d30>, '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 0x10b699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b92cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:20 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 07:19:20 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 07:19:20 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 07:19:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:20 INFO - Return code: 0 07:19:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0308 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Pmn9KB8Oi/Render', 07:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:20 INFO - 'HOME': '/Users/cltbld', 07:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:20 INFO - 'LOGNAME': 'cltbld', 07:19:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:20 INFO - 'MOZ_NO_REMOTE': '1', 07:19:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:20 INFO - 'NO_EM_RESTART': '1', 07:19:20 INFO - 'PAGER': '/bin/cat', 07:19:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:20 INFO - 'PWD': '/builds/slave/test', 07:19:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:20 INFO - 'SHELL': '/bin/bash', 07:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3Nd8RX2leZ/Listeners', 07:19:20 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 07:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:20 INFO - 'USER': 'cltbld', 07:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:20 INFO - 'XPC_FLAGS': '0x0', 07:19:20 INFO - 'XPC_SERVICE_NAME': '0', 07:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:19:20 INFO - mozversion application_buildid: 20160916162818 07:19:20 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:20 INFO - mozversion application_name: Firefox 07:19:20 INFO - mozversion application_remotingname: firefox-dev 07:19:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:20 INFO - mozversion application_vendor: Mozilla 07:19:20 INFO - mozversion application_version: 50.0a2 07:19:20 INFO - mozversion platform_buildid: 20160916162818 07:19:20 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:20 INFO - mozversion platform_version: 50.0a2 07:19:20 INFO - using testdate: 1474121960 07:19:20 INFO - actual date: 1474121960 07:19:20 INFO - starting webserver on 'localhost:49714' 07:19:20 INFO - SUITE-START | Running 2 tests 07:19:20 INFO - TEST-START | dromaeo_css 07:19:20 INFO - operating with platform_type : mac_ 07:19:20 INFO - Initialising browser for dromaeo_css test... 07:19:20 INFO - TEST-INFO | started process 2055 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp4xYtSp/profile http://localhost:49714/getInfo.html) 07:19:22 INFO - PROCESS | 2055 | __metrics Screen width/height:1600/1200 07:19:22 INFO - PROCESS | 2055 | colorDepth:24 07:19:22 INFO - PROCESS | 2055 | Browser inner width/height: 1024/689 07:19:22 INFO - PROCESS | 2055 | __metrics 07:19:22 INFO - PROCESS | 2055 | 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" 07:19:22 INFO - PROCESS | 2055 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:23 INFO - TEST-INFO | 2055: exit 0 07:19:23 INFO - Browser initialized. 07:19:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:23 INFO - TEST-INFO | started process 2058 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp4xYtSp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:35 INFO - PROCESS | 2058 | RSS: Main: 215457792 07:19:35 INFO - PROCESS | 2058 | 07:20:27 INFO - PROCESS | 2058 | 1474122027139 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] 07:22:37 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49714/tests/dromaeo/cssquery-ext.html) 07:22:39 INFO - PROCESS | 2058 | RSS: Main: 339763200 07:22:39 INFO - PROCESS | 2058 | 07:25:41 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-ext.html (next: http://localhost:49714/tests/dromaeo/cssquery-jquery.html) 07:25:42 INFO - PROCESS | 2058 | RSS: Main: 359436288 07:25:42 INFO - PROCESS | 2058 | 07:28:44 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49714/tests/dromaeo/cssquery-mootools.html) 07:28:45 INFO - PROCESS | 2058 | RSS: Main: 354865152 07:28:45 INFO - PROCESS | 2058 | 07:31:47 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49714/tests/dromaeo/cssquery-prototype.html) 07:31:48 INFO - PROCESS | 2058 | RSS: Main: 328556544 07:31:48 INFO - PROCESS | 2058 | 07:34:51 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49714/tests/dromaeo/cssquery-yui.html) 07:34:52 INFO - PROCESS | 2058 | RSS: Main: 326332416 07:34:52 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:49714/tests/dromaeo/cssquery-yui.html (next: http://localhost:49714/tests/dromaeo/cssquery-dojo.html) 07:37:55 INFO - PROCESS | 2058 | RSS: Main: 364474368 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | __start_tp_report 07:37:55 INFO - PROCESS | 2058 | _x_x_mozilla_page_load 07:37:55 INFO - PROCESS | 2058 | _x_x_mozilla_page_load_details 07:37:55 INFO - PROCESS | 2058 | |i|pagename|runs| 07:37:55 INFO - PROCESS | 2058 | |0;dojo.html;2785.4289870576536;3253.4469140246156;3253.7234335081516;3320.1533608929717;3379.1383197284704;5739.397363276853;5839.591228613998;5840.06558950568;5856.853578660535;5875.3830847760955;5814.622049566786;5900.852478688035;5902.616329938551;5911.704414779266;5914.556408269375;5177.482251774812;5245.344331958505;5254.553362964152;5277.815276465324;5381.946180538203;5315.813946511873;5337.119375303089;5350.892982140354;5359.008583412081;5361.490658387447;5258.684478931257;5281.419043905167;5318.149096144618;5330.200469929503;5340.225667278242;5369.650972686783;5372.006178856885;5380.4350543192795;5386.811461598864;5425.945740542584;5100.464451232604;5158.226266060084;5219.791208351661;5294.894102117974;5299.072662284085;5685.8009969651275;5700.059490184115;5716.828495145152;5725.856853578653;5797.072468405077;6207.099970504251;6211.378862113797;6218.502519798405;6225.595336303171;6232.750689972396;6119.204503414572;6177.474914632262;6227.283862355796;6294.937050629526;6297.937020629781;6005.189299444519;6011.098335249748;6022.6988650566855;6037.275526936795;6046.939530604682;6404.487640988803;6410.166678331787;6427.742890284429;6430.131932189094;6444.355564443612;6101.237345331833;6122.969385153135;6131.499004853578;6151.84620384494;6192.164057852222;6277.591956522902;6283.905741413882;6289.40250676185;6289.7484100635465;6314.589551679218;6178.351273116348;6222.968885155546;6233.937660623426;6235;6251.187345645037;5121.795128194905;6133.754649813957;6134.4978165938865;6176.722047507873;6228.439440450381;5018.5232402921665;5032.446430892599;5057.544820966131;5065.6200784941275;5073.416557095908;3763.34141525232;3817.541894972621;4531.977340113344;4576.58810707038;4602.424696912886;5932.673702946379;5932.8813423732145;5951.345352011275;5955.404459554096;5974.522038237017;6147.047207682816;6175;6236.594621349688;6261.906071408933;6286.591371560834;5852.707364631751;5916.053431450947;5943.762249510058;5948;5953.880922381615;5518.91721624176;5536.307961504812;5538.390777014525;5543.307086614173;5544.889102218014;6186.628802271878;6277.1525844010475;6371.426571608577;6397.168368112116;6440.838979025468;5190.97404512975;5216.686998780086;6115.514161902999;6284.088807123033;6285.685715714288;6320.399562041599;6322.841928951629;6325.335839736761;6328.6519241442165;6344.71448784815;6122.6938653068055;6168.1056246844855;6188.164597779242;6200.503959683305;6215.720292586935;6142.600730952474;6147.262328520606;6191.783287584913;6200.844978875564;6222.595531290452;6236.064590311489;6246.500279977682;6250.093736408105;6250.874982500415;6267.2479302484;6029.698515074317;6233.875322493434;6243.406876346739;6246.250449946036;6248.4688801452385;6027.78902738402;6037.18498002764;6097.725602347817;6110.908336375047;6181.134641150153;5649.096144616841;5730.283714535683;6236.220472440945;6261.96869015652;6267.2792628848265;5877.059670452707;5906.202662640489;6111.388861113853;6111.969440152949;6115.724792384264 07:37:55 INFO - PROCESS | 2058 | |1;ext.html;479.64506265363633;489.7012822178473;513.7559659161899;516.8113638521941;528.6775067209002;30174.396512069747;31317.530237046463;35258.29483410337;43389;43669.56330436691;44897.551024489745;45277.773611131895;45341.7732911335;45375.31937020939;45451;9733.464659443727;9929.056739609761;9935.453550054744;9948.900510994907;9960.950195249014;8786.86819697705;8965.641374345018;10200.438975856325;11871.584494542692;12131.393430328493;12075.27548347102;12149.331786751725;12255.938720306385;12262.325572093532;12267.754644907096;11785.587504437304;12100.031997440183;12170.391480426031;12177;12191.268523888551;12008.759824803456;12100.334481603508;12124.454399552042;12129.514819407214;12143.453544590515;42208.15583688309;42508.937276568024;42556.57443425588;42564.57435425668;42677.35983960243;3487.285106553176;3552.3960926642535;3598.8380522876273;3648.5074514940407;3981.402789581557;26836.59745103825;27768.750406231567;27925.58111628307;30791.230219244473;32407.351852963046;3057.159281197666;3057.2356910772305;3242.9837850810595;3277.5411442398085;3281.2125089978235;542623;544179;544642;544898;545737.999999996;50941;61317.69341153266;61751;61841.45396365037;61884.690576546825;64883;65102;65150;65164.02253966194;65307.67346163334;17258.568535786704;17483.912580437016;17516.386926457766;17527.211275492635;17544.385946491813;12230.327331996648;12368.257904525757;13441.663958400923;13553.390097445441;13593.456261749618;15857.969231999883;16014.359425623077;16111.436099736688;16639.33442662304;16936.661266774594;6276.659665013092;6344.460720838781;6444.484441244689;6496.805095847132;6502.934970650233;857.5840717252128;931.5063016601213;975.2782940623887;1007.2646967713464;1101.1796211822243;31375.529367059513;31826.522602160985;31971.52042719361;32207.838960805046;32494.350112997607;727.65072765072;1071.8231491803765;1076.7738774857212;1084.1001968366238;1160.2980196980873;15024.474143405147;15542.766858497132;15549.533513994598;15726.370945162065;15727.05294863667;672645;715626;717642;717731;718170;59173;59362.10956835651;59558.10662840061;59563.70218148968;59591;1266.860645329013;1396.4763213794827;1415.445121555602;1440.863118003809;1445.4507287230786;30891;30941;31705.04884853458;31862.362752745277;32312;52658.473415265355;53246.4675353257;53317.733411334244;53356;53357.19964200618;571496;574665;574823;575340;577050;26667.7333226673;26733.86633066822;26765.598516022666;27647;28119.87520499157;49475.01049979052;49897.25154122763;50194.749026254634;50390.24414633857;50528.74735626298;40118;40905.59094409018;42381.36427953645;43142.56857431385;43150.568494315914;40728.33444989965;41158.765237042935;41274.58725412827;42730;42830.78584607057;39143.02142446348;39498.40752388774;40294;40518.18963620652;40578.39132413075;34431.827840860635;36196.81901590475;36372.78743422047;36634.8168259157;36964.63035369612;22361.329160125224;26197.607035894856;26664.733352667;26840;27161 07:37:55 INFO - PROCESS | 2058 | |2;jquery.html;316.7735069425362;348.1644054269754;364.58437381385227;375.2363939383355;395.40688966550175;16035.679286414264;17356.218970146358;18057.638847223046;21214.681779773277;21330.253441129524;19979.500512487197;20443.897780511114;20555.075021624027;20709.68935465969;20946.371608851758;4710.858674239761;5224.373075230968;5470.480304371078;5475.945240547603;5483.670979741209;3917.2361389529055;4110.219058378913;6086.513078953673;6110.9694451527685;6175.845603859917;5882.911756323659;6147.754089836401;6165.167702360169;6184.319724830288;6185.226846644169;4868.196747536652;5392.948375066863;5798.449147330997;6178.382161783808;6180.505559555225;5872.530197584183;5874.706264686792;5878.206442130343;5883.735231914531;5886.882262354772;12458.688532786662;12649.747005059939;12755.61733148007;12941.805872911913;13199.472021119143;2149.0544160569298;2262.366537369539;2372.9169479068323;2389.593769059264;2411.879406029709;9938.503074846301;10069.043440873178;10152.441615711134;12967.740645187043;13874.098183618033;1771.8682303727599;1773.4147731248656;1791.973120403195;1806.3136008316756;1816.300724221171;115169;119538.8046119545;121673.99999999911;123113;127009;8893.377463577488;10738.140948724084;11027.235642297193;11155.219134660658;11270.661880143609;13666.52167174144;13671.17972921628;13686.726265474636;13695.972802039887;13696.589102326747;8163.836723265502;8356.080831108573;8429.114942931026;8476.990668312783;8526.275266602408;6383.457406120532;8488.830223395498;8489.702860399857;8640.135986401436;8672.436291641023;4279.422277992493;4374.737515749065;4393.29707246839;4440.444944381952;4472.575105364985;1472.8968972172058;1641.7537369394447;1838.1911958738112;1844.769403824522;1865.9813401865808;774.9263819937097;807.9676812927418;904.2088172848757;981.906718861707;984.6602921991902;20827.479313017295;21360.57278854414;21784;21819.890900545397;21829.781702182776;907.6097278170319;922.9123233292827;941.7457286532734;963.9228861691188;994.1649014827435;9398.624055037722;10732;11183.220185223947;11335.886641133484;11345.602903898489;557995;561742.191289041;598615;617403;617493;140236.99999999796;140538;142410;144477;146433;945.4232917920064;947.1002547579827;960.8846938367301;961.5048250151178;961.6297725375781;17368.305267789652;17912.731309030114;18337.908310458897;18380;18414.447566573024;56481.71759141178;57055.42944570667;57808.4219157803;58575.121373180285;59379.406205939114;63510.68244658932;81318.18681813106;84359.57820210859;84891;84980.72528912195;7567.9243207567215;7680.961595191989;7850.764477065697;8020.759377218693;8056.315213206943;8927.776805579913;11550.94224528872;11867.109966752356;11985.28088314704;12539.059570532429;10487.26589138753;10515.57937682484;10754.946225268823;10954.123670106532;11193.72015699614;9255.444673319622;9320.487373194539;9852.359596626196;10046.799064018533;10967.067799237155;9295.628174872929;10320.948395258276;10544.630937917136;10905.945470272598;10923.781524369628;6965.33829286217;7217.494775365674;7283.271672832674;7710.498817576839;7989.48068375577;10755.071412242902;11208.27146235492;11305.208635395444;11313.547458101584;11446.599369022044 07:37:55 INFO - PROCESS | 2058 | |3;mootools.html;1549.3182999480227;1588.7458006718925;1784.27817156444;1887.537553299442;1939.3793985924501;8642.827143457127;9114.13415725506;9139.543022848864;9141.588628511716;9144.679936202234;8034.276915077642;8242.876356854653;8675.479471231714;8894.066123057082;8894.91105088949;2259.412552736288;2280.532490839374;2292.266474728088;2294.89672964717;2302.182725132579;2277.339571524256;2313.2135074074895;2315.5021670340875;2316.073570571776;2321.918732844351;2087.2485905074163;2142.8928553572337;2326.1393284484766;2376.4177776444794;2382.7378988311357;2320.814334853216;2323.4772176260376;2330.394097534653;2339.157903154863;2354.9764502355015;2348.0607756897207;2354.682117914077;2359.1153317505937;2359.398353419867;2363.102021231921;8952.507612081334;9008.549572521413;9031.638734450615;9032.14194651507;9046.276297896218;5192.844214673566;5308;5381.434949330303;5389.110796718537;5400.162974738882;3409.6931276185264;3424.880129195491;3556.3776339140804;3574.6246644102516;3594.622564630702;987.4568987057162;988.3229987458614;1008.1682611845257;1022.049493970607;1042.9791404171874;12007.759844803142;12134.817977730341;12172.939135304356;12189.756204875854;12208.511659533697;3671.908202294964;4523.231050721385;4680.15757163707;4708.058388322349;4715.717056976592;10056.849147262797;10154.746131346776;10169.593216271414;10173.491325433699;10176.135028522658;5334.67991920486;5339.546138578186;5345.3852806926925;5361.195820626937;5362.758675859595;4766.142094422968;4879.219324907997;4901.313816065754;4915.139850526144;4916.967436838232;3908.355121404936;3910.413437984325;3932.7640341579595;3964.742291751027;4047.6357127858046;1143.8112711402691;1224.7856625090574;1231.94456249469;1236.5239382837672;1248.4694005047818;778.2217782217782;912.8174365127002;919.6275508419101;954.6944977607099;963.3978763272954;5835.620684655484;5880.882382352414;5907.940920590825;5922.407759224129;6076.5442591805795;943.3538026451765;944.6835310171032;952.4713783850029;966.7486453522135;989.0307498651218;3699.7975657910747;3957.22834047358;3975.860844870416;4018.477597912253;4092.1815636872743;10492.947535262274;11011.504482298316;11084.667459976214;11099;11122.054625356936;11037.558497660164;11043.39261340634;11050;11068.446577671084;11069.778604427866;6435.292117867031;6602.570832895846;8424.705135320357;8503.277221436247;8514.531700756394;5892.292924849045;5911.91132133027;5964.761409543569;6092.055731361646;6149.508039356931;10021.649242276486;10090.8486372703;10167.084962353423;10244.078032977068;10254.436006019741;10104.94947525287;10412.062914337746;10415.895841041493;10537.578496860346;10609.310394824312;7704.614769261627;7885.580595492637;7890.68437262526;7898.170692077249;7905.051393832777;8260.793480163044;8422.494650321001;8484.40609157353;8498.872516912375;8574;7934.166912474338;7965.203479651988;7988.081370642451;8000.359971202173;8102.797430064295;7721.305082542598;7763.922360776546;7873.960630196812;7902;7907.920920790949;6890.758823441155;7062.329078737512;7064.32888875556;7076.787696369117;7085;5061.94991028634;5105.387353517602;5205.45342739007;5206.817761378334;5226.099514769339;4506.526814684411;5064.34163558735;5081.263216833612;5179.792808287626;5374.113271310346 07:37:55 INFO - PROCESS | 2058 | |4;prototype.html;259.23654836506483;296.0747663551402;297.49722968183767;304.56903481573573;308.93975674743433;4616.222754239617;5269.68381897086;5281.471852814717;5385.946140538594;5436.592255580837;4284.892877678052;5008.574271186944;5040.269160971657;5293.867653308669;5395.460453954603;3057.449659061368;3438.6905178533925;4060.2691515527194;4069.776162311072;4120.855770048049;3967.7222594418404;4158.625723684868;4160.667146628262;4160.667146628262;4170.353595192735;3391.9491207631904;3554.382755846084;3588.802615856127;3810.9420912707105;4080.3879418087377;4069.104796944667;4086.693497987663;4097.938530922009;4125.773082480478;4158.272302347093;3148.8425578721203;3458.3429148461964;3546.6453354664586;3569.6430356964356;3593.6226696196786;4597.793099310539;4854.514548545118;5131.692098474066;5192.584593232532;5196.8960620787375;4597.632189424871;4631.4673812511655;4646.35157959963;4659.32439796231;4711.929321060187;5513.862153446155;5537.972310138463;5564.857529850996;5583.609147359686;5601;3299.5545601343993;3531.5408996830247;3534.9116272093147;3893.240818040483;3901.1807520420743;4401.679496151141;5653;5746.310442746897;5778.0466223073145;5860.945029894618;3674.5669189016303;3720.4932002539017;3974.7813870236837;3982.183652351261;3988.461557689675;4047;4058.553559108496;4060.39094135882;4066.959330406658;4086.6322031017353;3573.6962358199326;3966.643002129822;4085.9172319334807;4619.769011549409;4645.605123564535;5553.4724201200825;5620.91568626471;5639.774409023676;5651.361105279423;5672.993313543829;1262.958059600833;1344.327836081959;1381.412899517701;1385.0650810702734;1386.2167875150508;823.5840900345407;905.366243629462;908.9909100909039;929.121979729163;948.127722495306;5180.533751962342;5199.280791129727;5204.959008198375;5224.268602395668;5235.895282094337;4867.70793752376;4943.431505376856;5041.899162016813;5515.200295957064;5778.133280008033;4589.380433641482;4837.346958160673;5096.388433387943;5112.054269960069;5166.9224961625605;2510.9748902511105;2920.445115428104;2994.77539184562;2996;3018.4566777979735;4649.418822647169;5522.861928451741;5838.737256823453;5869.970650146722;5874.471297583153;5719.399463056402;5756.42435756421;5776.277965254343;5779;5857.7949771757785;5110.904529143034;5622.353429355677;5797.4782269595935;5802.390748971382;5830.609450125428;5382.165764306539;5409.861455986095;5576.498115169654;5631;5642;5034.546890779848;5465.453454654503;5547.805826796042;5550.58370622213;5553.778168803018;4401.295792673157;4902.264660300983;5431.83704488866;5842.743810080853;5855.745537967828;3365.848536815898;3846.8461261549223;3884.4173373994126;3916.334223182009;3930.8600505854124;4728.219843725747;5485.862853428696;5590.496855282881;5630.410011394804;5665.405132461032;5039.319691841554;5328.873694544324;5331.227761563888;5486.561075113901;5505.366882808528;5192.805654699365;5322.361316642027;5388.602072077888;5413.621046526863;5422.6076877729165;4537.387452693844;4930.852074437629;5262.89474210506;5342.75957581902;5373.570114390917;4485.730856148642;4934.925976110433;5190.117679994334;5392.919106213331;5479.232907393048;4706.55259265187;5135.870108576108;5478.972954878241;5549.7780088796;5584.022796010763 07:37:55 INFO - PROCESS | 2058 | |5;yui.html;731.641495667123;750.332204338139;792.5007245435374;872.4765140915451;873.6156091319823;15386.692266154685;15897;16536.255868485903;16541.917290413545;16917.238724257408;14921.104733715985;15300.617484562892;15366.23168841559;15401.76897346538;15412.07527548345;705.6224919667978;728.6283995162453;866.7529754020121;873.4759144513273;891.1534042659499;887.8579427291635;897.5736525150534;898.6809682562695;898.7483504618684;899.0784445942928;961.2742379503466;961.5817119552981;962.7063745557602;965.4400447740329;968.6319198704517;740.9777706668808;763.1757701682226;766.6051983228642;952.2572393533055;953.8903026151977;565.8557067947647;681.7750142452978;691.9827004324882;703.736098962889;703.8944158376233;15348.616284592863;15414.922925385414;15421.228938553028;15653.686926261411;15694.372225110981;14260.500882469063;14516.564503064925;14551.272436378245;14581.781273280909;14660;10799.460026998619;12935.741285174245;13538.932305338509;13584.524541640994;13611.890801210648;4616.12326821132;4638.67529272951;4724.984128412408;4775.450823155347;4801.831935882227;114463;116702;119618;121663;124263.13605295928;9079.769210543278;11216.517304550116;11343;11553.768924621461;11644.592439264585;23316.417089572897;23416.53166936652;23441.648375274384;23451.41371465693;23455.648165277533;7595.202503737139;7605.619719014027;7762.417818663623;7924.128345881948;7942.324902383362;3514.5958214805114;4021.8738753149164;4331.220380331571;4360.193364227628;4423.955760442419;3575;3716.21959388526;3717.3494638438165;3717.442383642421;3723.385641369198;590.9940900590939;673.723773252964;675.3449154320301;675.5642610516288;677.1975209376905;517.5600739371579;614.852435415506;620.8665136995477;627.1611298808111;688.132952479869;9128.3153763468;9742.853857192147;9781.364211326241;9795.951020244853;9999.700008999742;571.948524632785;611.3458599298797;694.3160986428364;697.4664381747865;710.1726488640779;4525.615322697608;4602.378678878308;4712.198926182557;4756.833510827105;4828.444728856227;94004.22807622515;162522.3747762531;165476;170864;177546;137229;138815;140386;141342.2932885329;141481;117147;122437;123943;124287;128190.35904820416;8904.465732056098;9208.355415120877;9243.2143267823;9249.860123091905;9408.576614052246;62657;63068;64242;64895.67552162209;66638.00042999456;68857.24571017103;84897.57551212204;95608.0439195627;97130;99676;11788.29270243788;12023.21849079807;12038.097142714156;12195.817062744243;12456.626301210978;12503.312317822607;12834;13264.535741249396;13761.31193440304;14100.506482273071;12184;12316.199447035913;12524.373781310716;12561.685957851127;12959.287239201558;12569.297601015825;12877.678058048623;12885.935570322088;13322.041497496468;13524.053316267768;12854.421551030038;13170.80243796363;13638.727225455634;14427.855721443071;15114.09315441077;10572.84140737905;11383.943080284545;12075.27548347102;12163.635090947286;12178.208416452902;9820.4107753535;11342.60300889465;11757.529698812294;11889.524419023142;11977.580784672495 07:37:55 INFO - PROCESS | 2058 | __end_tp_report 07:37:55 INFO - PROCESS | 2058 | __start_cc_report 07:37:55 INFO - PROCESS | 2058 | _x_x_mozilla_cycle_collect,1223 07:37:55 INFO - PROCESS | 2058 | __end_cc_report 07:37:55 INFO - PROCESS | 2058 | __startTimestamp1474123075600__endTimestamp 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | ------- Summary: start ------- 07:37:55 INFO - PROCESS | 2058 | Number of tests: 6 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#0] dojo.html Cycles:165 Average:5789.13 Median:6037.18 stddev:671.17 (11.1%) stddev-sans-first:630.52 07:37:55 INFO - PROCESS | 2058 | Values: 2785.4 3253.4 3253.7 3320.2 3379.1 5739.4 5839.6 5840.1 5856.9 5875.4 5814.6 5900.9 5902.6 5911.7 5914.6 5177.5 5245.3 5254.6 5277.8 5381.9 5315.8 5337.1 5350.9 5359.0 5361.5 5258.7 5281.4 5318.1 5330.2 5340.2 5369.7 5372.0 5380.4 5386.8 5425.9 5100.5 5158.2 5219.8 5294.9 5299.1 5685.8 5700.1 5716.8 5725.9 5797.1 6207.1 6211.4 6218.5 6225.6 6232.8 6119.2 6177.5 6227.3 6294.9 6297.9 6005.2 6011.1 6022.7 6037.3 6046.9 6404.5 6410.2 6427.7 6430.1 6444.4 6101.2 6123.0 6131.5 6151.8 6192.2 6277.6 6283.9 6289.4 6289.7 6314.6 6178.4 6223.0 6233.9 6235.0 6251.2 5121.8 6133.8 6134.5 6176.7 6228.4 5018.5 5032.4 5057.5 5065.6 5073.4 3763.3 3817.5 4532.0 4576.6 4602.4 5932.7 5932.9 5951.3 5955.4 5974.5 6147.0 6175.0 6236.6 6261.9 6286.6 5852.7 5916.1 5943.8 5948.0 5953.9 5518.9 5536.3 5538.4 5543.3 5544.9 6186.6 6277.2 6371.4 6397.2 6440.8 5191.0 5216.7 6115.5 6284.1 6285.7 6320.4 6322.8 6325.3 6328.7 6344.7 6122.7 6168.1 6188.2 6200.5 6215.7 6142.6 6147.3 6191.8 6200.8 6222.6 6236.1 6246.5 6250.1 6250.9 6267.2 6029.7 6233.9 6243.4 6246.3 6248.5 6027.8 6037.2 6097.7 6110.9 6181.1 5649.1 5730.3 6236.2 6262.0 6267.3 5877.1 5906.2 6111.4 6112.0 6115.7 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#1] ext.html Cycles:180 Average:74473.70 Median:38053.83 stddev:164043.34 (431.1%) stddev-sans-first:164409.45 07:37:55 INFO - PROCESS | 2058 | Values: 479.6 489.7 513.8 516.8 528.7 30174.4 31317.5 35258.3 43389.0 43669.6 44897.6 45277.8 45341.8 45375.3 45451.0 9733.5 9929.1 9935.5 9948.9 9961.0 8786.9 8965.6 10200.4 11871.6 12131.4 12075.3 12149.3 12255.9 12262.3 12267.8 11785.6 12100.0 12170.4 12177.0 12191.3 12008.8 12100.3 12124.5 12129.5 12143.5 42208.2 42508.9 42556.6 42564.6 42677.4 3487.3 3552.4 3598.8 3648.5 3981.4 26836.6 27768.8 27925.6 30791.2 32407.4 3057.2 3057.2 3243.0 3277.5 3281.2 542623.0 544179.0 544642.0 544898.0 545738.0 50941.0 61317.7 61751.0 61841.5 61884.7 64883.0 65102.0 65150.0 65164.0 65307.7 17258.6 17483.9 17516.4 17527.2 17544.4 12230.3 12368.3 13441.7 13553.4 13593.5 15858.0 16014.4 16111.4 16639.3 16936.7 6276.7 6344.5 6444.5 6496.8 6502.9 857.6 931.5 975.3 1007.3 1101.2 31375.5 31826.5 31971.5 32207.8 32494.4 727.7 1071.8 1076.8 1084.1 1160.3 15024.5 15542.8 15549.5 15726.4 15727.1 672645.0 715626.0 717642.0 717731.0 718170.0 59173.0 59362.1 59558.1 59563.7 59591.0 1266.9 1396.5 1415.4 1440.9 1445.5 30891.0 30941.0 31705.0 31862.4 32312.0 52658.5 53246.5 53317.7 53356.0 53357.2 571496.0 574665.0 574823.0 575340.0 577050.0 26667.7 26733.9 26765.6 27647.0 28119.9 49475.0 49897.3 50194.7 50390.2 50528.7 40118.0 40905.6 42381.4 43142.6 43150.6 40728.3 41158.8 41274.6 42730.0 42830.8 39143.0 39498.4 40294.0 40518.2 40578.4 34431.8 36196.8 36372.8 36634.8 36964.6 22361.3 26197.6 26664.7 26840.0 27161.0 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#2] jquery.html Cycles:180 Average:34937.00 Median:2146.24 stddev:99417.06 (4632.2%) stddev-sans-first:99661.97 07:37:55 INFO - PROCESS | 2058 | Values: 316.8 348.2 364.6 375.2 395.4 16035.7 17356.2 18057.6 21214.7 21330.3 19979.5 20443.9 20555.1 20709.7 20946.4 4710.9 5224.4 5470.5 5475.9 5483.7 3917.2 4110.2 6086.5 6111.0 6175.8 5882.9 6147.8 6165.2 6184.3 6185.2 4868.2 5392.9 5798.4 6178.4 6180.5 5872.5 5874.7 5878.2 5883.7 5886.9 12458.7 12649.7 12755.6 12941.8 13199.5 2149.1 2262.4 2372.9 2389.6 2411.9 9938.5 10069.0 10152.4 12967.7 13874.1 1771.9 1773.4 1792.0 1806.3 1816.3 115169.0 119538.8 121674.0 123113.0 127009.0 8893.4 10738.1 11027.2 11155.2 11270.7 13666.5 13671.2 13686.7 13696.0 13696.6 8163.8 8356.1 8429.1 8477.0 8526.3 6383.5 8488.8 8489.7 8640.1 8672.4 4279.4 4374.7 4393.3 4440.4 4472.6 1472.9 1641.8 1838.2 1844.8 1866.0 774.9 808.0 904.2 981.9 984.7 20827.5 21360.6 21784.0 21819.9 21829.8 907.6 922.9 941.7 963.9 994.2 9398.6 10732.0 11183.2 11335.9 11345.6 557995.0 561742.2 598615.0 617403.0 617493.0 140237.0 140538.0 142410.0 144477.0 146433.0 945.4 947.1 960.9 961.5 961.6 17368.3 17912.7 18337.9 18380.0 18414.4 56481.7 57055.4 57808.4 58575.1 59379.4 63510.7 81318.2 84359.6 84891.0 84980.7 7567.9 7681.0 7850.8 8020.8 8056.3 8927.8 11550.9 11867.1 11985.3 12539.1 10487.3 10515.6 10754.9 10954.1 11193.7 9255.4 9320.5 9852.4 10046.8 10967.1 9295.6 10320.9 10544.6 10905.9 10923.8 6965.3 7217.5 7283.3 7710.5 7989.5 10755.1 11208.3 11305.2 11313.5 11446.6 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#3] mootools.html Cycles:180 Average:5813.77 Median:4890.27 stddev:3316.40 (67.8%) stddev-sans-first:3310.22 07:37:55 INFO - PROCESS | 2058 | Values: 1549.3 1588.7 1784.3 1887.5 1939.4 8642.8 9114.1 9139.5 9141.6 9144.7 8034.3 8242.9 8675.5 8894.1 8894.9 2259.4 2280.5 2292.3 2294.9 2302.2 2277.3 2313.2 2315.5 2316.1 2321.9 2087.2 2142.9 2326.1 2376.4 2382.7 2320.8 2323.5 2330.4 2339.2 2355.0 2348.1 2354.7 2359.1 2359.4 2363.1 8952.5 9008.5 9031.6 9032.1 9046.3 5192.8 5308.0 5381.4 5389.1 5400.2 3409.7 3424.9 3556.4 3574.6 3594.6 987.5 988.3 1008.2 1022.0 1043.0 12007.8 12134.8 12172.9 12189.8 12208.5 3671.9 4523.2 4680.2 4708.1 4715.7 10056.8 10154.7 10169.6 10173.5 10176.1 5334.7 5339.5 5345.4 5361.2 5362.8 4766.1 4879.2 4901.3 4915.1 4917.0 3908.4 3910.4 3932.8 3964.7 4047.6 1143.8 1224.8 1231.9 1236.5 1248.5 778.2 912.8 919.6 954.7 963.4 5835.6 5880.9 5907.9 5922.4 6076.5 943.4 944.7 952.5 966.7 989.0 3699.8 3957.2 3975.9 4018.5 4092.2 10492.9 11011.5 11084.7 11099.0 11122.1 11037.6 11043.4 11050.0 11068.4 11069.8 6435.3 6602.6 8424.7 8503.3 8514.5 5892.3 5911.9 5964.8 6092.1 6149.5 10021.6 10090.8 10167.1 10244.1 10254.4 10104.9 10412.1 10415.9 10537.6 10609.3 7704.6 7885.6 7890.7 7898.2 7905.1 8260.8 8422.5 8484.4 8498.9 8574.0 7934.2 7965.2 7988.1 8000.4 8102.8 7721.3 7763.9 7874.0 7902.0 7907.9 6890.8 7062.3 7064.3 7076.8 7085.0 5061.9 5105.4 5205.5 5206.8 5226.1 4506.5 5064.3 5081.3 5179.8 5374.1 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#4] prototype.html Cycles:180 Average:4466.91 Median:5039.79 stddev:1357.55 (26.9%) stddev-sans-first:1324.11 07:37:55 INFO - PROCESS | 2058 | Values: 259.2 296.1 297.5 304.6 308.9 4616.2 5269.7 5281.5 5385.9 5436.6 4284.9 5008.6 5040.3 5293.9 5395.5 3057.4 3438.7 4060.3 4069.8 4120.9 3967.7 4158.6 4160.7 4160.7 4170.4 3391.9 3554.4 3588.8 3810.9 4080.4 4069.1 4086.7 4097.9 4125.8 4158.3 3148.8 3458.3 3546.6 3569.6 3593.6 4597.8 4854.5 5131.7 5192.6 5196.9 4597.6 4631.5 4646.4 4659.3 4711.9 5513.9 5538.0 5564.9 5583.6 5601.0 3299.6 3531.5 3534.9 3893.2 3901.2 4401.7 5653.0 5746.3 5778.0 5860.9 3674.6 3720.5 3974.8 3982.2 3988.5 4047.0 4058.6 4060.4 4067.0 4086.6 3573.7 3966.6 4085.9 4619.8 4645.6 5553.5 5620.9 5639.8 5651.4 5673.0 1263.0 1344.3 1381.4 1385.1 1386.2 823.6 905.4 909.0 929.1 948.1 5180.5 5199.3 5205.0 5224.3 5235.9 4867.7 4943.4 5041.9 5515.2 5778.1 4589.4 4837.3 5096.4 5112.1 5166.9 2511.0 2920.4 2994.8 2996.0 3018.5 4649.4 5522.9 5838.7 5870.0 5874.5 5719.4 5756.4 5776.3 5779.0 5857.8 5110.9 5622.4 5797.5 5802.4 5830.6 5382.2 5409.9 5576.5 5631.0 5642.0 5034.5 5465.5 5547.8 5550.6 5553.8 4401.3 4902.3 5431.8 5842.7 5855.7 3365.8 3846.8 3884.4 3916.3 3930.9 4728.2 5485.9 5590.5 5630.4 5665.4 5039.3 5328.9 5331.2 5486.6 5505.4 5192.8 5322.4 5388.6 5413.6 5422.6 4537.4 4930.9 5262.9 5342.8 5373.6 4485.7 4934.9 5190.1 5392.9 5479.2 4706.6 5135.9 5479.0 5549.8 5584.0 07:37:55 INFO - PROCESS | 2058 | 07:37:55 INFO - PROCESS | 2058 | [#5] yui.html Cycles:180 Average:26040.08 Median:3717.40 stddev:42514.59 (1143.7%) stddev-sans-first:42591.39 07:37:55 INFO - PROCESS | 2058 | Values: 731.6 750.3 792.5 872.5 873.6 15386.7 15897.0 16536.3 16541.9 16917.2 14921.1 15300.6 15366.2 15401.8 15412.1 705.6 728.6 866.8 873.5 891.2 887.9 897.6 898.7 898.7 899.1 961.3 961.6 962.7 965.4 968.6 741.0 763.2 766.6 952.3 953.9 565.9 681.8 692.0 703.7 703.9 15348.6 15414.9 15421.2 15653.7 15694.4 14260.5 14516.6 14551.3 14581.8 14660.0 10799.5 12935.7 13538.9 13584.5 13611.9 4616.1 4638.7 4725.0 4775.5 4801.8 114463.0 116702.0 119618.0 121663.0 124263.1 9079.8 11216.5 11343.0 11553.8 11644.6 23316.4 23416.5 23441.6 23451.4 23455.6 7595.2 7605.6 7762.4 7924.1 7942.3 3514.6 4021.9 4331.2 4360.2 4424.0 3575.0 3716.2 3717.3 3717.4 3723.4 591.0 673.7 675.3 675.6 677.2 517.6 614.9 620.9 627.2 688.1 9128.3 9742.9 9781.4 9796.0 9999.7 571.9 611.3 694.3 697.5 710.2 4525.6 4602.4 4712.2 4756.8 4828.4 94004.2 162522.4 165476.0 170864.0 177546.0 137229.0 138815.0 140386.0 141342.3 141481.0 117147.0 122437.0 123943.0 124287.0 128190.4 8904.5 9208.4 9243.2 9249.9 9408.6 62657.0 63068.0 64242.0 64895.7 66638.0 68857.2 84897.6 95608.0 97130.0 99676.0 11788.3 12023.2 12038.1 12195.8 12456.6 12503.3 12834.0 13264.5 13761.3 14100.5 12184.0 12316.2 12524.4 12561.7 12959.3 12569.3 12877.7 12885.9 13322.0 13524.1 12854.4 13170.8 13638.7 14427.9 15114.1 10572.8 11383.9 12075.3 12163.6 12178.2 9820.4 11342.6 11757.5 11889.5 11977.6 07:37:55 INFO - PROCESS | 2058 | -------- Summary: end -------- 07:37:55 INFO - PROCESS | 2058 | 07:37:56 INFO - TEST-INFO | 2058: exit 0 07:37:56 INFO - TEST-OK | dromaeo_css | took 1115665ms 07:37:56 INFO - TEST-START | kraken 07:37:56 INFO - operating with platform_type : mac_ 07:37:56 INFO - Initialising browser for kraken test... 07:37:56 INFO - TEST-INFO | started process 2061 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpSwUndz/profile http://localhost:49714/getInfo.html) 07:37:58 INFO - PROCESS | 2061 | __metrics Screen width/height:1600/1200 07:37:58 INFO - PROCESS | 2061 | colorDepth:24 07:37:58 INFO - PROCESS | 2061 | Browser inner width/height: 1024/689 07:37:58 INFO - PROCESS | 2061 | __metrics 07:37:58 INFO - PROCESS | 2061 | 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" 07:37:58 INFO - PROCESS | 2061 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:59 INFO - TEST-INFO | 2061: exit 0 07:37:59 INFO - Browser initialized. 07:37:59 INFO - Running cycle 1/1 for kraken test... 07:37:59 INFO - TEST-INFO | started process 2062 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpSwUndz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:11 INFO - PROCESS | 2062 | RSS: Main: 215801856 07:38:11 INFO - PROCESS | 2062 | 07:38:13 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:14 INFO - PROCESS | 2062 | RSS: Main: 279334912 07:38:14 INFO - PROCESS | 2062 | 07:38:17 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-dft) 07:38:17 INFO - PROCESS | 2062 | RSS: Main: 282607616 07:38:17 INFO - PROCESS | 2062 | 07:38:21 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-fft) 07:38:21 INFO - PROCESS | 2062 | RSS: Main: 379023360 07:38:21 INFO - PROCESS | 2062 | 07:38:23 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49714/tests/kraken/driver.html?testName=audio-oscillator) 07:38:24 INFO - PROCESS | 2062 | RSS: Main: 284987392 07:38:24 INFO - PROCESS | 2062 | 07:38:26 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:27 INFO - PROCESS | 2062 | RSS: Main: 285364224 07:38:27 INFO - PROCESS | 2062 | 07:38:30 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:30 INFO - PROCESS | 2062 | RSS: Main: 323252224 07:38:30 INFO - PROCESS | 2062 | 07:38:33 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49714/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:34 INFO - PROCESS | 2062 | RSS: Main: 323735552 07:38:34 INFO - PROCESS | 2062 | 07:38:37 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49714/tests/kraken/driver.html?testName=json-parse-financial) 07:38:37 INFO - PROCESS | 2062 | RSS: Main: 323178496 07:38:37 INFO - PROCESS | 2062 | 07:38:39 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49714/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:40 INFO - PROCESS | 2062 | RSS: Main: 298258432 07:38:40 INFO - PROCESS | 2062 | 07:38:42 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:42 INFO - PROCESS | 2062 | RSS: Main: 302804992 07:38:42 INFO - PROCESS | 2062 | 07:38:44 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:45 INFO - PROCESS | 2062 | RSS: Main: 309837824 07:38:45 INFO - PROCESS | 2062 | 07:38:48 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:49 INFO - PROCESS | 2062 | RSS: Main: 323014656 07:38:49 INFO - PROCESS | 2062 | 07:38:52 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:52 INFO - PROCESS | 2062 | RSS: Main: 319201280 07:38:52 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49714/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49714/tests/kraken/driver.html?testName=ai-astar) 07:38:54 INFO - PROCESS | 2062 | RSS: Main: 362246144 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | __start_tp_report 07:38:54 INFO - PROCESS | 2062 | _x_x_mozilla_page_load 07:38:54 INFO - PROCESS | 2062 | _x_x_mozilla_page_load_details 07:38:54 INFO - PROCESS | 2062 | |i|pagename|runs| 07:38:54 INFO - PROCESS | 2062 | |0;ai-astar;115;116;127;115;113;115;114;116;115;114 07:38:54 INFO - PROCESS | 2062 | |1;audio-beat-detection;145;108;130;156;109;127;106;136;145;105 07:38:54 INFO - PROCESS | 2062 | |2;audio-dft;185;184;185;183;187;183;183;197;263;204 07:38:54 INFO - PROCESS | 2062 | |3;audio-fft;73;79;77;164;56;59;73;89;170;51 07:38:54 INFO - PROCESS | 2062 | |4;audio-oscillator;84;85;137;71;71;86;154;100;100;121 07:38:54 INFO - PROCESS | 2062 | |5;imaging-gaussian-blur;89;87;74;109;134;107;90;85;87;84 07:38:54 INFO - PROCESS | 2062 | |6;imaging-darkroom;93;93;92;92;95;92;93;92;119;93 07:38:54 INFO - PROCESS | 2062 | |7;imaging-desaturate;84;86;85;97;130;133;81;85;85;84 07:38:54 INFO - PROCESS | 2062 | |8;json-parse-financial;58;57;58;59;56;56;58;56;56;55 07:38:54 INFO - PROCESS | 2062 | |9;json-stringify-tinderbox;65;69;66;76;71;77;72;72;71;70 07:38:54 INFO - PROCESS | 2062 | |10;stanford-crypto-aes;85;82;82;81;81;83;87;89;88;87 07:38:54 INFO - PROCESS | 2062 | |11;stanford-crypto-ccm;137;137;151;129;136;144;152;138;178;138 07:38:54 INFO - PROCESS | 2062 | |12;stanford-crypto-pbkdf2;161;163;160;159;160;161;163;179;222;145 07:38:54 INFO - PROCESS | 2062 | |13;stanford-crypto-sha256-iterative;67;65;67;69;85;82;65;68;65;66 07:38:54 INFO - PROCESS | 2062 | __end_tp_report 07:38:54 INFO - PROCESS | 2062 | __start_cc_report 07:38:54 INFO - PROCESS | 2062 | _x_x_mozilla_cycle_collect,2310 07:38:54 INFO - PROCESS | 2062 | __end_cc_report 07:38:54 INFO - PROCESS | 2062 | __startTimestamp1474123134710__endTimestamp 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | ------- Summary: start ------- 07:38:54 INFO - PROCESS | 2062 | Number of tests: 14 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#0] ai-astar Cycles:10 Average:116.00 Median:115.00 stddev:3.97 (3.5%) stddev-sans-first:4.20 07:38:54 INFO - PROCESS | 2062 | Values: 115.0 116.0 127.0 115.0 113.0 115.0 114.0 116.0 115.0 114.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:133.00 stddev:18.82 (14.2%) stddev-sans-first:18.76 07:38:54 INFO - PROCESS | 2062 | Values: 145.0 108.0 130.0 156.0 109.0 127.0 106.0 136.0 145.0 105.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#2] audio-dft Cycles:10 Average:195.40 Median:186.00 stddev:24.78 (13.3%) stddev-sans-first:25.99 07:38:54 INFO - PROCESS | 2062 | Values: 185.0 184.0 185.0 183.0 187.0 183.0 183.0 197.0 263.0 204.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#3] audio-fft Cycles:10 Average:89.10 Median:73.00 stddev:42.65 (58.4%) stddev-sans-first:44.84 07:38:54 INFO - PROCESS | 2062 | Values: 73.0 79.0 77.0 164.0 56.0 59.0 73.0 89.0 170.0 51.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:71.00 stddev:28.04 (39.5%) stddev-sans-first:29.07 07:38:54 INFO - PROCESS | 2062 | Values: 84.0 85.0 137.0 71.0 71.0 86.0 154.0 100.0 100.0 121.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.00 stddev:17.35 (20.2%) stddev-sans-first:18.29 07:38:54 INFO - PROCESS | 2062 | Values: 89.0 87.0 74.0 109.0 134.0 107.0 90.0 85.0 87.0 84.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:93.00 stddev:8.34 (9.0%) stddev-sans-first:8.80 07:38:54 INFO - PROCESS | 2062 | Values: 93.0 93.0 92.0 92.0 95.0 92.0 93.0 92.0 119.0 93.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:85.00 stddev:19.70 (23.2%) stddev-sans-first:20.49 07:38:54 INFO - PROCESS | 2062 | Values: 84.0 86.0 85.0 97.0 130.0 133.0 81.0 85.0 85.0 84.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.50 stddev:1.29 (2.2%) stddev-sans-first:1.30 07:38:54 INFO - PROCESS | 2062 | Values: 58.0 57.0 58.0 59.0 56.0 56.0 58.0 56.0 56.0 55.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:3.78 (5.3%) stddev-sans-first:3.36 07:38:54 INFO - PROCESS | 2062 | Values: 65.0 69.0 66.0 76.0 71.0 77.0 72.0 72.0 71.0 70.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:86.00 stddev:3.06 (3.6%) stddev-sans-first:3.24 07:38:54 INFO - PROCESS | 2062 | Values: 85.0 82.0 82.0 81.0 81.0 83.0 87.0 89.0 88.0 87.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:141.00 stddev:13.86 (9.8%) stddev-sans-first:14.46 07:38:54 INFO - PROCESS | 2062 | Values: 137.0 137.0 151.0 129.0 136.0 144.0 152.0 138.0 178.0 138.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:162.00 stddev:20.86 (12.9%) stddev-sans-first:22.01 07:38:54 INFO - PROCESS | 2062 | Values: 161.0 163.0 160.0 159.0 160.0 161.0 163.0 179.0 222.0 145.0 07:38:54 INFO - PROCESS | 2062 | 07:38:54 INFO - PROCESS | 2062 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.50 stddev:7.32 (10.9%) stddev-sans-first:7.69 07:38:54 INFO - PROCESS | 2062 | Values: 67.0 65.0 67.0 69.0 85.0 82.0 65.0 68.0 65.0 66.0 07:38:54 INFO - PROCESS | 2062 | -------- Summary: end -------- 07:38:54 INFO - PROCESS | 2062 | 07:38:55 INFO - TEST-INFO | 2062: exit 0 07:38:55 INFO - TEST-OK | kraken | took 59375ms 07:38:55 INFO - SUITE-END | took 1175s 07:38:55 INFO - Completed test suite (00:19:35) 07:38:55 INFO - javascript benchmark 07:38:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2785.4289870576536, 3253.4469140246156, 3253.7234335081516, 3320.1533608929717, 3379.1383197284704, 5739.397363276853, 5839.591228613998, 5840.06558950568, 5856.853578660535, 5875.3830847760955, 5814.622049566786, 5900.852478688035, 5902.616329938551, 5911.704414779266, 5914.556408269375, 5177.482251774812, 5245.344331958505, 5254.553362964152, 5277.815276465324, 5381.946180538203, 5315.813946511873, 5337.119375303089, 5350.892982140354, 5359.008583412081, 5361.490658387447, 5258.684478931257, 5281.419043905167, 5318.149096144618, 5330.200469929503, 5340.225667278242, 5369.650972686783, 5372.006178856885, 5380.4350543192795, 5386.811461598864, 5425.945740542584, 5100.464451232604, 5158.226266060084, 5219.791208351661, 5294.894102117974, 5299.072662284085, 5685.8009969651275, 5700.059490184115, 5716.828495145152, 5725.856853578653, 5797.072468405077, 6207.099970504251, 6211.378862113797, 6218.502519798405, 6225.595336303171, 6232.750689972396, 6119.204503414572, 6177.474914632262, 6227.283862355796, 6294.937050629526, 6297.937020629781, 6005.189299444519, 6011.098335249748, 6022.6988650566855, 6037.275526936795, 6046.939530604682, 6404.487640988803, 6410.166678331787, 6427.742890284429, 6430.131932189094, 6444.355564443612, 6101.237345331833, 6122.969385153135, 6131.499004853578, 6151.84620384494, 6192.164057852222, 6277.591956522902, 6283.905741413882, 6289.40250676185, 6289.7484100635465, 6314.589551679218, 6178.351273116348, 6222.968885155546, 6233.937660623426, 6235.0, 6251.187345645037, 5121.795128194905, 6133.754649813957, 6134.4978165938865, 6176.722047507873, 6228.439440450381, 5018.5232402921665, 5032.446430892599, 5057.544820966131, 5065.6200784941275, 5073.416557095908, 3763.34141525232, 3817.541894972621, 4531.977340113344, 4576.58810707038, 4602.424696912886, 5932.673702946379, 5932.8813423732145, 5951.345352011275, 5955.404459554096, 5974.522038237017, 6147.047207682816, 6175.0, 6236.594621349688, 6261.906071408933, 6286.591371560834, 5852.707364631751, 5916.053431450947, 5943.762249510058, 5948.0, 5953.880922381615, 5518.91721624176, 5536.307961504812, 5538.390777014525, 5543.307086614173, 5544.889102218014, 6186.628802271878, 6277.1525844010475, 6371.426571608577, 6397.168368112116, 6440.838979025468, 5190.97404512975, 5216.686998780086, 6115.514161902999, 6284.088807123033, 6285.685715714288, 6320.399562041599, 6322.841928951629, 6325.335839736761, 6328.6519241442165, 6344.71448784815, 6122.6938653068055, 6168.1056246844855, 6188.164597779242, 6200.503959683305, 6215.720292586935, 6142.600730952474, 6147.262328520606, 6191.783287584913, 6200.844978875564, 6222.595531290452, 6236.064590311489, 6246.500279977682, 6250.093736408105, 6250.874982500415, 6267.2479302484, 6029.698515074317, 6233.875322493434, 6243.406876346739, 6246.250449946036, 6248.4688801452385, 6027.78902738402, 6037.18498002764, 6097.725602347817, 6110.908336375047, 6181.134641150153, 5649.096144616841, 5730.283714535683, 6236.220472440945, 6261.96869015652, 6267.2792628848265, 5877.059670452707, 5906.202662640489, 6111.388861113853, 6111.969440152949, 6115.724792384264], "value": 5743.3580572539995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.64506265363633, 489.7012822178473, 513.7559659161899, 516.8113638521941, 528.6775067209002, 30174.396512069747, 31317.530237046463, 35258.29483410337, 43389.0, 43669.56330436691, 44897.551024489745, 45277.773611131895, 45341.7732911335, 45375.31937020939, 45451.0, 9733.464659443727, 9929.056739609761, 9935.453550054744, 9948.900510994907, 9960.950195249014, 8786.86819697705, 8965.641374345018, 10200.438975856325, 11871.584494542692, 12131.393430328493, 12075.27548347102, 12149.331786751725, 12255.938720306385, 12262.325572093532, 12267.754644907096, 11785.587504437304, 12100.031997440183, 12170.391480426031, 12177.0, 12191.268523888551, 12008.759824803456, 12100.334481603508, 12124.454399552042, 12129.514819407214, 12143.453544590515, 42208.15583688309, 42508.937276568024, 42556.57443425588, 42564.57435425668, 42677.35983960243, 3487.285106553176, 3552.3960926642535, 3598.8380522876273, 3648.5074514940407, 3981.402789581557, 26836.59745103825, 27768.750406231567, 27925.58111628307, 30791.230219244473, 32407.351852963046, 3057.159281197666, 3057.2356910772305, 3242.9837850810595, 3277.5411442398085, 3281.2125089978235, 542623.0, 544179.0, 544642.0, 544898.0, 545737.999999996, 50941.0, 61317.69341153266, 61751.0, 61841.45396365037, 61884.690576546825, 64883.0, 65102.0, 65150.0, 65164.02253966194, 65307.67346163334, 17258.568535786704, 17483.912580437016, 17516.386926457766, 17527.211275492635, 17544.385946491813, 12230.327331996648, 12368.257904525757, 13441.663958400923, 13553.390097445441, 13593.456261749618, 15857.969231999883, 16014.359425623077, 16111.436099736688, 16639.33442662304, 16936.661266774594, 6276.659665013092, 6344.460720838781, 6444.484441244689, 6496.805095847132, 6502.934970650233, 857.5840717252128, 931.5063016601213, 975.2782940623887, 1007.2646967713464, 1101.1796211822243, 31375.529367059513, 31826.522602160985, 31971.52042719361, 32207.838960805046, 32494.350112997607, 727.65072765072, 1071.8231491803765, 1076.7738774857212, 1084.1001968366238, 1160.2980196980873, 15024.474143405147, 15542.766858497132, 15549.533513994598, 15726.370945162065, 15727.05294863667, 672645.0, 715626.0, 717642.0, 717731.0, 718170.0, 59173.0, 59362.10956835651, 59558.10662840061, 59563.70218148968, 59591.0, 1266.860645329013, 1396.4763213794827, 1415.445121555602, 1440.863118003809, 1445.4507287230786, 30891.0, 30941.0, 31705.04884853458, 31862.362752745277, 32312.0, 52658.473415265355, 53246.4675353257, 53317.733411334244, 53356.0, 53357.19964200618, 571496.0, 574665.0, 574823.0, 575340.0, 577050.0, 26667.7333226673, 26733.86633066822, 26765.598516022666, 27647.0, 28119.87520499157, 49475.01049979052, 49897.25154122763, 50194.749026254634, 50390.24414633857, 50528.74735626298, 40118.0, 40905.59094409018, 42381.36427953645, 43142.56857431385, 43150.568494315914, 40728.33444989965, 41158.765237042935, 41274.58725412827, 42730.0, 42830.78584607057, 39143.02142446348, 39498.40752388774, 40294.0, 40518.18963620652, 40578.39132413075, 34431.827840860635, 36196.81901590475, 36372.78743422047, 36634.8168259157, 36964.63035369612, 22361.329160125224, 26197.607035894856, 26664.733352667, 26840.0, 27161.0], "value": 20796.960183757157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.7735069425362, 348.1644054269754, 364.58437381385227, 375.2363939383355, 395.40688966550175, 16035.679286414264, 17356.218970146358, 18057.638847223046, 21214.681779773277, 21330.253441129524, 19979.500512487197, 20443.897780511114, 20555.075021624027, 20709.68935465969, 20946.371608851758, 4710.858674239761, 5224.373075230968, 5470.480304371078, 5475.945240547603, 5483.670979741209, 3917.2361389529055, 4110.219058378913, 6086.513078953673, 6110.9694451527685, 6175.845603859917, 5882.911756323659, 6147.754089836401, 6165.167702360169, 6184.319724830288, 6185.226846644169, 4868.196747536652, 5392.948375066863, 5798.449147330997, 6178.382161783808, 6180.505559555225, 5872.530197584183, 5874.706264686792, 5878.206442130343, 5883.735231914531, 5886.882262354772, 12458.688532786662, 12649.747005059939, 12755.61733148007, 12941.805872911913, 13199.472021119143, 2149.0544160569298, 2262.366537369539, 2372.9169479068323, 2389.593769059264, 2411.879406029709, 9938.503074846301, 10069.043440873178, 10152.441615711134, 12967.740645187043, 13874.098183618033, 1771.8682303727599, 1773.4147731248656, 1791.973120403195, 1806.3136008316756, 1816.300724221171, 115169.0, 119538.8046119545, 121673.99999999911, 123113.0, 127009.0, 8893.377463577488, 10738.140948724084, 11027.235642297193, 11155.219134660658, 11270.661880143609, 13666.52167174144, 13671.17972921628, 13686.726265474636, 13695.972802039887, 13696.589102326747, 8163.836723265502, 8356.080831108573, 8429.114942931026, 8476.990668312783, 8526.275266602408, 6383.457406120532, 8488.830223395498, 8489.702860399857, 8640.135986401436, 8672.436291641023, 4279.422277992493, 4374.737515749065, 4393.29707246839, 4440.444944381952, 4472.575105364985, 1472.8968972172058, 1641.7537369394447, 1838.1911958738112, 1844.769403824522, 1865.9813401865808, 774.9263819937097, 807.9676812927418, 904.2088172848757, 981.906718861707, 984.6602921991902, 20827.479313017295, 21360.57278854414, 21784.0, 21819.890900545397, 21829.781702182776, 907.6097278170319, 922.9123233292827, 941.7457286532734, 963.9228861691188, 994.1649014827435, 9398.624055037722, 10732.0, 11183.220185223947, 11335.886641133484, 11345.602903898489, 557995.0, 561742.191289041, 598615.0, 617403.0, 617493.0, 140236.99999999796, 140538.0, 142410.0, 144477.0, 146433.0, 945.4232917920064, 947.1002547579827, 960.8846938367301, 961.5048250151178, 961.6297725375781, 17368.305267789652, 17912.731309030114, 18337.908310458897, 18380.0, 18414.447566573024, 56481.71759141178, 57055.42944570667, 57808.4219157803, 58575.121373180285, 59379.406205939114, 63510.68244658932, 81318.18681813106, 84359.57820210859, 84891.0, 84980.72528912195, 7567.9243207567215, 7680.961595191989, 7850.764477065697, 8020.759377218693, 8056.315213206943, 8927.776805579913, 11550.94224528872, 11867.109966752356, 11985.28088314704, 12539.059570532429, 10487.26589138753, 10515.57937682484, 10754.946225268823, 10954.123670106532, 11193.72015699614, 9255.444673319622, 9320.487373194539, 9852.359596626196, 10046.799064018533, 10967.067799237155, 9295.628174872929, 10320.948395258276, 10544.630937917136, 10905.945470272598, 10923.781524369628, 6965.33829286217, 7217.494775365674, 7283.271672832674, 7710.498817576839, 7989.48068375577, 10755.071412242902, 11208.27146235492, 11305.208635395444, 11313.547458101584, 11446.599369022044], "value": 9190.287085040578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1549.3182999480227, 1588.7458006718925, 1784.27817156444, 1887.537553299442, 1939.3793985924501, 8642.827143457127, 9114.13415725506, 9139.543022848864, 9141.588628511716, 9144.679936202234, 8034.276915077642, 8242.876356854653, 8675.479471231714, 8894.066123057082, 8894.91105088949, 2259.412552736288, 2280.532490839374, 2292.266474728088, 2294.89672964717, 2302.182725132579, 2277.339571524256, 2313.2135074074895, 2315.5021670340875, 2316.073570571776, 2321.918732844351, 2087.2485905074163, 2142.8928553572337, 2326.1393284484766, 2376.4177776444794, 2382.7378988311357, 2320.814334853216, 2323.4772176260376, 2330.394097534653, 2339.157903154863, 2354.9764502355015, 2348.0607756897207, 2354.682117914077, 2359.1153317505937, 2359.398353419867, 2363.102021231921, 8952.507612081334, 9008.549572521413, 9031.638734450615, 9032.14194651507, 9046.276297896218, 5192.844214673566, 5308.0, 5381.434949330303, 5389.110796718537, 5400.162974738882, 3409.6931276185264, 3424.880129195491, 3556.3776339140804, 3574.6246644102516, 3594.622564630702, 987.4568987057162, 988.3229987458614, 1008.1682611845257, 1022.049493970607, 1042.9791404171874, 12007.759844803142, 12134.817977730341, 12172.939135304356, 12189.756204875854, 12208.511659533697, 3671.908202294964, 4523.231050721385, 4680.15757163707, 4708.058388322349, 4715.717056976592, 10056.849147262797, 10154.746131346776, 10169.593216271414, 10173.491325433699, 10176.135028522658, 5334.67991920486, 5339.546138578186, 5345.3852806926925, 5361.195820626937, 5362.758675859595, 4766.142094422968, 4879.219324907997, 4901.313816065754, 4915.139850526144, 4916.967436838232, 3908.355121404936, 3910.413437984325, 3932.7640341579595, 3964.742291751027, 4047.6357127858046, 1143.8112711402691, 1224.7856625090574, 1231.94456249469, 1236.5239382837672, 1248.4694005047818, 778.2217782217782, 912.8174365127002, 919.6275508419101, 954.6944977607099, 963.3978763272954, 5835.620684655484, 5880.882382352414, 5907.940920590825, 5922.407759224129, 6076.5442591805795, 943.3538026451765, 944.6835310171032, 952.4713783850029, 966.7486453522135, 989.0307498651218, 3699.7975657910747, 3957.22834047358, 3975.860844870416, 4018.477597912253, 4092.1815636872743, 10492.947535262274, 11011.504482298316, 11084.667459976214, 11099.0, 11122.054625356936, 11037.558497660164, 11043.39261340634, 11050.0, 11068.446577671084, 11069.778604427866, 6435.292117867031, 6602.570832895846, 8424.705135320357, 8503.277221436247, 8514.531700756394, 5892.292924849045, 5911.91132133027, 5964.761409543569, 6092.055731361646, 6149.508039356931, 10021.649242276486, 10090.8486372703, 10167.084962353423, 10244.078032977068, 10254.436006019741, 10104.94947525287, 10412.062914337746, 10415.895841041493, 10537.578496860346, 10609.310394824312, 7704.614769261627, 7885.580595492637, 7890.68437262526, 7898.170692077249, 7905.051393832777, 8260.793480163044, 8422.494650321001, 8484.40609157353, 8498.872516912375, 8574.0, 7934.166912474338, 7965.203479651988, 7988.081370642451, 8000.359971202173, 8102.797430064295, 7721.305082542598, 7763.922360776546, 7873.960630196812, 7902.0, 7907.920920790949, 6890.758823441155, 7062.329078737512, 7064.32888875556, 7076.787696369117, 7085.0, 5061.94991028634, 5105.387353517602, 5205.45342739007, 5206.817761378334, 5226.099514769339, 4506.526814684411, 5064.34163558735, 5081.263216833612, 5179.792808287626, 5374.113271310346], "value": 4636.234244591431, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.23654836506483, 296.0747663551402, 297.49722968183767, 304.56903481573573, 308.93975674743433, 4616.222754239617, 5269.68381897086, 5281.471852814717, 5385.946140538594, 5436.592255580837, 4284.892877678052, 5008.574271186944, 5040.269160971657, 5293.867653308669, 5395.460453954603, 3057.449659061368, 3438.6905178533925, 4060.2691515527194, 4069.776162311072, 4120.855770048049, 3967.7222594418404, 4158.625723684868, 4160.667146628262, 4160.667146628262, 4170.353595192735, 3391.9491207631904, 3554.382755846084, 3588.802615856127, 3810.9420912707105, 4080.3879418087377, 4069.104796944667, 4086.693497987663, 4097.938530922009, 4125.773082480478, 4158.272302347093, 3148.8425578721203, 3458.3429148461964, 3546.6453354664586, 3569.6430356964356, 3593.6226696196786, 4597.793099310539, 4854.514548545118, 5131.692098474066, 5192.584593232532, 5196.8960620787375, 4597.632189424871, 4631.4673812511655, 4646.35157959963, 4659.32439796231, 4711.929321060187, 5513.862153446155, 5537.972310138463, 5564.857529850996, 5583.609147359686, 5601.0, 3299.5545601343993, 3531.5408996830247, 3534.9116272093147, 3893.240818040483, 3901.1807520420743, 4401.679496151141, 5653.0, 5746.310442746897, 5778.0466223073145, 5860.945029894618, 3674.5669189016303, 3720.4932002539017, 3974.7813870236837, 3982.183652351261, 3988.461557689675, 4047.0, 4058.553559108496, 4060.39094135882, 4066.959330406658, 4086.6322031017353, 3573.6962358199326, 3966.643002129822, 4085.9172319334807, 4619.769011549409, 4645.605123564535, 5553.4724201200825, 5620.91568626471, 5639.774409023676, 5651.361105279423, 5672.993313543829, 1262.958059600833, 1344.327836081959, 1381.412899517701, 1385.0650810702734, 1386.2167875150508, 823.5840900345407, 905.366243629462, 908.9909100909039, 929.121979729163, 948.127722495306, 5180.533751962342, 5199.280791129727, 5204.959008198375, 5224.268602395668, 5235.895282094337, 4867.70793752376, 4943.431505376856, 5041.899162016813, 5515.200295957064, 5778.133280008033, 4589.380433641482, 4837.346958160673, 5096.388433387943, 5112.054269960069, 5166.9224961625605, 2510.9748902511105, 2920.445115428104, 2994.77539184562, 2996.0, 3018.4566777979735, 4649.418822647169, 5522.861928451741, 5838.737256823453, 5869.970650146722, 5874.471297583153, 5719.399463056402, 5756.42435756421, 5776.277965254343, 5779.0, 5857.7949771757785, 5110.904529143034, 5622.353429355677, 5797.4782269595935, 5802.390748971382, 5830.609450125428, 5382.165764306539, 5409.861455986095, 5576.498115169654, 5631.0, 5642.0, 5034.546890779848, 5465.453454654503, 5547.805826796042, 5550.58370622213, 5553.778168803018, 4401.295792673157, 4902.264660300983, 5431.83704488866, 5842.743810080853, 5855.745537967828, 3365.848536815898, 3846.8461261549223, 3884.4173373994126, 3916.334223182009, 3930.8600505854124, 4728.219843725747, 5485.862853428696, 5590.496855282881, 5630.410011394804, 5665.405132461032, 5039.319691841554, 5328.873694544324, 5331.227761563888, 5486.561075113901, 5505.366882808528, 5192.805654699365, 5322.361316642027, 5388.602072077888, 5413.621046526863, 5422.6076877729165, 4537.387452693844, 4930.852074437629, 5262.89474210506, 5342.75957581902, 5373.570114390917, 4485.730856148642, 4934.925976110433, 5190.117679994334, 5392.919106213331, 5479.232907393048, 4706.55259265187, 5135.870108576108, 5478.972954878241, 5549.7780088796, 5584.022796010763], "value": 4035.4686263907115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.641495667123, 750.332204338139, 792.5007245435374, 872.4765140915451, 873.6156091319823, 15386.692266154685, 15897.0, 16536.255868485903, 16541.917290413545, 16917.238724257408, 14921.104733715985, 15300.617484562892, 15366.23168841559, 15401.76897346538, 15412.07527548345, 705.6224919667978, 728.6283995162453, 866.7529754020121, 873.4759144513273, 891.1534042659499, 887.8579427291635, 897.5736525150534, 898.6809682562695, 898.7483504618684, 899.0784445942928, 961.2742379503466, 961.5817119552981, 962.7063745557602, 965.4400447740329, 968.6319198704517, 740.9777706668808, 763.1757701682226, 766.6051983228642, 952.2572393533055, 953.8903026151977, 565.8557067947647, 681.7750142452978, 691.9827004324882, 703.736098962889, 703.8944158376233, 15348.616284592863, 15414.922925385414, 15421.228938553028, 15653.686926261411, 15694.372225110981, 14260.500882469063, 14516.564503064925, 14551.272436378245, 14581.781273280909, 14660.0, 10799.460026998619, 12935.741285174245, 13538.932305338509, 13584.524541640994, 13611.890801210648, 4616.12326821132, 4638.67529272951, 4724.984128412408, 4775.450823155347, 4801.831935882227, 114463.0, 116702.0, 119618.0, 121663.0, 124263.13605295928, 9079.769210543278, 11216.517304550116, 11343.0, 11553.768924621461, 11644.592439264585, 23316.417089572897, 23416.53166936652, 23441.648375274384, 23451.41371465693, 23455.648165277533, 7595.202503737139, 7605.619719014027, 7762.417818663623, 7924.128345881948, 7942.324902383362, 3514.5958214805114, 4021.8738753149164, 4331.220380331571, 4360.193364227628, 4423.955760442419, 3575.0, 3716.21959388526, 3717.3494638438165, 3717.442383642421, 3723.385641369198, 590.9940900590939, 673.723773252964, 675.3449154320301, 675.5642610516288, 677.1975209376905, 517.5600739371579, 614.852435415506, 620.8665136995477, 627.1611298808111, 688.132952479869, 9128.3153763468, 9742.853857192147, 9781.364211326241, 9795.951020244853, 9999.700008999742, 571.948524632785, 611.3458599298797, 694.3160986428364, 697.4664381747865, 710.1726488640779, 4525.615322697608, 4602.378678878308, 4712.198926182557, 4756.833510827105, 4828.444728856227, 94004.22807622515, 162522.3747762531, 165476.0, 170864.0, 177546.0, 137229.0, 138815.0, 140386.0, 141342.2932885329, 141481.0, 117147.0, 122437.0, 123943.0, 124287.0, 128190.35904820416, 8904.465732056098, 9208.355415120877, 9243.2143267823, 9249.860123091905, 9408.576614052246, 62657.0, 63068.0, 64242.0, 64895.67552162209, 66638.00042999456, 68857.24571017103, 84897.57551212204, 95608.0439195627, 97130.0, 99676.0, 11788.29270243788, 12023.21849079807, 12038.097142714156, 12195.817062744243, 12456.626301210978, 12503.312317822607, 12834.0, 13264.535741249396, 13761.31193440304, 14100.506482273071, 12184.0, 12316.199447035913, 12524.373781310716, 12561.685957851127, 12959.287239201558, 12569.297601015825, 12877.678058048623, 12885.935570322088, 13322.041497496468, 13524.053316267768, 12854.421551030038, 13170.80243796363, 13638.727225455634, 14427.855721443071, 15114.09315441077, 10572.84140737905, 11383.943080284545, 12075.27548347102, 12163.635090947286, 12178.208416452902, 9820.4107753535, 11342.60300889465, 11757.529698812294, 11889.524419023142, 11977.580784672495], "value": 7996.740445054787, "unit": "score"}], "name": "dromaeo_css", "value": 7400.352403104471, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 127.0, 115.0, 113.0, 115.0, 114.0, 116.0, 115.0, 114.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 130.0, 156.0, 109.0, 127.0, 106.0, 136.0, 145.0, 105.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 183.0, 187.0, 183.0, 183.0, 197.0, 263.0, 204.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 77.0, 164.0, 56.0, 59.0, 73.0, 89.0, 170.0, 51.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 137.0, 71.0, 71.0, 86.0, 154.0, 100.0, 100.0, 121.0], "value": 100.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 74.0, 109.0, 134.0, 107.0, 90.0, 85.0, 87.0, 84.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 92.0, 95.0, 92.0, 93.0, 92.0, 119.0, 93.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 97.0, 130.0, 133.0, 81.0, 85.0, 85.0, 84.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 59.0, 56.0, 56.0, 58.0, 56.0, 56.0, 55.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 66.0, 76.0, 71.0, 77.0, 72.0, 72.0, 71.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 82.0, 81.0, 81.0, 83.0, 87.0, 89.0, 88.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 151.0, 129.0, 136.0, 144.0, 152.0, 138.0, 178.0, 138.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 160.0, 159.0, 160.0, 161.0, 163.0, 179.0, 222.0, 145.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 69.0, 85.0, 82.0, 65.0, 68.0, 65.0, 66.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1506.6000000000001, "alertThreshold": 2.0}]} 07:38:55 INFO - Return code: 0 07:38:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:56 INFO - # TBPL SUCCESS # 07:38:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:56 INFO - 2016-09-17 07:38:56.046 screenresolution[2066:21074] starting screenresolution argv=screenresolution get 07:38:56 INFO - 2016-09-17 07:38:56.049 screenresolution[2066:21074] Display 0: 1600x1200x32@60 07:38:56 INFO - 2016-09-17 07:38:56.055 screenresolution[2067:21077] starting screenresolution argv=screenresolution list 07:38:56 INFO - Available Modes on Display 0 07:38:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:56 INFO - Intel Iris: 07:38:56 INFO - Chipset Model: Intel Iris 07:38:56 INFO - Type: GPU 07:38:56 INFO - Bus: Built-In 07:38:56 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:56 INFO - Vendor: Intel (0x8086) 07:38:56 INFO - Device ID: 0x0a2e 07:38:56 INFO - Revision ID: 0x0009 07:38:56 INFO - Displays: 07:38:56 INFO - Display: 07:38:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:56 INFO - Display Serial Number: 1600x1200 60 07:38:56 INFO - Main Display: Yes 07:38:56 INFO - Mirror: Off 07:38:56 INFO - Online: Yes 07:38:56 INFO - Rotation: Supported 07:38:56 INFO - Return code: 0 07:38:56 INFO - Running post-action listener: _resource_record_post_action 07:38:56 INFO - [mozharness: 2016-09-17 14:38:56.290025Z] Finished run-tests step (success) 07:38:56 INFO - Running post-run listener: _resource_record_post_run 07:38:56 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 872448; Write bytes: 665335296; Read time: 7; Write time: 1873 07:38:56 INFO - TinderboxPrint: CPU usage
53.2% 07:38:56 INFO - TinderboxPrint: I/O read bytes / time
872,448 / 7 07:38:56 INFO - TinderboxPrint: I/O write bytes / time
665,335,296 / 1,873 07:38:56 INFO - TinderboxPrint: CPU idle
2,262.1 (46.8%) 07:38:56 INFO - TinderboxPrint: CPU system
967.1 (20.0%) 07:38:56 INFO - TinderboxPrint: CPU user
1,604.4 (33.2%) 07:38:56 INFO - TinderboxPrint: Swap in / out
364,609,536 / 0 07:38:56 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361693696; Write bytes: 358168576; Read time: 27493; Write time: 744 07:38:56 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 856064; Write bytes: 296066560; Read time: 4; Write time: 1088 07:38:56 INFO - Running post-run listener: _upload_blobber_files 07:38:56 INFO - Blob upload gear active. 07:38:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:56 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 07:38:56 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:38:56 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:57 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:58 INFO - (blobuploader) - INFO - Done attempting. 07:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:59 INFO - (blobuploader) - INFO - Done attempting. 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:01 INFO - (blobuploader) - INFO - Done attempting. 07:39:01 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:01 INFO - Return code: 0 07:39:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/150cd1a300be1d6c483871b07301daa67240592424fe68fc1ff3fbe6f8783f7202d50c2e667bc7018f4cabed49b75039b2960f2ebbeab72e93f47b65fc6423dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ba8a098522e0268edebbd666a7fb50a8df2496df523a689ce8e01855c9606e8e023eac5b6f783fb5fc67d9ce0f2d9f89574308d00eb4b45121d0c3509a6cd40"} 07:39:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:01 INFO - Contents: 07:39:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/150cd1a300be1d6c483871b07301daa67240592424fe68fc1ff3fbe6f8783f7202d50c2e667bc7018f4cabed49b75039b2960f2ebbeab72e93f47b65fc6423dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ba8a098522e0268edebbd666a7fb50a8df2496df523a689ce8e01855c9606e8e023eac5b6f783fb5fc67d9ce0f2d9f89574308d00eb4b45121d0c3509a6cd40"} 07:39:01 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:01 INFO - Copying logs to upload dir... 07:39:01 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.093088 ========= master_lag: 1.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-17 07:39:03.252165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.259998) ========= 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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/150cd1a300be1d6c483871b07301daa67240592424fe68fc1ff3fbe6f8783f7202d50c2e667bc7018f4cabed49b75039b2960f2ebbeab72e93f47b65fc6423dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ba8a098522e0268edebbd666a7fb50a8df2496df523a689ce8e01855c9606e8e023eac5b6f783fb5fc67d9ce0f2d9f89574308d00eb4b45121d0c3509a6cd40"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/150cd1a300be1d6c483871b07301daa67240592424fe68fc1ff3fbe6f8783f7202d50c2e667bc7018f4cabed49b75039b2960f2ebbeab72e93f47b65fc6423dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ba8a098522e0268edebbd666a7fb50a8df2496df523a689ce8e01855c9606e8e023eac5b6f783fb5fc67d9ce0f2d9f89574308d00eb4b45121d0c3509a6cd40"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.347063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.347617) ========= 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.4Pmn9KB8Oi/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.3Nd8RX2leZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005166 ========= master_lag: 0.71 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:04.065603) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-09-17 07:39:04.065895) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-09-17 07:39:07.431137) ========= ========= Total master_lag: 2.24 =========