builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0229 starttime: 1474121884.82 results: success (0) buildid: 20160917071801 builduid: 8a8dcbf091294cf9b7f3972945c2e6e6 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.820966) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.821328) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.821617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.863267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.863562) ========= 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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:04-- 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% 42.1M=0s 2016-09-17 07:18:05 (42.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.452126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.338234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.338569) ========= 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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.026203 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.386676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:05.386994) ========= 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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:05,473 truncating revision to first 12 chars 2016-09-17 07:18:05,474 Setting DEBUG logging. 2016-09-17 07:18:05,474 attempt 1/10 2016-09-17 07:18:05,474 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:06,506 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:06.723746) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.724044) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.724472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.724730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.743077) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:18:06.743355) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:06 INFO - MultiFileLogger online at 20160917 07:18:06 in /builds/slave/test 07:18:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:06 INFO - {'append_to_log': False, 07:18:06 INFO - 'base_work_dir': '/builds/slave/test', 07:18:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:06 INFO - 'branch': 'Mozilla-Aurora', 07:18:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:06 INFO - 'config_files': ('talos/mac_config.py',), 07:18:06 INFO - 'default_actions': ('clobber', 07:18:06 INFO - 'read-buildbot-config', 07:18:06 INFO - 'download-and-extract', 07:18:06 INFO - 'populate-webroot', 07:18:06 INFO - 'create-virtualenv', 07:18:06 INFO - 'install', 07:18:06 INFO - 'run-tests'), 07:18:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:06 INFO - 'download_minidump_stackwalk': True, 07:18:06 INFO - 'download_symbols': 'ondemand', 07:18:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:06 INFO - 'installer_path': 'installer.exe', 07:18:06 INFO - 'log_level': 'info', 07:18:06 INFO - 'log_name': 'talos', 07:18:06 INFO - 'log_to_console': True, 07:18:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:06 INFO - 'opt_config_files': (), 07:18:06 INFO - 'pip_index': False, 07:18:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:06 INFO - 'cmd': ('bash', 07:18:06 INFO - '-c', 07:18:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:06 INFO - 'enabled': True, 07:18:06 INFO - 'halt_on_failure': False, 07:18:06 INFO - 'name': 'check_screen_resolution'},), 07:18:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:06 INFO - 'cmd': ('bash', 07:18:06 INFO - '-c', 07:18:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:06 INFO - 'enabled': True, 07:18:06 INFO - 'halt_on_failure': False, 07:18:06 INFO - 'name': 'check_screen_resolution'},), 07:18:06 INFO - 'run_cmd_checks_enabled': True, 07:18:06 INFO - 'sps_profile': False, 07:18:06 INFO - 'sps_profile_interval': 0, 07:18:06 INFO - 'suite': 'dromaeojs-e10s', 07:18:06 INFO - 'system_bits': '32', 07:18:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:06 INFO - 'title': 't-yosemite-r7-0229', 07:18:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:06 INFO - 'use_talos_json': True, 07:18:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:06 INFO - 'work_dir': 'build'} 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.939477Z] Running clobber step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: clobber 07:18:06 INFO - rmtree: /builds/slave/test/build 07:18:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:09 INFO - Running post-action listener: _resource_record_post_action 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.063443Z] Finished clobber step (success) 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.063588Z] Running read-buildbot-config step. 07:18:09 INFO - Running pre-action listener: _resource_record_pre_action 07:18:09 INFO - Running main action method: read_buildbot_config 07:18:09 INFO - Using buildbot properties: 07:18:09 INFO - { 07:18:09 INFO - "project": "", 07:18:09 INFO - "product": "firefox", 07:18:09 INFO - "script_repo_revision": "production", 07:18:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:18:09 INFO - "repository": "", 07:18:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:18:09 INFO - "buildid": "20160917071801", 07:18:09 INFO - "stage_platform": "macosx64", 07:18:09 INFO - "basedir": "/builds/slave/test", 07:18:09 INFO - "buildnumber": 28, 07:18:09 INFO - "platform": "yosemite_r7", 07:18:09 INFO - "mozci_request": { 07:18:09 INFO - "priority": -1, 07:18:09 INFO - "type": "trigger_all_talos_jobs", 07:18:09 INFO - "times": 6 07:18:09 INFO - }, 07:18:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:18:09 INFO - "slavebuilddir": "test", 07:18:09 INFO - "branch": "mozilla-aurora", 07:18:09 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:09 INFO - "slavename": "t-yosemite-r7-0229", 07:18:09 INFO - "builduid": "8a8dcbf091294cf9b7f3972945c2e6e6", 07:18:09 INFO - "repo_path": "releases/mozilla-aurora" 07:18:09 INFO - } 07:18:09 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:09 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:09 INFO - Running post-action listener: _resource_record_post_action 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.067506Z] Finished read-buildbot-config step (success) 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.067625Z] Running download-and-extract step. 07:18:09 INFO - Running pre-action listener: _resource_record_pre_action 07:18:09 INFO - Running main action method: download_and_extract 07:18:09 INFO - mkdir: /builds/slave/test/build/tests 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.test_packages.json 07:18:09 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:09 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:09 INFO - Downloaded 1199 bytes. 07:18:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:09 INFO - Using the following test package requirements: 07:18:09 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:09 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:09 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:09 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'jsshell-mac.zip'], 07:18:09 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:09 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:09 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:09 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:09 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:09 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:09 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 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:09 INFO - Downloaded 16309409 bytes. 07:18:09 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:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:12 INFO - Return code: 0 07:18:12 INFO - 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:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:13 INFO - Downloaded 16309409 bytes. 07:18:13 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:13 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:16 INFO - Return code: 0 07:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:16 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:16 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:16 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:17 INFO - Downloaded 14413740 bytes. 07:18:17 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:17 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:17 INFO - Return code: 0 07:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:17 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:17 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:19 INFO - Downloaded 115349660 bytes. 07:18:19 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:19 INFO - mkdir: /builds/slave/test/properties 07:18:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:19 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:19 INFO - Contents: 07:18:19 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:20 INFO - Running post-action listener: _resource_record_post_action 07:18:20 INFO - Running post-action listener: set_extra_try_arguments 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.118172Z] Finished download-and-extract step (success) 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.118298Z] Running populate-webroot step. 07:18:20 INFO - Running pre-action listener: _resource_record_pre_action 07:18:20 INFO - Running main action method: populate_webroot 07:18:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:20 INFO - '--tppagecycles', 07:18:20 INFO - '7'], 07:18:20 INFO - 'tests': ['tsvgm']}}, 07:18:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['damp', 'tps']}, 07:18:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['damp', 'tps']}, 07:18:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['dromaeo_dom']}, 07:18:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['basic_compositor_video']}, 07:18:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tsvgx', 07:18:20 INFO - 'tsvgr_opacity', 07:18:20 INFO - 'tart', 07:18:20 INFO - 'tscrollx', 07:18:20 INFO - 'cart']}, 07:18:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:20 INFO - 'tsvgr_opacity', 07:18:20 INFO - 'tart', 07:18:20 INFO - 'tscrollx', 07:18:20 INFO - 'cart']}, 07:18:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tp5o']}, 07:18:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['tp5o']}, 07:18:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s', 07:18:20 INFO - '--xperf_path', 07:18:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:20 INFO - 'tests': ['tp5n']}, 07:18:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--xperf_path', 07:18:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:20 INFO - 'tests': ['tp5n']}}, 07:18:20 INFO - 'talos.zip': {'path': '', 07:18:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:20 INFO - Running post-action listener: _resource_record_post_action 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.137522Z] Finished populate-webroot step (success) 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.137646Z] Running create-virtualenv step. 07:18:20 INFO - Running pre-action listener: _resource_record_pre_action 07:18:20 INFO - Running main action method: create_virtualenv 07:18:20 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:20 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:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:20 INFO - Using real prefix '/tools/python27' 07:18:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:20 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:23 INFO - Installing pip.................done. 07:18:23 INFO - Return code: 0 07:18:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:23 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:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 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:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:23 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:23 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:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:23 INFO - 'HOME': '/Users/cltbld', 07:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:23 INFO - 'LOGNAME': 'cltbld', 07:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:23 INFO - 'MOZ_NO_REMOTE': '1', 07:18:23 INFO - 'NO_EM_RESTART': '1', 07:18:23 INFO - 'PAGER': '/bin/cat', 07:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:23 INFO - 'PWD': '/builds/slave/test', 07:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:23 INFO - 'SHELL': '/bin/bash', 07:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:23 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:23 INFO - 'USER': 'cltbld', 07:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:23 INFO - 'XPC_FLAGS': '0x0', 07:18:23 INFO - 'XPC_SERVICE_NAME': '0', 07:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:24 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:24 INFO - Cleaning up... 07:18:24 INFO - Return code: 0 07:18:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:24 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:24 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:24 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:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:24 INFO - 'HOME': '/Users/cltbld', 07:18:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:24 INFO - 'LOGNAME': 'cltbld', 07:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:24 INFO - 'MOZ_NO_REMOTE': '1', 07:18:24 INFO - 'NO_EM_RESTART': '1', 07:18:24 INFO - 'PAGER': '/bin/cat', 07:18:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:24 INFO - 'PWD': '/builds/slave/test', 07:18:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:24 INFO - 'SHELL': '/bin/bash', 07:18:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:24 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:24 INFO - 'USER': 'cltbld', 07:18:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:24 INFO - 'XPC_FLAGS': '0x0', 07:18:24 INFO - 'XPC_SERVICE_NAME': '0', 07:18:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:24 INFO - Downloading/unpacking psutil>=3.1.1 07:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:27 INFO - Installing collected packages: psutil 07:18:27 INFO - Running setup.py install for psutil 07:18:28 INFO - building 'psutil._psutil_osx' extension 07:18:28 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:28 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:28 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:28 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:28 INFO - building 'psutil._psutil_posix' extension 07:18:28 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:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:28 INFO - ^ 07:18:28 INFO - 1 warning generated. 07:18:28 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:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:29 INFO - Successfully installed psutil 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing mozsystemmonitor==0.3 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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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', 'mozsystemmonitor==0.3'] 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 mozsystemmonitor==0.3 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/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.68FhipIa1l/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 mozsystemmonitor==0.3 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 mozsystemmonitor-0.3.tar.gz 07:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:31 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:31 INFO - Installing collected packages: mozsystemmonitor 07:18:31 INFO - Running setup.py install for mozsystemmonitor 07:18:31 INFO - Successfully installed mozsystemmonitor 07:18:31 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing jsonschema==2.5.1 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', '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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 07:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/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.68FhipIa1l/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:34 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:34 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:34 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:34 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:34 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:34 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 functools32-3.2.3-2.tar.gz 07:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:35 INFO - warning: no files found matching '*.txt' 07:18:35 INFO - no previously-included directories found matching 'build' 07:18:35 INFO - no previously-included directories found matching 'dist' 07:18:35 INFO - no previously-included directories found matching '.git*' 07:18:35 INFO - Installing collected packages: jsonschema, functools32 07:18:35 INFO - Running setup.py install for functools32 07:18:35 INFO - warning: no files found matching '*.txt' 07:18:35 INFO - no previously-included directories found matching 'build' 07:18:35 INFO - no previously-included directories found matching 'dist' 07:18:35 INFO - no previously-included directories found matching '.git*' 07:18:35 INFO - Successfully installed jsonschema functools32 07:18:35 INFO - Cleaning up... 07:18:35 INFO - Return code: 0 07:18:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:35 INFO - 'HOME': '/Users/cltbld', 07:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:35 INFO - 'LOGNAME': 'cltbld', 07:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:35 INFO - 'MOZ_NO_REMOTE': '1', 07:18:35 INFO - 'NO_EM_RESTART': '1', 07:18:35 INFO - 'PAGER': '/bin/cat', 07:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:35 INFO - 'PWD': '/builds/slave/test', 07:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:35 INFO - 'SHELL': '/bin/bash', 07:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:35 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:35 INFO - 'USER': 'cltbld', 07:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:35 INFO - 'XPC_FLAGS': '0x0', 07:18:35 INFO - 'XPC_SERVICE_NAME': '0', 07:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:35 INFO - Cleaning up... 07:18:35 INFO - Return code: 0 07:18:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:35 INFO - 'HOME': '/Users/cltbld', 07:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:35 INFO - 'LOGNAME': 'cltbld', 07:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:35 INFO - 'MOZ_NO_REMOTE': '1', 07:18:35 INFO - 'NO_EM_RESTART': '1', 07:18:35 INFO - 'PAGER': '/bin/cat', 07:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:35 INFO - 'PWD': '/builds/slave/test', 07:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:35 INFO - 'SHELL': '/bin/bash', 07:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:35 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:35 INFO - 'USER': 'cltbld', 07:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:35 INFO - 'XPC_FLAGS': '0x0', 07:18:35 INFO - 'XPC_SERVICE_NAME': '0', 07:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:36 INFO - Downloading/unpacking 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:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:39 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:39 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:39 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:39 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:39 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:39 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:39 INFO - Downloading docopt-0.6.1.tar.gz 07:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:39 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:39 INFO - Running setup.py install for blobuploader 07:18:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:39 INFO - Running setup.py install for requests 07:18:40 INFO - Running setup.py install for docopt 07:18:40 INFO - Successfully installed blobuploader requests docopt 07:18:40 INFO - Cleaning up... 07:18:40 INFO - Return code: 0 07:18:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:40 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:40 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:40 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:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:40 INFO - 'HOME': '/Users/cltbld', 07:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:40 INFO - 'LOGNAME': 'cltbld', 07:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:40 INFO - 'MOZ_NO_REMOTE': '1', 07:18:40 INFO - 'NO_EM_RESTART': '1', 07:18:40 INFO - 'PAGER': '/bin/cat', 07:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:40 INFO - 'PWD': '/builds/slave/test', 07:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:40 INFO - 'SHELL': '/bin/bash', 07:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:40 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:40 INFO - 'USER': 'cltbld', 07:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:40 INFO - 'XPC_FLAGS': '0x0', 07:18:40 INFO - 'XPC_SERVICE_NAME': '0', 07:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:40 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-1iSODW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-jIiU7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-9zokP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GZqmX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-A5l8bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-NUmwf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-kDhQ1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-RcLiKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-1ZZLRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XXCPYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:41 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Hsu38y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-V7QZYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-CVCkAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-x_LqRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HWl2Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-LB_vKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:42 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-jVc7VS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:42 INFO - Running setup.py install for manifestparser 07:18:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozcrash 07:18:42 INFO - Running setup.py install for mozdebug 07:18:43 INFO - Running setup.py install for mozdevice 07:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Running setup.py install for mozfile 07:18:43 INFO - Running setup.py install for mozhttpd 07:18:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Running setup.py install for mozinfo 07:18:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Running setup.py install for mozInstall 07:18:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Running setup.py install for mozleak 07:18:43 INFO - Running setup.py install for mozlog 07:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for moznetwork 07:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozprocess 07:18:44 INFO - Running setup.py install for mozprofile 07:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozrunner 07:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozscreenshot 07:18:44 INFO - Running setup.py install for moztest 07:18:45 INFO - Running setup.py install for mozversion 07:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:45 INFO - Cleaning up... 07:18:45 INFO - Return code: 0 07:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:45 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:45 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:45 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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:45 INFO - 'HOME': '/Users/cltbld', 07:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:45 INFO - 'LOGNAME': 'cltbld', 07:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:45 INFO - 'MOZ_NO_REMOTE': '1', 07:18:45 INFO - 'NO_EM_RESTART': '1', 07:18:45 INFO - 'PAGER': '/bin/cat', 07:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:45 INFO - 'PWD': '/builds/slave/test', 07:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:45 INFO - 'SHELL': '/bin/bash', 07:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:45 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:45 INFO - 'USER': 'cltbld', 07:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:45 INFO - 'XPC_FLAGS': '0x0', 07:18:45 INFO - 'XPC_SERVICE_NAME': '0', 07:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:45 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-AQMcPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:45 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-kcED5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:45 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-2VDc4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:45 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-uzKyZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:45 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-gWs5Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-6GAbfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-9fOq9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-A7NwC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cGZJdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-aHNYR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-zj719r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-FGqtZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qd16pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:46 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Vj9pts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:47 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-c7LY9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:47 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-97it7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:47 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-D37wmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:47 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:47 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:47 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:47 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:47 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:47 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:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:47 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:47 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:47 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:47 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:47 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:47 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:49 INFO - Downloading blessings-1.6.tar.gz 07:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:49 INFO - Installing collected packages: blessings 07:18:49 INFO - Running setup.py install for blessings 07:18:50 INFO - Successfully installed blessings 07:18:50 INFO - Cleaning up... 07:18:50 INFO - Return code: 0 07:18:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:50 INFO - Reading from file tmpfile_stdout 07:18:50 INFO - Current package versions: 07:18:50 INFO - blessings == 1.6 07:18:50 INFO - blobuploader == 1.2.4 07:18:50 INFO - docopt == 0.6.1 07:18:50 INFO - functools32 == 3.2.3-2 07:18:50 INFO - jsonschema == 2.5.1 07:18:50 INFO - manifestparser == 1.1 07:18:50 INFO - mozInstall == 1.12 07:18:50 INFO - mozcrash == 0.17 07:18:50 INFO - mozdebug == 0.1 07:18:50 INFO - mozdevice == 0.48 07:18:50 INFO - mozfile == 1.2 07:18:50 INFO - mozhttpd == 0.7 07:18:50 INFO - mozinfo == 0.9 07:18:50 INFO - mozleak == 0.1 07:18:50 INFO - mozlog == 3.3 07:18:50 INFO - moznetwork == 0.27 07:18:50 INFO - mozprocess == 0.23 07:18:50 INFO - mozprofile == 0.28 07:18:50 INFO - mozrunner == 6.12 07:18:50 INFO - mozscreenshot == 0.1 07:18:50 INFO - mozsystemmonitor == 0.3 07:18:50 INFO - moztest == 0.7 07:18:50 INFO - mozversion == 1.4 07:18:50 INFO - psutil == 3.1.1 07:18:50 INFO - requests == 1.2.3 07:18:50 INFO - wsgiref == 0.1.2 07:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:50 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:50 INFO - 'HOME': '/Users/cltbld', 07:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:50 INFO - 'LOGNAME': 'cltbld', 07:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:50 INFO - 'MOZ_NO_REMOTE': '1', 07:18:50 INFO - 'NO_EM_RESTART': '1', 07:18:50 INFO - 'PAGER': '/bin/cat', 07:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:50 INFO - 'PWD': '/builds/slave/test', 07:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:50 INFO - 'SHELL': '/bin/bash', 07:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:50 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:50 INFO - 'USER': 'cltbld', 07:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:50 INFO - 'XPC_FLAGS': '0x0', 07:18:50 INFO - 'XPC_SERVICE_NAME': '0', 07:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:50 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:50 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:50 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:50 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:50 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:50 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:50 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:50 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:50 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:50 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:50 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:50 INFO - Cleaning up... 07:18:50 INFO - Return code: 0 07:18:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:50 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 0x10805ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b34238b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, '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.22mDxqXLFQ/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.68FhipIa1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:50 INFO - 'HOME': '/Users/cltbld', 07:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:50 INFO - 'LOGNAME': 'cltbld', 07:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:50 INFO - 'MOZ_NO_REMOTE': '1', 07:18:50 INFO - 'NO_EM_RESTART': '1', 07:18:50 INFO - 'PAGER': '/bin/cat', 07:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:50 INFO - 'PWD': '/builds/slave/test', 07:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:50 INFO - 'SHELL': '/bin/bash', 07:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:18:50 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:50 INFO - 'USER': 'cltbld', 07:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:50 INFO - 'XPC_FLAGS': '0x0', 07:18:50 INFO - 'XPC_SERVICE_NAME': '0', 07:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:50 INFO - Cleaning up... 07:18:50 INFO - Return code: 0 07:18:50 INFO - Running post-action listener: _resource_record_post_action 07:18:50 INFO - Running post-action listener: _start_resource_monitoring 07:18:51 INFO - Starting resource monitoring. 07:18:51 INFO - [mozharness: 2016-09-17 14:18:51.008017Z] Finished create-virtualenv step (success) 07:18:51 INFO - [mozharness: 2016-09-17 14:18:51.008950Z] Running install step. 07:18:51 INFO - Running pre-action listener: _resource_record_pre_action 07:18:51 INFO - Running main action method: install 07:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:51 INFO - Reading from file tmpfile_stdout 07:18:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:51 INFO - Reading from file tmpfile_stdout 07:18:51 INFO - Output received: 07:18:51 INFO - Usage: mozinstall [options] installer 07:18:51 INFO - Options: 07:18:51 INFO - -h, --help show this help message and exit 07:18:51 INFO - -d DEST, --destination=DEST 07:18:51 INFO - Directory to install application into. [default: 07:18:51 INFO - "/builds/slave/test"] 07:18:51 INFO - --app=APP Application being installed. [default: firefox] 07:18:51 INFO - mkdir: /builds/slave/test/build/application 07:18:51 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:51 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:23 INFO - Reading from file tmpfile_stdout 07:19:23 INFO - Output received: 07:19:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:23 INFO - Running post-action listener: _resource_record_post_action 07:19:23 INFO - [mozharness: 2016-09-17 14:19:23.760057Z] Finished install step (success) 07:19:23 INFO - [mozharness: 2016-09-17 14:19:23.760204Z] Running run-tests step. 07:19:23 INFO - Running pre-action listener: _resource_record_pre_action 07:19:23 INFO - Running main action method: run_tests 07:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:23 INFO - 2016-09-17 07:19:23.774 screenresolution[1655:8868] starting screenresolution argv=screenresolution get 07:19:23 INFO - 2016-09-17 07:19:23.777 screenresolution[1655:8868] Display 0: 1600x1200x32@60 07:19:23 INFO - 2016-09-17 07:19:23.782 screenresolution[1656:8870] starting screenresolution argv=screenresolution list 07:19:23 INFO - Available Modes on Display 0 07:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:24 INFO - Intel Iris: 07:19:24 INFO - Chipset Model: Intel Iris 07:19:24 INFO - Type: GPU 07:19:24 INFO - Bus: Built-In 07:19:24 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:24 INFO - Vendor: Intel (0x8086) 07:19:24 INFO - Device ID: 0x0a2e 07:19:24 INFO - Revision ID: 0x0009 07:19:24 INFO - Displays: 07:19:24 INFO - Display: 07:19:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:24 INFO - Display Serial Number: 1600x1200 60 07:19:24 INFO - Main Display: Yes 07:19:24 INFO - Mirror: Off 07:19:24 INFO - Online: Yes 07:19:24 INFO - Rotation: Supported 07:19:24 INFO - Return code: 0 07:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:24 INFO - Python 2.7.3 07:19:24 INFO - Return code: 0 07:19:24 INFO - grabbing minidump binary from tooltool 07:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:24 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 0x107fc2d30>, '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 0x107d67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107fbab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:24 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:24 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:24 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:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:24 INFO - Return code: 0 07:19:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:24 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-e10s', '--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-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:19:24 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-e10s --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-0229 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.22mDxqXLFQ/Render', 07:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:24 INFO - 'HOME': '/Users/cltbld', 07:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:24 INFO - 'LOGNAME': 'cltbld', 07:19:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:24 INFO - 'MOZ_NO_REMOTE': '1', 07:19:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:24 INFO - 'NO_EM_RESTART': '1', 07:19:24 INFO - 'PAGER': '/bin/cat', 07:19:24 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:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:24 INFO - 'PWD': '/builds/slave/test', 07:19:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:24 INFO - 'SHELL': '/bin/bash', 07:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68FhipIa1l/Listeners', 07:19:24 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 07:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:24 INFO - 'USER': 'cltbld', 07:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:24 INFO - 'XPC_FLAGS': '0x0', 07:19:24 INFO - 'XPC_SERVICE_NAME': '0', 07:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--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-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:19:24 INFO - mozversion application_buildid: 20160916162818 07:19:24 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:24 INFO - mozversion application_name: Firefox 07:19:24 INFO - mozversion application_remotingname: firefox-dev 07:19:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:24 INFO - mozversion application_vendor: Mozilla 07:19:24 INFO - mozversion application_version: 50.0a2 07:19:24 INFO - mozversion platform_buildid: 20160916162818 07:19:24 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:24 INFO - mozversion platform_version: 50.0a2 07:19:24 INFO - using testdate: 1474121964 07:19:24 INFO - actual date: 1474121964 07:19:24 INFO - starting webserver on 'localhost:49267' 07:19:24 INFO - SUITE-START | Running 2 tests 07:19:24 INFO - TEST-START | dromaeo_css 07:19:24 INFO - operating with platform_type : mac_ 07:19:24 INFO - Initialising browser for dromaeo_css test... 07:19:24 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpD8FTAH/profile http://localhost:49267/getInfo.html) 07:19:27 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 07:19:27 INFO - PROCESS | 1666 | colorDepth:24 07:19:27 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 07:19:27 INFO - PROCESS | 1666 | __metrics 07:19:27 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:27 INFO - TEST-INFO | 1666: exit 0 07:19:27 INFO - Browser initialized. 07:19:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:27 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpD8FTAH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:39 INFO - PROCESS | 1670 | RSS: Main: 226775040 07:19:39 INFO - PROCESS | 1670 | 07:20:29 INFO - PROCESS | 1670 | 1474122029598 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:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 07:22:41 INFO - PROCESS | 1670 | RSS: Main: 239878144 07:22:41 INFO - PROCESS | 1670 | 07:25:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 07:25:44 INFO - PROCESS | 1670 | RSS: Main: 249077760 07:25:44 INFO - PROCESS | 1670 | 07:28:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 07:28:47 INFO - PROCESS | 1670 | RSS: Main: 252391424 07:28:47 INFO - PROCESS | 1670 | 07:31:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 07:31:49 INFO - PROCESS | 1670 | RSS: Main: 252354560 07:31:49 INFO - PROCESS | 1670 | 07:34:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 07:34:53 INFO - PROCESS | 1670 | RSS: Main: 256532480 07:34:53 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 07:37:55 INFO - PROCESS | 1670 | RSS: Main: 256802816 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | __start_tp_report 07:37:55 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 07:37:55 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 07:37:55 INFO - PROCESS | 1670 | |i|pagename|runs| 07:37:55 INFO - PROCESS | 1670 | |0;dojo.html;3441.6558344165587;3446.4313388290934;3462.7576069675124;3479.965200347996;3485.634008429115;6053.061775424816;6057.182280392146;6125.234345706786;6132.478739307158;6141.048137538689;6090.17782599349;6099.841030204269;6137.846553836156;6162.476189523896;6200.844978875531;5461.098918678424;5499.7250137493165;5529.225908372831;5547.472990065938;5603.495685388314;5440.211169380437;5458.099413596752;5500.889982200353;5506.476884695948;5560.193771903072;5504.752286147113;5505.3393592768925;5524.447555244484;5544.63959842609;5546.805861794838;5497.835064948038;5508.724563771795;5554.361248456439;5569.832905012856;5647.09646456567;5291.973540132275;5316.760745766411;5358.169483730028;5389.407165211824;5463.590230732671;4719.126961512097;5763.337216220105;6104.26748790148;6104.328523862371;6141.170941922828;6157.107219453199;6291.653959032252;6327.050942358637;6349.682515874188;6365.3634636536435;5399.78400863965;6265.248170219603;6373.936260637381;6380.074889141095;6381.361863813581;4492.303692927601;5930.584859059867;5962.791302304442;6189.133521307021;6224.564280500322;6601.834954126185;6611.041398997215;6620.602763834186;6627.701753421107;6633.402993730588;5202.323697919246;6200.534959878028;6216.564840461125;6265.592736472206;6301.52738544611;6447.677616119176;6468.2561505427475;6471.482281417475;6479.449246814073;6483;6319.715612797435;6344.397282258178;6394.232692076887;6403.295637479873;6405.135306733625;6217.751289948352;6301.52738544611;6314.684265786692;6318.4313411793155;6397.936020639734;5120.539151476385;5182.585393168538;5186.611004174702;5188.662736922088;5197.350331208599;3953.2686453006286;4611.1930412177735;4649.256119020941;4661.324108004321;4681.7190968542;6013.9699301503215;6021.969890150609;6054.606450580786;6116.031445914963;6174.938250617526;6440.388163124496;6467.353264673589;6473.805785826433;6492.156019717407;6511.674416279167;6157.84605384869;6163.013917773224;6177.660228687465;6178.289496707846;6182.165407669997;5498;5666.065099258576;5744.913826292609;5746;5748.798792042258;6597.175353080865;6650.634215118215;6654.600723956578;6659.700313485905;6686.665666716644;6457.773977910751;6469.256035555972;6485.238561634146;6486.254080780771;6490.772822951174;6391.9041214380895;6482.611043337415;6515.771947981798;6526.804195873941;6606.702698378488;5392.245085688085;6305.936940630535;6353.269374021862;6356.110144579855;6357.841053973688;6397.808065758035;6411.519136064907;6433.5496515243485;6449.064885591657;6464.288928217987;6221.377862213742;6242.969909964805;6251.53113516479;6305.085762564494;6310.6213627182515;6234.719437625227;6246.281677607133;6259.62442253448;6274.749010039547;6275.623462592259;4921.434035086011;6111.908321375272;6134.6625935573975;6306.873862522815;6323.841903952438;6194.659293738708;6202.658853762906;6244.344343843831;6247.844148832481;6291.874162516632;6241.500679945684;6287.119803227643;6296.968515157395;6307.90538141919;6323.841903952254 07:37:55 INFO - PROCESS | 1670 | |1;ext.html;523.3667262612239;530.0459173487724;531.2005431825103;564.0383146734818;573.1374281706031;30617.540736888935;32980;42432.36351454731;42549.787251063775;42703.50537731184;43250.351244731355;43665;43778.34332485007;43820.56179438205;43830;9674.9516252419;9835.31152819303;9837.065478779541;9849.310548261588;9873.950630246838;9779.413235205875;9784.755381115458;9797.902020979805;9853.162481189107;9854.556544955494;8641.395102342838;9757.463339516324;9806.901930980706;9837.409755414663;9888.505574721272;8641.913580864206;9627.855582166238;9861.556229969668;9869.90130098697;9877.506124693737;9759.072888075621;9783.217342612574;9799;9818;9820.65627703027;37488.625113748785;40122.796316110565;42125;42255.57744422547;42613.147737045394;3164.715175634181;3281.917952051194;3356;3433.1073920780777;3529.0824385659657;30731.078067658007;32326.030219093464;32634.510482342783;33075.50386744177;33572;2955.793094483387;3061.0357737312897;3251.203455153479;3294.934101317984;3381.8647254109806;423494.9999999969;496435;509816;510959;511310;31842.567084481256;32146.19634509156;64611;65051;65316;36058.45912311317;36099;36258.274834503165;36658.45012324817;36752.448713269325;15730.842691572938;15756.448524301595;15901.284442200129;15932.761008584881;15947.963382380109;13146.553879073294;13390.397432115578;13393.933030334785;13441;13460.461581536629;7864.921350786533;7875;7913.287804097544;7931.960340198378;7988.241117093867;3285.227971426713;3301.257217126127;3304.058343372127;3319.71782398494;3319.883804066846;925.2366797392178;955.4267439536222;965.7875267441152;983.9852402213972;1031.454400293579;31076.22309442282;31092.378152436824;31233;31258.843705781324;31393;1081.7511972246427;1089.4661615808197;1098.9780204395868;1140.9828852567218;1179.4751335655722;16059.197040147947;16513;16562.83437165637;16623.91944523602;16684.49946501607;665049;665717;672634;678408;682483.5875820689;61081;61719;61863.381366185764;61897.69051154715;62019;1443.5813614051808;1450.2893582144673;1470.9186501100824;1476.756335204679;1479.8224213094497;29018.854905725337;31155.065348039596;31173.8441307792;31815.045548632654;31857.203569910936;55171.44828551663;55297;55537.722311388185;55630.16554751763;55793.16310255268;558147.209263951;563161;563539;565416.1729191327;566465;27438.039668611502;27900.883964641187;28665.996690115746;28677.42645147127;28958.565621516114;49963.75018124886;50207.74896125496;50257.49742502528;50909.74545127251;51273;39658.40512392374;41220.79389603033;41368.965775855846;41724;42422.36366454567;33152.342382881245;41066.384004239364;41435.37846932238;41650.375244371964;42351.78824105983;24059;36234.637653623126;36967.70613028531;39655.40516892307;40533.39199911945;30977;36568;36623.084422888576;36996.44505332368;37046.814765927076;26667.599985999837;26945.461090778466;27084.864575676995;27109.322266942694;27332.897083285017 07:37:55 INFO - PROCESS | 1670 | |2;jquery.html;317.29243786356426;382.7818143658115;389.0332523678659;393.44327776196684;405.8944674384661;17404.56488587786;20097.497562560944;21548.89225553874;21736.456588585275;21995.450113747163;18951.33670321539;20286.69569956452;20746.170153193892;21017.894910525465;21215.893920530376;5340.866478338054;5569.860753481175;5745.827625171251;6039.818805435844;6054.5459090568165;5691.743871525791;5978.581499295051;6054.303755068157;6094.146819445281;6110.663913484756;5918.644881307114;5951.642901425928;6053.455189032986;6070.453659170674;6082.635041897478;5981.58128930975;6057.182280392135;6068.969655151696;6069.15031895535;6082.513398928098;5738.081906894877;5793.7392817323325;5816.680082595455;5884.411558844125;5887.7939272125695;13546;22336.329910102722;25418.110366137098;25522.361940951625;26033.219003429927;2121.9045142968603;2242.2712618398923;2421.1646981791255;2532.569463191262;3011.6687164411896;10353.948230258877;10515.106215971651;10639.893601063968;10776.892231077745;13199.736005279841;1605.3418098579643;1830.066666000336;1838.5403649087727;1896.0993528074055;1949.7619011927386;121611;125119;126127.36936315351;127513;128907;9195.586198621078;11465.369404682822;11994.460249288803;12037.939810300892;12269.263844169378;14630.26848657563;14641.048331858396;14813.629659258604;14830.555083347517;14864.851351486346;8646.654133834701;8674.08922063187;8931.598078086372;8947.328950328621;9053.864192037125;8868.645254189889;8880.289576833839;8892.822143557092;8939.061398553058;9034.503102329434;4509.706869053501;4529.932051019237;4536.387587675622;4540.137373898949;4552.385427967249;1924.8460123190391;1986.5629561496448;1993.3421970749619;1994.7207390965277;1999.7700264469481;898.3576742629051;930.3487558708796;971.1017308989157;975.8097171051577;983.4640121133999;17202;21977.793095924208;22062.88968555147;22098.0055897485;22259.998300076535;957.5260246178186;974.1427543761586;983.1839573154265;990.8266053440619;1026.810040142576;11103.666889993314;11431.657050288504;11486.253393529394;11664.125190610737;11671.12466565011;584589;585411;588196;593351;601513;103459.48270258751;111517;125959.3702031484;134636;138659.61340386668;922.9169374756304;985.3742873275521;1007.5717819926502;1027.4554486122368;1030.217035053365;16596.506314431757;17550.38573649916;19398.709019365004;19410.126544305258;19480.512987175432;37612.435813463366;48928.77678058077;52485.475145248056;55242;56240.156397654886;75446;75587.62206188934;77844;81406.18593813985;82995;7925.762227133196;7931.444798864024;7959.880601790862;8043.879341809995;8183.95908020456;10447.42539160323;12428.192167509083;12534.247945123321;12574.937125314314;12950.805737914126;10407.739806504898;10668;10743.24797264184;10884.183686223407;11085.168612354268;10294.639687610897;10399.220058495794;10508.894911050791;10586.258961872903;10856.782864342827;8804.823903522021;10747;10818;10974.780504389708;11152.275102118336;7814.804629884298;7831.451798374059;7905.288524032635;8223.917760822314;8299.253067223979;8166.83666326682;10024;10779.89220107789;11066.225364224749;11305.943470282926 07:37:55 INFO - PROCESS | 1670 | |3;mootools.html;1542.0285220311205;1586.8809839262055;1823.890566566006;1891.536573539483;1917.1756144857707;8291.875621865676;8803.471791692495;8958.910410895889;9415.105564971325;9415.623375065006;9055.592498337573;9282.211012063966;9284.90715092849;9297.581608827602;9302.720918372443;2377.5958087125186;2412.433078226615;2417.758224177586;2421.2131057406327;2431.3192306154306;2358.8230882683783;2397.5564520563667;2413.1674572272536;2439.0487709793106;2442.3405680466262;2371.7746814052725;2415.5893498105274;2425.042108367194;2429.9392515187087;2440.0849681369486;2355.6937598112136;2371.300466362419;2414.782669559739;2419.548271037372;2423.7212720537186;2132.658774596073;2261.6882208319134;2355.823313251496;2415.1667674652394;2424.115197952752;8510.106438823896;8929.06244844295;9317.953410232973;9385.671501497483;9395.34232603718;4714.622830173578;5395.406505284415;5553.333599968029;5566.109422492423;5580.079286917654;3709.55485341758;3779.6976241900584;3794.6395092466173;3796.2787070456525;3817.0457385653585;1106.7675788084512;1115.2472081345059;1116.6426743442103;1116.8644550702409;1132.399828091113;11952.40237988097;12003.57987470443;12163;12186.695332616668;12210.450529726273;4468.50846406895;4602.769861506911;4690.695104818176;4714.834980775725;4724.527547245317;9388.295877809054;9503.809923801486;9633.940266570671;9634.518274086267;9648.324617276723;5313.495217954288;5400.83797486076;5407.810726624549;5409.56723462122;5413.2150838128255;4510.458744950627;4898.485659005753;5027.044861476308;5058.570021548136;5064.392272927273;3241.7244534214856;3877.3020856245585;3966.3852102924093;3968.2857085724845;4020.3366444536323;1165.6653132163626;1184.3604453594962;1198.5445530698278;1207.4626791077885;1220.170284206748;803.7950322667682;887.627196577439;902.3367824648877;920.185635712386;954.61815273891;5034.949650503448;6060.757569697251;6120.60216085953;6161.691915404212;6341.524385671001;972.9756756081155;991.1773228220703;1008.6318493749728;1014.0366651680932;1036.9688909332733;4055.168690418457;4076.30702780528;4186.476690413699;4240.957590424056;4288.50682171548;9485;10903.945480272549;11118.555257789598;11160.441977901073;11313.660590182308;10586.788264234672;11085.057770089472;11186.832197517044;11190.32858028521;11195.776084478428;8100.473469224482;8571.357148213785;8584.570771461402;8616.009158946676;8624.137586241326;6170.981788004929;6181.81454556364;6216.595921265103;6316.62100273985;6394.0408938659575;9885.851712224467;10367.585296588051;10402.323848949794;10469.31949423285;10738.40938748345;10403.167746580104;10464.947675261576;10782.083522900328;10921.890781092088;10942.507587158438;7269.3094156055095;7690.461667683208;7853.685852566062;7894.921050789419;7967.442279040411;8800.251978581893;8861.601227944888;8930.19628233462;8934.642614295355;8962.193402593797;7739.806504837199;7867.606619669108;7876.527408355517;7908.485948413335;8147.429679922348;7833.647485863264;7909.367250620052;7911.485753426009;7917.366610671248;7928.682852685828;7346.816329591803;7373.9262607375385;7450.515716478411;7456.179820219663;7458.92541074604;4843.297721830385;5007.449180590205;5161.81933632321;5164.251183578285;5325.254464375068;5080;5177.197534382177;5238.528532432099;5309.177077552986;5400.675959442446 07:37:55 INFO - PROCESS | 1670 | |4;prototype.html;302.8410084705528;308.76842368223834;315.6496289119078;318.1346736875697;318.91229911774275;5536.944630553693;5624.634398764078;5720.313562372516;5777.797777077803;5824.242848429703;4444.555544445554;4591.5867571918525;5645.689487078209;5675.177099320596;5698.145278208271;3950.9209815803665;3960.584138665442;3976.886471787897;4123.876283711494;4311.935320970188;4082.448869402622;4291.433626726238;4345.565443455661;4361.018770776582;4370.344448332759;3504.281622267442;4314.97842510787;4319.978400107995;4323.135372925428;4328.956710432887;3545.3618348697223;4317.352397140423;4338.461999730162;4349.347597860315;4352.673549483801;3785.523645778149;3789.5263092113487;3795.221979494225;3801.1067399161175;3817.675497582709;4726.976365118187;4945.809952580601;5559.833205003856;5572.275604171432;5592.888142237173;4382.312809568307;5029.346184995927;5042.218456139304;5066.873328166788;5069.239614057884;5540.36285827127;5666.716664166775;6012.8496787580225;6040.456358927674;6091.238595175603;4014.7992600369867;4142.730722503027;4185.97907010466;4192.8532501362615;4210.578942105795;4959.156943319608;5042.4535609517325;5871.823845284648;6339.2709838368255;6396.3283855194995;3349.8995030149135;3969.2932039222833;4283.807228674717;4294.484661840599;4295.613394794484;4281.828726850891;4308.89227769302;4323.416338794285;4331.935020974688;4337.501187363494;4326.567343265649;5005.199168133081;5032.421271553745;5052.898942021139;5069.797208111634;5024.949750502521;5789.797357092481;6061.727222275008;6111.908321375183;6129.785457508966;1447.392095319947;1478.728293667445;1478.8521147885126;1483.406637345049;1488.2409970914696;794.8529522038441;813.341193633159;837.6848348093434;915.4919019943995;920.1350730313484;3967.7619342838943;4556.430446194226;4591.678582499193;4652.767361631905;4771.73755443454;4908.852734417973;4965.659271996541;5321.920171197435;5327.38735045467;5350.30446042012;3982.980085099556;4375.44671641569;4811.687240329367;4820.421549414093;4842.038974215448;2349.706286714161;2791.7855732756316;2795.8881644733983;2800.9299767505977;2817.633447777867;4711.340412342275;5046.1169295373165;5110.591152707849;5115.590752739772;5445.700486473203;4948.826791062295;5232.566856411787;5368.91946620801;5389.407165211824;5429.859729456779;4623.352730617717;5078.501841956631;5186.343627742433;5436;5492.3522943117705;4900.240462728283;5233.188855727369;5254.422013578427;5278.548399190254;5282.339707536558;4785.377900872864;5269.209618557247;5655.264815573949;6123.418275263843;6130.5402094842175;5559.4982257984875;5843.561732869966;6379.808605741835;6385.712642931172;6452.870942581216;3551.8224088795973;3852.9211820690252;3961.980190099031;4063.593640635913;4096.160287141189;5651.180578815966;6147.170132032118;6181.474574661204;6244.094606282155;6297.496200303873;5132.43543210254;5285.810692594289;5355.715427657936;5738.331675770786;6056.57603967718;5159.742012899265;5414.783408663609;5460.563154947516;5968.687378504971;6015.939840601713;5521.751521181476;5577.27495425592;5633.718314084362;5948.762049518144;5965.34381218057;5423.674579525241;5538.944610554004;5976.701164941823;5999.940000600112;6081.027035674271;5804.751978324681;5860.94139058621;5880.50015748649;5881.529477641693;5907.5273978080795 07:37:55 INFO - PROCESS | 1670 | |5;yui.html;649.6167261315824;706.240791149514;807.43479564305;857.4126723194609;858.347655781606;11825.467853946571;14899.702005959874;16304.347826086972;16416.917915410435;16479.928804627692;14976.700465990674;14996.550103496886;14999.550013499613;15016.174110423923;15016.399344026253;701.2356531380794;720.5892641194521;872.6989188729877;898.865170224465;906.8367693815144;910.9544522773868;913.3652111782314;913.3971578758021;914.6524320758102;915.4919019943928;966.7873067925045;967.9467629280387;970.6214576315243;973.5862258540128;974.3666616699131;951.7953639967405;956.5073986896789;961.4183419031525;965.3049804141007;966.5457235099527;710.783211120608;716.7240612364225;717.2827172827173;721.2787212787213;721.797896588956;14690.853091469162;14803.92598037003;14856.257187140707;15118.470853520073;15148.469803556822;12103.878961210363;14908.478203262834;14959.92520037393;15022.624434389118;15037.398504059935;11849.763004739943;12089.153759236857;12148.21036632625;12200.511979520808;12599.685007874785;3857.961420385788;3869.787161706105;3878.1661942682176;3917.5103112110987;3963.128111815396;104325.95674043239;109113;119795;119900;122939;11331.320120792614;11364.94317528407;11468.713282168013;11496.42517874103;11522.53909843597;22777.202797902326;23471.7652823473;23622.527549448914;23640.645390319158;23650.29049128529;7923.484973476706;8227.71203007892;8276;8301.8339633207;8488.236058754741;4493.550644935481;4517.051419201939;4583.449986001701;4614.377059097046;4674.579287864109;3701.481792549045;3785.8107094645156;3820.3887378019385;3826.6556009959236;3829.061879839457;665.1585744033802;681.5842336174929;684.0389253099403;692.3042342445809;696.7178292791427;564.5681053993698;604.4016423740466;667.7829705345782;669.3507297921009;685.4379408884666;9019.188273055457;9672.854907176397;9709.805803883883;9789.265805064506;9872.210223182128;589.3311091910713;621.2172662445352;645.612632420544;655.9639219842954;705.0657878311258;4244.681648876347;4545.727256364628;4742.9762851186215;4748.240281554935;4795.856124316276;90762.54618726864;91748.54125729328;102091.48954255182;129784;134641;93973.0602693978;124651.37674311752;127077;127889;136318;77962.61018694984;82927.5853620728;103749.48125259476;104820;109363;9531.52342382892;9884.229789942689;10016.749581260527;10029.147522460673;10078.092971632295;49133.75433122811;52314.738426307624;53340;59139.817203656545;66124.6693766528;74549.25450745423;78756.60621696855;92205.53897230471;99643.50178249062;102401.48799255956;13418.329083545588;13497.797533036815;13650.482831285104;13731.45074197021;13928.233947133005;15081.24593770329;15225.923870380577;15311.15788631636;15485.690286194438;15686.843131568538;13419.865801342252;14346.210958397389;14362.640933976316;14537.636559086108;15263.694726105638;11753.706157345792;11827.17209795306;12496.937515312366;14631.26843657834;14709.852901470847;13407.731845363232;13453.865461345262;13465.461381545021;13800.37898294559;14474.493392731203;11638.592649257236;11797.35114568707;11943.701407464883;12279.631611051684;12360;10187.337823041478;11483.655490335303;11520;11674.182807203753;12275.2021118627 07:37:55 INFO - PROCESS | 1670 | __end_tp_report 07:37:55 INFO - PROCESS | 1670 | __start_cc_report 07:37:55 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,431 07:37:55 INFO - PROCESS | 1670 | __end_cc_report 07:37:55 INFO - PROCESS | 1670 | __startTimestamp1474123075826__endTimestamp 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | ------- Summary: start ------- 07:37:55 INFO - PROCESS | 1670 | Number of tests: 6 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5948.02 Median:6200.84 stddev:675.93 (10.9%) stddev-sans-first:648.78 07:37:55 INFO - PROCESS | 1670 | Values: 3441.7 3446.4 3462.8 3480.0 3485.6 6053.1 6057.2 6125.2 6132.5 6141.0 6090.2 6099.8 6137.8 6162.5 6200.8 5461.1 5499.7 5529.2 5547.5 5603.5 5440.2 5458.1 5500.9 5506.5 5560.2 5504.8 5505.3 5524.4 5544.6 5546.8 5497.8 5508.7 5554.4 5569.8 5647.1 5292.0 5316.8 5358.2 5389.4 5463.6 4719.1 5763.3 6104.3 6104.3 6141.2 6157.1 6291.7 6327.1 6349.7 6365.4 5399.8 6265.2 6373.9 6380.1 6381.4 4492.3 5930.6 5962.8 6189.1 6224.6 6601.8 6611.0 6620.6 6627.7 6633.4 5202.3 6200.5 6216.6 6265.6 6301.5 6447.7 6468.3 6471.5 6479.4 6483.0 6319.7 6344.4 6394.2 6403.3 6405.1 6217.8 6301.5 6314.7 6318.4 6397.9 5120.5 5182.6 5186.6 5188.7 5197.4 3953.3 4611.2 4649.3 4661.3 4681.7 6014.0 6022.0 6054.6 6116.0 6174.9 6440.4 6467.4 6473.8 6492.2 6511.7 6157.8 6163.0 6177.7 6178.3 6182.2 5498.0 5666.1 5744.9 5746.0 5748.8 6597.2 6650.6 6654.6 6659.7 6686.7 6457.8 6469.3 6485.2 6486.3 6490.8 6391.9 6482.6 6515.8 6526.8 6606.7 5392.2 6305.9 6353.3 6356.1 6357.8 6397.8 6411.5 6433.5 6449.1 6464.3 6221.4 6243.0 6251.5 6305.1 6310.6 6234.7 6246.3 6259.6 6274.7 6275.6 4921.4 6111.9 6134.7 6306.9 6323.8 6194.7 6202.7 6244.3 6247.8 6291.9 6241.5 6287.1 6297.0 6307.9 6323.8 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:70107.63 Median:41294.88 stddev:155470.00 (376.5%) stddev-sans-first:155818.35 07:37:55 INFO - PROCESS | 1670 | Values: 523.4 530.0 531.2 564.0 573.1 30617.5 32980.0 42432.4 42549.8 42703.5 43250.4 43665.0 43778.3 43820.6 43830.0 9675.0 9835.3 9837.1 9849.3 9874.0 9779.4 9784.8 9797.9 9853.2 9854.6 8641.4 9757.5 9806.9 9837.4 9888.5 8641.9 9627.9 9861.6 9869.9 9877.5 9759.1 9783.2 9799.0 9818.0 9820.7 37488.6 40122.8 42125.0 42255.6 42613.1 3164.7 3281.9 3356.0 3433.1 3529.1 30731.1 32326.0 32634.5 33075.5 33572.0 2955.8 3061.0 3251.2 3294.9 3381.9 423495.0 496435.0 509816.0 510959.0 511310.0 31842.6 32146.2 64611.0 65051.0 65316.0 36058.5 36099.0 36258.3 36658.5 36752.4 15730.8 15756.4 15901.3 15932.8 15948.0 13146.6 13390.4 13393.9 13441.0 13460.5 7864.9 7875.0 7913.3 7932.0 7988.2 3285.2 3301.3 3304.1 3319.7 3319.9 925.2 955.4 965.8 984.0 1031.5 31076.2 31092.4 31233.0 31258.8 31393.0 1081.8 1089.5 1099.0 1141.0 1179.5 16059.2 16513.0 16562.8 16623.9 16684.5 665049.0 665717.0 672634.0 678408.0 682483.6 61081.0 61719.0 61863.4 61897.7 62019.0 1443.6 1450.3 1470.9 1476.8 1479.8 29018.9 31155.1 31173.8 31815.0 31857.2 55171.4 55297.0 55537.7 55630.2 55793.2 558147.2 563161.0 563539.0 565416.2 566465.0 27438.0 27900.9 28666.0 28677.4 28958.6 49963.8 50207.7 50257.5 50909.7 51273.0 39658.4 41220.8 41369.0 41724.0 42422.4 33152.3 41066.4 41435.4 41650.4 42351.8 24059.0 36234.6 36967.7 39655.4 40533.4 30977.0 36568.0 36623.1 36996.4 37046.8 26667.6 26945.5 27084.9 27109.3 27332.9 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34770.79 Median:2476.87 stddev:98745.33 (3986.7%) stddev-sans-first:98988.45 07:37:55 INFO - PROCESS | 1670 | Values: 317.3 382.8 389.0 393.4 405.9 17404.6 20097.5 21548.9 21736.5 21995.5 18951.3 20286.7 20746.2 21017.9 21215.9 5340.9 5569.9 5745.8 6039.8 6054.5 5691.7 5978.6 6054.3 6094.1 6110.7 5918.6 5951.6 6053.5 6070.5 6082.6 5981.6 6057.2 6069.0 6069.2 6082.5 5738.1 5793.7 5816.7 5884.4 5887.8 13546.0 22336.3 25418.1 25522.4 26033.2 2121.9 2242.3 2421.2 2532.6 3011.7 10353.9 10515.1 10639.9 10776.9 13199.7 1605.3 1830.1 1838.5 1896.1 1949.8 121611.0 125119.0 126127.4 127513.0 128907.0 9195.6 11465.4 11994.5 12037.9 12269.3 14630.3 14641.0 14813.6 14830.6 14864.9 8646.7 8674.1 8931.6 8947.3 9053.9 8868.6 8880.3 8892.8 8939.1 9034.5 4509.7 4529.9 4536.4 4540.1 4552.4 1924.8 1986.6 1993.3 1994.7 1999.8 898.4 930.3 971.1 975.8 983.5 17202.0 21977.8 22062.9 22098.0 22260.0 957.5 974.1 983.2 990.8 1026.8 11103.7 11431.7 11486.3 11664.1 11671.1 584589.0 585411.0 588196.0 593351.0 601513.0 103459.5 111517.0 125959.4 134636.0 138659.6 922.9 985.4 1007.6 1027.5 1030.2 16596.5 17550.4 19398.7 19410.1 19480.5 37612.4 48928.8 52485.5 55242.0 56240.2 75446.0 75587.6 77844.0 81406.2 82995.0 7925.8 7931.4 7959.9 8043.9 8184.0 10447.4 12428.2 12534.2 12574.9 12950.8 10407.7 10668.0 10743.2 10884.2 11085.2 10294.6 10399.2 10508.9 10586.3 10856.8 8804.8 10747.0 10818.0 10974.8 11152.3 7814.8 7831.5 7905.3 8223.9 8299.3 8166.8 10024.0 10779.9 11066.2 11305.9 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5911.06 Median:5017.25 stddev:3327.76 (66.3%) stddev-sans-first:3320.90 07:37:55 INFO - PROCESS | 1670 | Values: 1542.0 1586.9 1823.9 1891.5 1917.2 8291.9 8803.5 8958.9 9415.1 9415.6 9055.6 9282.2 9284.9 9297.6 9302.7 2377.6 2412.4 2417.8 2421.2 2431.3 2358.8 2397.6 2413.2 2439.0 2442.3 2371.8 2415.6 2425.0 2429.9 2440.1 2355.7 2371.3 2414.8 2419.5 2423.7 2132.7 2261.7 2355.8 2415.2 2424.1 8510.1 8929.1 9318.0 9385.7 9395.3 4714.6 5395.4 5553.3 5566.1 5580.1 3709.6 3779.7 3794.6 3796.3 3817.0 1106.8 1115.2 1116.6 1116.9 1132.4 11952.4 12003.6 12163.0 12186.7 12210.5 4468.5 4602.8 4690.7 4714.8 4724.5 9388.3 9503.8 9633.9 9634.5 9648.3 5313.5 5400.8 5407.8 5409.6 5413.2 4510.5 4898.5 5027.0 5058.6 5064.4 3241.7 3877.3 3966.4 3968.3 4020.3 1165.7 1184.4 1198.5 1207.5 1220.2 803.8 887.6 902.3 920.2 954.6 5034.9 6060.8 6120.6 6161.7 6341.5 973.0 991.2 1008.6 1014.0 1037.0 4055.2 4076.3 4186.5 4241.0 4288.5 9485.0 10903.9 11118.6 11160.4 11313.7 10586.8 11085.1 11186.8 11190.3 11195.8 8100.5 8571.4 8584.6 8616.0 8624.1 6171.0 6181.8 6216.6 6316.6 6394.0 9885.9 10367.6 10402.3 10469.3 10738.4 10403.2 10464.9 10782.1 10921.9 10942.5 7269.3 7690.5 7853.7 7894.9 7967.4 8800.3 8861.6 8930.2 8934.6 8962.2 7739.8 7867.6 7876.5 7908.5 8147.4 7833.6 7909.4 7911.5 7917.4 7928.7 7346.8 7373.9 7450.5 7456.2 7458.9 4843.3 5007.4 5161.8 5164.3 5325.3 5080.0 5177.2 5238.5 5309.2 5400.7 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4638.27 Median:5049.51 stddev:1409.13 (27.9%) stddev-sans-first:1375.00 07:37:55 INFO - PROCESS | 1670 | Values: 302.8 308.8 315.6 318.1 318.9 5536.9 5624.6 5720.3 5777.8 5824.2 4444.6 4591.6 5645.7 5675.2 5698.1 3950.9 3960.6 3976.9 4123.9 4311.9 4082.4 4291.4 4345.6 4361.0 4370.3 3504.3 4315.0 4320.0 4323.1 4329.0 3545.4 4317.4 4338.5 4349.3 4352.7 3785.5 3789.5 3795.2 3801.1 3817.7 4727.0 4945.8 5559.8 5572.3 5592.9 4382.3 5029.3 5042.2 5066.9 5069.2 5540.4 5666.7 6012.8 6040.5 6091.2 4014.8 4142.7 4186.0 4192.9 4210.6 4959.2 5042.5 5871.8 6339.3 6396.3 3349.9 3969.3 4283.8 4294.5 4295.6 4281.8 4308.9 4323.4 4331.9 4337.5 4326.6 5005.2 5032.4 5052.9 5069.8 5024.9 5789.8 6061.7 6111.9 6129.8 1447.4 1478.7 1478.9 1483.4 1488.2 794.9 813.3 837.7 915.5 920.1 3967.8 4556.4 4591.7 4652.8 4771.7 4908.9 4965.7 5321.9 5327.4 5350.3 3983.0 4375.4 4811.7 4820.4 4842.0 2349.7 2791.8 2795.9 2800.9 2817.6 4711.3 5046.1 5110.6 5115.6 5445.7 4948.8 5232.6 5368.9 5389.4 5429.9 4623.4 5078.5 5186.3 5436.0 5492.4 4900.2 5233.2 5254.4 5278.5 5282.3 4785.4 5269.2 5655.3 6123.4 6130.5 5559.5 5843.6 6379.8 6385.7 6452.9 3551.8 3852.9 3962.0 4063.6 4096.2 5651.2 6147.2 6181.5 6244.1 6297.5 5132.4 5285.8 5355.7 5738.3 6056.6 5159.7 5414.8 5460.6 5968.7 6015.9 5521.8 5577.3 5633.7 5948.8 5965.3 5423.7 5538.9 5976.7 5999.9 6081.0 5804.8 5860.9 5880.5 5881.5 5907.5 07:37:55 INFO - PROCESS | 1670 | 07:37:55 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:23340.19 Median:3803.10 stddev:35524.34 (934.1%) stddev-sans-first:35583.14 07:37:55 INFO - PROCESS | 1670 | Values: 649.6 706.2 807.4 857.4 858.3 11825.5 14899.7 16304.3 16416.9 16479.9 14976.7 14996.6 14999.6 15016.2 15016.4 701.2 720.6 872.7 898.9 906.8 911.0 913.4 913.4 914.7 915.5 966.8 967.9 970.6 973.6 974.4 951.8 956.5 961.4 965.3 966.5 710.8 716.7 717.3 721.3 721.8 14690.9 14803.9 14856.3 15118.5 15148.5 12103.9 14908.5 14959.9 15022.6 15037.4 11849.8 12089.2 12148.2 12200.5 12599.7 3858.0 3869.8 3878.2 3917.5 3963.1 104326.0 109113.0 119795.0 119900.0 122939.0 11331.3 11364.9 11468.7 11496.4 11522.5 22777.2 23471.8 23622.5 23640.6 23650.3 7923.5 8227.7 8276.0 8301.8 8488.2 4493.6 4517.1 4583.4 4614.4 4674.6 3701.5 3785.8 3820.4 3826.7 3829.1 665.2 681.6 684.0 692.3 696.7 564.6 604.4 667.8 669.4 685.4 9019.2 9672.9 9709.8 9789.3 9872.2 589.3 621.2 645.6 656.0 705.1 4244.7 4545.7 4743.0 4748.2 4795.9 90762.5 91748.5 102091.5 129784.0 134641.0 93973.1 124651.4 127077.0 127889.0 136318.0 77962.6 82927.6 103749.5 104820.0 109363.0 9531.5 9884.2 10016.7 10029.1 10078.1 49133.8 52314.7 53340.0 59139.8 66124.7 74549.3 78756.6 92205.5 99643.5 102401.5 13418.3 13497.8 13650.5 13731.5 13928.2 15081.2 15225.9 15311.2 15485.7 15686.8 13419.9 14346.2 14362.6 14537.6 15263.7 11753.7 11827.2 12496.9 14631.3 14709.9 13407.7 13453.9 13465.5 13800.4 14474.5 11638.6 11797.4 11943.7 12279.6 12360.0 10187.3 11483.7 11520.0 11674.2 12275.2 07:37:55 INFO - PROCESS | 1670 | -------- Summary: end -------- 07:37:55 INFO - PROCESS | 1670 | 07:37:56 INFO - TEST-INFO | 1670: exit 0 07:37:56 INFO - TEST-OK | dromaeo_css | took 1112122ms 07:37:56 INFO - TEST-START | kraken 07:37:56 INFO - operating with platform_type : mac_ 07:37:56 INFO - Initialising browser for kraken test... 07:37:56 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpwNjKeq/profile http://localhost:49267/getInfo.html) 07:37:59 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 07:37:59 INFO - PROCESS | 1680 | colorDepth:24 07:37:59 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 07:37:59 INFO - PROCESS | 1680 | __metrics 07:37:59 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:38:00 INFO - TEST-INFO | 1680: exit 0 07:38:00 INFO - Browser initialized. 07:38:00 INFO - Running cycle 1/1 for kraken test... 07:38:00 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpwNjKeq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:12 INFO - PROCESS | 1682 | RSS: Main: 226881536 07:38:12 INFO - PROCESS | 1682 | 07:38:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:15 INFO - PROCESS | 1682 | RSS: Main: 239517696 07:38:15 INFO - PROCESS | 1682 | 07:38:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 07:38:19 INFO - PROCESS | 1682 | RSS: Main: 248139776 07:38:19 INFO - PROCESS | 1682 | 07:38:22 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 07:38:23 INFO - PROCESS | 1682 | RSS: Main: 251695104 07:38:23 INFO - PROCESS | 1682 | 07:38:25 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 07:38:25 INFO - PROCESS | 1682 | RSS: Main: 252174336 07:38:25 INFO - PROCESS | 1682 | 07:38:27 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:28 INFO - PROCESS | 1682 | RSS: Main: 252858368 07:38:28 INFO - PROCESS | 1682 | 07:38:31 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:31 INFO - PROCESS | 1682 | RSS: Main: 252456960 07:38:31 INFO - PROCESS | 1682 | 07:38:34 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:35 INFO - PROCESS | 1682 | RSS: Main: 252837888 07:38:35 INFO - PROCESS | 1682 | 07:38:38 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 07:38:39 INFO - PROCESS | 1682 | RSS: Main: 254369792 07:38:39 INFO - PROCESS | 1682 | 07:38:40 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:41 INFO - PROCESS | 1682 | RSS: Main: 254308352 07:38:41 INFO - PROCESS | 1682 | 07:38:43 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:44 INFO - PROCESS | 1682 | RSS: Main: 253853696 07:38:44 INFO - PROCESS | 1682 | 07:38:46 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:46 INFO - PROCESS | 1682 | RSS: Main: 254173184 07:38:46 INFO - PROCESS | 1682 | 07:38:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:50 INFO - PROCESS | 1682 | RSS: Main: 254484480 07:38:50 INFO - PROCESS | 1682 | 07:38:53 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:53 INFO - PROCESS | 1682 | RSS: Main: 254488576 07:38:53 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 07:38:55 INFO - PROCESS | 1682 | RSS: Main: 256466944 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | __start_tp_report 07:38:55 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 07:38:55 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 07:38:55 INFO - PROCESS | 1682 | |i|pagename|runs| 07:38:55 INFO - PROCESS | 1682 | |0;ai-astar;121;119;109;118;118;120;102;120;137;115 07:38:55 INFO - PROCESS | 1682 | |1;audio-beat-detection;163;138;166;153;113;130;110;143;147;109 07:38:55 INFO - PROCESS | 1682 | |2;audio-dft;195;197;198;194;203;193;200;201;198;200 07:38:55 INFO - PROCESS | 1682 | |3;audio-fft;65;76;76;160;58;67;79;84;146;58 07:38:55 INFO - PROCESS | 1682 | |4;audio-oscillator;71;70;116;72;71;73;115;70;73;110 07:38:55 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;90;88;134;87;90;86;90;119;135;92 07:38:55 INFO - PROCESS | 1682 | |6;imaging-darkroom;96;97;95;96;98;96;95;106;152;120 07:38:55 INFO - PROCESS | 1682 | |7;imaging-desaturate;91;96;90;97;94;91;76;82;138;91 07:38:55 INFO - PROCESS | 1682 | |8;json-parse-financial;57;59;59;59;58;60;57;57;56;56 07:38:55 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;83;101;58;91;112;104;76;103;103;69 07:38:55 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;84;82;85;86;85;85;91;96;97;107 07:38:55 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;137;154;122;147;153;136;149;155;159;138 07:38:55 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;165;167;166;161;166;164;164;174;168;163 07:38:55 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;67;66;66;65;74;86;85;87;86;75 07:38:55 INFO - PROCESS | 1682 | __end_tp_report 07:38:55 INFO - PROCESS | 1682 | __start_cc_report 07:38:55 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1089 07:38:55 INFO - PROCESS | 1682 | __end_cc_report 07:38:55 INFO - PROCESS | 1682 | __startTimestamp1474123135978__endTimestamp 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | ------- Summary: start ------- 07:38:55 INFO - PROCESS | 1682 | Number of tests: 14 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:117.90 Median:119.50 stddev:8.97 (7.5%) stddev-sans-first:9.45 07:38:55 INFO - PROCESS | 1682 | Values: 121.0 119.0 109.0 118.0 118.0 120.0 102.0 120.0 137.0 115.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:145.00 stddev:21.21 (14.6%) stddev-sans-first:20.33 07:38:55 INFO - PROCESS | 1682 | Values: 163.0 138.0 166.0 153.0 113.0 130.0 110.0 143.0 147.0 109.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:197.90 Median:199.00 stddev:3.21 (1.6%) stddev-sans-first:3.23 07:38:55 INFO - PROCESS | 1682 | Values: 195.0 197.0 198.0 194.0 203.0 193.0 200.0 201.0 198.0 200.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:86.90 Median:71.50 stddev:36.04 (50.4%) stddev-sans-first:37.35 07:38:55 INFO - PROCESS | 1682 | Values: 65.0 76.0 76.0 160.0 58.0 67.0 79.0 84.0 146.0 58.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:71.00 stddev:20.49 (28.9%) stddev-sans-first:21.17 07:38:55 INFO - PROCESS | 1682 | Values: 71.0 70.0 116.0 72.0 71.0 73.0 115.0 70.0 73.0 110.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:101.10 Median:89.00 stddev:20.01 (22.5%) stddev-sans-first:20.81 07:38:55 INFO - PROCESS | 1682 | Values: 90.0 88.0 134.0 87.0 90.0 86.0 90.0 119.0 135.0 92.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:96.00 stddev:18.23 (19.0%) stddev-sans-first:19.04 07:38:55 INFO - PROCESS | 1682 | Values: 96.0 97.0 95.0 96.0 98.0 96.0 95.0 106.0 152.0 120.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:91.00 stddev:16.52 (18.2%) stddev-sans-first:17.47 07:38:55 INFO - PROCESS | 1682 | Values: 91.0 96.0 90.0 97.0 94.0 91.0 76.0 82.0 138.0 91.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.50 stddev:1.40 (2.4%) stddev-sans-first:1.45 07:38:55 INFO - PROCESS | 1682 | Values: 57.0 59.0 59.0 59.0 58.0 60.0 57.0 57.0 56.0 56.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:90.00 Median:63.50 stddev:17.80 (28.0%) stddev-sans-first:18.69 07:38:55 INFO - PROCESS | 1682 | Values: 83.0 101.0 58.0 91.0 112.0 104.0 76.0 103.0 103.0 69.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.50 stddev:7.93 (9.3%) stddev-sans-first:8.13 07:38:55 INFO - PROCESS | 1682 | Values: 84.0 82.0 85.0 86.0 85.0 85.0 91.0 96.0 97.0 107.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:151.00 stddev:11.47 (7.6%) stddev-sans-first:11.79 07:38:55 INFO - PROCESS | 1682 | Values: 137.0 154.0 122.0 147.0 153.0 136.0 149.0 155.0 159.0 138.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:166.00 stddev:3.52 (2.1%) stddev-sans-first:3.72 07:38:55 INFO - PROCESS | 1682 | Values: 165.0 167.0 166.0 161.0 166.0 164.0 164.0 174.0 168.0 163.0 07:38:55 INFO - PROCESS | 1682 | 07:38:55 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:80.00 stddev:9.48 (11.8%) stddev-sans-first:9.51 07:38:55 INFO - PROCESS | 1682 | Values: 67.0 66.0 66.0 65.0 74.0 86.0 85.0 87.0 86.0 75.0 07:38:55 INFO - PROCESS | 1682 | -------- Summary: end -------- 07:38:55 INFO - PROCESS | 1682 | 07:38:56 INFO - TEST-INFO | 1682: exit 0 07:38:56 INFO - TEST-OK | kraken | took 60278ms 07:38:56 INFO - SUITE-END | took 1172s 07:38:57 INFO - Completed test suite (00:19:32) 07:38:57 INFO - javascript benchmark 07:38:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7450.150581180676, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.6558344165587, 3446.4313388290934, 3462.7576069675124, 3479.965200347996, 3485.634008429115, 6053.061775424816, 6057.182280392146, 6125.234345706786, 6132.478739307158, 6141.048137538689, 6090.17782599349, 6099.841030204269, 6137.846553836156, 6162.476189523896, 6200.844978875531, 5461.098918678424, 5499.7250137493165, 5529.225908372831, 5547.472990065938, 5603.495685388314, 5440.211169380437, 5458.099413596752, 5500.889982200353, 5506.476884695948, 5560.193771903072, 5504.752286147113, 5505.3393592768925, 5524.447555244484, 5544.63959842609, 5546.805861794838, 5497.835064948038, 5508.724563771795, 5554.361248456439, 5569.832905012856, 5647.09646456567, 5291.973540132275, 5316.760745766411, 5358.169483730028, 5389.407165211824, 5463.590230732671, 4719.126961512097, 5763.337216220105, 6104.26748790148, 6104.328523862371, 6141.170941922828, 6157.107219453199, 6291.653959032252, 6327.050942358637, 6349.682515874188, 6365.3634636536435, 5399.78400863965, 6265.248170219603, 6373.936260637381, 6380.074889141095, 6381.361863813581, 4492.303692927601, 5930.584859059867, 5962.791302304442, 6189.133521307021, 6224.564280500322, 6601.834954126185, 6611.041398997215, 6620.602763834186, 6627.701753421107, 6633.402993730588, 5202.323697919246, 6200.534959878028, 6216.564840461125, 6265.592736472206, 6301.52738544611, 6447.677616119176, 6468.2561505427475, 6471.482281417475, 6479.449246814073, 6483.0, 6319.715612797435, 6344.397282258178, 6394.232692076887, 6403.295637479873, 6405.135306733625, 6217.751289948352, 6301.52738544611, 6314.684265786692, 6318.4313411793155, 6397.936020639734, 5120.539151476385, 5182.585393168538, 5186.611004174702, 5188.662736922088, 5197.350331208599, 3953.2686453006286, 4611.1930412177735, 4649.256119020941, 4661.324108004321, 4681.7190968542, 6013.9699301503215, 6021.969890150609, 6054.606450580786, 6116.031445914963, 6174.938250617526, 6440.388163124496, 6467.353264673589, 6473.805785826433, 6492.156019717407, 6511.674416279167, 6157.84605384869, 6163.013917773224, 6177.660228687465, 6178.289496707846, 6182.165407669997, 5498.0, 5666.065099258576, 5744.913826292609, 5746.0, 5748.798792042258, 6597.175353080865, 6650.634215118215, 6654.600723956578, 6659.700313485905, 6686.665666716644, 6457.773977910751, 6469.256035555972, 6485.238561634146, 6486.254080780771, 6490.772822951174, 6391.9041214380895, 6482.611043337415, 6515.771947981798, 6526.804195873941, 6606.702698378488, 5392.245085688085, 6305.936940630535, 6353.269374021862, 6356.110144579855, 6357.841053973688, 6397.808065758035, 6411.519136064907, 6433.5496515243485, 6449.064885591657, 6464.288928217987, 6221.377862213742, 6242.969909964805, 6251.53113516479, 6305.085762564494, 6310.6213627182515, 6234.719437625227, 6246.281677607133, 6259.62442253448, 6274.749010039547, 6275.623462592259, 4921.434035086011, 6111.908321375272, 6134.6625935573975, 6306.873862522815, 6323.841903952438, 6194.659293738708, 6202.658853762906, 6244.344343843831, 6247.844148832481, 6291.874162516632, 6241.500679945684, 6287.119803227643, 6296.968515157395, 6307.90538141919, 6323.841903952254], "value": 5906.677689091907, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.3667262612239, 530.0459173487724, 531.2005431825103, 564.0383146734818, 573.1374281706031, 30617.540736888935, 32980.0, 42432.36351454731, 42549.787251063775, 42703.50537731184, 43250.351244731355, 43665.0, 43778.34332485007, 43820.56179438205, 43830.0, 9674.9516252419, 9835.31152819303, 9837.065478779541, 9849.310548261588, 9873.950630246838, 9779.413235205875, 9784.755381115458, 9797.902020979805, 9853.162481189107, 9854.556544955494, 8641.395102342838, 9757.463339516324, 9806.901930980706, 9837.409755414663, 9888.505574721272, 8641.913580864206, 9627.855582166238, 9861.556229969668, 9869.90130098697, 9877.506124693737, 9759.072888075621, 9783.217342612574, 9799.0, 9818.0, 9820.65627703027, 37488.625113748785, 40122.796316110565, 42125.0, 42255.57744422547, 42613.147737045394, 3164.715175634181, 3281.917952051194, 3356.0, 3433.1073920780777, 3529.0824385659657, 30731.078067658007, 32326.030219093464, 32634.510482342783, 33075.50386744177, 33572.0, 2955.793094483387, 3061.0357737312897, 3251.203455153479, 3294.934101317984, 3381.8647254109806, 423494.9999999969, 496435.0, 509816.0, 510959.0, 511310.0, 31842.567084481256, 32146.19634509156, 64611.0, 65051.0, 65316.0, 36058.45912311317, 36099.0, 36258.274834503165, 36658.45012324817, 36752.448713269325, 15730.842691572938, 15756.448524301595, 15901.284442200129, 15932.761008584881, 15947.963382380109, 13146.553879073294, 13390.397432115578, 13393.933030334785, 13441.0, 13460.461581536629, 7864.921350786533, 7875.0, 7913.287804097544, 7931.960340198378, 7988.241117093867, 3285.227971426713, 3301.257217126127, 3304.058343372127, 3319.71782398494, 3319.883804066846, 925.2366797392178, 955.4267439536222, 965.7875267441152, 983.9852402213972, 1031.454400293579, 31076.22309442282, 31092.378152436824, 31233.0, 31258.843705781324, 31393.0, 1081.7511972246427, 1089.4661615808197, 1098.9780204395868, 1140.9828852567218, 1179.4751335655722, 16059.197040147947, 16513.0, 16562.83437165637, 16623.91944523602, 16684.49946501607, 665049.0, 665717.0, 672634.0, 678408.0, 682483.5875820689, 61081.0, 61719.0, 61863.381366185764, 61897.69051154715, 62019.0, 1443.5813614051808, 1450.2893582144673, 1470.9186501100824, 1476.756335204679, 1479.8224213094497, 29018.854905725337, 31155.065348039596, 31173.8441307792, 31815.045548632654, 31857.203569910936, 55171.44828551663, 55297.0, 55537.722311388185, 55630.16554751763, 55793.16310255268, 558147.209263951, 563161.0, 563539.0, 565416.1729191327, 566465.0, 27438.039668611502, 27900.883964641187, 28665.996690115746, 28677.42645147127, 28958.565621516114, 49963.75018124886, 50207.74896125496, 50257.49742502528, 50909.74545127251, 51273.0, 39658.40512392374, 41220.79389603033, 41368.965775855846, 41724.0, 42422.36366454567, 33152.342382881245, 41066.384004239364, 41435.37846932238, 41650.375244371964, 42351.78824105983, 24059.0, 36234.637653623126, 36967.70613028531, 39655.40516892307, 40533.39199911945, 30977.0, 36568.0, 36623.084422888576, 36996.44505332368, 37046.814765927076, 26667.599985999837, 26945.461090778466, 27084.864575676995, 27109.322266942694, 27332.897083285017], "value": 19183.07275128084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.29243786356426, 382.7818143658115, 389.0332523678659, 393.44327776196684, 405.8944674384661, 17404.56488587786, 20097.497562560944, 21548.89225553874, 21736.456588585275, 21995.450113747163, 18951.33670321539, 20286.69569956452, 20746.170153193892, 21017.894910525465, 21215.893920530376, 5340.866478338054, 5569.860753481175, 5745.827625171251, 6039.818805435844, 6054.5459090568165, 5691.743871525791, 5978.581499295051, 6054.303755068157, 6094.146819445281, 6110.663913484756, 5918.644881307114, 5951.642901425928, 6053.455189032986, 6070.453659170674, 6082.635041897478, 5981.58128930975, 6057.182280392135, 6068.969655151696, 6069.15031895535, 6082.513398928098, 5738.081906894877, 5793.7392817323325, 5816.680082595455, 5884.411558844125, 5887.7939272125695, 13546.0, 22336.329910102722, 25418.110366137098, 25522.361940951625, 26033.219003429927, 2121.9045142968603, 2242.2712618398923, 2421.1646981791255, 2532.569463191262, 3011.6687164411896, 10353.948230258877, 10515.106215971651, 10639.893601063968, 10776.892231077745, 13199.736005279841, 1605.3418098579643, 1830.066666000336, 1838.5403649087727, 1896.0993528074055, 1949.7619011927386, 121611.0, 125119.0, 126127.36936315351, 127513.0, 128907.0, 9195.586198621078, 11465.369404682822, 11994.460249288803, 12037.939810300892, 12269.263844169378, 14630.26848657563, 14641.048331858396, 14813.629659258604, 14830.555083347517, 14864.851351486346, 8646.654133834701, 8674.08922063187, 8931.598078086372, 8947.328950328621, 9053.864192037125, 8868.645254189889, 8880.289576833839, 8892.822143557092, 8939.061398553058, 9034.503102329434, 4509.706869053501, 4529.932051019237, 4536.387587675622, 4540.137373898949, 4552.385427967249, 1924.8460123190391, 1986.5629561496448, 1993.3421970749619, 1994.7207390965277, 1999.7700264469481, 898.3576742629051, 930.3487558708796, 971.1017308989157, 975.8097171051577, 983.4640121133999, 17202.0, 21977.793095924208, 22062.88968555147, 22098.0055897485, 22259.998300076535, 957.5260246178186, 974.1427543761586, 983.1839573154265, 990.8266053440619, 1026.810040142576, 11103.666889993314, 11431.657050288504, 11486.253393529394, 11664.125190610737, 11671.12466565011, 584589.0, 585411.0, 588196.0, 593351.0, 601513.0, 103459.48270258751, 111517.0, 125959.3702031484, 134636.0, 138659.61340386668, 922.9169374756304, 985.3742873275521, 1007.5717819926502, 1027.4554486122368, 1030.217035053365, 16596.506314431757, 17550.38573649916, 19398.709019365004, 19410.126544305258, 19480.512987175432, 37612.435813463366, 48928.77678058077, 52485.475145248056, 55242.0, 56240.156397654886, 75446.0, 75587.62206188934, 77844.0, 81406.18593813985, 82995.0, 7925.762227133196, 7931.444798864024, 7959.880601790862, 8043.879341809995, 8183.95908020456, 10447.42539160323, 12428.192167509083, 12534.247945123321, 12574.937125314314, 12950.805737914126, 10407.739806504898, 10668.0, 10743.24797264184, 10884.183686223407, 11085.168612354268, 10294.639687610897, 10399.220058495794, 10508.894911050791, 10586.258961872903, 10856.782864342827, 8804.823903522021, 10747.0, 10818.0, 10974.780504389708, 11152.275102118336, 7814.804629884298, 7831.451798374059, 7905.288524032635, 8223.917760822314, 8299.253067223979, 8166.83666326682, 10024.0, 10779.89220107789, 11066.225364224749, 11305.943470282926], "value": 9594.131412046354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1542.0285220311205, 1586.8809839262055, 1823.890566566006, 1891.536573539483, 1917.1756144857707, 8291.875621865676, 8803.471791692495, 8958.910410895889, 9415.105564971325, 9415.623375065006, 9055.592498337573, 9282.211012063966, 9284.90715092849, 9297.581608827602, 9302.720918372443, 2377.5958087125186, 2412.433078226615, 2417.758224177586, 2421.2131057406327, 2431.3192306154306, 2358.8230882683783, 2397.5564520563667, 2413.1674572272536, 2439.0487709793106, 2442.3405680466262, 2371.7746814052725, 2415.5893498105274, 2425.042108367194, 2429.9392515187087, 2440.0849681369486, 2355.6937598112136, 2371.300466362419, 2414.782669559739, 2419.548271037372, 2423.7212720537186, 2132.658774596073, 2261.6882208319134, 2355.823313251496, 2415.1667674652394, 2424.115197952752, 8510.106438823896, 8929.06244844295, 9317.953410232973, 9385.671501497483, 9395.34232603718, 4714.622830173578, 5395.406505284415, 5553.333599968029, 5566.109422492423, 5580.079286917654, 3709.55485341758, 3779.6976241900584, 3794.6395092466173, 3796.2787070456525, 3817.0457385653585, 1106.7675788084512, 1115.2472081345059, 1116.6426743442103, 1116.8644550702409, 1132.399828091113, 11952.40237988097, 12003.57987470443, 12163.0, 12186.695332616668, 12210.450529726273, 4468.50846406895, 4602.769861506911, 4690.695104818176, 4714.834980775725, 4724.527547245317, 9388.295877809054, 9503.809923801486, 9633.940266570671, 9634.518274086267, 9648.324617276723, 5313.495217954288, 5400.83797486076, 5407.810726624549, 5409.56723462122, 5413.2150838128255, 4510.458744950627, 4898.485659005753, 5027.044861476308, 5058.570021548136, 5064.392272927273, 3241.7244534214856, 3877.3020856245585, 3966.3852102924093, 3968.2857085724845, 4020.3366444536323, 1165.6653132163626, 1184.3604453594962, 1198.5445530698278, 1207.4626791077885, 1220.170284206748, 803.7950322667682, 887.627196577439, 902.3367824648877, 920.185635712386, 954.61815273891, 5034.949650503448, 6060.757569697251, 6120.60216085953, 6161.691915404212, 6341.524385671001, 972.9756756081155, 991.1773228220703, 1008.6318493749728, 1014.0366651680932, 1036.9688909332733, 4055.168690418457, 4076.30702780528, 4186.476690413699, 4240.957590424056, 4288.50682171548, 9485.0, 10903.945480272549, 11118.555257789598, 11160.441977901073, 11313.660590182308, 10586.788264234672, 11085.057770089472, 11186.832197517044, 11190.32858028521, 11195.776084478428, 8100.473469224482, 8571.357148213785, 8584.570771461402, 8616.009158946676, 8624.137586241326, 6170.981788004929, 6181.81454556364, 6216.595921265103, 6316.62100273985, 6394.0408938659575, 9885.851712224467, 10367.585296588051, 10402.323848949794, 10469.31949423285, 10738.40938748345, 10403.167746580104, 10464.947675261576, 10782.083522900328, 10921.890781092088, 10942.507587158438, 7269.3094156055095, 7690.461667683208, 7853.685852566062, 7894.921050789419, 7967.442279040411, 8800.251978581893, 8861.601227944888, 8930.19628233462, 8934.642614295355, 8962.193402593797, 7739.806504837199, 7867.606619669108, 7876.527408355517, 7908.485948413335, 8147.429679922348, 7833.647485863264, 7909.367250620052, 7911.485753426009, 7917.366610671248, 7928.682852685828, 7346.816329591803, 7373.9262607375385, 7450.515716478411, 7456.179820219663, 7458.92541074604, 4843.297721830385, 5007.449180590205, 5161.81933632321, 5164.251183578285, 5325.254464375068, 5080.0, 5177.197534382177, 5238.528532432099, 5309.177077552986, 5400.675959442446], "value": 4733.974997604468, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.8410084705528, 308.76842368223834, 315.6496289119078, 318.1346736875697, 318.91229911774275, 5536.944630553693, 5624.634398764078, 5720.313562372516, 5777.797777077803, 5824.242848429703, 4444.555544445554, 4591.5867571918525, 5645.689487078209, 5675.177099320596, 5698.145278208271, 3950.9209815803665, 3960.584138665442, 3976.886471787897, 4123.876283711494, 4311.935320970188, 4082.448869402622, 4291.433626726238, 4345.565443455661, 4361.018770776582, 4370.344448332759, 3504.281622267442, 4314.97842510787, 4319.978400107995, 4323.135372925428, 4328.956710432887, 3545.3618348697223, 4317.352397140423, 4338.461999730162, 4349.347597860315, 4352.673549483801, 3785.523645778149, 3789.5263092113487, 3795.221979494225, 3801.1067399161175, 3817.675497582709, 4726.976365118187, 4945.809952580601, 5559.833205003856, 5572.275604171432, 5592.888142237173, 4382.312809568307, 5029.346184995927, 5042.218456139304, 5066.873328166788, 5069.239614057884, 5540.36285827127, 5666.716664166775, 6012.8496787580225, 6040.456358927674, 6091.238595175603, 4014.7992600369867, 4142.730722503027, 4185.97907010466, 4192.8532501362615, 4210.578942105795, 4959.156943319608, 5042.4535609517325, 5871.823845284648, 6339.2709838368255, 6396.3283855194995, 3349.8995030149135, 3969.2932039222833, 4283.807228674717, 4294.484661840599, 4295.613394794484, 4281.828726850891, 4308.89227769302, 4323.416338794285, 4331.935020974688, 4337.501187363494, 4326.567343265649, 5005.199168133081, 5032.421271553745, 5052.898942021139, 5069.797208111634, 5024.949750502521, 5789.797357092481, 6061.727222275008, 6111.908321375183, 6129.785457508966, 1447.392095319947, 1478.728293667445, 1478.8521147885126, 1483.406637345049, 1488.2409970914696, 794.8529522038441, 813.341193633159, 837.6848348093434, 915.4919019943995, 920.1350730313484, 3967.7619342838943, 4556.430446194226, 4591.678582499193, 4652.767361631905, 4771.73755443454, 4908.852734417973, 4965.659271996541, 5321.920171197435, 5327.38735045467, 5350.30446042012, 3982.980085099556, 4375.44671641569, 4811.687240329367, 4820.421549414093, 4842.038974215448, 2349.706286714161, 2791.7855732756316, 2795.8881644733983, 2800.9299767505977, 2817.633447777867, 4711.340412342275, 5046.1169295373165, 5110.591152707849, 5115.590752739772, 5445.700486473203, 4948.826791062295, 5232.566856411787, 5368.91946620801, 5389.407165211824, 5429.859729456779, 4623.352730617717, 5078.501841956631, 5186.343627742433, 5436.0, 5492.3522943117705, 4900.240462728283, 5233.188855727369, 5254.422013578427, 5278.548399190254, 5282.339707536558, 4785.377900872864, 5269.209618557247, 5655.264815573949, 6123.418275263843, 6130.5402094842175, 5559.4982257984875, 5843.561732869966, 6379.808605741835, 6385.712642931172, 6452.870942581216, 3551.8224088795973, 3852.9211820690252, 3961.980190099031, 4063.593640635913, 4096.160287141189, 5651.180578815966, 6147.170132032118, 6181.474574661204, 6244.094606282155, 6297.496200303873, 5132.43543210254, 5285.810692594289, 5355.715427657936, 5738.331675770786, 6056.57603967718, 5159.742012899265, 5414.783408663609, 5460.563154947516, 5968.687378504971, 6015.939840601713, 5521.751521181476, 5577.27495425592, 5633.718314084362, 5948.762049518144, 5965.34381218057, 5423.674579525241, 5538.944610554004, 5976.701164941823, 5999.940000600112, 6081.027035674271, 5804.751978324681, 5860.94139058621, 5880.50015748649, 5881.529477641693, 5907.5273978080795], "value": 4189.150489549358, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.6167261315824, 706.240791149514, 807.43479564305, 857.4126723194609, 858.347655781606, 11825.467853946571, 14899.702005959874, 16304.347826086972, 16416.917915410435, 16479.928804627692, 14976.700465990674, 14996.550103496886, 14999.550013499613, 15016.174110423923, 15016.399344026253, 701.2356531380794, 720.5892641194521, 872.6989188729877, 898.865170224465, 906.8367693815144, 910.9544522773868, 913.3652111782314, 913.3971578758021, 914.6524320758102, 915.4919019943928, 966.7873067925045, 967.9467629280387, 970.6214576315243, 973.5862258540128, 974.3666616699131, 951.7953639967405, 956.5073986896789, 961.4183419031525, 965.3049804141007, 966.5457235099527, 710.783211120608, 716.7240612364225, 717.2827172827173, 721.2787212787213, 721.797896588956, 14690.853091469162, 14803.92598037003, 14856.257187140707, 15118.470853520073, 15148.469803556822, 12103.878961210363, 14908.478203262834, 14959.92520037393, 15022.624434389118, 15037.398504059935, 11849.763004739943, 12089.153759236857, 12148.21036632625, 12200.511979520808, 12599.685007874785, 3857.961420385788, 3869.787161706105, 3878.1661942682176, 3917.5103112110987, 3963.128111815396, 104325.95674043239, 109113.0, 119795.0, 119900.0, 122939.0, 11331.320120792614, 11364.94317528407, 11468.713282168013, 11496.42517874103, 11522.53909843597, 22777.202797902326, 23471.7652823473, 23622.527549448914, 23640.645390319158, 23650.29049128529, 7923.484973476706, 8227.71203007892, 8276.0, 8301.8339633207, 8488.236058754741, 4493.550644935481, 4517.051419201939, 4583.449986001701, 4614.377059097046, 4674.579287864109, 3701.481792549045, 3785.8107094645156, 3820.3887378019385, 3826.6556009959236, 3829.061879839457, 665.1585744033802, 681.5842336174929, 684.0389253099403, 692.3042342445809, 696.7178292791427, 564.5681053993698, 604.4016423740466, 667.7829705345782, 669.3507297921009, 685.4379408884666, 9019.188273055457, 9672.854907176397, 9709.805803883883, 9789.265805064506, 9872.210223182128, 589.3311091910713, 621.2172662445352, 645.612632420544, 655.9639219842954, 705.0657878311258, 4244.681648876347, 4545.727256364628, 4742.9762851186215, 4748.240281554935, 4795.856124316276, 90762.54618726864, 91748.54125729328, 102091.48954255182, 129784.0, 134641.0, 93973.0602693978, 124651.37674311752, 127077.0, 127889.0, 136318.0, 77962.61018694984, 82927.5853620728, 103749.48125259476, 104820.0, 109363.0, 9531.52342382892, 9884.229789942689, 10016.749581260527, 10029.147522460673, 10078.092971632295, 49133.75433122811, 52314.738426307624, 53340.0, 59139.817203656545, 66124.6693766528, 74549.25450745423, 78756.60621696855, 92205.53897230471, 99643.50178249062, 102401.48799255956, 13418.329083545588, 13497.797533036815, 13650.482831285104, 13731.45074197021, 13928.233947133005, 15081.24593770329, 15225.923870380577, 15311.15788631636, 15485.690286194438, 15686.843131568538, 13419.865801342252, 14346.210958397389, 14362.640933976316, 14537.636559086108, 15263.694726105638, 11753.706157345792, 11827.17209795306, 12496.937515312366, 14631.26843657834, 14709.852901470847, 13407.731845363232, 13453.865461345262, 13465.461381545021, 13800.37898294559, 14474.493392731203, 11638.592649257236, 11797.35114568707, 11943.701407464883, 12279.631611051684, 12360.0, 10187.337823041478, 11483.655490335303, 11520.0, 11674.182807203753, 12275.2021118627], "value": 7931.02724456721, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 109.0, 118.0, 118.0, 120.0, 102.0, 120.0, 137.0, 115.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 138.0, 166.0, 153.0, 113.0, 130.0, 110.0, 143.0, 147.0, 109.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 198.0, 194.0, 203.0, 193.0, 200.0, 201.0, 198.0, 200.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 76.0, 160.0, 58.0, 67.0, 79.0, 84.0, 146.0, 58.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 116.0, 72.0, 71.0, 73.0, 115.0, 70.0, 73.0, 110.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 134.0, 87.0, 90.0, 86.0, 90.0, 119.0, 135.0, 92.0], "value": 101.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 96.0, 98.0, 96.0, 95.0, 106.0, 152.0, 120.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 90.0, 97.0, 94.0, 91.0, 76.0, 82.0, 138.0, 91.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 59.0, 59.0, 58.0, 60.0, 57.0, 57.0, 56.0, 56.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 101.0, 58.0, 91.0, 112.0, 104.0, 76.0, 103.0, 103.0, 69.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 85.0, 86.0, 85.0, 85.0, 91.0, 96.0, 97.0, 107.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 154.0, 122.0, 147.0, 153.0, 136.0, 149.0, 155.0, 159.0, 138.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 166.0, 161.0, 166.0, 164.0, 164.0, 174.0, 168.0, 163.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 74.0, 86.0, 85.0, 87.0, 86.0, 75.0], "value": 75.7, "unit": "score"}]}]} 07:38:57 INFO - Return code: 0 07:38:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:57 ERROR - # TBPL SUCCESS # 07:38:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:57 INFO - 2016-09-17 07:38:57.298 screenresolution[1687:11775] starting screenresolution argv=screenresolution get 07:38:57 INFO - 2016-09-17 07:38:57.300 screenresolution[1687:11775] Display 0: 1600x1200x32@60 07:38:57 INFO - 2016-09-17 07:38:57.306 screenresolution[1688:11778] starting screenresolution argv=screenresolution list 07:38:57 INFO - Available Modes on Display 0 07:38:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:57 INFO - Intel Iris: 07:38:57 INFO - Chipset Model: Intel Iris 07:38:57 INFO - Type: GPU 07:38:57 INFO - Bus: Built-In 07:38:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:57 INFO - Vendor: Intel (0x8086) 07:38:57 INFO - Device ID: 0x0a2e 07:38:57 INFO - Revision ID: 0x0009 07:38:57 INFO - Displays: 07:38:57 INFO - Display: 07:38:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:57 INFO - Display Serial Number: 1600x1200 60 07:38:57 INFO - Main Display: Yes 07:38:57 INFO - Mirror: Off 07:38:57 INFO - Online: Yes 07:38:57 INFO - Rotation: Supported 07:38:57 INFO - Return code: 0 07:38:57 INFO - Running post-action listener: _resource_record_post_action 07:38:57 INFO - [mozharness: 2016-09-17 14:38:57.540842Z] Finished run-tests step (success) 07:38:57 INFO - Running post-run listener: _resource_record_post_run 07:38:57 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 31538176; Write bytes: 666304000; Read time: 206; Write time: 1874 07:38:57 INFO - TinderboxPrint: CPU usage
54.3% 07:38:57 INFO - TinderboxPrint: I/O read bytes / time
31,538,176 / 206 07:38:57 INFO - TinderboxPrint: I/O write bytes / time
666,304,000 / 1,874 07:38:57 INFO - TinderboxPrint: CPU idle
2,207.1 (45.7%) 07:38:57 INFO - TinderboxPrint: CPU system
977.8 (20.3%) 07:38:57 INFO - TinderboxPrint: CPU user
1,640.7 (34.0%) 07:38:57 INFO - TinderboxPrint: Swap in / out
392,658,944 / 0 07:38:57 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 356684288; Write bytes: 356603392; Read time: 27391; Write time: 725 07:38:57 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30596096; Write bytes: 299669504; Read time: 193; Write time: 1113 07:38:58 INFO - Running post-run listener: _upload_blobber_files 07:38:58 INFO - Blob upload gear active. 07:38:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:58 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:58 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:58 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:58 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:59 INFO - (blobuploader) - INFO - Done attempting. 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.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-e10s_raw.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/resource-usage.json ... 07:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:02 INFO - (blobuploader) - INFO - Done attempting. 07:39:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:02 INFO - Return code: 0 07:39:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b565655f70da6bc29ec263a5984f380609885f27a8374a71d2f221d4faeece6d641e4dff612c2f8d695c38b820a6b61fb77aaaae8a4240a90b0222677dd7143b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da2de9e303e13545d84418660d6794aac4d897a8ac33991da81c1d4913a22eacaf640a374b6ce564eda99f4c8e9e76a17f93a9bea9330226ca95390904e9637b"} 07:39:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:02 INFO - Contents: 07:39:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b565655f70da6bc29ec263a5984f380609885f27a8374a71d2f221d4faeece6d641e4dff612c2f8d695c38b820a6b61fb77aaaae8a4240a90b0222677dd7143b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da2de9e303e13545d84418660d6794aac4d897a8ac33991da81c1d4913a22eacaf640a374b6ce564eda99f4c8e9e76a17f93a9bea9330226ca95390904e9637b"} 07:39:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:02 INFO - Copying logs to upload dir... 07:39:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.039557 ========= master_lag: 2.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:39:05.733115) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:05.736581) ========= 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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b565655f70da6bc29ec263a5984f380609885f27a8374a71d2f221d4faeece6d641e4dff612c2f8d695c38b820a6b61fb77aaaae8a4240a90b0222677dd7143b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da2de9e303e13545d84418660d6794aac4d897a8ac33991da81c1d4913a22eacaf640a374b6ce564eda99f4c8e9e76a17f93a9bea9330226ca95390904e9637b"} 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.011521 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b565655f70da6bc29ec263a5984f380609885f27a8374a71d2f221d4faeece6d641e4dff612c2f8d695c38b820a6b61fb77aaaae8a4240a90b0222677dd7143b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da2de9e303e13545d84418660d6794aac4d897a8ac33991da81c1d4913a22eacaf640a374b6ce564eda99f4c8e9e76a17f93a9bea9330226ca95390904e9637b"}' ========= master_lag: 0.57 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:06.315143) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-17 07:39:06.315489) ========= 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.22mDxqXLFQ/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.68FhipIa1l/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.005065 ========= master_lag: 3.53 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-17 07:39:09.846617) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:09.846931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:10.013493) ========= ========= Total master_lag: 7.11 =========