builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0102 starttime: 1474121881.51 results: success (0) buildid: 20160917071758 builduid: 1b6c39c2d55c4d94808fe56a4a733623 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.511755) ========= 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.512251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.512560) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.576890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.577158) ========= 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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.8M=0.001s 2016-09-17 07:18:02 (10.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.796276 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.399974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.400343) ========= 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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.021216 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.448892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:02.449225) ========= 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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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,525 truncating revision to first 12 chars 2016-09-17 07:18:02,525 Setting DEBUG logging. 2016-09-17 07:18:02,525 attempt 1/10 2016-09-17 07:18:02,525 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,592 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.325355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:03.798260) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.798612) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.799233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.799695) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:03.819411) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-09-17 07:18:03.820007) ========= /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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:04 INFO - MultiFileLogger online at 20160917 07:18:04 in /builds/slave/test 07:18:04 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:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:04 INFO - {'append_to_log': False, 07:18:04 INFO - 'base_work_dir': '/builds/slave/test', 07:18:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:04 INFO - 'branch': 'Mozilla-Aurora', 07:18:04 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:04 INFO - 'config_files': ('talos/mac_config.py',), 07:18:04 INFO - 'default_actions': ('clobber', 07:18:04 INFO - 'read-buildbot-config', 07:18:04 INFO - 'download-and-extract', 07:18:04 INFO - 'populate-webroot', 07:18:04 INFO - 'create-virtualenv', 07:18:04 INFO - 'install', 07:18:04 INFO - 'run-tests'), 07:18:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:04 INFO - 'download_minidump_stackwalk': True, 07:18:04 INFO - 'download_symbols': 'ondemand', 07:18:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:04 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:04 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:04 INFO - 'installer_path': 'installer.exe', 07:18:04 INFO - 'log_level': 'info', 07:18:04 INFO - 'log_name': 'talos', 07:18:04 INFO - 'log_to_console': True, 07:18:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:04 INFO - 'opt_config_files': (), 07:18:04 INFO - 'pip_index': False, 07:18:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'run_cmd_checks_enabled': True, 07:18:04 INFO - 'sps_profile': False, 07:18:04 INFO - 'sps_profile_interval': 0, 07:18:04 INFO - 'suite': 'dromaeojs', 07:18:04 INFO - 'system_bits': '32', 07:18:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:04 INFO - 'title': 't-yosemite-r7-0102', 07:18:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:04 INFO - 'use_talos_json': True, 07:18:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:04 INFO - 'work_dir': 'build'} 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.025833Z] Running clobber step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: clobber 07:18:04 INFO - rmtree: /builds/slave/test/build 07:18:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:05 INFO - Running post-action listener: _resource_record_post_action 07:18:05 INFO - [mozharness: 2016-09-17 14:18:05.785752Z] Finished clobber step (success) 07:18:05 INFO - [mozharness: 2016-09-17 14:18:05.785879Z] Running read-buildbot-config step. 07:18:05 INFO - Running pre-action listener: _resource_record_pre_action 07:18:05 INFO - Running main action method: read_buildbot_config 07:18:05 INFO - Using buildbot properties: 07:18:05 INFO - { 07:18:05 INFO - "project": "", 07:18:05 INFO - "product": "firefox", 07:18:05 INFO - "script_repo_revision": "production", 07:18:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:05 INFO - "repository": "", 07:18:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:05 INFO - "buildid": "20160917071758", 07:18:05 INFO - "stage_platform": "macosx64", 07:18:05 INFO - "basedir": "/builds/slave/test", 07:18:05 INFO - "buildnumber": 42, 07:18:05 INFO - "platform": "yosemite_r7", 07:18:05 INFO - "mozci_request": { 07:18:05 INFO - "priority": -1, 07:18:05 INFO - "type": "trigger_all_talos_jobs", 07:18:05 INFO - "times": 6 07:18:05 INFO - }, 07:18:05 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:05 INFO - "slavebuilddir": "test", 07:18:05 INFO - "branch": "mozilla-aurora", 07:18:05 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:05 INFO - "slavename": "t-yosemite-r7-0102", 07:18:05 INFO - "builduid": "1b6c39c2d55c4d94808fe56a4a733623", 07:18:05 INFO - "repo_path": "releases/mozilla-aurora" 07:18:05 INFO - } 07:18:05 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:05 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:05 INFO - Running post-action listener: _resource_record_post_action 07:18:05 INFO - [mozharness: 2016-09-17 14:18:05.789682Z] Finished read-buildbot-config step (success) 07:18:05 INFO - [mozharness: 2016-09-17 14:18:05.789783Z] Running download-and-extract step. 07:18:05 INFO - Running pre-action listener: _resource_record_pre_action 07:18:05 INFO - Running main action method: download_and_extract 07:18:05 INFO - mkdir: /builds/slave/test/build/tests 07:18:05 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:05 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:05 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:05 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:06 INFO - Downloaded 1199 bytes. 07:18:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:06 INFO - Using the following test package requirements: 07:18:06 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'jsshell-mac.zip'], 07:18:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:06 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:06 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18:06 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:06 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:06 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:06 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:06 INFO - Downloaded 16309409 bytes. 07:18:06 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:06 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:09 INFO - Return code: 0 07:18:09 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:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:09 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:09 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:09 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:10 INFO - Downloaded 16309409 bytes. 07:18:10 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:10 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:13 INFO - Return code: 0 07:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:13 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:13 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:13 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:14 INFO - Downloaded 14413740 bytes. 07:18:14 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:14 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:14 INFO - Return code: 0 07:18:14 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:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:14 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:14 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:17 INFO - Running post-action listener: _resource_record_post_action 07:18:17 INFO - Running post-action listener: set_extra_try_arguments 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.091507Z] Finished download-and-extract step (success) 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.091640Z] Running populate-webroot step. 07:18:17 INFO - Running pre-action listener: _resource_record_pre_action 07:18:17 INFO - Running main action method: populate_webroot 07:18:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:17 INFO - '--tppagecycles', 07:18:17 INFO - '7'], 07:18:17 INFO - 'tests': ['tsvgm']}}, 07:18:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['damp', 'tps']}, 07:18:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['damp', 'tps']}, 07:18:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['dromaeo_dom']}, 07:18:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['basic_compositor_video']}, 07:18:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tsvgx', 07:18:17 INFO - 'tsvgr_opacity', 07:18:17 INFO - 'tart', 07:18:17 INFO - 'tscrollx', 07:18:17 INFO - 'cart']}, 07:18:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:17 INFO - 'tsvgr_opacity', 07:18:17 INFO - 'tart', 07:18:17 INFO - 'tscrollx', 07:18:17 INFO - 'cart']}, 07:18:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tp5o']}, 07:18:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['tp5o']}, 07:18:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s', 07:18:17 INFO - '--xperf_path', 07:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:17 INFO - 'tests': ['tp5n']}, 07:18:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--xperf_path', 07:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:17 INFO - 'tests': ['tp5n']}}, 07:18:17 INFO - 'talos.zip': {'path': '', 07:18:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:17 INFO - Running post-action listener: _resource_record_post_action 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.110469Z] Finished populate-webroot step (success) 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.110580Z] Running create-virtualenv step. 07:18:17 INFO - Running pre-action listener: _resource_record_pre_action 07:18:17 INFO - Running main action method: create_virtualenv 07:18:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:17 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:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:17 INFO - Using real prefix '/tools/python27' 07:18:17 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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:20 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:21 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:21 INFO - Cleaning up... 07:18:21 INFO - Return code: 0 07:18:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OD5JsQgE3F/Render', 07:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:21 INFO - 'HOME': '/Users/cltbld', 07:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:21 INFO - 'LOGNAME': 'cltbld', 07:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:21 INFO - 'MOZ_NO_REMOTE': '1', 07:18:21 INFO - 'NO_EM_RESTART': '1', 07:18:21 INFO - 'PAGER': '/bin/cat', 07:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:21 INFO - 'PWD': '/builds/slave/test', 07:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:21 INFO - 'SHELL': '/bin/bash', 07:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4XedGiVEa2/Listeners', 07:18:21 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 07:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:21 INFO - 'USER': 'cltbld', 07:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:21 INFO - 'XPC_FLAGS': '0x0', 07:18:21 INFO - 'XPC_SERVICE_NAME': '0', 07:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:21 INFO - Downloading/unpacking psutil>=3.1.1 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 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 - Installing collected packages: psutil 07:18:25 INFO - Running setup.py install for psutil 07:18:25 INFO - building 'psutil._psutil_osx' extension 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_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:26 INFO - Return code: 0 07:18:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OD5JsQgE3F/Render', 07:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:26 INFO - 'HOME': '/Users/cltbld', 07:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:26 INFO - 'LOGNAME': 'cltbld', 07:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:26 INFO - 'MOZ_NO_REMOTE': '1', 07:18:26 INFO - 'NO_EM_RESTART': '1', 07:18:26 INFO - 'PAGER': '/bin/cat', 07:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:26 INFO - 'PWD': '/builds/slave/test', 07:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:26 INFO - 'SHELL': '/bin/bash', 07:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4XedGiVEa2/Listeners', 07:18:26 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 07:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:26 INFO - 'USER': 'cltbld', 07:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:26 INFO - 'XPC_FLAGS': '0x0', 07:18:26 INFO - 'XPC_SERVICE_NAME': '0', 07:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:29 INFO - Successfully installed mozsystemmonitor 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OD5JsQgE3F/Render', 07:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:29 INFO - 'HOME': '/Users/cltbld', 07:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:29 INFO - 'LOGNAME': 'cltbld', 07:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:29 INFO - 'MOZ_NO_REMOTE': '1', 07:18:29 INFO - 'NO_EM_RESTART': '1', 07:18:29 INFO - 'PAGER': '/bin/cat', 07:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:29 INFO - 'PWD': '/builds/slave/test', 07:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:29 INFO - 'SHELL': '/bin/bash', 07:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4XedGiVEa2/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 07:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:29 INFO - 'USER': 'cltbld', 07:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:29 INFO - 'XPC_FLAGS': '0x0', 07:18:29 INFO - 'XPC_SERVICE_NAME': '0', 07:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:29 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:32 INFO - warning: no files found matching '*.txt' 07:18:32 INFO - no previously-included directories found matching 'build' 07:18:32 INFO - no previously-included directories found matching 'dist' 07:18:32 INFO - no previously-included directories found matching '.git*' 07:18:32 INFO - Installing collected packages: jsonschema, functools32 07:18:32 INFO - Running setup.py install for functools32 07:18:32 INFO - warning: no files found matching '*.txt' 07:18:32 INFO - no previously-included directories found matching 'build' 07:18:32 INFO - no previously-included directories found matching 'dist' 07:18:32 INFO - no previously-included directories found matching '.git*' 07:18:32 INFO - Successfully installed jsonschema functools32 07:18:32 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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:33 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:36 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:36 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 - 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:36 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:36 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:36 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:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:37 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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-BuBL7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-XI4z3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:38 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-OWKtAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:38 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-NHSgIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:38 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jyC04e-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-4QKxD0-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-f1VlmF-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jIuiVv-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-W7qFFp-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-TfkmUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-bdp18m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-_hj7Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:39 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-EcupzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:39 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-B13F6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:39 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-pB9VvQ-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-btYq1_-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-kODYia-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:40 INFO - Running setup.py install for mozdebug 07:18:40 INFO - Running setup.py install for mozdevice 07:18:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:40 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:41 INFO - Running setup.py install for mozlog 07:18:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for moznetwork 07:18:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:41 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:42 INFO - Running setup.py install for moztest 07:18:42 INFO - Running setup.py install for mozversion 07:18:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:42 INFO - Cleaning up... 07:18:42 INFO - Return code: 0 07:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:42 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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:42 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:42 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:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OD5JsQgE3F/Render', 07:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:42 INFO - 'HOME': '/Users/cltbld', 07:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:42 INFO - 'LOGNAME': 'cltbld', 07:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:42 INFO - 'MOZ_NO_REMOTE': '1', 07:18:42 INFO - 'NO_EM_RESTART': '1', 07:18:42 INFO - 'PAGER': '/bin/cat', 07:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:42 INFO - 'PWD': '/builds/slave/test', 07:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:42 INFO - 'SHELL': '/bin/bash', 07:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4XedGiVEa2/Listeners', 07:18:42 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 07:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:42 INFO - 'USER': 'cltbld', 07:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:42 INFO - 'XPC_FLAGS': '0x0', 07:18:42 INFO - 'XPC_SERVICE_NAME': '0', 07:18:42 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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hms9xc-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-mIbB3Y-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jLqxCU-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:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-lv3fTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-SDVgYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-eWwyb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-KwpMXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-yxUTRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:43 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hcPCD6-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-EO3v3J-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-yQQDcY-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-p5_A5s-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/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-BI_CdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-HJIh9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-6oK9_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-FCHcsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:44 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-rAco2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:44 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:44 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:44 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:44 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:44 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:44 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:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:47 INFO - Installing collected packages: blessings 07:18:47 INFO - Running setup.py install for blessings 07:18:47 INFO - Successfully installed blessings 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 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 - 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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 0x1040f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10418c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe808d9eac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, '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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.OD5JsQgE3F/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.4XedGiVEa2/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:48 INFO - Cleaning up... 07:18:48 INFO - Return code: 0 07:18:48 INFO - Running post-action listener: _resource_record_post_action 07:18:48 INFO - Running post-action listener: _start_resource_monitoring 07:18:48 INFO - Starting resource monitoring. 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.105214Z] Finished create-virtualenv step (success) 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.106180Z] Running install step. 07:18:48 INFO - Running pre-action listener: _resource_record_pre_action 07:18:48 INFO - Running main action method: install 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Output received: 07:18:48 INFO - Usage: mozinstall [options] installer 07:18:48 INFO - Options: 07:18:48 INFO - -h, --help show this help message and exit 07:18:48 INFO - -d DEST, --destination=DEST 07:18:48 INFO - Directory to install application into. [default: 07:18:48 INFO - "/builds/slave/test"] 07:18:48 INFO - --app=APP Application being installed. [default: firefox] 07:18:48 INFO - mkdir: /builds/slave/test/build/application 07:18:48 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:48 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:20 INFO - Reading from file tmpfile_stdout 07:19:20 INFO - Output received: 07:19:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:20 INFO - Running post-action listener: _resource_record_post_action 07:19:20 INFO - [mozharness: 2016-09-17 14:19:20.988099Z] Finished install step (success) 07:19:20 INFO - [mozharness: 2016-09-17 14:19:20.988227Z] Running run-tests step. 07:19:20 INFO - Running pre-action listener: _resource_record_pre_action 07:19:20 INFO - Running main action method: run_tests 07:19:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:21 INFO - 2016-09-17 07:19:21.003 screenresolution[6771:133555] starting screenresolution argv=screenresolution get 07:19:21 INFO - 2016-09-17 07:19:21.006 screenresolution[6771:133555] Display 0: 1600x1200x32@60 07:19:21 INFO - 2016-09-17 07:19:21.011 screenresolution[6772:133559] starting screenresolution argv=screenresolution list 07:19:21 INFO - Available Modes on Display 0 07:19:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:21 INFO - Intel Iris: 07:19:21 INFO - Chipset Model: Intel Iris 07:19:21 INFO - Type: GPU 07:19:21 INFO - Bus: Built-In 07:19:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:21 INFO - Vendor: Intel (0x8086) 07:19:21 INFO - Device ID: 0x0a2e 07:19:21 INFO - Revision ID: 0x0009 07:19:21 INFO - Displays: 07:19:21 INFO - Display: 07:19:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:21 INFO - Display Serial Number: 1600x1200 60 07:19:21 INFO - Main Display: Yes 07:19:21 INFO - Mirror: Off 07:19:21 INFO - Online: Yes 07:19:21 INFO - Rotation: Supported 07:19:21 INFO - Return code: 0 07:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:21 INFO - Python 2.7.3 07:19:21 INFO - Return code: 0 07:19:21 INFO - grabbing minidump binary from tooltool 07:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:21 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 0x10405bd30>, '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 0x103dc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104053b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:21 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:21 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:21 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:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:21 INFO - Return code: 0 07:19:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:21 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-0102', '--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:21 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-0102 --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:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OD5JsQgE3F/Render', 07:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:21 INFO - 'HOME': '/Users/cltbld', 07:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:21 INFO - 'LOGNAME': 'cltbld', 07:19:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:21 INFO - 'MOZ_NO_REMOTE': '1', 07:19:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'NO_EM_RESTART': '1', 07:19:21 INFO - 'PAGER': '/bin/cat', 07:19:21 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:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:21 INFO - 'PWD': '/builds/slave/test', 07:19:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:21 INFO - 'SHELL': '/bin/bash', 07:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4XedGiVEa2/Listeners', 07:19:21 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 07:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:21 INFO - 'USER': 'cltbld', 07:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:21 INFO - 'XPC_FLAGS': '0x0', 07:19:21 INFO - 'XPC_SERVICE_NAME': '0', 07:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:21 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-0102', '--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:21 INFO - mozversion application_buildid: 20160916162818 07:19:21 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:21 INFO - mozversion application_name: Firefox 07:19:21 INFO - mozversion application_remotingname: firefox-dev 07:19:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion application_vendor: Mozilla 07:19:21 INFO - mozversion application_version: 50.0a2 07:19:21 INFO - mozversion platform_buildid: 20160916162818 07:19:21 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion platform_version: 50.0a2 07:19:21 INFO - using testdate: 1474121961 07:19:21 INFO - actual date: 1474121961 07:19:21 INFO - starting webserver on 'localhost:59088' 07:19:21 INFO - SUITE-START | Running 2 tests 07:19:21 INFO - TEST-START | dromaeo_css 07:19:21 INFO - operating with platform_type : mac_ 07:19:21 INFO - Initialising browser for dromaeo_css test... 07:19:21 INFO - TEST-INFO | started process 6782 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmphI4yC7/profile http://localhost:59088/getInfo.html) 07:19:24 INFO - PROCESS | 6782 | __metrics Screen width/height:1600/1200 07:19:24 INFO - PROCESS | 6782 | colorDepth:24 07:19:24 INFO - PROCESS | 6782 | Browser inner width/height: 1024/689 07:19:24 INFO - PROCESS | 6782 | __metrics 07:19:24 INFO - PROCESS | 6782 | 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:24 INFO - PROCESS | 6782 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:24 INFO - TEST-INFO | 6782: exit 0 07:19:24 INFO - Browser initialized. 07:19:24 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:24 INFO - TEST-INFO | started process 6784 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmphI4yC7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:36 INFO - PROCESS | 6784 | RSS: Main: 215601152 07:19:36 INFO - PROCESS | 6784 | 07:20:27 INFO - PROCESS | 6784 | 1474122027749 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:39 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59088/tests/dromaeo/cssquery-ext.html) 07:22:40 INFO - PROCESS | 6784 | RSS: Main: 336764928 07:22:40 INFO - PROCESS | 6784 | 07:25:43 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-ext.html (next: http://localhost:59088/tests/dromaeo/cssquery-jquery.html) 07:25:44 INFO - PROCESS | 6784 | RSS: Main: 361005056 07:25:44 INFO - PROCESS | 6784 | 07:28:46 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59088/tests/dromaeo/cssquery-mootools.html) 07:28:47 INFO - PROCESS | 6784 | RSS: Main: 327061504 07:28:47 INFO - PROCESS | 6784 | 07:31:49 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59088/tests/dromaeo/cssquery-prototype.html) 07:31:50 INFO - PROCESS | 6784 | RSS: Main: 325296128 07:31:50 INFO - PROCESS | 6784 | 07:34:53 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59088/tests/dromaeo/cssquery-yui.html) 07:34:54 INFO - PROCESS | 6784 | RSS: Main: 325406720 07:34:54 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:59088/tests/dromaeo/cssquery-yui.html (next: http://localhost:59088/tests/dromaeo/cssquery-dojo.html) 07:37:57 INFO - PROCESS | 6784 | RSS: Main: 500015104 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | __start_tp_report 07:37:57 INFO - PROCESS | 6784 | _x_x_mozilla_page_load 07:37:57 INFO - PROCESS | 6784 | _x_x_mozilla_page_load_details 07:37:57 INFO - PROCESS | 6784 | |i|pagename|runs| 07:37:57 INFO - PROCESS | 6784 | |0;dojo.html;3274.2632907595794;3276.475763877779;3306.933861322772;3324.16895776056;3373.3421982713367;5705.515031222346;5840.445157710011;5857.41425857414;5861.325947516037;5892.3812999635065;5763.193152958579;5840.503557197632;5856.707164641773;5858.88282234355;5884.646921184732;5124.92312615311;5213.400458947212;5220.216967454874;5246.475352464742;5358.276632654581;5279.656822306557;5313.814016509423;5323.068463018956;5331.786728530854;5339.252504649352;5244.527992480676;5258.684478931257;5271.920921186185;5277.050130976423;5318.069337865877;5341.385740639818;5348.3849357323625;5356.785728570852;5361.839144825662;5412.702301373423;5043.571296439807;5138.614603904722;5211.1922651989;5263.499967503081;5275.129603615399;5678.006348888928;5678.4605462480995;5681.488666020036;5721.370649228582;5780.046292361755;6270.561060725751;6277;6292.779752708633;6296.433321001132;6305.747770089237;6130.2452696569835;6168.568200225986;6232;6251.687415629201;6255.7184926677955;5930.28836539618;5938.673372964528;5956.136360227742;5969.8806023879715;6000.279966404016;6344.587601805868;6354.555181137276;6360.681965901779;6374.745010199633;6376.5855219411515;6136.631802091889;6138.541671868701;6217.875642487125;6218.036204388327;6269.529785266123;6263.248410190807;6274.058891166362;6287.779927702509;6290.685465726695;6294.937050629526;6232.657203853832;6281.057841323746;6293.244810622755;6313.621182729051;6315.179026726496;5087.796488140433;6192.628442293387;6217.129601855744;6259.624422534663;6295.496360291166;4945.8021679132435;4969.801207951714;5039.395272567315;5040.168372218616;5050.267711181858;4492.989077457546;4524.7511386873375;4538.364628951949;4564.178447879347;4574.656900732459;5867.14926335679;5871.1486834409625;5871.471567558855;5887.4406931341455;5905.409459054146;6184.690765461709;6184.845378865474;6188.164597779332;6207.503399728011;6257.843553911189;5856.385079566669;5869.354371019184;5881.9705901470215;5929.584929054925;5932.584719069624;5520.254765606672;5521.282233309725;5521.944780552143;5525.475079867487;5539.975104605661;6115.453310713017;6243.094751261133;6251.531135164972;6287.937120628918;6411.198600174978;6152.800203960185;6243.063540468966;6247.406496382836;6249.625022498664;6256.092866534236;6392.137061496638;6404.519661025349;6404.679766011774;6419.358064193543;6419.743210271724;6091.360407157184;6223.284322302993;6249.156363890998;6272.121902933502;6282.403171698681;6189;6207.81376558704;6237.656928868956;6255.56211065221;6267.874642507216;6181.81454556364;6196.876062478816;6208.565400421927;6213.720382582705;6215.471684906834;6036.034234522455;6125.693715314127;6157.9384206157365;6189.690515474118;6234.781782637768;5292.603054770831;5995.970020149872;6045.365236650088;6058.303295121117;6078.483328917092;5219.243209734643;5247.711375874211;6205.131281620667;6209.130721699056;6233.781817636361;5694.743736531949;5843.240378750906;5915.171875937458;5928.911066334094;5997.70011499432 07:37:57 INFO - PROCESS | 6784 | |1;ext.html;498.2750098606526;514.4906542522903;525.1702310349646;548;555.7665780372243;30517.694823051763;32725;41696.95757606057;41943;42132.73601791944;43529;43760.343594846105;43863.78068109655;43927.78036109815;43963.56036439635;9717.31978761487;9885.505724713772;9900;9901;9910;8729.956350218241;8910.777230569223;11850.7629847403;12047.216930899507;12053.698657533545;12158.270503769758;12222.694432639166;12238.143329966906;12261.877381226162;12288.815667764946;12011.579594714187;12066.276023438622;12069.456874440628;12077.45651445687;12078.879211207863;11941.164118511708;11969.64091077269;12017.759644807142;12020.038396928225;12031.217970831867;41766.58233417657;42032;42198.367024494655;42201.57798422038;42251.15497690029;3428.708559772422;3539.610642829287;3563.6080031196548;4016.598340165989;4174.186033723425;30964.690353096405;30989.535156972663;31064.378712425623;32565.860194893303;32589.511157332658;3126.8592913318957;3328;3373.375925453799;3389.5593572835373;3395.3548825723033;540458.2977085089;542347;542662;543939;546069;65434;65603;65772;65779;65898.67050664716;68017;68156.6592167036;68228.31771682319;68235;68240.63518729598;14877.553673389815;17886.373976910745;17897.37359192422;17911;17914.462566122776;12351.25892446456;12525.248485090924;13552.051356405154;13626.93186534061;13639.659008524866;16723.996560206182;16739.497234147155;19403.90298048529;20349.287774927805;20361.694574581394;7939.563324017119;7957.880631790527;7994.840103197904;7996.880436738861;8089.39329550286;868.561376504861;947.2942657719905;1013.5135135135176;1042.8383600541777;1114.849495318138;27380.45239095207;29906.028740625483;31856.08554272123;31904.361912761615;32025;1070.373831308693;1090.3621381491757;1129.3957732613012;1140.196161706012;1207.673928039442;15757.842421575637;16112.597185070466;16116.35534578627;16186.271617777229;16471.505854824376;666242;667854;669234;669543;669640.6517967477;61663;62093.379066209665;62110.37889621136;62242;62255.754884902046;1311.2591385866954;1423.4413317417204;1464.7290251303543;1489.2330449818353;1490.1878476230697;31166.688333116377;31296.687033129376;32051.839740801148;32123.875664351635;32569.02292931216;51578.74210628923;52297;52448.21327680012;52461;52643;576365;578681;579586;580555;581197;27697.307567310174;28419;28432.57351139693;29062.982795602053;29064.27339316534;39620.603793962844;49001.754991224814;49213.26180107373;50082.49917500924;50629.73425664388;33995.49006764851;40029;40426;41207.58792412157;42801.35797963096;40385.1537815464;40426.59573404228;40778;41374.96562585839;42044.36933446062;38466.0383490415;38729.8381048569;38985.90647982035;39444.2111157781;39827.80516584507;32943;35073;35772.4634130483;35830.104247394025;35878.64121358753;26256.60615090737;26484;26532.86733566397;26570.335741606614;27133.72866271391 07:37:57 INFO - PROCESS | 6784 | |2;jquery.html;321.5353813739145;368.2120262637955;375.67879463059086;378.18878505605466;398.2512875793508;15973.840261597381;17164.39924602639;21088.472788180305;21721.67417488739;21879.890600546994;21455.249066282682;21493.462663433384;22093.005814738335;22653;22988.19541316054;5831.679257640828;5919.763209471626;6134.938650613503;6203.2245969253845;6220.377962203766;3932.705047121463;6196.1944947157035;6253.656048917308;6254.968725156391;6273.05904114382;4694.155052090622;6866.416354609864;6885.965570172142;6928.61892595907;6955.02629631852;6116.082587611894;6202.689865506707;6567.835804104888;6891.689873955684;6914.9654251728925;6021.404327853123;6051.2133422655215;6053.545984051214;6060.181875446802;6068.393160683941;13334.599962001155;14857.628559285997;20651.793482065288;26083.869580652165;26402.471950561074;2134.8292136629034;2172.8696278223356;2190.2224710227956;2235.50818819861;2255.481239314967;10196.133328667072;10524.947375263151;10882.347059176396;11396.430178491126;16352.83647163525;1877.427384647682;1880.1257415301814;1881.0876724788466;1908.2176307713771;1962.6271008508481;82063;98347.50826245823;114236.42881785621;125921.74078259284;132503.33748331293;11307.095432365391;11426.88573114275;11602.535898564132;11658.883411165949;11819.527218911147;14588.270586470633;14591.78112328316;14623.19572423527;14624.634384140269;14625.414983400759;8517.44636598619;8778.868316975251;8788.42875213109;8901.866472002925;8902.643894244287;8929.73210803677;8933.553322333857;8955.104489551122;9095.226905713089;9097.727068187965;4361.127774445124;4488.304312831517;4497.977510112429;4505.279155335131;4566.223741963808;1642.7535869619653;1732.506235722813;1890.9621807563772;1924.8363889069585;1937.7965313642146;798.5168972771506;934.5981028457314;973.5229737428609;1026.1021606094666;1085.766560189563;17901.462956111336;18910.62178756417;22178.44553886166;22776.772232277795;22955.655665165035;970.9126178643955;984.8768903887014;1001.7595777013464;1033.18894667687;1038.127972503101;11104.222704410613;11974.042076633848;11978.58074967372;11979.640610781691;12006.939965300118;648549.7572512107;660078;665550;674056;676004;101395;129009;138643.30678346544;138980.30509847385;144434.5556544442;941.3928016429502;990.1732053735212;1001.1240164855751;1004.5529739265957;1069.4438891776388;19646.312379066665;21125.260615878986;21330.76027017141;21386.679199811704;21444.03501842452;56902.57743556444;57272;57565.42434575601;58260.70869645794;59981.70009149926;83161;84245;85883.57058214668;86384.56807715922;87460;7271.854562908607;7388.371988381048;7562.546247225184;7800.414968877243;8433.662653494037;7541.698332066656;7570.242975702386;7581.166071731995;10293.84559231597;12438.09685624487;11397.487113079766;11468.885311146782;11657.88342116568;11918.404079795802;12016.63950081499;11254.718632034264;11446.141539384746;11586.073114151017;12016.63950081499;12059.939700301793;9938.204943604349;11324.547018119183;12066.457009434422;12161.635150945485;12240.02079833629;8185.345172386314;8380.832383352421;8445.1977062179;8547.572621368783;8566.314694824274;9257.398269112486;11278.830817537579;11643.243189192675;11953.94023029914;12069.517219311128 07:37:57 INFO - PROCESS | 6784 | |3;mootools.html;1594.0356084568837;1616.6685829404973;1867.6638205123081;1967.08530533302;1969.4091772468255;8749.562521873904;9349.270384978776;9350.392224505402;9361.157495825375;9367.76580585484;8159.632816523256;8416;8859.955700221506;9030.277577793777;9098.13567711068;2410.614301711722;2442.4992876460283;2445.58425067738;2447.5227330670436;2451.656768052474;2396.281115665302;2435.1355268879556;2444.5721998650256;2445.4742230420457;2445.8654773987423;2408.8313818032743;2416.4442178299;2426.1387207541334;2434.537437886796;2437.9512409751796;2360.704911886014;2406.867622280774;2410.987945060272;2419.52819200256;2420.721617014048;2371.786539211462;2393.9281821545383;2394.257780088161;2397.4006498375406;2398.424378149249;9135.269178465707;9219.723408297761;9231.815363692756;9248.583813728399;9269.073092690744;5356.705381204032;5551.056320425538;5554.055810512223;5556.138798486241;5557.499825015768;3523.2953409317984;3664.285464334456;3674.614165512636;3736.9626303736886;3748;1098.0666433531514;1099.8020356335855;1111.5664890692635;1116.5198964445285;1129.5651174297955;10283.691489255334;11361.943190283995;12201.511939522408;12276.447559859827;12276.815847762291;4165.708400411942;4271.252530807096;4504.459464864237;4558.589726924526;4682.250839865674;9454.196393306645;9456.290778191664;9469.242460603273;9469.337146399686;9491.240700743923;5261.894762104736;5266.815661451831;5270.183121616155;5276.577873770166;5280.735963201824;4482.4172857528965;4675.976620116946;4864.148773964542;4938.481459446706;4964.826231081967;3798.848046078181;3839.1745774658584;3859.4755071746504;3922.2547715933883;3949.743266687656;1014.8680671512656;1178.8408564843808;1187.8633957094871;1188.976220475603;1196.3599474281218;928.9117533834275;932.1843387036344;932.9346945713735;954.4273435938381;995.1093771074848;5866.941330586725;5939.287285525765;6132.877342453126;6143.447089761943;6257.559551774812;430.4425768629632;433.0883490252972;433.1899348218851;434.4287262250131;437.3832895617164;3997.600239975979;4023.6982226333143;4064.146529228895;4104.056067104582;4119.423280740699;10753.56985720578;11236.269642473377;11259.042981346514;11264.929831665979;11278.15413843965;11114.610988615359;11166.385848778233;11167.939045790637;11181.105511559055;11191.552337906392;8342.874856877152;8370.455920365157;8442.831143377098;8445.535495547681;8455.492670439793;5980.730867110903;5987.461128498456;6002.879942401215;6090.1169330445955;6276.372362763687;9506.762330941781;10072.194224461879;10356.275060745675;10393.42836143989;10535.63125290611;10396.272260941661;10502.10732087781;10642.946785266024;10657.25399222047;10682.786344273227;6725.663716814238;6939.895901561582;7072.893906591508;7075.6815943281645;7213.891791623025;8596.484210947363;8744.519051452231;8770.298376130022;8785.956070219609;8800.03199648051;7613.581253031137;7788.961055194688;7803.297703206738;7867.370610351272;7891.092524359542;6445.871082578416;7655.157932627518;7655.387568994356;7688.577128258;7862.01724784402;7141.607211603412;7237.095363079615;7238.23998480152;7250.528715633467;7257.637118144177;4958.281049247911;5009;5075.923861142012;5128.153854614096;5285;5048.192289233852;5116.053530096944;5119.513646203604;5146.76839542214;5168.819091331786 07:37:57 INFO - PROCESS | 6784 | |4;prototype.html;300.11316559566484;300.9077050307384;310.0342433320207;319.88644031368847;321.3909641229868;4371.956280437194;4812.374391329126;5325.387580428247;5378.1663842104435;5384.3807962084375;4395.978020109903;4593.88515287118;5327.307450031501;5331.600129990247;5343.332083489564;3626.41977283634;3896.415537669358;4044;4096.959030409687;4138.544760076405;3542.8051457169845;4077.4903137107863;4082.6733861291027;4117.155983023488;4161.396597493362;4053.432519447265;4055.4930633670792;4067.2882245607047;4118.485189351331;4124.835006599732;3518.7888726676483;3644.2893635741043;4061.939070913939;4088.550259471456;4111.7944102794745;2981.6720160782297;3539.982300088509;3560.5193298904574;3579.5658951912687;3587.7847329160336;4337.132573485284;4432.335149727534;5199.9600079983775;5254.21186821976;5294.947050529522;3893.6495715385468;4118.402831589403;4130.421740956269;4131.814068366931;4461.933071003938;4670.229412146991;5604.355499117612;5615.9157612635845;5630.4369563043365;5662.405447428002;3387.136280248521;3829.6744776694013;3865.8067096645054;3937.3503371943593;3963.7621742695533;4667;5645.830625081254;5679.545636349082;6001.819945401645;6105.908411373833;3312.966870331266;3910.441498236591;3918.7256892017285;3930.194310166409;3942.8225729842225;3391.5251864738957;3989.6608788252743;4013.5986401359633;4017.0961533655277;4018.3570628699267;3883.669888059547;4667.8599642010795;4672.929906051412;4676.742779147112;4678.953210467852;4697.389339385858;5311.282976798083;5664.405237450091;5691.630044047124;5742.210141306718;1181.5628217559386;1196.4735516372941;1196.6111013920336;1253.858988320642;1357.267075779088;761.4974117082699;817.9631916563769;914.1726737302619;914.6387177065155;920.8112336970905;4719.507564402317;4885.926711099336;4955.281484184773;5075.3402057732255;5196.714180720021;5145.614078944094;5761.769529218868;5785.739641716133;5786.363500014995;5809.419058094157;4509.458864936229;4867.637061622751;5134.8973020540125;5200.375954885362;5202.8959420812125;2881.654201495834;2964.6214510252835;3033.2871775132826;3036.9240768980953;3049.1919641295076;5171.456997015334;5352.839414817562;5942.6731529766275;6004.42957918997;6005.579609427386;4732.337472753748;5154.969006198775;5569.526590239875;5943.851403714855;5956.8808623828145;4581.518940511199;5527.005139074925;5911.9113213301835;5928.940710593011;5939.693267481233;4363.454568178978;4923.6878024568805;5753.899826563358;5774.682392468455;5792.594518383673;4984.775685094107;5226.947730522798;5577.163425486047;5684.63049901755;5737.713114344349;5209.843704688866;5729.369769325454;5944.375840536854;5959.582829201914;6060;3008.3080891394666;3285.1110611399017;3342.431786596333;3373.4771110477914;3387.3394688036583;4466.436747138476;4499.572540608637;4499.87503124219;4725.810967561259;4763.2378819390115;3907.374820028782;4372.606465418127;4623.12160689468;4723.763811809327;4785.660015195751;3920.725549211528;4199.853005144805;4600.412857564135;4621.4223222097235;4640.310952792471;4307.3754305626135;4870.149343249543;5577.693226872561;5595.363014040906;5607.775688972395;4851.223804191312;4968.341937063654;5092.516210959953;5336.919946200887;5662.801801936913;5038.949610503994;5480.451954804487;5554.000279949648;5555.611087232529;5565.666060036411 07:37:57 INFO - PROCESS | 6784 | |5;yui.html;721.6067243352372;747.5365273530408;770.0566805663061;780.992190078099;848.5417874347852;15801.762973555391;16831.158442077907;16861.24124414403;16896.408625698103;16919;15070.623234419145;15189.164595947219;15189.924050379761;15192.468263610748;15208.315625796839;715.6028404235647;733.1861633586715;888.9955550222272;896.7354630384052;911.9817603647923;886.8049029213595;899.4153800029967;899.95050272235;901.900790912999;904.3940559824933;960.3757557587554;962.6630679262272;964.0504103458088;965.3098034905041;966.367029595616;747.0363231431447;940.4404379394249;956.531299663167;957.9701820542945;958.9808203835919;720.6180724216174;720.7153174496098;724.2974314914525;724.463896716426;724.7064938699835;15373;15439.073655580702;15449.304781284869;15454.690906181926;15466.45867394647;14145.434182632682;14444.133351998913;14479;14487.203203823785;14518.637034074127;11519.36643484608;11731.17881748278;12749.450109977948;13061.673458163526;13594.456221751117;4597.3333866589155;4638.2578787394195;4645.419322584677;4700;4825.662203645747;111951.88048119497;114300;114684.42657786657;117885;118382.40808795988;10755.946220268848;11041.779164416666;11165;11202.66392008241;11557.768844623228;21816.89091554564;22612.547749044927;22809.087636494354;22911.885440573024;22984.65523017156;8381.49711017341;8472.279856212292;8485.448445851;8699.521526316114;8727.170918762708;4523.88690282739;4525.592696657317;4682.180618391768;4756.524347565216;4811.08589368026;3446.5347178131133;3579.534660494119;3603.8017909015257;3609;3904.668103211202;726.6875243645284;728.1709912153285;732.5311800447718;746.58191412809;748.76413929612;559.3092530724552;591.2018774654182;611.4222060153113;656.3075954867566;701.2636731431976;7038;7916.683332666629;9379.577918993662;9558.378705384128;9646.324757266924;561.0894902855782;634.7461015593707;661.4675186474838;694.8132193177951;734.4638413957734;4207.221663992172;4680.929786053144;4696.412948381452;4765.332853400527;4768.213244814636;90560;108879;148355.77466338014;152167.71748423783;158574;70464.29535704578;107390.46304768583;110029;116005.8399416012;125447.3727631356;103887;107658;108781.45609272062;114394.28408574045;116080;9537.52312384364;10125.443100629536;10189;10193.082622564005;10212.948935255276;56749.43250567441;57657.135142973726;58171.836563269346;60324;60712.57149713962;89590;90164.54917725369;91260;92007;96755;13207.66980825487;13218.272994985367;13478.52825151115;13758.243296618783;13770.793438098237;14591.854081459049;15231.238438078273;16340.836591633932;16398.67202655964;16409.261583228545;14376.640583985483;14513.274336283355;14601.26993650292;14844.257787110817;15225.238738063274;14257.786133207803;14410.639734006734;14750.409983600535;15243.16162611067;15369.538913832603;13338.466461341826;14106.365213565208;14309.856901430852;14821.036632618845;15017.02389344689;11405.372704501333;11423.828642570534;11431.314121152758;11653.126015548698;11768.352740599006;10936.781264374828;11126;11372.886271137513;11389.202755807013;11403.429828508708 07:37:57 INFO - PROCESS | 6784 | __end_tp_report 07:37:57 INFO - PROCESS | 6784 | __start_cc_report 07:37:57 INFO - PROCESS | 6784 | _x_x_mozilla_cycle_collect,1275 07:37:57 INFO - PROCESS | 6784 | __end_cc_report 07:37:57 INFO - PROCESS | 6784 | __startTimestamp1474123077823__endTimestamp 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | ------- Summary: start ------- 07:37:57 INFO - PROCESS | 6784 | Number of tests: 6 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#0] dojo.html Cycles:165 Average:5788.26 Median:5995.97 stddev:642.11 (10.7%) stddev-sans-first:613.04 07:37:57 INFO - PROCESS | 6784 | Values: 3274.3 3276.5 3306.9 3324.2 3373.3 5705.5 5840.4 5857.4 5861.3 5892.4 5763.2 5840.5 5856.7 5858.9 5884.6 5124.9 5213.4 5220.2 5246.5 5358.3 5279.7 5313.8 5323.1 5331.8 5339.3 5244.5 5258.7 5271.9 5277.1 5318.1 5341.4 5348.4 5356.8 5361.8 5412.7 5043.6 5138.6 5211.2 5263.5 5275.1 5678.0 5678.5 5681.5 5721.4 5780.0 6270.6 6277.0 6292.8 6296.4 6305.7 6130.2 6168.6 6232.0 6251.7 6255.7 5930.3 5938.7 5956.1 5969.9 6000.3 6344.6 6354.6 6360.7 6374.7 6376.6 6136.6 6138.5 6217.9 6218.0 6269.5 6263.2 6274.1 6287.8 6290.7 6294.9 6232.7 6281.1 6293.2 6313.6 6315.2 5087.8 6192.6 6217.1 6259.6 6295.5 4945.8 4969.8 5039.4 5040.2 5050.3 4493.0 4524.8 4538.4 4564.2 4574.7 5867.1 5871.1 5871.5 5887.4 5905.4 6184.7 6184.8 6188.2 6207.5 6257.8 5856.4 5869.4 5882.0 5929.6 5932.6 5520.3 5521.3 5521.9 5525.5 5540.0 6115.5 6243.1 6251.5 6287.9 6411.2 6152.8 6243.1 6247.4 6249.6 6256.1 6392.1 6404.5 6404.7 6419.4 6419.7 6091.4 6223.3 6249.2 6272.1 6282.4 6189.0 6207.8 6237.7 6255.6 6267.9 6181.8 6196.9 6208.6 6213.7 6215.5 6036.0 6125.7 6157.9 6189.7 6234.8 5292.6 5996.0 6045.4 6058.3 6078.5 5219.2 5247.7 6205.1 6209.1 6233.8 5694.7 5843.2 5915.2 5928.9 5997.7 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#1] ext.html Cycles:180 Average:73775.77 Median:35854.37 stddev:160028.29 (446.3%) stddev-sans-first:160382.64 07:37:57 INFO - PROCESS | 6784 | Values: 498.3 514.5 525.2 548.0 555.8 30517.7 32725.0 41697.0 41943.0 42132.7 43529.0 43760.3 43863.8 43927.8 43963.6 9717.3 9885.5 9900.0 9901.0 9910.0 8730.0 8910.8 11850.8 12047.2 12053.7 12158.3 12222.7 12238.1 12261.9 12288.8 12011.6 12066.3 12069.5 12077.5 12078.9 11941.2 11969.6 12017.8 12020.0 12031.2 41766.6 42032.0 42198.4 42201.6 42251.2 3428.7 3539.6 3563.6 4016.6 4174.2 30964.7 30989.5 31064.4 32565.9 32589.5 3126.9 3328.0 3373.4 3389.6 3395.4 540458.3 542347.0 542662.0 543939.0 546069.0 65434.0 65603.0 65772.0 65779.0 65898.7 68017.0 68156.7 68228.3 68235.0 68240.6 14877.6 17886.4 17897.4 17911.0 17914.5 12351.3 12525.2 13552.1 13626.9 13639.7 16724.0 16739.5 19403.9 20349.3 20361.7 7939.6 7957.9 7994.8 7996.9 8089.4 868.6 947.3 1013.5 1042.8 1114.8 27380.5 29906.0 31856.1 31904.4 32025.0 1070.4 1090.4 1129.4 1140.2 1207.7 15757.8 16112.6 16116.4 16186.3 16471.5 666242.0 667854.0 669234.0 669543.0 669640.7 61663.0 62093.4 62110.4 62242.0 62255.8 1311.3 1423.4 1464.7 1489.2 1490.2 31166.7 31296.7 32051.8 32123.9 32569.0 51578.7 52297.0 52448.2 52461.0 52643.0 576365.0 578681.0 579586.0 580555.0 581197.0 27697.3 28419.0 28432.6 29063.0 29064.3 39620.6 49001.8 49213.3 50082.5 50629.7 33995.5 40029.0 40426.0 41207.6 42801.4 40385.2 40426.6 40778.0 41375.0 42044.4 38466.0 38729.8 38985.9 39444.2 39827.8 32943.0 35073.0 35772.5 35830.1 35878.6 26256.6 26484.0 26532.9 26570.3 27133.7 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#2] jquery.html Cycles:180 Average:37136.42 Median:22714.89 stddev:110477.85 (486.4%) stddev-sans-first:110753.19 07:37:57 INFO - PROCESS | 6784 | Values: 321.5 368.2 375.7 378.2 398.3 15973.8 17164.4 21088.5 21721.7 21879.9 21455.2 21493.5 22093.0 22653.0 22988.2 5831.7 5919.8 6134.9 6203.2 6220.4 3932.7 6196.2 6253.7 6255.0 6273.1 4694.2 6866.4 6886.0 6928.6 6955.0 6116.1 6202.7 6567.8 6891.7 6915.0 6021.4 6051.2 6053.5 6060.2 6068.4 13334.6 14857.6 20651.8 26083.9 26402.5 2134.8 2172.9 2190.2 2235.5 2255.5 10196.1 10524.9 10882.3 11396.4 16352.8 1877.4 1880.1 1881.1 1908.2 1962.6 82063.0 98347.5 114236.4 125921.7 132503.3 11307.1 11426.9 11602.5 11658.9 11819.5 14588.3 14591.8 14623.2 14624.6 14625.4 8517.4 8778.9 8788.4 8901.9 8902.6 8929.7 8933.6 8955.1 9095.2 9097.7 4361.1 4488.3 4498.0 4505.3 4566.2 1642.8 1732.5 1891.0 1924.8 1937.8 798.5 934.6 973.5 1026.1 1085.8 17901.5 18910.6 22178.4 22776.8 22955.7 970.9 984.9 1001.8 1033.2 1038.1 11104.2 11974.0 11978.6 11979.6 12006.9 648549.8 660078.0 665550.0 674056.0 676004.0 101395.0 129009.0 138643.3 138980.3 144434.6 941.4 990.2 1001.1 1004.6 1069.4 19646.3 21125.3 21330.8 21386.7 21444.0 56902.6 57272.0 57565.4 58260.7 59981.7 83161.0 84245.0 85883.6 86384.6 87460.0 7271.9 7388.4 7562.5 7800.4 8433.7 7541.7 7570.2 7581.2 10293.8 12438.1 11397.5 11468.9 11657.9 11918.4 12016.6 11254.7 11446.1 11586.1 12016.6 12059.9 9938.2 11324.5 12066.5 12161.6 12240.0 8185.3 8380.8 8445.2 8547.6 8566.3 9257.4 11278.8 11643.2 11953.9 12069.5 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#3] mootools.html Cycles:180 Average:5820.21 Median:4773.20 stddev:3305.91 (69.3%) stddev-sans-first:3299.93 07:37:57 INFO - PROCESS | 6784 | Values: 1594.0 1616.7 1867.7 1967.1 1969.4 8749.6 9349.3 9350.4 9361.2 9367.8 8159.6 8416.0 8860.0 9030.3 9098.1 2410.6 2442.5 2445.6 2447.5 2451.7 2396.3 2435.1 2444.6 2445.5 2445.9 2408.8 2416.4 2426.1 2434.5 2438.0 2360.7 2406.9 2411.0 2419.5 2420.7 2371.8 2393.9 2394.3 2397.4 2398.4 9135.3 9219.7 9231.8 9248.6 9269.1 5356.7 5551.1 5554.1 5556.1 5557.5 3523.3 3664.3 3674.6 3737.0 3748.0 1098.1 1099.8 1111.6 1116.5 1129.6 10283.7 11361.9 12201.5 12276.4 12276.8 4165.7 4271.3 4504.5 4558.6 4682.3 9454.2 9456.3 9469.2 9469.3 9491.2 5261.9 5266.8 5270.2 5276.6 5280.7 4482.4 4676.0 4864.1 4938.5 4964.8 3798.8 3839.2 3859.5 3922.3 3949.7 1014.9 1178.8 1187.9 1189.0 1196.4 928.9 932.2 932.9 954.4 995.1 5866.9 5939.3 6132.9 6143.4 6257.6 430.4 433.1 433.2 434.4 437.4 3997.6 4023.7 4064.1 4104.1 4119.4 10753.6 11236.3 11259.0 11264.9 11278.2 11114.6 11166.4 11167.9 11181.1 11191.6 8342.9 8370.5 8442.8 8445.5 8455.5 5980.7 5987.5 6002.9 6090.1 6276.4 9506.8 10072.2 10356.3 10393.4 10535.6 10396.3 10502.1 10642.9 10657.3 10682.8 6725.7 6939.9 7072.9 7075.7 7213.9 8596.5 8744.5 8770.3 8786.0 8800.0 7613.6 7789.0 7803.3 7867.4 7891.1 6445.9 7655.2 7655.4 7688.6 7862.0 7141.6 7237.1 7238.2 7250.5 7257.6 4958.3 5009.0 5075.9 5128.2 5285.0 5048.2 5116.1 5119.5 5146.8 5168.8 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#4] prototype.html Cycles:180 Average:4384.03 Median:4677.85 stddev:1357.30 (29.0%) stddev-sans-first:1326.05 07:37:57 INFO - PROCESS | 6784 | Values: 300.1 300.9 310.0 319.9 321.4 4372.0 4812.4 5325.4 5378.2 5384.4 4396.0 4593.9 5327.3 5331.6 5343.3 3626.4 3896.4 4044.0 4097.0 4138.5 3542.8 4077.5 4082.7 4117.2 4161.4 4053.4 4055.5 4067.3 4118.5 4124.8 3518.8 3644.3 4061.9 4088.6 4111.8 2981.7 3540.0 3560.5 3579.6 3587.8 4337.1 4432.3 5200.0 5254.2 5294.9 3893.6 4118.4 4130.4 4131.8 4461.9 4670.2 5604.4 5615.9 5630.4 5662.4 3387.1 3829.7 3865.8 3937.4 3963.8 4667.0 5645.8 5679.5 6001.8 6105.9 3313.0 3910.4 3918.7 3930.2 3942.8 3391.5 3989.7 4013.6 4017.1 4018.4 3883.7 4667.9 4672.9 4676.7 4679.0 4697.4 5311.3 5664.4 5691.6 5742.2 1181.6 1196.5 1196.6 1253.9 1357.3 761.5 818.0 914.2 914.6 920.8 4719.5 4885.9 4955.3 5075.3 5196.7 5145.6 5761.8 5785.7 5786.4 5809.4 4509.5 4867.6 5134.9 5200.4 5202.9 2881.7 2964.6 3033.3 3036.9 3049.2 5171.5 5352.8 5942.7 6004.4 6005.6 4732.3 5155.0 5569.5 5943.9 5956.9 4581.5 5527.0 5911.9 5928.9 5939.7 4363.5 4923.7 5753.9 5774.7 5792.6 4984.8 5226.9 5577.2 5684.6 5737.7 5209.8 5729.4 5944.4 5959.6 6060.0 3008.3 3285.1 3342.4 3373.5 3387.3 4466.4 4499.6 4499.9 4725.8 4763.2 3907.4 4372.6 4623.1 4723.8 4785.7 3920.7 4199.9 4600.4 4621.4 4640.3 4307.4 4870.1 5577.7 5595.4 5607.8 4851.2 4968.3 5092.5 5336.9 5662.8 5038.9 5480.5 5554.0 5555.6 5565.7 07:37:57 INFO - PROCESS | 6784 | 07:37:57 INFO - PROCESS | 6784 | [#5] yui.html Cycles:180 Average:24160.13 Median:22948.27 stddev:37160.46 (161.9%) stddev-sans-first:37223.03 07:37:57 INFO - PROCESS | 6784 | Values: 721.6 747.5 770.1 781.0 848.5 15801.8 16831.2 16861.2 16896.4 16919.0 15070.6 15189.2 15189.9 15192.5 15208.3 715.6 733.2 889.0 896.7 912.0 886.8 899.4 900.0 901.9 904.4 960.4 962.7 964.1 965.3 966.4 747.0 940.4 956.5 958.0 959.0 720.6 720.7 724.3 724.5 724.7 15373.0 15439.1 15449.3 15454.7 15466.5 14145.4 14444.1 14479.0 14487.2 14518.6 11519.4 11731.2 12749.5 13061.7 13594.5 4597.3 4638.3 4645.4 4700.0 4825.7 111951.9 114300.0 114684.4 117885.0 118382.4 10755.9 11041.8 11165.0 11202.7 11557.8 21816.9 22612.5 22809.1 22911.9 22984.7 8381.5 8472.3 8485.4 8699.5 8727.2 4523.9 4525.6 4682.2 4756.5 4811.1 3446.5 3579.5 3603.8 3609.0 3904.7 726.7 728.2 732.5 746.6 748.8 559.3 591.2 611.4 656.3 701.3 7038.0 7916.7 9379.6 9558.4 9646.3 561.1 634.7 661.5 694.8 734.5 4207.2 4680.9 4696.4 4765.3 4768.2 90560.0 108879.0 148355.8 152167.7 158574.0 70464.3 107390.5 110029.0 116005.8 125447.4 103887.0 107658.0 108781.5 114394.3 116080.0 9537.5 10125.4 10189.0 10193.1 10212.9 56749.4 57657.1 58171.8 60324.0 60712.6 89590.0 90164.5 91260.0 92007.0 96755.0 13207.7 13218.3 13478.5 13758.2 13770.8 14591.9 15231.2 16340.8 16398.7 16409.3 14376.6 14513.3 14601.3 14844.3 15225.2 14257.8 14410.6 14750.4 15243.2 15369.5 13338.5 14106.4 14309.9 14821.0 15017.0 11405.4 11423.8 11431.3 11653.1 11768.4 10936.8 11126.0 11372.9 11389.2 11403.4 07:37:57 INFO - PROCESS | 6784 | -------- Summary: end -------- 07:37:57 INFO - PROCESS | 6784 | 07:37:58 INFO - TEST-INFO | 6784: exit 0 07:37:58 INFO - TEST-OK | dromaeo_css | took 1116886ms 07:37:58 INFO - TEST-START | kraken 07:37:58 INFO - operating with platform_type : mac_ 07:37:58 INFO - Initialising browser for kraken test... 07:37:58 INFO - TEST-INFO | started process 6788 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpeAp7HD/profile http://localhost:59088/getInfo.html) 07:38:00 INFO - PROCESS | 6788 | __metrics Screen width/height:1600/1200 07:38:00 INFO - PROCESS | 6788 | colorDepth:24 07:38:00 INFO - PROCESS | 6788 | Browser inner width/height: 1024/689 07:38:00 INFO - PROCESS | 6788 | __metrics 07:38:01 INFO - PROCESS | 6788 | 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:38:01 INFO - PROCESS | 6788 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:38:01 INFO - TEST-INFO | 6788: exit 0 07:38:01 INFO - Browser initialized. 07:38:01 INFO - Running cycle 1/1 for kraken test... 07:38:01 INFO - TEST-INFO | started process 6789 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpeAp7HD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:13 INFO - PROCESS | 6789 | RSS: Main: 215908352 07:38:13 INFO - PROCESS | 6789 | 07:38:16 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59088/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:17 INFO - PROCESS | 6789 | RSS: Main: 279330816 07:38:17 INFO - PROCESS | 6789 | 07:38:19 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59088/tests/kraken/driver.html?testName=audio-dft) 07:38:20 INFO - PROCESS | 6789 | RSS: Main: 282017792 07:38:20 INFO - PROCESS | 6789 | 07:38:23 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59088/tests/kraken/driver.html?testName=audio-fft) 07:38:24 INFO - PROCESS | 6789 | RSS: Main: 378753024 07:38:24 INFO - PROCESS | 6789 | 07:38:26 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59088/tests/kraken/driver.html?testName=audio-oscillator) 07:38:27 INFO - PROCESS | 6789 | RSS: Main: 284860416 07:38:27 INFO - PROCESS | 6789 | 07:38:29 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59088/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:29 INFO - PROCESS | 6789 | RSS: Main: 285040640 07:38:29 INFO - PROCESS | 6789 | 07:38:32 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59088/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:33 INFO - PROCESS | 6789 | RSS: Main: 322428928 07:38:33 INFO - PROCESS | 6789 | 07:38:36 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59088/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:36 INFO - PROCESS | 6789 | RSS: Main: 324669440 07:38:36 INFO - PROCESS | 6789 | 07:38:39 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59088/tests/kraken/driver.html?testName=json-parse-financial) 07:38:40 INFO - PROCESS | 6789 | RSS: Main: 322904064 07:38:40 INFO - PROCESS | 6789 | 07:38:41 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59088/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:42 INFO - PROCESS | 6789 | RSS: Main: 298262528 07:38:42 INFO - PROCESS | 6789 | 07:38:44 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:44 INFO - PROCESS | 6789 | RSS: Main: 303362048 07:38:44 INFO - PROCESS | 6789 | 07:38:47 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:47 INFO - PROCESS | 6789 | RSS: Main: 313860096 07:38:47 INFO - PROCESS | 6789 | 07:38:50 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:51 INFO - PROCESS | 6789 | RSS: Main: 320856064 07:38:51 INFO - PROCESS | 6789 | 07:38:54 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:55 INFO - PROCESS | 6789 | RSS: Main: 323284992 07:38:55 INFO - PROCESS | 6789 | 07:38:56 INFO - PROCESS | 6789 | Cycle 1(1): loaded http://localhost:59088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59088/tests/kraken/driver.html?testName=ai-astar) 07:38:57 INFO - PROCESS | 6789 | RSS: Main: 366632960 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | __start_tp_report 07:38:57 INFO - PROCESS | 6789 | _x_x_mozilla_page_load 07:38:57 INFO - PROCESS | 6789 | _x_x_mozilla_page_load_details 07:38:57 INFO - PROCESS | 6789 | |i|pagename|runs| 07:38:57 INFO - PROCESS | 6789 | |0;ai-astar;114;115;127;114;112;112;115;162;167;113 07:38:57 INFO - PROCESS | 6789 | |1;audio-beat-detection;146;108;133;157;109;131;127;107;159;111 07:38:57 INFO - PROCESS | 6789 | |2;audio-dft;185;187;186;185;184;184;185;186;187;186 07:38:57 INFO - PROCESS | 6789 | |3;audio-fft;73;81;79;165;58;58;58;66;142;58 07:38:57 INFO - PROCESS | 6789 | |4;audio-oscillator;85;85;136;71;76;87;124;71;70;120 07:38:57 INFO - PROCESS | 6789 | |5;imaging-gaussian-blur;88;85;88;85;90;84;87;84;87;85 07:38:57 INFO - PROCESS | 6789 | |6;imaging-darkroom;94;93;95;95;94;93;94;94;93;112 07:38:57 INFO - PROCESS | 6789 | |7;imaging-desaturate;85;88;84;85;84;83;83;84;86;84 07:38:57 INFO - PROCESS | 6789 | |8;json-parse-financial;57;57;57;57;57;58;56;58;56;57 07:38:57 INFO - PROCESS | 6789 | |9;json-stringify-tinderbox;67;67;67;75;74;80;72;71;72;72 07:38:57 INFO - PROCESS | 6789 | |10;stanford-crypto-aes;83;81;86;83;81;82;89;83;88;86 07:38:57 INFO - PROCESS | 6789 | |11;stanford-crypto-ccm;137;135;137;147;149;133;145;149;244;137 07:38:57 INFO - PROCESS | 6789 | |12;stanford-crypto-pbkdf2;162;164;164;166;162;166;215;218;172;162 07:38:57 INFO - PROCESS | 6789 | |13;stanford-crypto-sha256-iterative;68;66;69;67;66;67;65;65;66;65 07:38:57 INFO - PROCESS | 6789 | __end_tp_report 07:38:57 INFO - PROCESS | 6789 | __start_cc_report 07:38:57 INFO - PROCESS | 6789 | _x_x_mozilla_cycle_collect,2184 07:38:57 INFO - PROCESS | 6789 | __end_cc_report 07:38:57 INFO - PROCESS | 6789 | __startTimestamp1474123137041__endTimestamp 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | ------- Summary: start ------- 07:38:57 INFO - PROCESS | 6789 | Number of tests: 14 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#0] ai-astar Cycles:10 Average:125.10 Median:115.00 stddev:21.24 (18.5%) stddev-sans-first:22.15 07:38:57 INFO - PROCESS | 6789 | Values: 114.0 115.0 127.0 114.0 112.0 112.0 115.0 162.0 167.0 113.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:132.00 stddev:20.13 (15.2%) stddev-sans-first:20.36 07:38:57 INFO - PROCESS | 6789 | Values: 146.0 108.0 133.0 157.0 109.0 131.0 127.0 107.0 159.0 111.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#2] audio-dft Cycles:10 Average:185.50 Median:186.00 stddev:1.08 (0.6%) stddev-sans-first:1.13 07:38:57 INFO - PROCESS | 6789 | Values: 185.0 187.0 186.0 185.0 184.0 184.0 185.0 186.0 187.0 186.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#3] audio-fft Cycles:10 Average:83.80 Median:62.00 stddev:38.16 (61.6%) stddev-sans-first:40.28 07:38:57 INFO - PROCESS | 6789 | Values: 73.0 81.0 79.0 165.0 58.0 58.0 58.0 66.0 142.0 58.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:73.50 stddev:24.69 (33.6%) stddev-sans-first:26.04 07:38:57 INFO - PROCESS | 6789 | Values: 85.0 85.0 136.0 71.0 76.0 87.0 124.0 71.0 70.0 120.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.00 (2.3%) stddev-sans-first:2.03 07:38:57 INFO - PROCESS | 6789 | Values: 88.0 85.0 88.0 85.0 90.0 84.0 87.0 84.0 87.0 85.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:94.00 stddev:5.77 (6.1%) stddev-sans-first:6.09 07:38:57 INFO - PROCESS | 6789 | Values: 94.0 93.0 95.0 95.0 94.0 93.0 94.0 94.0 93.0 112.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.51 (1.8%) stddev-sans-first:1.59 07:38:57 INFO - PROCESS | 6789 | Values: 85.0 88.0 84.0 85.0 84.0 83.0 83.0 84.0 86.0 84.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 07:38:57 INFO - PROCESS | 6789 | Values: 57.0 57.0 57.0 57.0 57.0 58.0 56.0 58.0 56.0 57.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:4.11 (5.7%) stddev-sans-first:3.99 07:38:57 INFO - PROCESS | 6789 | Values: 67.0 67.0 67.0 75.0 74.0 80.0 72.0 71.0 72.0 72.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:2.86 (3.4%) stddev-sans-first:3.00 07:38:57 INFO - PROCESS | 6789 | Values: 83.0 81.0 86.0 83.0 81.0 82.0 89.0 83.0 88.0 86.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:146.00 stddev:33.13 (22.7%) stddev-sans-first:34.73 07:38:57 INFO - PROCESS | 6789 | Values: 137.0 135.0 137.0 147.0 149.0 133.0 145.0 149.0 244.0 137.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:166.00 stddev:22.03 (13.3%) stddev-sans-first:22.85 07:38:57 INFO - PROCESS | 6789 | Values: 162.0 164.0 164.0 166.0 162.0 166.0 215.0 218.0 172.0 162.0 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - PROCESS | 6789 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.35 (2.0%) stddev-sans-first:1.30 07:38:57 INFO - PROCESS | 6789 | Values: 68.0 66.0 69.0 67.0 66.0 67.0 65.0 65.0 66.0 65.0 07:38:57 INFO - PROCESS | 6789 | -------- Summary: end -------- 07:38:57 INFO - PROCESS | 6789 | 07:38:57 INFO - TEST-INFO | 6789: exit 0 07:38:57 INFO - TEST-OK | kraken | took 59456ms 07:38:57 INFO - SUITE-END | took 1176s 07:38:58 INFO - Completed test suite (00:19:36) 07:38:58 INFO - javascript benchmark 07:38:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3274.2632907595794, 3276.475763877779, 3306.933861322772, 3324.16895776056, 3373.3421982713367, 5705.515031222346, 5840.445157710011, 5857.41425857414, 5861.325947516037, 5892.3812999635065, 5763.193152958579, 5840.503557197632, 5856.707164641773, 5858.88282234355, 5884.646921184732, 5124.92312615311, 5213.400458947212, 5220.216967454874, 5246.475352464742, 5358.276632654581, 5279.656822306557, 5313.814016509423, 5323.068463018956, 5331.786728530854, 5339.252504649352, 5244.527992480676, 5258.684478931257, 5271.920921186185, 5277.050130976423, 5318.069337865877, 5341.385740639818, 5348.3849357323625, 5356.785728570852, 5361.839144825662, 5412.702301373423, 5043.571296439807, 5138.614603904722, 5211.1922651989, 5263.499967503081, 5275.129603615399, 5678.006348888928, 5678.4605462480995, 5681.488666020036, 5721.370649228582, 5780.046292361755, 6270.561060725751, 6277.0, 6292.779752708633, 6296.433321001132, 6305.747770089237, 6130.2452696569835, 6168.568200225986, 6232.0, 6251.687415629201, 6255.7184926677955, 5930.28836539618, 5938.673372964528, 5956.136360227742, 5969.8806023879715, 6000.279966404016, 6344.587601805868, 6354.555181137276, 6360.681965901779, 6374.745010199633, 6376.5855219411515, 6136.631802091889, 6138.541671868701, 6217.875642487125, 6218.036204388327, 6269.529785266123, 6263.248410190807, 6274.058891166362, 6287.779927702509, 6290.685465726695, 6294.937050629526, 6232.657203853832, 6281.057841323746, 6293.244810622755, 6313.621182729051, 6315.179026726496, 5087.796488140433, 6192.628442293387, 6217.129601855744, 6259.624422534663, 6295.496360291166, 4945.8021679132435, 4969.801207951714, 5039.395272567315, 5040.168372218616, 5050.267711181858, 4492.989077457546, 4524.7511386873375, 4538.364628951949, 4564.178447879347, 4574.656900732459, 5867.14926335679, 5871.1486834409625, 5871.471567558855, 5887.4406931341455, 5905.409459054146, 6184.690765461709, 6184.845378865474, 6188.164597779332, 6207.503399728011, 6257.843553911189, 5856.385079566669, 5869.354371019184, 5881.9705901470215, 5929.584929054925, 5932.584719069624, 5520.254765606672, 5521.282233309725, 5521.944780552143, 5525.475079867487, 5539.975104605661, 6115.453310713017, 6243.094751261133, 6251.531135164972, 6287.937120628918, 6411.198600174978, 6152.800203960185, 6243.063540468966, 6247.406496382836, 6249.625022498664, 6256.092866534236, 6392.137061496638, 6404.519661025349, 6404.679766011774, 6419.358064193543, 6419.743210271724, 6091.360407157184, 6223.284322302993, 6249.156363890998, 6272.121902933502, 6282.403171698681, 6189.0, 6207.81376558704, 6237.656928868956, 6255.56211065221, 6267.874642507216, 6181.81454556364, 6196.876062478816, 6208.565400421927, 6213.720382582705, 6215.471684906834, 6036.034234522455, 6125.693715314127, 6157.9384206157365, 6189.690515474118, 6234.781782637768, 5292.603054770831, 5995.970020149872, 6045.365236650088, 6058.303295121117, 6078.483328917092, 5219.243209734643, 5247.711375874211, 6205.131281620667, 6209.130721699056, 6233.781817636361, 5694.743736531949, 5843.240378750906, 5915.171875937458, 5928.911066334094, 5997.70011499432], "value": 5747.08437206602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2750098606526, 514.4906542522903, 525.1702310349646, 548.0, 555.7665780372243, 30517.694823051763, 32725.0, 41696.95757606057, 41943.0, 42132.73601791944, 43529.0, 43760.343594846105, 43863.78068109655, 43927.78036109815, 43963.56036439635, 9717.31978761487, 9885.505724713772, 9900.0, 9901.0, 9910.0, 8729.956350218241, 8910.777230569223, 11850.7629847403, 12047.216930899507, 12053.698657533545, 12158.270503769758, 12222.694432639166, 12238.143329966906, 12261.877381226162, 12288.815667764946, 12011.579594714187, 12066.276023438622, 12069.456874440628, 12077.45651445687, 12078.879211207863, 11941.164118511708, 11969.64091077269, 12017.759644807142, 12020.038396928225, 12031.217970831867, 41766.58233417657, 42032.0, 42198.367024494655, 42201.57798422038, 42251.15497690029, 3428.708559772422, 3539.610642829287, 3563.6080031196548, 4016.598340165989, 4174.186033723425, 30964.690353096405, 30989.535156972663, 31064.378712425623, 32565.860194893303, 32589.511157332658, 3126.8592913318957, 3328.0, 3373.375925453799, 3389.5593572835373, 3395.3548825723033, 540458.2977085089, 542347.0, 542662.0, 543939.0, 546069.0, 65434.0, 65603.0, 65772.0, 65779.0, 65898.67050664716, 68017.0, 68156.6592167036, 68228.31771682319, 68235.0, 68240.63518729598, 14877.553673389815, 17886.373976910745, 17897.37359192422, 17911.0, 17914.462566122776, 12351.25892446456, 12525.248485090924, 13552.051356405154, 13626.93186534061, 13639.659008524866, 16723.996560206182, 16739.497234147155, 19403.90298048529, 20349.287774927805, 20361.694574581394, 7939.563324017119, 7957.880631790527, 7994.840103197904, 7996.880436738861, 8089.39329550286, 868.561376504861, 947.2942657719905, 1013.5135135135176, 1042.8383600541777, 1114.849495318138, 27380.45239095207, 29906.028740625483, 31856.08554272123, 31904.361912761615, 32025.0, 1070.373831308693, 1090.3621381491757, 1129.3957732613012, 1140.196161706012, 1207.673928039442, 15757.842421575637, 16112.597185070466, 16116.35534578627, 16186.271617777229, 16471.505854824376, 666242.0, 667854.0, 669234.0, 669543.0, 669640.6517967477, 61663.0, 62093.379066209665, 62110.37889621136, 62242.0, 62255.754884902046, 1311.2591385866954, 1423.4413317417204, 1464.7290251303543, 1489.2330449818353, 1490.1878476230697, 31166.688333116377, 31296.687033129376, 32051.839740801148, 32123.875664351635, 32569.02292931216, 51578.74210628923, 52297.0, 52448.21327680012, 52461.0, 52643.0, 576365.0, 578681.0, 579586.0, 580555.0, 581197.0, 27697.307567310174, 28419.0, 28432.57351139693, 29062.982795602053, 29064.27339316534, 39620.603793962844, 49001.754991224814, 49213.26180107373, 50082.49917500924, 50629.73425664388, 33995.49006764851, 40029.0, 40426.0, 41207.58792412157, 42801.35797963096, 40385.1537815464, 40426.59573404228, 40778.0, 41374.96562585839, 42044.36933446062, 38466.0383490415, 38729.8381048569, 38985.90647982035, 39444.2111157781, 39827.80516584507, 32943.0, 35073.0, 35772.4634130483, 35830.104247394025, 35878.64121358753, 26256.60615090737, 26484.0, 26532.86733566397, 26570.335741606614, 27133.72866271391], "value": 21222.368758730558, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.5353813739145, 368.2120262637955, 375.67879463059086, 378.18878505605466, 398.2512875793508, 15973.840261597381, 17164.39924602639, 21088.472788180305, 21721.67417488739, 21879.890600546994, 21455.249066282682, 21493.462663433384, 22093.005814738335, 22653.0, 22988.19541316054, 5831.679257640828, 5919.763209471626, 6134.938650613503, 6203.2245969253845, 6220.377962203766, 3932.705047121463, 6196.1944947157035, 6253.656048917308, 6254.968725156391, 6273.05904114382, 4694.155052090622, 6866.416354609864, 6885.965570172142, 6928.61892595907, 6955.02629631852, 6116.082587611894, 6202.689865506707, 6567.835804104888, 6891.689873955684, 6914.9654251728925, 6021.404327853123, 6051.2133422655215, 6053.545984051214, 6060.181875446802, 6068.393160683941, 13334.599962001155, 14857.628559285997, 20651.793482065288, 26083.869580652165, 26402.471950561074, 2134.8292136629034, 2172.8696278223356, 2190.2224710227956, 2235.50818819861, 2255.481239314967, 10196.133328667072, 10524.947375263151, 10882.347059176396, 11396.430178491126, 16352.83647163525, 1877.427384647682, 1880.1257415301814, 1881.0876724788466, 1908.2176307713771, 1962.6271008508481, 82063.0, 98347.50826245823, 114236.42881785621, 125921.74078259284, 132503.33748331293, 11307.095432365391, 11426.88573114275, 11602.535898564132, 11658.883411165949, 11819.527218911147, 14588.270586470633, 14591.78112328316, 14623.19572423527, 14624.634384140269, 14625.414983400759, 8517.44636598619, 8778.868316975251, 8788.42875213109, 8901.866472002925, 8902.643894244287, 8929.73210803677, 8933.553322333857, 8955.104489551122, 9095.226905713089, 9097.727068187965, 4361.127774445124, 4488.304312831517, 4497.977510112429, 4505.279155335131, 4566.223741963808, 1642.7535869619653, 1732.506235722813, 1890.9621807563772, 1924.8363889069585, 1937.7965313642146, 798.5168972771506, 934.5981028457314, 973.5229737428609, 1026.1021606094666, 1085.766560189563, 17901.462956111336, 18910.62178756417, 22178.44553886166, 22776.772232277795, 22955.655665165035, 970.9126178643955, 984.8768903887014, 1001.7595777013464, 1033.18894667687, 1038.127972503101, 11104.222704410613, 11974.042076633848, 11978.58074967372, 11979.640610781691, 12006.939965300118, 648549.7572512107, 660078.0, 665550.0, 674056.0, 676004.0, 101395.0, 129009.0, 138643.30678346544, 138980.30509847385, 144434.5556544442, 941.3928016429502, 990.1732053735212, 1001.1240164855751, 1004.5529739265957, 1069.4438891776388, 19646.312379066665, 21125.260615878986, 21330.76027017141, 21386.679199811704, 21444.03501842452, 56902.57743556444, 57272.0, 57565.42434575601, 58260.70869645794, 59981.70009149926, 83161.0, 84245.0, 85883.57058214668, 86384.56807715922, 87460.0, 7271.854562908607, 7388.371988381048, 7562.546247225184, 7800.414968877243, 8433.662653494037, 7541.698332066656, 7570.242975702386, 7581.166071731995, 10293.84559231597, 12438.09685624487, 11397.487113079766, 11468.885311146782, 11657.88342116568, 11918.404079795802, 12016.63950081499, 11254.718632034264, 11446.141539384746, 11586.073114151017, 12016.63950081499, 12059.939700301793, 9938.204943604349, 11324.547018119183, 12066.457009434422, 12161.635150945485, 12240.02079833629, 8185.345172386314, 8380.832383352421, 8445.1977062179, 8547.572621368783, 8566.314694824274, 9257.398269112486, 11278.830817537579, 11643.243189192675, 11953.94023029914, 12069.517219311128], "value": 9745.367839966257, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1594.0356084568837, 1616.6685829404973, 1867.6638205123081, 1967.08530533302, 1969.4091772468255, 8749.562521873904, 9349.270384978776, 9350.392224505402, 9361.157495825375, 9367.76580585484, 8159.632816523256, 8416.0, 8859.955700221506, 9030.277577793777, 9098.13567711068, 2410.614301711722, 2442.4992876460283, 2445.58425067738, 2447.5227330670436, 2451.656768052474, 2396.281115665302, 2435.1355268879556, 2444.5721998650256, 2445.4742230420457, 2445.8654773987423, 2408.8313818032743, 2416.4442178299, 2426.1387207541334, 2434.537437886796, 2437.9512409751796, 2360.704911886014, 2406.867622280774, 2410.987945060272, 2419.52819200256, 2420.721617014048, 2371.786539211462, 2393.9281821545383, 2394.257780088161, 2397.4006498375406, 2398.424378149249, 9135.269178465707, 9219.723408297761, 9231.815363692756, 9248.583813728399, 9269.073092690744, 5356.705381204032, 5551.056320425538, 5554.055810512223, 5556.138798486241, 5557.499825015768, 3523.2953409317984, 3664.285464334456, 3674.614165512636, 3736.9626303736886, 3748.0, 1098.0666433531514, 1099.8020356335855, 1111.5664890692635, 1116.5198964445285, 1129.5651174297955, 10283.691489255334, 11361.943190283995, 12201.511939522408, 12276.447559859827, 12276.815847762291, 4165.708400411942, 4271.252530807096, 4504.459464864237, 4558.589726924526, 4682.250839865674, 9454.196393306645, 9456.290778191664, 9469.242460603273, 9469.337146399686, 9491.240700743923, 5261.894762104736, 5266.815661451831, 5270.183121616155, 5276.577873770166, 5280.735963201824, 4482.4172857528965, 4675.976620116946, 4864.148773964542, 4938.481459446706, 4964.826231081967, 3798.848046078181, 3839.1745774658584, 3859.4755071746504, 3922.2547715933883, 3949.743266687656, 1014.8680671512656, 1178.8408564843808, 1187.8633957094871, 1188.976220475603, 1196.3599474281218, 928.9117533834275, 932.1843387036344, 932.9346945713735, 954.4273435938381, 995.1093771074848, 5866.941330586725, 5939.287285525765, 6132.877342453126, 6143.447089761943, 6257.559551774812, 430.4425768629632, 433.0883490252972, 433.1899348218851, 434.4287262250131, 437.3832895617164, 3997.600239975979, 4023.6982226333143, 4064.146529228895, 4104.056067104582, 4119.423280740699, 10753.56985720578, 11236.269642473377, 11259.042981346514, 11264.929831665979, 11278.15413843965, 11114.610988615359, 11166.385848778233, 11167.939045790637, 11181.105511559055, 11191.552337906392, 8342.874856877152, 8370.455920365157, 8442.831143377098, 8445.535495547681, 8455.492670439793, 5980.730867110903, 5987.461128498456, 6002.879942401215, 6090.1169330445955, 6276.372362763687, 9506.762330941781, 10072.194224461879, 10356.275060745675, 10393.42836143989, 10535.63125290611, 10396.272260941661, 10502.10732087781, 10642.946785266024, 10657.25399222047, 10682.786344273227, 6725.663716814238, 6939.895901561582, 7072.893906591508, 7075.6815943281645, 7213.891791623025, 8596.484210947363, 8744.519051452231, 8770.298376130022, 8785.956070219609, 8800.03199648051, 7613.581253031137, 7788.961055194688, 7803.297703206738, 7867.370610351272, 7891.092524359542, 6445.871082578416, 7655.157932627518, 7655.387568994356, 7688.577128258, 7862.01724784402, 7141.607211603412, 7237.095363079615, 7238.23998480152, 7250.528715633467, 7257.637118144177, 4958.281049247911, 5009.0, 5075.923861142012, 5128.153854614096, 5285.0, 5048.192289233852, 5116.053530096944, 5119.513646203604, 5146.76839542214, 5168.819091331786], "value": 4584.912067424226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.11316559566484, 300.9077050307384, 310.0342433320207, 319.88644031368847, 321.3909641229868, 4371.956280437194, 4812.374391329126, 5325.387580428247, 5378.1663842104435, 5384.3807962084375, 4395.978020109903, 4593.88515287118, 5327.307450031501, 5331.600129990247, 5343.332083489564, 3626.41977283634, 3896.415537669358, 4044.0, 4096.959030409687, 4138.544760076405, 3542.8051457169845, 4077.4903137107863, 4082.6733861291027, 4117.155983023488, 4161.396597493362, 4053.432519447265, 4055.4930633670792, 4067.2882245607047, 4118.485189351331, 4124.835006599732, 3518.7888726676483, 3644.2893635741043, 4061.939070913939, 4088.550259471456, 4111.7944102794745, 2981.6720160782297, 3539.982300088509, 3560.5193298904574, 3579.5658951912687, 3587.7847329160336, 4337.132573485284, 4432.335149727534, 5199.9600079983775, 5254.21186821976, 5294.947050529522, 3893.6495715385468, 4118.402831589403, 4130.421740956269, 4131.814068366931, 4461.933071003938, 4670.229412146991, 5604.355499117612, 5615.9157612635845, 5630.4369563043365, 5662.405447428002, 3387.136280248521, 3829.6744776694013, 3865.8067096645054, 3937.3503371943593, 3963.7621742695533, 4667.0, 5645.830625081254, 5679.545636349082, 6001.819945401645, 6105.908411373833, 3312.966870331266, 3910.441498236591, 3918.7256892017285, 3930.194310166409, 3942.8225729842225, 3391.5251864738957, 3989.6608788252743, 4013.5986401359633, 4017.0961533655277, 4018.3570628699267, 3883.669888059547, 4667.8599642010795, 4672.929906051412, 4676.742779147112, 4678.953210467852, 4697.389339385858, 5311.282976798083, 5664.405237450091, 5691.630044047124, 5742.210141306718, 1181.5628217559386, 1196.4735516372941, 1196.6111013920336, 1253.858988320642, 1357.267075779088, 761.4974117082699, 817.9631916563769, 914.1726737302619, 914.6387177065155, 920.8112336970905, 4719.507564402317, 4885.926711099336, 4955.281484184773, 5075.3402057732255, 5196.714180720021, 5145.614078944094, 5761.769529218868, 5785.739641716133, 5786.363500014995, 5809.419058094157, 4509.458864936229, 4867.637061622751, 5134.8973020540125, 5200.375954885362, 5202.8959420812125, 2881.654201495834, 2964.6214510252835, 3033.2871775132826, 3036.9240768980953, 3049.1919641295076, 5171.456997015334, 5352.839414817562, 5942.6731529766275, 6004.42957918997, 6005.579609427386, 4732.337472753748, 5154.969006198775, 5569.526590239875, 5943.851403714855, 5956.8808623828145, 4581.518940511199, 5527.005139074925, 5911.9113213301835, 5928.940710593011, 5939.693267481233, 4363.454568178978, 4923.6878024568805, 5753.899826563358, 5774.682392468455, 5792.594518383673, 4984.775685094107, 5226.947730522798, 5577.163425486047, 5684.63049901755, 5737.713114344349, 5209.843704688866, 5729.369769325454, 5944.375840536854, 5959.582829201914, 6060.0, 3008.3080891394666, 3285.1110611399017, 3342.431786596333, 3373.4771110477914, 3387.3394688036583, 4466.436747138476, 4499.572540608637, 4499.87503124219, 4725.810967561259, 4763.2378819390115, 3907.374820028782, 4372.606465418127, 4623.12160689468, 4723.763811809327, 4785.660015195751, 3920.725549211528, 4199.853005144805, 4600.412857564135, 4621.4223222097235, 4640.310952792471, 4307.3754305626135, 4870.149343249543, 5577.693226872561, 5595.363014040906, 5607.775688972395, 4851.223804191312, 4968.341937063654, 5092.516210959953, 5336.919946200887, 5662.801801936913, 5038.949610503994, 5480.451954804487, 5554.000279949648, 5555.611087232529, 5565.666060036411], "value": 3960.414584972606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.6067243352372, 747.5365273530408, 770.0566805663061, 780.992190078099, 848.5417874347852, 15801.762973555391, 16831.158442077907, 16861.24124414403, 16896.408625698103, 16919.0, 15070.623234419145, 15189.164595947219, 15189.924050379761, 15192.468263610748, 15208.315625796839, 715.6028404235647, 733.1861633586715, 888.9955550222272, 896.7354630384052, 911.9817603647923, 886.8049029213595, 899.4153800029967, 899.95050272235, 901.900790912999, 904.3940559824933, 960.3757557587554, 962.6630679262272, 964.0504103458088, 965.3098034905041, 966.367029595616, 747.0363231431447, 940.4404379394249, 956.531299663167, 957.9701820542945, 958.9808203835919, 720.6180724216174, 720.7153174496098, 724.2974314914525, 724.463896716426, 724.7064938699835, 15373.0, 15439.073655580702, 15449.304781284869, 15454.690906181926, 15466.45867394647, 14145.434182632682, 14444.133351998913, 14479.0, 14487.203203823785, 14518.637034074127, 11519.36643484608, 11731.17881748278, 12749.450109977948, 13061.673458163526, 13594.456221751117, 4597.3333866589155, 4638.2578787394195, 4645.419322584677, 4700.0, 4825.662203645747, 111951.88048119497, 114300.0, 114684.42657786657, 117885.0, 118382.40808795988, 10755.946220268848, 11041.779164416666, 11165.0, 11202.66392008241, 11557.768844623228, 21816.89091554564, 22612.547749044927, 22809.087636494354, 22911.885440573024, 22984.65523017156, 8381.49711017341, 8472.279856212292, 8485.448445851, 8699.521526316114, 8727.170918762708, 4523.88690282739, 4525.592696657317, 4682.180618391768, 4756.524347565216, 4811.08589368026, 3446.5347178131133, 3579.534660494119, 3603.8017909015257, 3609.0, 3904.668103211202, 726.6875243645284, 728.1709912153285, 732.5311800447718, 746.58191412809, 748.76413929612, 559.3092530724552, 591.2018774654182, 611.4222060153113, 656.3075954867566, 701.2636731431976, 7038.0, 7916.683332666629, 9379.577918993662, 9558.378705384128, 9646.324757266924, 561.0894902855782, 634.7461015593707, 661.4675186474838, 694.8132193177951, 734.4638413957734, 4207.221663992172, 4680.929786053144, 4696.412948381452, 4765.332853400527, 4768.213244814636, 90560.0, 108879.0, 148355.77466338014, 152167.71748423783, 158574.0, 70464.29535704578, 107390.46304768583, 110029.0, 116005.8399416012, 125447.3727631356, 103887.0, 107658.0, 108781.45609272062, 114394.28408574045, 116080.0, 9537.52312384364, 10125.443100629536, 10189.0, 10193.082622564005, 10212.948935255276, 56749.43250567441, 57657.135142973726, 58171.836563269346, 60324.0, 60712.57149713962, 89590.0, 90164.54917725369, 91260.0, 92007.0, 96755.0, 13207.66980825487, 13218.272994985367, 13478.52825151115, 13758.243296618783, 13770.793438098237, 14591.854081459049, 15231.238438078273, 16340.836591633932, 16398.67202655964, 16409.261583228545, 14376.640583985483, 14513.274336283355, 14601.26993650292, 14844.257787110817, 15225.238738063274, 14257.786133207803, 14410.639734006734, 14750.409983600535, 15243.16162611067, 15369.538913832603, 13338.466461341826, 14106.365213565208, 14309.856901430852, 14821.036632618845, 15017.02389344689, 11405.372704501333, 11423.828642570534, 11431.314121152758, 11653.126015548698, 11768.352740599006, 10936.781264374828, 11126.0, 11372.886271137513, 11389.202755807013, 11403.429828508708], "value": 8072.514956395891, "unit": "score"}], "name": "dromaeo_css", "value": 7473.574719170193, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 127.0, 114.0, 112.0, 112.0, 115.0, 162.0, 167.0, 113.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 133.0, 157.0, 109.0, 131.0, 127.0, 107.0, 159.0, 111.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 186.0, 185.0, 184.0, 184.0, 185.0, 186.0, 187.0, 186.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 79.0, 165.0, 58.0, 58.0, 58.0, 66.0, 142.0, 58.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 136.0, 71.0, 76.0, 87.0, 124.0, 71.0, 70.0, 120.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 90.0, 84.0, 87.0, 84.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 95.0, 94.0, 93.0, 94.0, 94.0, 93.0, 112.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 84.0, 85.0, 84.0, 83.0, 83.0, 84.0, 86.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 57.0, 58.0, 56.0, 58.0, 56.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 75.0, 74.0, 80.0, 72.0, 71.0, 72.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 86.0, 83.0, 81.0, 82.0, 89.0, 83.0, 88.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 137.0, 147.0, 149.0, 133.0, 145.0, 149.0, 244.0, 137.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 164.0, 166.0, 162.0, 166.0, 215.0, 218.0, 172.0, 162.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 67.0, 66.0, 67.0, 65.0, 65.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1488.0, "alertThreshold": 2.0}]} 07:38:58 INFO - Return code: 0 07:38:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:58 INFO - # TBPL SUCCESS # 07:38:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:58 INFO - 2016-09-17 07:38:58.353 screenresolution[6793:135666] starting screenresolution argv=screenresolution get 07:38:58 INFO - 2016-09-17 07:38:58.356 screenresolution[6793:135666] Display 0: 1600x1200x32@60 07:38:58 INFO - 2016-09-17 07:38:58.362 screenresolution[6794:135669] starting screenresolution argv=screenresolution list 07:38:58 INFO - Available Modes on Display 0 07:38:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:58 INFO - Intel Iris: 07:38:58 INFO - Chipset Model: Intel Iris 07:38:58 INFO - Type: GPU 07:38:58 INFO - Bus: Built-In 07:38:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:58 INFO - Vendor: Intel (0x8086) 07:38:58 INFO - Device ID: 0x0a2e 07:38:58 INFO - Revision ID: 0x0009 07:38:58 INFO - Displays: 07:38:58 INFO - Display: 07:38:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:58 INFO - Display Serial Number: 1600x1200 60 07:38:58 INFO - Main Display: Yes 07:38:58 INFO - Mirror: Off 07:38:58 INFO - Online: Yes 07:38:58 INFO - Rotation: Supported 07:38:58 INFO - Return code: 0 07:38:58 INFO - Running post-action listener: _resource_record_post_action 07:38:58 INFO - [mozharness: 2016-09-17 14:38:58.596338Z] Finished run-tests step (success) 07:38:58 INFO - Running post-run listener: _resource_record_post_run 07:38:58 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 680448; Write bytes: 663355392; Read time: 6; Write time: 1783 07:38:58 INFO - TinderboxPrint: CPU usage
53.3% 07:38:58 INFO - TinderboxPrint: I/O read bytes / time
680,448 / 6 07:38:58 INFO - TinderboxPrint: I/O write bytes / time
663,355,392 / 1,783 07:38:58 INFO - TinderboxPrint: CPU idle
2,258.8 (46.7%) 07:38:58 INFO - TinderboxPrint: CPU system
970.9 (20.1%) 07:38:58 INFO - TinderboxPrint: CPU user
1,611.9 (33.3%) 07:38:58 INFO - TinderboxPrint: Swap in / out
364,625,920 / 0 07:38:58 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 355660288; Write bytes: 356492800; Read time: 27541; Write time: 641 07:38:58 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 671744; Write bytes: 296884736; Read time: 6; Write time: 1109 07:38:59 INFO - Running post-run listener: _upload_blobber_files 07:38:59 INFO - Blob upload gear active. 07:38:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:59 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:59 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:59 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:59 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:00 INFO - (blobuploader) - INFO - Done attempting. 07:39:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: 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 - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:03 INFO - (blobuploader) - INFO - Done attempting. 07:39:03 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:03 INFO - Return code: 0 07:39:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3865b5543f185c1eceb7e60ef125a62bb9aa0040204946650a236819e8a09e191b3f1e4e66b845493e714695baa1638a1a74a93049756e41d9c6eb4f3c48777", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee0683e174b1917b2a6a4d489f4a43e09d3fa8ec075e769577045692fe1c479a1ca1277076d7f09054a0b1644551c22fb3b369eb0cd51189c000e0dac598b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9214943604a3e1e85e4d89aee7671262e7d75adb355e41ec431930a91abf7f5615a48dee6f278e41edc778387922cdfa25ac8c60168113f216af6ad02989b50"} 07:39:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:03 INFO - Contents: 07:39:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f3865b5543f185c1eceb7e60ef125a62bb9aa0040204946650a236819e8a09e191b3f1e4e66b845493e714695baa1638a1a74a93049756e41d9c6eb4f3c48777", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee0683e174b1917b2a6a4d489f4a43e09d3fa8ec075e769577045692fe1c479a1ca1277076d7f09054a0b1644551c22fb3b369eb0cd51189c000e0dac598b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9214943604a3e1e85e4d89aee7671262e7d75adb355e41ec431930a91abf7f5615a48dee6f278e41edc778387922cdfa25ac8c60168113f216af6ad02989b50"} 07:39:03 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:03 INFO - Copying logs to upload dir... 07:39:03 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.024345 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-09-17 07:39:04.055002) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 20 secs) (at 2016-09-17 07:39:04.058724) ========= 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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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/f3865b5543f185c1eceb7e60ef125a62bb9aa0040204946650a236819e8a09e191b3f1e4e66b845493e714695baa1638a1a74a93049756e41d9c6eb4f3c48777", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee0683e174b1917b2a6a4d489f4a43e09d3fa8ec075e769577045692fe1c479a1ca1277076d7f09054a0b1644551c22fb3b369eb0cd51189c000e0dac598b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9214943604a3e1e85e4d89aee7671262e7d75adb355e41ec431930a91abf7f5615a48dee6f278e41edc778387922cdfa25ac8c60168113f216af6ad02989b50"} 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.011357 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/f3865b5543f185c1eceb7e60ef125a62bb9aa0040204946650a236819e8a09e191b3f1e4e66b845493e714695baa1638a1a74a93049756e41d9c6eb4f3c48777", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee0683e174b1917b2a6a4d489f4a43e09d3fa8ec075e769577045692fe1c479a1ca1277076d7f09054a0b1644551c22fb3b369eb0cd51189c000e0dac598b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9214943604a3e1e85e4d89aee7671262e7d75adb355e41ec431930a91abf7f5615a48dee6f278e41edc778387922cdfa25ac8c60168113f216af6ad02989b50"}' ========= master_lag: 20.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 20 secs) (at 2016-09-17 07:39:24.267196) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:24.267537) ========= 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.OD5JsQgE3F/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.4XedGiVEa2/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.005778 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:24.374054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:24.374374) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:24.374683) ========= ========= Total master_lag: 20.59 =========