builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0301 starttime: 1474121881.53 results: success (0) buildid: 20160917071756 builduid: 3f67a34e5c564fbd999209d5f98df0a3 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.528873) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.529289) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.529595) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.571839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.572254) ========= 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.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-17 07:18:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-09-17 07:18:02 (17.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.586367 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.182845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.183146) ========= 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.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.025608 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.231727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:18:02.232048) ========= 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.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-17 07:18:02,318 truncating revision to first 12 chars 2016-09-17 07:18:02,318 Setting DEBUG logging. 2016-09-17 07:18:02,318 attempt 1/10 2016-09-17 07:18:02,318 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:04,207 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=2.147921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:18:04.401975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.402310) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.402710) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.402994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.421328) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:18:04.421612) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:18:04 INFO - MultiFileLogger online at 20160917 07:18:04 in /builds/slave/test 07:18:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:18:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:04 INFO - {'append_to_log': False, 07:18:04 INFO - 'base_work_dir': '/builds/slave/test', 07:18:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:04 INFO - 'branch': 'Mozilla-Aurora', 07:18:04 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:04 INFO - 'config_files': ('talos/mac_config.py',), 07:18:04 INFO - 'default_actions': ('clobber', 07:18:04 INFO - 'read-buildbot-config', 07:18:04 INFO - 'download-and-extract', 07:18:04 INFO - 'populate-webroot', 07:18:04 INFO - 'create-virtualenv', 07:18:04 INFO - 'install', 07:18:04 INFO - 'run-tests'), 07:18:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:04 INFO - 'download_minidump_stackwalk': True, 07:18:04 INFO - 'download_symbols': 'ondemand', 07:18:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:04 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:04 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:04 INFO - 'installer_path': 'installer.exe', 07:18:04 INFO - 'log_level': 'info', 07:18:04 INFO - 'log_name': 'talos', 07:18:04 INFO - 'log_to_console': True, 07:18:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:04 INFO - 'opt_config_files': (), 07:18:04 INFO - 'pip_index': False, 07:18:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'run_cmd_checks_enabled': True, 07:18:04 INFO - 'sps_profile': False, 07:18:04 INFO - 'sps_profile_interval': 0, 07:18:04 INFO - 'suite': 'dromaeojs', 07:18:04 INFO - 'system_bits': '32', 07:18:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:04 INFO - 'title': 't-yosemite-r7-0301', 07:18:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:04 INFO - 'use_talos_json': True, 07:18:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:04 INFO - 'work_dir': 'build'} 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.632892Z] Running clobber step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: clobber 07:18:04 INFO - rmtree: /builds/slave/test/build 07:18:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:06 INFO - Running post-action listener: _resource_record_post_action 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.711816Z] Finished clobber step (success) 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.711946Z] Running read-buildbot-config step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: read_buildbot_config 07:18:06 INFO - Using buildbot properties: 07:18:06 INFO - { 07:18:06 INFO - "project": "", 07:18:06 INFO - "product": "firefox", 07:18:06 INFO - "script_repo_revision": "production", 07:18:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:06 INFO - "repository": "", 07:18:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:06 INFO - "buildid": "20160917071756", 07:18:06 INFO - "stage_platform": "macosx64", 07:18:06 INFO - "basedir": "/builds/slave/test", 07:18:06 INFO - "buildnumber": 45, 07:18:06 INFO - "platform": "yosemite_r7", 07:18:06 INFO - "mozci_request": { 07:18:06 INFO - "priority": -1, 07:18:06 INFO - "type": "trigger_all_talos_jobs", 07:18:06 INFO - "times": 6 07:18:06 INFO - }, 07:18:06 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:06 INFO - "slavebuilddir": "test", 07:18:06 INFO - "branch": "mozilla-aurora", 07:18:06 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:06 INFO - "slavename": "t-yosemite-r7-0301", 07:18:06 INFO - "builduid": "3f67a34e5c564fbd999209d5f98df0a3", 07:18:06 INFO - "repo_path": "releases/mozilla-aurora" 07:18:06 INFO - } 07:18:06 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:06 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:06 INFO - Running post-action listener: _resource_record_post_action 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.715756Z] Finished read-buildbot-config step (success) 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.715867Z] Running download-and-extract step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: download_and_extract 07:18:06 INFO - mkdir: /builds/slave/test/build/tests 07:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json 07:18:06 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:06 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:07 INFO - Downloaded 1199 bytes. 07:18:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:07 INFO - Using the following test package requirements: 07:18:07 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:07 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:07 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:07 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'jsshell-mac.zip'], 07:18:07 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:07 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:07 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:07 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:07 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:07 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:07 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18:07 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:07 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:07 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:07 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:07 INFO - Downloaded 16309409 bytes. 07:18:07 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:07 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:10 INFO - Return code: 0 07:18:10 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:10 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:10 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:10 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:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:10 INFO - Downloaded 16309409 bytes. 07:18:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:14 INFO - Return code: 0 07:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:14 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:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:18:14 INFO - Downloaded 14413740 bytes. 07:18:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:15 INFO - Return code: 0 07:18:15 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:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:15 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:15 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:17 INFO - Downloaded 115349660 bytes. 07:18:17 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:17 INFO - mkdir: /builds/slave/test/properties 07:18:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:17 INFO - Contents: 07:18:17 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:17 INFO - Running post-action listener: _resource_record_post_action 07:18:17 INFO - Running post-action listener: set_extra_try_arguments 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.709245Z] Finished download-and-extract step (success) 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.709398Z] Running populate-webroot step. 07:18:17 INFO - Running pre-action listener: _resource_record_pre_action 07:18:17 INFO - Running main action method: populate_webroot 07:18:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:17 INFO - '--tppagecycles', 07:18:17 INFO - '7'], 07:18:17 INFO - 'tests': ['tsvgm']}}, 07:18:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['damp', 'tps']}, 07:18:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['damp', 'tps']}, 07:18:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['dromaeo_dom']}, 07:18:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['basic_compositor_video']}, 07:18:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['a11yr', 07:18:17 INFO - 'ts_paint', 07:18:17 INFO - 'tpaint', 07:18:17 INFO - 'sessionrestore', 07:18:17 INFO - 'sessionrestore_no_auto_restore', 07:18:17 INFO - 'tabpaint']}, 07:18:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tsvgx', 07:18:17 INFO - 'tsvgr_opacity', 07:18:17 INFO - 'tart', 07:18:17 INFO - 'tscrollx', 07:18:17 INFO - 'cart']}, 07:18:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:17 INFO - 'tsvgr_opacity', 07:18:17 INFO - 'tart', 07:18:17 INFO - 'tscrollx', 07:18:17 INFO - 'cart']}, 07:18:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s'], 07:18:17 INFO - 'tests': ['tp5o']}, 07:18:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'tests': ['tp5o']}, 07:18:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--disable-e10s', 07:18:17 INFO - '--xperf_path', 07:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:17 INFO - 'tests': ['tp5n']}, 07:18:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:17 INFO - 'talos_options': ['--xperf_path', 07:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:17 INFO - 'tests': ['tp5n']}}, 07:18:17 INFO - 'talos.zip': {'path': '', 07:18:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:17 INFO - Running post-action listener: _resource_record_post_action 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.728504Z] Finished populate-webroot step (success) 07:18:17 INFO - [mozharness: 2016-09-17 14:18:17.728604Z] Running create-virtualenv step. 07:18:17 INFO - Running pre-action listener: _resource_record_pre_action 07:18:17 INFO - Running main action method: create_virtualenv 07:18:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:18:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:17 INFO - Using real prefix '/tools/python27' 07:18:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:21 INFO - Installing pip.................done. 07:18:21 INFO - Return code: 0 07:18:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:21 INFO - 'HOME': '/Users/cltbld', 07:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:21 INFO - 'LOGNAME': 'cltbld', 07:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:21 INFO - 'MOZ_NO_REMOTE': '1', 07:18:21 INFO - 'NO_EM_RESTART': '1', 07:18:21 INFO - 'PAGER': '/bin/cat', 07:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:21 INFO - 'PWD': '/builds/slave/test', 07:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:21 INFO - 'SHELL': '/bin/bash', 07:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:21 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:21 INFO - 'USER': 'cltbld', 07:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:21 INFO - 'XPC_FLAGS': '0x0', 07:18:21 INFO - 'XPC_SERVICE_NAME': '0', 07:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:18:21 INFO - Cleaning up... 07:18:21 INFO - Return code: 0 07:18:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:21 INFO - 'HOME': '/Users/cltbld', 07:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:21 INFO - 'LOGNAME': 'cltbld', 07:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:21 INFO - 'MOZ_NO_REMOTE': '1', 07:18:21 INFO - 'NO_EM_RESTART': '1', 07:18:21 INFO - 'PAGER': '/bin/cat', 07:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:21 INFO - 'PWD': '/builds/slave/test', 07:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:21 INFO - 'SHELL': '/bin/bash', 07:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:21 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:21 INFO - 'USER': 'cltbld', 07:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:21 INFO - 'XPC_FLAGS': '0x0', 07:18:21 INFO - 'XPC_SERVICE_NAME': '0', 07:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:21 INFO - Downloading/unpacking psutil>=3.1.1 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:25 INFO - Installing collected packages: psutil 07:18:25 INFO - Running setup.py install for psutil 07:18:25 INFO - building 'psutil._psutil_osx' extension 07:18:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:26 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:26 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:26 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:26 INFO - building 'psutil._psutil_posix' extension 07:18:26 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:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:26 INFO - ^ 07:18:26 INFO - 1 warning generated. 07:18:26 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:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:26 INFO - Successfully installed psutil 07:18:26 INFO - Cleaning up... 07:18:26 INFO - Return code: 0 07:18:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:26 INFO - 'HOME': '/Users/cltbld', 07:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:26 INFO - 'LOGNAME': 'cltbld', 07:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:26 INFO - 'MOZ_NO_REMOTE': '1', 07:18:26 INFO - 'NO_EM_RESTART': '1', 07:18:26 INFO - 'PAGER': '/bin/cat', 07:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:26 INFO - 'PWD': '/builds/slave/test', 07:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:26 INFO - 'SHELL': '/bin/bash', 07:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:26 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:26 INFO - 'USER': 'cltbld', 07:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:26 INFO - 'XPC_FLAGS': '0x0', 07:18:26 INFO - 'XPC_SERVICE_NAME': '0', 07:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:29 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:29 INFO - Installing collected packages: mozsystemmonitor 07:18:29 INFO - Running setup.py install for mozsystemmonitor 07:18:29 INFO - Successfully installed mozsystemmonitor 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/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.QwXjriJpw5/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:30 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:32 INFO - Downloading/unpacking functools32 (from 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:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:33 INFO - warning: no files found matching '*.txt' 07:18:33 INFO - no previously-included directories found matching 'build' 07:18:33 INFO - no previously-included directories found matching 'dist' 07:18:33 INFO - no previously-included directories found matching '.git*' 07:18:33 INFO - Installing collected packages: jsonschema, functools32 07:18:33 INFO - Running setup.py install for functools32 07:18:33 INFO - warning: no files found matching '*.txt' 07:18:33 INFO - no previously-included directories found matching 'build' 07:18:33 INFO - no previously-included directories found matching 'dist' 07:18:33 INFO - no previously-included directories found matching '.git*' 07:18:33 INFO - Successfully installed jsonschema functools32 07:18:33 INFO - Cleaning up... 07:18:33 INFO - Return code: 0 07:18:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:33 INFO - 'HOME': '/Users/cltbld', 07:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:33 INFO - 'LOGNAME': 'cltbld', 07:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:33 INFO - 'MOZ_NO_REMOTE': '1', 07:18:33 INFO - 'NO_EM_RESTART': '1', 07:18:33 INFO - 'PAGER': '/bin/cat', 07:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:33 INFO - 'PWD': '/builds/slave/test', 07:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:33 INFO - 'SHELL': '/bin/bash', 07:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:33 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:33 INFO - 'USER': 'cltbld', 07:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:33 INFO - 'XPC_FLAGS': '0x0', 07:18:33 INFO - 'XPC_SERVICE_NAME': '0', 07:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:33 INFO - Cleaning up... 07:18:33 INFO - Return code: 0 07:18:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:33 INFO - 'HOME': '/Users/cltbld', 07:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:33 INFO - 'LOGNAME': 'cltbld', 07:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:33 INFO - 'MOZ_NO_REMOTE': '1', 07:18:33 INFO - 'NO_EM_RESTART': '1', 07:18:33 INFO - 'PAGER': '/bin/cat', 07:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:33 INFO - 'PWD': '/builds/slave/test', 07:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:33 INFO - 'SHELL': '/bin/bash', 07:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:33 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:33 INFO - 'USER': 'cltbld', 07:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:33 INFO - 'XPC_FLAGS': '0x0', 07:18:33 INFO - 'XPC_SERVICE_NAME': '0', 07:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:33 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:37 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:37 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:37 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:37 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:37 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:37 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:37 INFO - Downloading docopt-0.6.1.tar.gz 07:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:37 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:37 INFO - Running setup.py install for blobuploader 07:18:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:37 INFO - Running setup.py install for requests 07:18:38 INFO - Running setup.py install for docopt 07:18:38 INFO - Successfully installed blobuploader requests docopt 07:18:38 INFO - Cleaning up... 07:18:38 INFO - Return code: 0 07:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:38 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:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:38 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:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:38 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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:38 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:38 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:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:38 INFO - 'HOME': '/Users/cltbld', 07:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:38 INFO - 'LOGNAME': 'cltbld', 07:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:38 INFO - 'MOZ_NO_REMOTE': '1', 07:18:38 INFO - 'NO_EM_RESTART': '1', 07:18:38 INFO - 'PAGER': '/bin/cat', 07:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:38 INFO - 'PWD': '/builds/slave/test', 07:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:38 INFO - 'SHELL': '/bin/bash', 07:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:38 INFO - 'USER': 'cltbld', 07:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:38 INFO - 'XPC_FLAGS': '0x0', 07:18:38 INFO - 'XPC_SERVICE_NAME': '0', 07:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-EP5ccN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ekyaAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:38 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-V6NQdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-X4cgAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-UqNzKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-021tU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pVGH2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-1NeCJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-B4Bq7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-n16PvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-XJ97Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nSZwVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:39 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-9E0m84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:40 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3JYtRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:40 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-eYohLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:40 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RVFfBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:40 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Gs87Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:40 INFO - Running setup.py install for manifestparser 07:18:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozcrash 07:18:40 INFO - Running setup.py install for mozdebug 07:18:40 INFO - Running setup.py install for mozdevice 07:18:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozfile 07:18:41 INFO - Running setup.py install for mozhttpd 07:18:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozinfo 07:18:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozInstall 07:18:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozleak 07:18:41 INFO - Running setup.py install for mozlog 07:18:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for moznetwork 07:18:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozprocess 07:18:42 INFO - Running setup.py install for mozprofile 07:18:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozrunner 07:18:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozscreenshot 07:18:42 INFO - Running setup.py install for moztest 07:18:42 INFO - Running setup.py install for mozversion 07:18:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:43 INFO - Cleaning up... 07:18:43 INFO - Return code: 0 07:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:43 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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:43 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:43 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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:43 INFO - 'HOME': '/Users/cltbld', 07:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:43 INFO - 'LOGNAME': 'cltbld', 07:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:43 INFO - 'MOZ_NO_REMOTE': '1', 07:18:43 INFO - 'NO_EM_RESTART': '1', 07:18:43 INFO - 'PAGER': '/bin/cat', 07:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:43 INFO - 'PWD': '/builds/slave/test', 07:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:43 INFO - 'SHELL': '/bin/bash', 07:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:43 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:43 INFO - 'USER': 'cltbld', 07:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:43 INFO - 'XPC_FLAGS': '0x0', 07:18:43 INFO - 'XPC_SERVICE_NAME': '0', 07:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-G7sJ_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-S_OKGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Oo39Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ryFebX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jS6zi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-zDhGVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-8Q9hYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-lDZWDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-fkaejz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-cVoFGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-71qO29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2xttc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2eAMBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jE5Thm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RKBxUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3LWwNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:45 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-YSCoas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:45 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:45 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:45 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:45 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:45 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:45 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:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:45 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:45 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:45 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:45 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:45 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:45 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 - Downloading blessings-1.6.tar.gz 07:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:47 INFO - Installing collected packages: blessings 07:18:47 INFO - Running setup.py install for blessings 07:18:47 INFO - Successfully installed blessings 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Current package versions: 07:18:48 INFO - blessings == 1.6 07:18:48 INFO - blobuploader == 1.2.4 07:18:48 INFO - docopt == 0.6.1 07:18:48 INFO - functools32 == 3.2.3-2 07:18:48 INFO - jsonschema == 2.5.1 07:18:48 INFO - manifestparser == 1.1 07:18:48 INFO - mozInstall == 1.12 07:18:48 INFO - mozcrash == 0.17 07:18:48 INFO - mozdebug == 0.1 07:18:48 INFO - mozdevice == 0.48 07:18:48 INFO - mozfile == 1.2 07:18:48 INFO - mozhttpd == 0.7 07:18:48 INFO - mozinfo == 0.9 07:18:48 INFO - mozleak == 0.1 07:18:48 INFO - mozlog == 3.3 07:18:48 INFO - moznetwork == 0.27 07:18:48 INFO - mozprocess == 0.23 07:18:48 INFO - mozprofile == 0.28 07:18:48 INFO - mozrunner == 6.12 07:18:48 INFO - mozscreenshot == 0.1 07:18:48 INFO - mozsystemmonitor == 0.3 07:18:48 INFO - moztest == 0.7 07:18:48 INFO - mozversion == 1.4 07:18:48 INFO - psutil == 3.1.1 07:18:48 INFO - requests == 1.2.3 07:18:48 INFO - wsgiref == 0.1.2 07:18:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:48 INFO - 'HOME': '/Users/cltbld', 07:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:48 INFO - 'LOGNAME': 'cltbld', 07:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:48 INFO - 'MOZ_NO_REMOTE': '1', 07:18:48 INFO - 'NO_EM_RESTART': '1', 07:18:48 INFO - 'PAGER': '/bin/cat', 07:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:48 INFO - 'PWD': '/builds/slave/test', 07:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:48 INFO - 'SHELL': '/bin/bash', 07:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:48 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:48 INFO - 'USER': 'cltbld', 07:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:48 INFO - 'XPC_FLAGS': '0x0', 07:18:48 INFO - 'XPC_SERVICE_NAME': '0', 07:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 INFO - Cleaning up... 07:18:48 INFO - Return code: 0 07:18:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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 0x10b06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e149ecf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, '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.IRkRagS7kx/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.QwXjriJpw5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:48 INFO - 'HOME': '/Users/cltbld', 07:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:48 INFO - 'LOGNAME': 'cltbld', 07:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:48 INFO - 'MOZ_NO_REMOTE': '1', 07:18:48 INFO - 'NO_EM_RESTART': '1', 07:18:48 INFO - 'PAGER': '/bin/cat', 07:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:48 INFO - 'PWD': '/builds/slave/test', 07:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:48 INFO - 'SHELL': '/bin/bash', 07:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:18:48 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:48 INFO - 'USER': 'cltbld', 07:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:48 INFO - 'XPC_FLAGS': '0x0', 07:18:48 INFO - 'XPC_SERVICE_NAME': '0', 07:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:48 INFO - Cleaning up... 07:18:48 INFO - Return code: 0 07:18:48 INFO - Running post-action listener: _resource_record_post_action 07:18:48 INFO - Running post-action listener: _start_resource_monitoring 07:18:48 INFO - Starting resource monitoring. 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.660444Z] Finished create-virtualenv step (success) 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.661373Z] Running install step. 07:18:48 INFO - Running pre-action listener: _resource_record_pre_action 07:18:48 INFO - Running main action method: install 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:49 INFO - Reading from file tmpfile_stdout 07:18:49 INFO - Output received: 07:18:49 INFO - Usage: mozinstall [options] installer 07:18:49 INFO - Options: 07:18:49 INFO - -h, --help show this help message and exit 07:18:49 INFO - -d DEST, --destination=DEST 07:18:49 INFO - Directory to install application into. [default: 07:18:49 INFO - "/builds/slave/test"] 07:18:49 INFO - --app=APP Application being installed. [default: firefox] 07:18:49 INFO - mkdir: /builds/slave/test/build/application 07:18:49 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:49 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:21 INFO - Reading from file tmpfile_stdout 07:19:21 INFO - Output received: 07:19:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:21 INFO - Running post-action listener: _resource_record_post_action 07:19:21 INFO - [mozharness: 2016-09-17 14:19:21.007399Z] Finished install step (success) 07:19:21 INFO - [mozharness: 2016-09-17 14:19:21.007536Z] Running run-tests step. 07:19:21 INFO - Running pre-action listener: _resource_record_pre_action 07:19:21 INFO - Running main action method: run_tests 07:19:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:21 INFO - 2016-09-17 07:19:21.021 screenresolution[1689:15503] starting screenresolution argv=screenresolution get 07:19:21 INFO - 2016-09-17 07:19:21.024 screenresolution[1689:15503] Display 0: 1600x1200x32@60 07:19:21 INFO - 2016-09-17 07:19:21.030 screenresolution[1690:15506] starting screenresolution argv=screenresolution list 07:19:21 INFO - Available Modes on Display 0 07:19:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:21 INFO - Intel Iris: 07:19:21 INFO - Chipset Model: Intel Iris 07:19:21 INFO - Type: GPU 07:19:21 INFO - Bus: Built-In 07:19:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:21 INFO - Vendor: Intel (0x8086) 07:19:21 INFO - Device ID: 0x0a2e 07:19:21 INFO - Revision ID: 0x0009 07:19:21 INFO - Displays: 07:19:21 INFO - Display: 07:19:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:21 INFO - Display Serial Number: 1600x1200 60 07:19:21 INFO - Main Display: Yes 07:19:21 INFO - Mirror: Off 07:19:21 INFO - Online: Yes 07:19:21 INFO - Rotation: Supported 07:19:21 INFO - Return code: 0 07:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:21 INFO - Python 2.7.3 07:19:21 INFO - Return code: 0 07:19:21 INFO - grabbing minidump binary from tooltool 07:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfd30>, '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 0x10ad34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afc7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:19:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:19:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:21 INFO - Return code: 0 07:19:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRkRagS7kx/Render', 07:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:21 INFO - 'HOME': '/Users/cltbld', 07:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:21 INFO - 'LOGNAME': 'cltbld', 07:19:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:21 INFO - 'MOZ_NO_REMOTE': '1', 07:19:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'NO_EM_RESTART': '1', 07:19:21 INFO - 'PAGER': '/bin/cat', 07:19:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:21 INFO - 'PWD': '/builds/slave/test', 07:19:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:21 INFO - 'SHELL': '/bin/bash', 07:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwXjriJpw5/Listeners', 07:19:21 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 07:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:21 INFO - 'USER': 'cltbld', 07:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:21 INFO - 'XPC_FLAGS': '0x0', 07:19:21 INFO - 'XPC_SERVICE_NAME': '0', 07:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:19:21 INFO - mozversion application_buildid: 20160916162818 07:19:21 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:21 INFO - mozversion application_name: Firefox 07:19:21 INFO - mozversion application_remotingname: firefox-dev 07:19:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion application_vendor: Mozilla 07:19:21 INFO - mozversion application_version: 50.0a2 07:19:21 INFO - mozversion platform_buildid: 20160916162818 07:19:21 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion platform_version: 50.0a2 07:19:21 INFO - using testdate: 1474121961 07:19:21 INFO - actual date: 1474121961 07:19:21 INFO - starting webserver on 'localhost:49269' 07:19:21 INFO - SUITE-START | Running 2 tests 07:19:21 INFO - TEST-START | dromaeo_css 07:19:21 INFO - operating with platform_type : mac_ 07:19:21 INFO - Initialising browser for dromaeo_css test... 07:19:21 INFO - TEST-INFO | started process 1700 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpoyQxCE/profile http://localhost:49269/getInfo.html) 07:19:24 INFO - PROCESS | 1700 | __metrics Screen width/height:1600/1200 07:19:24 INFO - PROCESS | 1700 | colorDepth:24 07:19:24 INFO - PROCESS | 1700 | Browser inner width/height: 1024/689 07:19:24 INFO - PROCESS | 1700 | __metrics 07:19:24 INFO - PROCESS | 1700 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:24 INFO - PROCESS | 1700 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:25 INFO - TEST-INFO | 1700: exit 0 07:19:25 INFO - Browser initialized. 07:19:25 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:25 INFO - TEST-INFO | started process 1703 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpoyQxCE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:36 INFO - PROCESS | 1703 | RSS: Main: 215625728 07:19:36 INFO - PROCESS | 1703 | 07:20:28 INFO - PROCESS | 1703 | 1474122028556 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:38 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 07:22:40 INFO - PROCESS | 1703 | RSS: Main: 352505856 07:22:40 INFO - PROCESS | 1703 | 07:25:43 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 07:25:43 INFO - PROCESS | 1703 | RSS: Main: 361893888 07:25:43 INFO - PROCESS | 1703 | 07:28:45 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 07:28:46 INFO - PROCESS | 1703 | RSS: Main: 333348864 07:28:46 INFO - PROCESS | 1703 | 07:31:48 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 07:31:49 INFO - PROCESS | 1703 | RSS: Main: 349175808 07:31:49 INFO - PROCESS | 1703 | 07:34:52 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 07:34:53 INFO - PROCESS | 1703 | RSS: Main: 322416640 07:34:53 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 07:37:56 INFO - PROCESS | 1703 | RSS: Main: 366436352 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | __start_tp_report 07:37:56 INFO - PROCESS | 1703 | _x_x_mozilla_page_load 07:37:56 INFO - PROCESS | 1703 | _x_x_mozilla_page_load_details 07:37:56 INFO - PROCESS | 1703 | |i|pagename|runs| 07:37:56 INFO - PROCESS | 1703 | |0;dojo.html;2714.552098903681;3351.229217280025;3377.054424761068;3390.0677313738734;3427.348803727292;5774;5794.536437085034;5842.357340692521;5865.736041878115;5872.970635146824;5812.360640329571;5884.323302820177;5885.234919460475;5891.351951285355;5894.88210235795;5202.687838729688;5283.233931079991;5284.39229488608;5325.520703136717;5418.647787893774;5286.101362768339;5340.412554618989;5343.839684809462;5354.036273470773;5383.784648614051;4432.867013989586;5263.34208223972;5312.60155488338;5315.176147697094;5322.175062865243;5410.91883621746;5411.512963833255;5422.40353561108;5429.049916264638;5447.972760136174;5155.226715992593;5173.301604283449;5265.289185959923;5349.010433069894;5352.946470535284;5640;5709.714514274311;5736.1682556029145;5758.992176369119;5784.942150578482;6330.8100756977365;6336.493080553545;6339.36606339367;6350.4602108820345;6355.80932572019;6178.351273116348;6279.246490421178;6307.684615769193;6332;6362.1093046973465;5918.733656985446;5929.258842644669;5939.792107276267;5951.970240148771;5966.671833049224;6419.229692436844;6419.775307864203;6420.711068001951;6422.646754428458;6437.356264373525;6107.643995706967;6182.041783523561;6183.752649893954;6266.185395898504;6299.716512756937;6240.4071613196675;6310.873782524323;6338.651374174372;6357.4914006879335;6389.424951754364;6290.654014029181;6350.6189628621505;6369.808905732835;6381.585196962275;6385.584936979081;6287.49700023997;6375.74497020114;6379.170707808048;6383.71273292703;6418.8716225675225;4958.454569997298;5016.398032236155;5020.171671674206;5067.873303167376;5090.440051594322;3707.925841483155;4536.750478723702;4537.342085397599;4559.179347717376;4559.749213793273;5838.241028666246;5868.911966320508;5875.970620146872;5888.676122813201;5895;6216.595921265194;6255.218097737783;6281.905771413432;6321.905171422432;6336.398042185985;5919.911201331984;5922.378150294243;5930.851728706817;5946.43508866657;5964.970175149097;5575.219469274305;5575.386707462176;5576.247206627135;5587.413321601254;5594.3286805582775;6297.401746833952;6367.299597044414;6414.743410263537;6417.96791016042;6492.707828147744;6331;6331.556791024768;6333.556651034383;6341.714622842075;6341.8097457076365;6389.4888408926245;6402.455791257609;6431.742730290736;6459.450946669585;6475.093486911742;6155.661438620739;6240.4071613196675;6274.529410294337;6289.213848268966;6332.493400528039;6317.178766760292;6332.0818481320875;6352.809415717536;6361.077643741539;6362.109304697254;6083.6349819011;6268.811935641938;6269.185005949197;6281.309056003927;6281.623102613858;6104.389561044038;6141.385861413823;6165.753369865155;6169;6320.020396838498;6102.938970610237;6135.04893764628;6176.135341052346;6181.660008699565;6188.319284878569;6312.4634406076;6313.210848643919;6326;6332.145160403471;6336.017917222838;5900.409959004065;6001.819945401645;6019.789307374221;6160.045192995093;6160.691965401622 07:37:56 INFO - PROCESS | 1703 | |1;ext.html;488.3212334854551;531.4950796743094;537.900488409644;538.6552606331948;541.3017207801936;31996.880109196183;32036.67963320369;33704.32591348171;44307.3353899691;45721.771391143084;44304.778476107655;44629;44679;44733.7763311183;44780;9678.225741940645;9848.01519848013;9866.950665246664;9867.457289849055;9869.753256168582;8773.42972706772;8938.195562399384;9675.758106047335;11873.881261187407;12100.878991210064;12216.328101954388;12297.446614902306;12315.876841231608;12331.630051098437;12335.321557314344;12091.516339346459;12154.696132596668;12175.0868684849;12175.45210465531;12190.26858388495;12080.637580872588;12181.451834667461;12182.756344873142;12186.451609677497;12196.14626976112;42812.35781463281;43054.78472607648;43072.56927430748;43118.568814311766;43163.784181078896;3540.858365665396;3582.3372676055014;3594.658507441789;3745.3445647011663;3819.52255968004;31989.6801031989;32316.838415808004;32349.51475727866;32403.189920251945;34127.829360853284;3240.8055516669074;3263.494158405451;3269.019294211727;3270.411325961326;3278.1476816028003;402936;403036;404242;404528;404769.9761501203;67380.6630966842;67394;67425.32574674289;67534;67565.66217168883;35717.46423803645;36519.721809737064;36552.45171322432;68058.31941680619;68423;18012.639747205245;18168.909155454403;18195;18206.544836379195;18225.81774182241;12386.194897331825;13649.590512284647;13759.587212383645;13791.448342066205;13794.517191898236;17678.823211767976;20148;20871.8956405217;20954.47613809667;20974;8077.313428358655;8077.596120193967;8078.798030049178;8091;8142.267195952392;888.7911340834899;956.3114557518576;1022.8721409823772;1049.5434485998614;1062.1184416933927;31415.37169256602;31596.526052109235;31655.36689266202;32152.839235803673;32459.83770081182;1105.4362275239525;1117.1621284036971;1120.8374785656035;1171.5489536528498;1178.8703242643303;16700.49898503047;16725.912815667187;16730.916345418194;16776.909500882648;16954.321827126776;687653;694668;694740;695557;696245;64095.67952160209;64920.35079649238;64939.675301624135;64973.67513162404;65014;1290.9160904541175;1339.4240476595157;1339.80572816941;1344.4823742858873;1443.985560144385;24919.626205606568;32754.344913102083;32798.34403311968;34136;34506.65493345034;60048.79902402015;60622.69688651529;60704.69647651733;60706.69646651739;60854;505381;511379;511461;511693;511695;23026.424339391648;23130.612163270256;27490.17529474119;27737.583936240568;28823.71176288294;47885.52114478811;50506.74746626243;50682.35833403896;50957.490425095275;51424.74287628538;37920;41191;41324;41958;43511.56488435075;40867.1826563473;42697.146057079306;43023;43050;43558.56441435672;40489.39265911072;40943;41228;41501;41668.37497437601;32762.180945476553;34572;34612.480812787326;36382.90851274466;37059.88820335394;23243.651345230173;26860.462790744466;27234;27267;27293.317667058483 07:37:56 INFO - PROCESS | 1703 | |2;jquery.html;358.64135864135864;374.3785316374817;378.25861311828817;389.4372631547414;412.30732369619045;11775.764484710311;15291.158986255765;20602.38192854213;21357.893210533963;22268.331950041484;20950.89524552379;22792.316230513068;23256.18603348883;23468.709220992838;24272;5863.355030946593;6534.771283005096;6922.238553759083;6924.376806087451;6980.581165130108;6760.121184246041;6914.792556223321;6979.40675042622;7031.859362812741;7041.612711300876;6754.797356079325;6837.282085381038;6864.656767161647;6895.896561551581;7041.19026311973;6868.587884726932;6878.277780833016;6879.836763132386;6956.513044086916;6961.547499412522;6532.3467653234775;6729.764458243938;6732.764353247613;6735.730570777163;6740.224874139439;29077.41845163106;29079.56380654292;29584.55623165654;29981.850090749624;30057.947971820882;2156.7921963700223;2285.2001799370255;2411.843230190032;2559.552078386275;2605.2054123492326;17668.646627067516;17679.939203647824;17773.822261777346;17972.91013544937;18033.549161271072;2049.897505124738;2085.8331333493284;2097.224027109964;2103.1797598936428;2141.5716856628583;136142.91571252877;140818.5918140826;141891;154049.14877128048;156207.2189639056;16795.41216057432;20772.961351932343;22447.775522244894;22500.21249256268;22734.09063637469;36203.81898090493;36801.26397472036;36818.07954801151;37548.81225593909;37656.62343376586;8299;8563.486190828571;8821.558922053871;8883.555822208864;8984;8893.81215414466;8963.820723585492;9028.18746312835;9156.8626470603;9165.31260155491;4483.461984561808;4521.073179998092;4538.773061346986;4555.681102322806;4630.4443466784205;1680.9159542023094;1738.495836207511;1824.740928759152;1935.4290484307164;1963.3102649370169;820.749671350244;913.0139449394638;915.7619019055094;917.2570218123342;983.5328219096016;19475.539334549965;21336;21667;22270.777292227194;22314.88842555777;951.3435729346728;970.6893793985998;975.9756006099905;983.9360441571275;991.3605724307781;11167;11564.190506664452;11716.121290903046;11741.589044383405;11760.176787624956;570914;593499;602147;608153;611048;85137.57431212928;106522;129351.35324323318;136300;140697.29651351678;948.8719022931954;974.780674348266;995.7958618483339;996.8305388083899;1003.5734812704571;18463.16915738768;18948.621027579647;19294.614107717487;19406.417807465798;19406.70889936624;51934;52017;52752.23667300114;52854.471455284955;53454.19818702801;79317.20682793098;82747;82946;83393;83430;7874.858145568746;7950.403719720928;8229.917700822914;8420.326373890197;8474.940632420947;10843.349399036084;12087.758244834878;12850;13061.477540898257;13383.665408364868;9732.70801875945;10590.89409105899;10970.232083754061;10983.121350291798;11069.5572177112;10903.236773425784;10947.781044379228;11323.773524529628;11354.54581816718;11674.941625292158;11162.944185279022;11387.658370248906;11792.469338879942;11954.462049207981;11993.880061199625;8101.918980810116;8153.714619988272;8381.16188381157;8385.496870187808;8470.915290847259;9058.954705226432;11153.386563739072;11409.429528523706;11524.539018439169;11596.188266821242 07:37:56 INFO - PROCESS | 1703 | |3;mootools.html;1411.9223442710647;1588.9841101588988;1615.5557221764016;1863.9440816775493;1990.6814909614457;8045.356371490281;8909.821803563926;9143.588538515765;9151.405158664682;9331.766705832357;9075.36472446929;9170.403923744952;9177.990421053695;9178.632854685804;9181.632734690604;2400.243923164202;2427.793637540802;2428.113738485458;2428.162284012013;2431.294924471901;2400.255920664591;2425.684660994077;2447.559439300925;2449.0571130114945;2450.142450142445;2158.417227348615;2367.266147494274;2409.373562873648;2432.6959130108735;2435.464197876473;2082.531430428157;2343;2405.338531903723;2413;2430.1737409280754;2378.488624945645;2403.134871446278;2409.734929157791;2411.9517609647883;2412.179858848;9145.908540914572;9164.816703665956;9232.584533696;9252.722418327461;9291.58187881547;5267.815626453094;5450.155225939986;5452.018636645403;5460.6177567570285;5522.6134170608075;3074.2314421394653;3699.7595156315024;3750.5686846012786;3770.6040865709256;3811.0472381904524;1103.453790373762;1104.812181929074;1105.9944700276528;1128.554221082676;1131.796276670199;12018.879811201863;12128;12248.571300004458;12264.877351226374;12270.81593776094;4098.426220329156;4820.39745031871;4822.132016237111;4839.1531481991215;4963.57809586182;10447.373157610567;10448.843267350996;10467.42429166388;10541.84187237192;10546.841797373045;5510.9448905510435;5512.91730624041;5513.42109078549;5523.447655234525;5527.751251193706;4476.798544065525;4648.5119062498625;4809.759512024385;4943.8516844494725;5032.572231360302;4018.718689691752;4045.59544045599;4127.174565086994;4149.460570125864;4181.540030596632;1305.3538498443331;1311.3181145804128;1317.4335035934448;1323.735252949414;1327.3628658243904;934.1592566690033;937.4234845570062;941.7268991992382;960.279790157382;1011.944343061124;5781.392953739881;5975.462208401264;6026.186464827279;6153.938460615426;6159.784407545804;392.4388124981247;446.48654047845235;448.6881617275962;449.47860481840905;451.1293204116022;4035;4073.89815254621;4091.2635725569685;4114.526829414595;4209.431726716915;10729.463526823627;10954.452277386099;11071.72320691973;11075.833862492069;11095.83356249657;11007.229493935347;11018.669439916815;11021.504032318566;11026.503807328689;11033.007029367396;6972.616506092112;7933.484323518953;8318;8415.747527574182;8454.830903381899;6071.241094863142;6153.938460615336;6161.045038019114;6176.536759743127;6237.313895471404;10110.241731870286;10386.896131038593;10393.84409233847;10402.843957340494;10403.687889363331;9067.229285510806;9720.687707159443;9725;10156.39061656303;10345.896541034494;7121.003059571561;7341.375983041287;7531.623418828927;7694.538327700356;7867.921320786719;8416.15838416178;8760.255378292917;8760.605772740115;8761.430507017023;8761.474311541328;6744.494162937897;7680.270374314431;7809.570473624005;7843.56860372685;7946.602669866368;7513.737019204302;7658.349040331635;7670.42471814605;7700.152983171734;7785.377169826514;7132.322429369202;7278.417726581966;7279.017332660235;7288.161861385796;7291.125064992235;4333.956660433355;4989.55094041538;5073;5082.847514574569;5130.563902068366;4780;4955.538116255785;4974.5771609413605;5044.344235249394;5113.335266415343 07:37:56 INFO - PROCESS | 1703 | |4;prototype.html;304.58880511309746;305.68056381081766;314.2160310026484;318.1632307032504;318.4920052516236;4532.456105267368;5255.343082114731;5432.6468779529405;5500.724963751812;5531.695756733378;5142.717150556718;5256.053910296145;5320.339830084958;5341.252224688546;5421.376541697706;3326.301476689892;3355.748318876094;3967;4084.611961863618;4100.698937355428;3753.8175474725376;3788.3370410178245;4016.3774614934587;4145.3781932710035;4166.208420400129;3562.5240457881782;3985.664802291236;3988.703671306837;4090.3455447128467;4133.173365326931;3320.621941894111;3499.91250218744;4099.979500102495;4169.395437661552;4174.979125104385;3411.669448914925;3534.5228394166716;3634.909527141847;3646.5806432260365;3647.8723244686576;5136.43499215086;5314.847486767865;5317.703024125029;5347.465253474621;5361.437049109865;4709.882252943669;4754.310624959361;4766.261229509397;4776.331313616097;4808.4274912921765;4493.59557639814;5756.65460072397;5759.2512973313205;5763.164341170507;5807.506361959238;3350.530925670408;3619.3123306571665;3937.212557488485;3969.9007524811823;4030.375291829771;4975.4527002029745;5706.3722990471015;5939.287285525765;6010.609310394811;6028.367021462727;3075.8462076896067;3954.912399090216;4049.190161967618;4055.2497787909333;4087.6321131098343;4111.97200699825;4125.773082480492;4154.376843473503;4157.667386609064;4162.75023498585;3885.9028524286555;4362.825486980549;4674.95325046752;4684.953150468452;4804.879878003008;5051.949480505222;5465.699386533699;5743.913841292384;5745.339285982165;5753.626014309057;1353.9729205415836;1368.6304697731557;1371.7805151175764;1372.670559065817;1372.8283964504437;709.670003448399;737.4542838299619;870.4733636150038;891.7235656946367;920.3755371768833;4922.606191504671;5225.947740522623;5288.963748956679;5289.973550132302;5313.867153321198;4826.517348265145;5476.863078422992;5492.231087647732;5856.510982832279;5922.378150294243;4200.7059505834295;4641.837535686234;5144.459831717691;5219.121116398093;5306.602004849575;2654.588538776493;2694.63622410972;2915.2071476042506;3070.7583464071354;3086.305581244202;5726.79956201531;5954.940450595439;6031.215941927521;6062.878742425127;6081.331053584102;5961.374055724086;5976.910346344808;5996.430339117864;6001.819945401645;6034.09488576717;5677.971610142005;6036.3661815510495;6042.788502402483;6063.8467996642385;6075.544334174866;4867.853964381075;5587.44125587438;5876.297932302622;5891.612322914256;5896.174535564938;5807.332156802022;5811.593188476766;5815.870731780314;5827.94172058274;5869.325027621878;4555.594576814442;5846;6089.54328425361;6099.939000610115;6231.937680623136;3283.5731354924665;3709.7943168469815;3726.6087060859304;3921.3333733264844;3939.527256729211;4695.718256904596;5569.05326094573;5885.087811389241;5889.882202356014;5953.732082056405;4925.064237794808;5509.944900550943;5707.6290041147195;5729.169270455844;5753.3958934311295;4969.509147255881;5636.603449309545;5657.179708942263;5676.858078548069;5706.686132262766;4937.851864444073;5103.524790476542;5313.149896016619;5557.388687244481;5618.157276408571;4545.6136228420955;4606.815727371002;4904.681195722266;5402.621816472809;5635.605507614428;4643.83746568882;5181.844544663666;5423.376311724044;5607.8878422432135;5639.520640745419 07:37:56 INFO - PROCESS | 1703 | |5;yui.html;730.8976743255942;744.3896005275673;768.2240936653985;805.5126648377732;836.272442974612;15296.464623738171;16619;16633.50099497014;16637.83362166378;16639.33442662292;13861.4455421783;13951.860481395182;13958;13968.511102111428;14349.426022959069;706.2443185791205;726.5350175887436;887.1572006593714;890.9020007799169;920.1580553793272;887.1660638999352;905.4702998745742;906.2342320738998;906.2976193450096;906.6010955179732;965.8696076029717;966.5215718219491;968.1577027985662;968.389914353956;970.0832713086137;672.5359501943643;767.4052609227838;771.3327971304781;963.7397902566303;966.333230071252;567.2144080448567;687.1204857782047;705.7706245470191;706.4807366585555;708.7731925783751;15316.770248446282;15371.53885383429;15394.307256173499;15397.846021539754;15408.845911540853;14182.574522764333;14484.565463036126;14521.128732276096;14557.344919478648;14770.778438323434;9429.434233945916;11917.404129793562;12676.556320528829;13170.934145329307;13654.453821847113;4606.147862645421;4614.861554153381;4654.976725116353;4677.415323084614;4677.438707355105;88788;111269;113797.99999999917;116377;118580;10654.573817047387;11029;11094.833577496343;11251.324920504632;11488.253263537843;21061.78938210629;21102.577948440947;21146.788532114482;21252.36242912715;21271.89364053201;7594.354479869163;7912.525248485109;7973.760787176394;8128.309093727099;8147.185281471805;3781.3760729479327;4283.6144746972295;4380.518143004267;4437.844675436409;4453.51011388747;3948.427478015729;4241.406203131564;4245.57544245573;4274.871753847389;4274.893127671833;741.1809950005274;751.6883039096701;762.9961850190714;768.193396933229;778.7391223939931;572.1589263782233;614.4899733221415;665.9400653941168;677.8305423644089;701.2321507948787;8721.345899057596;9473.810523789623;9589.664361747446;9823.901760982442;9976.301658883809;594.3551246897078;660.8380946668001;676.736072931557;677.167084486075;695.1482999520429;4751.881202969884;4795.848996240877;4824.059308434892;4826.203676393426;4838.806447742051;90033;151548;161946;165473;169917;110833.89166108235;140857;145472;145908;147281;102438;117930;117980.41009794896;123290;124767;7634.847303054019;8905.465672059698;9008.90991090107;9090.54547272647;9169.083091690778;62839.37160628518;64661;65006.69986600147;66103.66948165228;68016.6599167001;99223.50388248013;100232.99767002236;100896;103919;107469.46265268624;12459.688507787378;12548.058895582686;12646.43091060886;12718.554850580185;13157.53948611794;12799.42402591867;12823.551175708806;12894.41975111141;12953.481860725466;13087.280199589113;12120.393980300772;12316.261024338568;12549.623511294676;13015.47938082504;13047.34763261814;11281.492332844877;13328.200307981551;13552.051356404956;13949.790753138508;14184.503542375967;13708.725825483634;14217.715645686822;14570.781438278222;14819.407223711361;14829.25853707332;11229;11590.826137607773;11911.225770324902;11984.700382490159;12039.458224379749;11197.776044479227;11530.884691153316;11679.357635330138;11838.763224735629;11956.043516518832 07:37:56 INFO - PROCESS | 1703 | __end_tp_report 07:37:56 INFO - PROCESS | 1703 | __start_cc_report 07:37:56 INFO - PROCESS | 1703 | _x_x_mozilla_cycle_collect,1415 07:37:56 INFO - PROCESS | 1703 | __end_cc_report 07:37:56 INFO - PROCESS | 1703 | __startTimestamp1474123076407__endTimestamp 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | ------- Summary: start ------- 07:37:56 INFO - PROCESS | 1703 | Number of tests: 6 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#0] dojo.html Cycles:165 Average:5854.52 Median:6135.05 stddev:685.39 (11.2%) stddev-sans-first:641.70 07:37:56 INFO - PROCESS | 1703 | Values: 2714.6 3351.2 3377.1 3390.1 3427.3 5774.0 5794.5 5842.4 5865.7 5873.0 5812.4 5884.3 5885.2 5891.4 5894.9 5202.7 5283.2 5284.4 5325.5 5418.6 5286.1 5340.4 5343.8 5354.0 5383.8 4432.9 5263.3 5312.6 5315.2 5322.2 5410.9 5411.5 5422.4 5429.0 5448.0 5155.2 5173.3 5265.3 5349.0 5352.9 5640.0 5709.7 5736.2 5759.0 5784.9 6330.8 6336.5 6339.4 6350.5 6355.8 6178.4 6279.2 6307.7 6332.0 6362.1 5918.7 5929.3 5939.8 5952.0 5966.7 6419.2 6419.8 6420.7 6422.6 6437.4 6107.6 6182.0 6183.8 6266.2 6299.7 6240.4 6310.9 6338.7 6357.5 6389.4 6290.7 6350.6 6369.8 6381.6 6385.6 6287.5 6375.7 6379.2 6383.7 6418.9 4958.5 5016.4 5020.2 5067.9 5090.4 3707.9 4536.8 4537.3 4559.2 4559.7 5838.2 5868.9 5876.0 5888.7 5895.0 6216.6 6255.2 6281.9 6321.9 6336.4 5919.9 5922.4 5930.9 5946.4 5965.0 5575.2 5575.4 5576.2 5587.4 5594.3 6297.4 6367.3 6414.7 6418.0 6492.7 6331.0 6331.6 6333.6 6341.7 6341.8 6389.5 6402.5 6431.7 6459.5 6475.1 6155.7 6240.4 6274.5 6289.2 6332.5 6317.2 6332.1 6352.8 6361.1 6362.1 6083.6 6268.8 6269.2 6281.3 6281.6 6104.4 6141.4 6165.8 6169.0 6320.0 6102.9 6135.0 6176.1 6181.7 6188.3 6312.5 6313.2 6326.0 6332.1 6336.0 5900.4 6001.8 6019.8 6160.0 6160.7 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#1] ext.html Cycles:180 Average:68847.04 Median:36451.32 stddev:146661.06 (402.3%) stddev-sans-first:146982.67 07:37:56 INFO - PROCESS | 1703 | Values: 488.3 531.5 537.9 538.7 541.3 31996.9 32036.7 33704.3 44307.3 45721.8 44304.8 44629.0 44679.0 44733.8 44780.0 9678.2 9848.0 9867.0 9867.5 9869.8 8773.4 8938.2 9675.8 11873.9 12100.9 12216.3 12297.4 12315.9 12331.6 12335.3 12091.5 12154.7 12175.1 12175.5 12190.3 12080.6 12181.5 12182.8 12186.5 12196.1 42812.4 43054.8 43072.6 43118.6 43163.8 3540.9 3582.3 3594.7 3745.3 3819.5 31989.7 32316.8 32349.5 32403.2 34127.8 3240.8 3263.5 3269.0 3270.4 3278.1 402936.0 403036.0 404242.0 404528.0 404770.0 67380.7 67394.0 67425.3 67534.0 67565.7 35717.5 36519.7 36552.5 68058.3 68423.0 18012.6 18168.9 18195.0 18206.5 18225.8 12386.2 13649.6 13759.6 13791.4 13794.5 17678.8 20148.0 20871.9 20954.5 20974.0 8077.3 8077.6 8078.8 8091.0 8142.3 888.8 956.3 1022.9 1049.5 1062.1 31415.4 31596.5 31655.4 32152.8 32459.8 1105.4 1117.2 1120.8 1171.5 1178.9 16700.5 16725.9 16730.9 16776.9 16954.3 687653.0 694668.0 694740.0 695557.0 696245.0 64095.7 64920.4 64939.7 64973.7 65014.0 1290.9 1339.4 1339.8 1344.5 1444.0 24919.6 32754.3 32798.3 34136.0 34506.7 60048.8 60622.7 60704.7 60706.7 60854.0 505381.0 511379.0 511461.0 511693.0 511695.0 23026.4 23130.6 27490.2 27737.6 28823.7 47885.5 50506.7 50682.4 50957.5 51424.7 37920.0 41191.0 41324.0 41958.0 43511.6 40867.2 42697.1 43023.0 43050.0 43558.6 40489.4 40943.0 41228.0 41501.0 41668.4 32762.2 34572.0 34612.5 36382.9 37059.9 23243.7 26860.5 27234.0 27267.0 27293.3 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#2] jquery.html Cycles:180 Average:37102.68 Median:36809.67 stddev:100128.88 (272.0%) stddev-sans-first:100371.76 07:37:56 INFO - PROCESS | 1703 | Values: 358.6 374.4 378.3 389.4 412.3 11775.8 15291.2 20602.4 21357.9 22268.3 20950.9 22792.3 23256.2 23468.7 24272.0 5863.4 6534.8 6922.2 6924.4 6980.6 6760.1 6914.8 6979.4 7031.9 7041.6 6754.8 6837.3 6864.7 6895.9 7041.2 6868.6 6878.3 6879.8 6956.5 6961.5 6532.3 6729.8 6732.8 6735.7 6740.2 29077.4 29079.6 29584.6 29981.9 30057.9 2156.8 2285.2 2411.8 2559.6 2605.2 17668.6 17679.9 17773.8 17972.9 18033.5 2049.9 2085.8 2097.2 2103.2 2141.6 136142.9 140818.6 141891.0 154049.1 156207.2 16795.4 20773.0 22447.8 22500.2 22734.1 36203.8 36801.3 36818.1 37548.8 37656.6 8299.0 8563.5 8821.6 8883.6 8984.0 8893.8 8963.8 9028.2 9156.9 9165.3 4483.5 4521.1 4538.8 4555.7 4630.4 1680.9 1738.5 1824.7 1935.4 1963.3 820.7 913.0 915.8 917.3 983.5 19475.5 21336.0 21667.0 22270.8 22314.9 951.3 970.7 976.0 983.9 991.4 11167.0 11564.2 11716.1 11741.6 11760.2 570914.0 593499.0 602147.0 608153.0 611048.0 85137.6 106522.0 129351.4 136300.0 140697.3 948.9 974.8 995.8 996.8 1003.6 18463.2 18948.6 19294.6 19406.4 19406.7 51934.0 52017.0 52752.2 52854.5 53454.2 79317.2 82747.0 82946.0 83393.0 83430.0 7874.9 7950.4 8229.9 8420.3 8474.9 10843.3 12087.8 12850.0 13061.5 13383.7 9732.7 10590.9 10970.2 10983.1 11069.6 10903.2 10947.8 11323.8 11354.5 11674.9 11162.9 11387.7 11792.5 11954.5 11993.9 8101.9 8153.7 8381.2 8385.5 8470.9 9059.0 11153.4 11409.4 11524.5 11596.2 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#3] mootools.html Cycles:180 Average:5852.37 Median:2549.82 stddev:3327.79 (130.5%) stddev-sans-first:3320.39 07:37:56 INFO - PROCESS | 1703 | Values: 1411.9 1589.0 1615.6 1863.9 1990.7 8045.4 8909.8 9143.6 9151.4 9331.8 9075.4 9170.4 9178.0 9178.6 9181.6 2400.2 2427.8 2428.1 2428.2 2431.3 2400.3 2425.7 2447.6 2449.1 2450.1 2158.4 2367.3 2409.4 2432.7 2435.5 2082.5 2343.0 2405.3 2413.0 2430.2 2378.5 2403.1 2409.7 2412.0 2412.2 9145.9 9164.8 9232.6 9252.7 9291.6 5267.8 5450.2 5452.0 5460.6 5522.6 3074.2 3699.8 3750.6 3770.6 3811.0 1103.5 1104.8 1106.0 1128.6 1131.8 12018.9 12128.0 12248.6 12264.9 12270.8 4098.4 4820.4 4822.1 4839.2 4963.6 10447.4 10448.8 10467.4 10541.8 10546.8 5510.9 5512.9 5513.4 5523.4 5527.8 4476.8 4648.5 4809.8 4943.9 5032.6 4018.7 4045.6 4127.2 4149.5 4181.5 1305.4 1311.3 1317.4 1323.7 1327.4 934.2 937.4 941.7 960.3 1011.9 5781.4 5975.5 6026.2 6153.9 6159.8 392.4 446.5 448.7 449.5 451.1 4035.0 4073.9 4091.3 4114.5 4209.4 10729.5 10954.5 11071.7 11075.8 11095.8 11007.2 11018.7 11021.5 11026.5 11033.0 6972.6 7933.5 8318.0 8415.7 8454.8 6071.2 6153.9 6161.0 6176.5 6237.3 10110.2 10386.9 10393.8 10402.8 10403.7 9067.2 9720.7 9725.0 10156.4 10345.9 7121.0 7341.4 7531.6 7694.5 7867.9 8416.2 8760.3 8760.6 8761.4 8761.5 6744.5 7680.3 7809.6 7843.6 7946.6 7513.7 7658.3 7670.4 7700.2 7785.4 7132.3 7278.4 7279.0 7288.2 7291.1 4334.0 4989.6 5073.0 5082.8 5130.6 4780.0 4955.5 4974.6 5044.3 5113.3 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#4] prototype.html Cycles:180 Average:4581.88 Median:5143.59 stddev:1435.26 (27.9%) stddev-sans-first:1402.93 07:37:56 INFO - PROCESS | 1703 | Values: 304.6 305.7 314.2 318.2 318.5 4532.5 5255.3 5432.6 5500.7 5531.7 5142.7 5256.1 5320.3 5341.3 5421.4 3326.3 3355.7 3967.0 4084.6 4100.7 3753.8 3788.3 4016.4 4145.4 4166.2 3562.5 3985.7 3988.7 4090.3 4133.2 3320.6 3499.9 4100.0 4169.4 4175.0 3411.7 3534.5 3634.9 3646.6 3647.9 5136.4 5314.8 5317.7 5347.5 5361.4 4709.9 4754.3 4766.3 4776.3 4808.4 4493.6 5756.7 5759.3 5763.2 5807.5 3350.5 3619.3 3937.2 3969.9 4030.4 4975.5 5706.4 5939.3 6010.6 6028.4 3075.8 3954.9 4049.2 4055.2 4087.6 4112.0 4125.8 4154.4 4157.7 4162.8 3885.9 4362.8 4675.0 4685.0 4804.9 5051.9 5465.7 5743.9 5745.3 5753.6 1354.0 1368.6 1371.8 1372.7 1372.8 709.7 737.5 870.5 891.7 920.4 4922.6 5225.9 5289.0 5290.0 5313.9 4826.5 5476.9 5492.2 5856.5 5922.4 4200.7 4641.8 5144.5 5219.1 5306.6 2654.6 2694.6 2915.2 3070.8 3086.3 5726.8 5954.9 6031.2 6062.9 6081.3 5961.4 5976.9 5996.4 6001.8 6034.1 5678.0 6036.4 6042.8 6063.8 6075.5 4867.9 5587.4 5876.3 5891.6 5896.2 5807.3 5811.6 5815.9 5827.9 5869.3 4555.6 5846.0 6089.5 6099.9 6231.9 3283.6 3709.8 3726.6 3921.3 3939.5 4695.7 5569.1 5885.1 5889.9 5953.7 4925.1 5509.9 5707.6 5729.2 5753.4 4969.5 5636.6 5657.2 5676.9 5706.7 4937.9 5103.5 5313.1 5557.4 5618.2 4545.6 4606.8 4904.7 5402.6 5635.6 4643.8 5181.8 5423.4 5607.9 5639.5 07:37:56 INFO - PROCESS | 1703 | 07:37:56 INFO - PROCESS | 1703 | [#5] yui.html Cycles:180 Average:25715.99 Median:12526.63 stddev:41611.92 (332.2%) stddev-sans-first:41686.37 07:37:56 INFO - PROCESS | 1703 | Values: 730.9 744.4 768.2 805.5 836.3 15296.5 16619.0 16633.5 16637.8 16639.3 13861.4 13951.9 13958.0 13968.5 14349.4 706.2 726.5 887.2 890.9 920.2 887.2 905.5 906.2 906.3 906.6 965.9 966.5 968.2 968.4 970.1 672.5 767.4 771.3 963.7 966.3 567.2 687.1 705.8 706.5 708.8 15316.8 15371.5 15394.3 15397.8 15408.8 14182.6 14484.6 14521.1 14557.3 14770.8 9429.4 11917.4 12676.6 13170.9 13654.5 4606.1 4614.9 4655.0 4677.4 4677.4 88788.0 111269.0 113798.0 116377.0 118580.0 10654.6 11029.0 11094.8 11251.3 11488.3 21061.8 21102.6 21146.8 21252.4 21271.9 7594.4 7912.5 7973.8 8128.3 8147.2 3781.4 4283.6 4380.5 4437.8 4453.5 3948.4 4241.4 4245.6 4274.9 4274.9 741.2 751.7 763.0 768.2 778.7 572.2 614.5 665.9 677.8 701.2 8721.3 9473.8 9589.7 9823.9 9976.3 594.4 660.8 676.7 677.2 695.1 4751.9 4795.8 4824.1 4826.2 4838.8 90033.0 151548.0 161946.0 165473.0 169917.0 110833.9 140857.0 145472.0 145908.0 147281.0 102438.0 117930.0 117980.4 123290.0 124767.0 7634.8 8905.5 9008.9 9090.5 9169.1 62839.4 64661.0 65006.7 66103.7 68016.7 99223.5 100233.0 100896.0 103919.0 107469.5 12459.7 12548.1 12646.4 12718.6 13157.5 12799.4 12823.6 12894.4 12953.5 13087.3 12120.4 12316.3 12549.6 13015.5 13047.3 11281.5 13328.2 13552.1 13949.8 14184.5 13708.7 14217.7 14570.8 14819.4 14829.3 11229.0 11590.8 11911.2 11984.7 12039.5 11197.8 11530.9 11679.4 11838.8 11956.0 07:37:56 INFO - PROCESS | 1703 | -------- Summary: end -------- 07:37:56 INFO - PROCESS | 1703 | 07:37:57 INFO - TEST-INFO | 1703: exit 0 07:37:57 INFO - TEST-OK | dromaeo_css | took 1115418ms 07:37:57 INFO - TEST-START | kraken 07:37:57 INFO - operating with platform_type : mac_ 07:37:57 INFO - Initialising browser for kraken test... 07:37:57 INFO - TEST-INFO | started process 1710 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpRjuGxJ/profile http://localhost:49269/getInfo.html) 07:37:59 INFO - PROCESS | 1710 | __metrics Screen width/height:1600/1200 07:37:59 INFO - PROCESS | 1710 | colorDepth:24 07:37:59 INFO - PROCESS | 1710 | Browser inner width/height: 1024/689 07:37:59 INFO - PROCESS | 1710 | __metrics 07:37:59 INFO - PROCESS | 1710 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:37:59 INFO - PROCESS | 1710 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:38:00 INFO - TEST-INFO | 1710: 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 1711 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpRjuGxJ/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 | 1711 | RSS: Main: 215547904 07:38:12 INFO - PROCESS | 1711 | 07:38:14 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:15 INFO - PROCESS | 1711 | RSS: Main: 278528000 07:38:15 INFO - PROCESS | 1711 | 07:38:18 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 07:38:18 INFO - PROCESS | 1711 | RSS: Main: 282390528 07:38:18 INFO - PROCESS | 1711 | 07:38:22 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 07:38:22 INFO - PROCESS | 1711 | RSS: Main: 378224640 07:38:22 INFO - PROCESS | 1711 | 07:38:24 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 07:38:25 INFO - PROCESS | 1711 | RSS: Main: 283607040 07:38:25 INFO - PROCESS | 1711 | 07:38:27 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:28 INFO - PROCESS | 1711 | RSS: Main: 284889088 07:38:28 INFO - PROCESS | 1711 | 07:38:30 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:31 INFO - PROCESS | 1711 | RSS: Main: 322162688 07:38:31 INFO - PROCESS | 1711 | 07:38:34 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:35 INFO - PROCESS | 1711 | RSS: Main: 324083712 07:38:35 INFO - PROCESS | 1711 | 07:38:37 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 07:38:38 INFO - PROCESS | 1711 | RSS: Main: 324268032 07:38:38 INFO - PROCESS | 1711 | 07:38:40 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:40 INFO - PROCESS | 1711 | RSS: Main: 297996288 07:38:40 INFO - PROCESS | 1711 | 07:38:42 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:43 INFO - PROCESS | 1711 | RSS: Main: 302727168 07:38:43 INFO - PROCESS | 1711 | 07:38:45 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:46 INFO - PROCESS | 1711 | RSS: Main: 303566848 07:38:46 INFO - PROCESS | 1711 | 07:38:49 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:49 INFO - PROCESS | 1711 | RSS: Main: 316469248 07:38:49 INFO - PROCESS | 1711 | 07:38:52 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:53 INFO - PROCESS | 1711 | RSS: Main: 317657088 07:38:53 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 07:38:55 INFO - PROCESS | 1711 | RSS: Main: 360890368 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | __start_tp_report 07:38:55 INFO - PROCESS | 1711 | _x_x_mozilla_page_load 07:38:55 INFO - PROCESS | 1711 | _x_x_mozilla_page_load_details 07:38:55 INFO - PROCESS | 1711 | |i|pagename|runs| 07:38:55 INFO - PROCESS | 1711 | |0;ai-astar;115;114;128;114;113;114;113;116;120;109 07:38:55 INFO - PROCESS | 1711 | |1;audio-beat-detection;145;114;132;147;127;158;134;139;144;107 07:38:55 INFO - PROCESS | 1711 | |2;audio-dft;182;184;184;185;182;186;183;182;184;183 07:38:55 INFO - PROCESS | 1711 | |3;audio-fft;71;80;74;163;82;82;81;68;136;58 07:38:55 INFO - PROCESS | 1711 | |4;audio-oscillator;82;83;134;69;88;77;122;69;70;120 07:38:55 INFO - PROCESS | 1711 | |5;imaging-gaussian-blur;88;83;88;84;86;86;134;85;90;84 07:38:55 INFO - PROCESS | 1711 | |6;imaging-darkroom;93;93;93;94;93;93;93;112;149;93 07:38:55 INFO - PROCESS | 1711 | |7;imaging-desaturate;84;86;85;86;85;84;85;85;84;81 07:38:55 INFO - PROCESS | 1711 | |8;json-parse-financial;56;56;56;57;55;56;58;58;56;56 07:38:55 INFO - PROCESS | 1711 | |9;json-stringify-tinderbox;69;66;67;76;74;80;74;72;72;73 07:38:55 INFO - PROCESS | 1711 | |10;stanford-crypto-aes;80;82;92;106;97;83;89;72;82;72 07:38:55 INFO - PROCESS | 1711 | |11;stanford-crypto-ccm;147;142;133;137;136;144;148;152;197;157 07:38:55 INFO - PROCESS | 1711 | |12;stanford-crypto-pbkdf2;160;160;160;160;160;163;160;159;163;223 07:38:55 INFO - PROCESS | 1711 | |13;stanford-crypto-sha256-iterative;66;66;66;65;65;69;80;82;78;65 07:38:55 INFO - PROCESS | 1711 | __end_tp_report 07:38:55 INFO - PROCESS | 1711 | __start_cc_report 07:38:55 INFO - PROCESS | 1711 | _x_x_mozilla_cycle_collect,2243 07:38:55 INFO - PROCESS | 1711 | __end_cc_report 07:38:55 INFO - PROCESS | 1711 | __startTimestamp1474123135282__endTimestamp 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | ------- Summary: start ------- 07:38:55 INFO - PROCESS | 1711 | Number of tests: 14 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.50 stddev:5.15 (4.5%) stddev-sans-first:5.45 07:38:55 INFO - PROCESS | 1711 | Values: 115.0 114.0 128.0 114.0 113.0 114.0 113.0 116.0 120.0 109.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:141.50 stddev:15.52 (11.0%) stddev-sans-first:16.01 07:38:55 INFO - PROCESS | 1711 | Values: 145.0 114.0 132.0 147.0 127.0 158.0 134.0 139.0 144.0 107.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.35 (0.7%) stddev-sans-first:1.32 07:38:55 INFO - PROCESS | 1711 | Values: 182.0 184.0 184.0 185.0 182.0 186.0 183.0 182.0 184.0 183.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#3] audio-fft Cycles:10 Average:89.50 Median:77.00 stddev:33.13 (43.0%) stddev-sans-first:34.45 07:38:55 INFO - PROCESS | 1711 | Values: 71.0 80.0 74.0 163.0 82.0 82.0 81.0 68.0 136.0 58.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:73.50 stddev:24.51 (33.4%) stddev-sans-first:25.76 07:38:55 INFO - PROCESS | 1711 | Values: 82.0 83.0 134.0 69.0 88.0 77.0 122.0 69.0 70.0 120.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.00 stddev:15.33 (17.8%) stddev-sans-first:16.23 07:38:55 INFO - PROCESS | 1711 | Values: 88.0 83.0 88.0 84.0 86.0 86.0 134.0 85.0 90.0 84.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:93.00 stddev:18.01 (19.4%) stddev-sans-first:18.90 07:38:55 INFO - PROCESS | 1711 | Values: 93.0 93.0 93.0 94.0 93.0 93.0 93.0 112.0 149.0 93.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 07:38:55 INFO - PROCESS | 1711 | Values: 84.0 86.0 85.0 86.0 85.0 84.0 85.0 85.0 84.0 81.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 07:38:55 INFO - PROCESS | 1711 | Values: 56.0 56.0 56.0 57.0 55.0 56.0 58.0 58.0 56.0 56.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.50 stddev:4.19 (5.7%) stddev-sans-first:4.27 07:38:55 INFO - PROCESS | 1711 | Values: 69.0 66.0 67.0 76.0 74.0 80.0 74.0 72.0 72.0 73.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:82.50 stddev:10.71 (13.0%) stddev-sans-first:11.17 07:38:55 INFO - PROCESS | 1711 | Values: 80.0 82.0 92.0 106.0 97.0 83.0 89.0 72.0 82.0 72.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:147.50 stddev:18.33 (12.4%) stddev-sans-first:19.42 07:38:55 INFO - PROCESS | 1711 | Values: 147.0 142.0 133.0 137.0 136.0 144.0 148.0 152.0 197.0 157.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:160.00 stddev:19.79 (12.4%) stddev-sans-first:20.84 07:38:55 INFO - PROCESS | 1711 | Values: 160.0 160.0 160.0 160.0 160.0 163.0 160.0 159.0 163.0 223.0 07:38:55 INFO - PROCESS | 1711 | 07:38:55 INFO - PROCESS | 1711 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:67.50 stddev:6.92 (10.3%) stddev-sans-first:7.18 07:38:55 INFO - PROCESS | 1711 | Values: 66.0 66.0 66.0 65.0 65.0 69.0 80.0 82.0 78.0 65.0 07:38:55 INFO - PROCESS | 1711 | -------- Summary: end -------- 07:38:55 INFO - PROCESS | 1711 | 07:38:56 INFO - TEST-INFO | 1711: exit 0 07:38:56 INFO - TEST-OK | kraken | took 59119ms 07:38:56 INFO - SUITE-END | took 1174s 07:38:56 INFO - Completed test suite (00:19:34) 07:38:56 INFO - javascript benchmark 07:38:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2714.552098903681, 3351.229217280025, 3377.054424761068, 3390.0677313738734, 3427.348803727292, 5774.0, 5794.536437085034, 5842.357340692521, 5865.736041878115, 5872.970635146824, 5812.360640329571, 5884.323302820177, 5885.234919460475, 5891.351951285355, 5894.88210235795, 5202.687838729688, 5283.233931079991, 5284.39229488608, 5325.520703136717, 5418.647787893774, 5286.101362768339, 5340.412554618989, 5343.839684809462, 5354.036273470773, 5383.784648614051, 4432.867013989586, 5263.34208223972, 5312.60155488338, 5315.176147697094, 5322.175062865243, 5410.91883621746, 5411.512963833255, 5422.40353561108, 5429.049916264638, 5447.972760136174, 5155.226715992593, 5173.301604283449, 5265.289185959923, 5349.010433069894, 5352.946470535284, 5640.0, 5709.714514274311, 5736.1682556029145, 5758.992176369119, 5784.942150578482, 6330.8100756977365, 6336.493080553545, 6339.36606339367, 6350.4602108820345, 6355.80932572019, 6178.351273116348, 6279.246490421178, 6307.684615769193, 6332.0, 6362.1093046973465, 5918.733656985446, 5929.258842644669, 5939.792107276267, 5951.970240148771, 5966.671833049224, 6419.229692436844, 6419.775307864203, 6420.711068001951, 6422.646754428458, 6437.356264373525, 6107.643995706967, 6182.041783523561, 6183.752649893954, 6266.185395898504, 6299.716512756937, 6240.4071613196675, 6310.873782524323, 6338.651374174372, 6357.4914006879335, 6389.424951754364, 6290.654014029181, 6350.6189628621505, 6369.808905732835, 6381.585196962275, 6385.584936979081, 6287.49700023997, 6375.74497020114, 6379.170707808048, 6383.71273292703, 6418.8716225675225, 4958.454569997298, 5016.398032236155, 5020.171671674206, 5067.873303167376, 5090.440051594322, 3707.925841483155, 4536.750478723702, 4537.342085397599, 4559.179347717376, 4559.749213793273, 5838.241028666246, 5868.911966320508, 5875.970620146872, 5888.676122813201, 5895.0, 6216.595921265194, 6255.218097737783, 6281.905771413432, 6321.905171422432, 6336.398042185985, 5919.911201331984, 5922.378150294243, 5930.851728706817, 5946.43508866657, 5964.970175149097, 5575.219469274305, 5575.386707462176, 5576.247206627135, 5587.413321601254, 5594.3286805582775, 6297.401746833952, 6367.299597044414, 6414.743410263537, 6417.96791016042, 6492.707828147744, 6331.0, 6331.556791024768, 6333.556651034383, 6341.714622842075, 6341.8097457076365, 6389.4888408926245, 6402.455791257609, 6431.742730290736, 6459.450946669585, 6475.093486911742, 6155.661438620739, 6240.4071613196675, 6274.529410294337, 6289.213848268966, 6332.493400528039, 6317.178766760292, 6332.0818481320875, 6352.809415717536, 6361.077643741539, 6362.109304697254, 6083.6349819011, 6268.811935641938, 6269.185005949197, 6281.309056003927, 6281.623102613858, 6104.389561044038, 6141.385861413823, 6165.753369865155, 6169.0, 6320.020396838498, 6102.938970610237, 6135.04893764628, 6176.135341052346, 6181.660008699565, 6188.319284878569, 6312.4634406076, 6313.210848643919, 6326.0, 6332.145160403471, 6336.017917222838, 5900.409959004065, 6001.819945401645, 6019.789307374221, 6160.045192995093, 6160.691965401622], "value": 5806.7265953784345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.3212334854551, 531.4950796743094, 537.900488409644, 538.6552606331948, 541.3017207801936, 31996.880109196183, 32036.67963320369, 33704.32591348171, 44307.3353899691, 45721.771391143084, 44304.778476107655, 44629.0, 44679.0, 44733.7763311183, 44780.0, 9678.225741940645, 9848.01519848013, 9866.950665246664, 9867.457289849055, 9869.753256168582, 8773.42972706772, 8938.195562399384, 9675.758106047335, 11873.881261187407, 12100.878991210064, 12216.328101954388, 12297.446614902306, 12315.876841231608, 12331.630051098437, 12335.321557314344, 12091.516339346459, 12154.696132596668, 12175.0868684849, 12175.45210465531, 12190.26858388495, 12080.637580872588, 12181.451834667461, 12182.756344873142, 12186.451609677497, 12196.14626976112, 42812.35781463281, 43054.78472607648, 43072.56927430748, 43118.568814311766, 43163.784181078896, 3540.858365665396, 3582.3372676055014, 3594.658507441789, 3745.3445647011663, 3819.52255968004, 31989.6801031989, 32316.838415808004, 32349.51475727866, 32403.189920251945, 34127.829360853284, 3240.8055516669074, 3263.494158405451, 3269.019294211727, 3270.411325961326, 3278.1476816028003, 402936.0, 403036.0, 404242.0, 404528.0, 404769.9761501203, 67380.6630966842, 67394.0, 67425.32574674289, 67534.0, 67565.66217168883, 35717.46423803645, 36519.721809737064, 36552.45171322432, 68058.31941680619, 68423.0, 18012.639747205245, 18168.909155454403, 18195.0, 18206.544836379195, 18225.81774182241, 12386.194897331825, 13649.590512284647, 13759.587212383645, 13791.448342066205, 13794.517191898236, 17678.823211767976, 20148.0, 20871.8956405217, 20954.47613809667, 20974.0, 8077.313428358655, 8077.596120193967, 8078.798030049178, 8091.0, 8142.267195952392, 888.7911340834899, 956.3114557518576, 1022.8721409823772, 1049.5434485998614, 1062.1184416933927, 31415.37169256602, 31596.526052109235, 31655.36689266202, 32152.839235803673, 32459.83770081182, 1105.4362275239525, 1117.1621284036971, 1120.8374785656035, 1171.5489536528498, 1178.8703242643303, 16700.49898503047, 16725.912815667187, 16730.916345418194, 16776.909500882648, 16954.321827126776, 687653.0, 694668.0, 694740.0, 695557.0, 696245.0, 64095.67952160209, 64920.35079649238, 64939.675301624135, 64973.67513162404, 65014.0, 1290.9160904541175, 1339.4240476595157, 1339.80572816941, 1344.4823742858873, 1443.985560144385, 24919.626205606568, 32754.344913102083, 32798.34403311968, 34136.0, 34506.65493345034, 60048.79902402015, 60622.69688651529, 60704.69647651733, 60706.69646651739, 60854.0, 505381.0, 511379.0, 511461.0, 511693.0, 511695.0, 23026.424339391648, 23130.612163270256, 27490.17529474119, 27737.583936240568, 28823.71176288294, 47885.52114478811, 50506.74746626243, 50682.35833403896, 50957.490425095275, 51424.74287628538, 37920.0, 41191.0, 41324.0, 41958.0, 43511.56488435075, 40867.1826563473, 42697.146057079306, 43023.0, 43050.0, 43558.56441435672, 40489.39265911072, 40943.0, 41228.0, 41501.0, 41668.37497437601, 32762.180945476553, 34572.0, 34612.480812787326, 36382.90851274466, 37059.88820335394, 23243.651345230173, 26860.462790744466, 27234.0, 27267.0, 27293.317667058483], "value": 21087.861276772983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.64135864135864, 374.3785316374817, 378.25861311828817, 389.4372631547414, 412.30732369619045, 11775.764484710311, 15291.158986255765, 20602.38192854213, 21357.893210533963, 22268.331950041484, 20950.89524552379, 22792.316230513068, 23256.18603348883, 23468.709220992838, 24272.0, 5863.355030946593, 6534.771283005096, 6922.238553759083, 6924.376806087451, 6980.581165130108, 6760.121184246041, 6914.792556223321, 6979.40675042622, 7031.859362812741, 7041.612711300876, 6754.797356079325, 6837.282085381038, 6864.656767161647, 6895.896561551581, 7041.19026311973, 6868.587884726932, 6878.277780833016, 6879.836763132386, 6956.513044086916, 6961.547499412522, 6532.3467653234775, 6729.764458243938, 6732.764353247613, 6735.730570777163, 6740.224874139439, 29077.41845163106, 29079.56380654292, 29584.55623165654, 29981.850090749624, 30057.947971820882, 2156.7921963700223, 2285.2001799370255, 2411.843230190032, 2559.552078386275, 2605.2054123492326, 17668.646627067516, 17679.939203647824, 17773.822261777346, 17972.91013544937, 18033.549161271072, 2049.897505124738, 2085.8331333493284, 2097.224027109964, 2103.1797598936428, 2141.5716856628583, 136142.91571252877, 140818.5918140826, 141891.0, 154049.14877128048, 156207.2189639056, 16795.41216057432, 20772.961351932343, 22447.775522244894, 22500.21249256268, 22734.09063637469, 36203.81898090493, 36801.26397472036, 36818.07954801151, 37548.81225593909, 37656.62343376586, 8299.0, 8563.486190828571, 8821.558922053871, 8883.555822208864, 8984.0, 8893.81215414466, 8963.820723585492, 9028.18746312835, 9156.8626470603, 9165.31260155491, 4483.461984561808, 4521.073179998092, 4538.773061346986, 4555.681102322806, 4630.4443466784205, 1680.9159542023094, 1738.495836207511, 1824.740928759152, 1935.4290484307164, 1963.3102649370169, 820.749671350244, 913.0139449394638, 915.7619019055094, 917.2570218123342, 983.5328219096016, 19475.539334549965, 21336.0, 21667.0, 22270.777292227194, 22314.88842555777, 951.3435729346728, 970.6893793985998, 975.9756006099905, 983.9360441571275, 991.3605724307781, 11167.0, 11564.190506664452, 11716.121290903046, 11741.589044383405, 11760.176787624956, 570914.0, 593499.0, 602147.0, 608153.0, 611048.0, 85137.57431212928, 106522.0, 129351.35324323318, 136300.0, 140697.29651351678, 948.8719022931954, 974.780674348266, 995.7958618483339, 996.8305388083899, 1003.5734812704571, 18463.16915738768, 18948.621027579647, 19294.614107717487, 19406.417807465798, 19406.70889936624, 51934.0, 52017.0, 52752.23667300114, 52854.471455284955, 53454.19818702801, 79317.20682793098, 82747.0, 82946.0, 83393.0, 83430.0, 7874.858145568746, 7950.403719720928, 8229.917700822914, 8420.326373890197, 8474.940632420947, 10843.349399036084, 12087.758244834878, 12850.0, 13061.477540898257, 13383.665408364868, 9732.70801875945, 10590.89409105899, 10970.232083754061, 10983.121350291798, 11069.5572177112, 10903.236773425784, 10947.781044379228, 11323.773524529628, 11354.54581816718, 11674.941625292158, 11162.944185279022, 11387.658370248906, 11792.469338879942, 11954.462049207981, 11993.880061199625, 8101.918980810116, 8153.714619988272, 8381.16188381157, 8385.496870187808, 8470.915290847259, 9058.954705226432, 11153.386563739072, 11409.429528523706, 11524.539018439169, 11596.188266821242], "value": 10585.28201196406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.9223442710647, 1588.9841101588988, 1615.5557221764016, 1863.9440816775493, 1990.6814909614457, 8045.356371490281, 8909.821803563926, 9143.588538515765, 9151.405158664682, 9331.766705832357, 9075.36472446929, 9170.403923744952, 9177.990421053695, 9178.632854685804, 9181.632734690604, 2400.243923164202, 2427.793637540802, 2428.113738485458, 2428.162284012013, 2431.294924471901, 2400.255920664591, 2425.684660994077, 2447.559439300925, 2449.0571130114945, 2450.142450142445, 2158.417227348615, 2367.266147494274, 2409.373562873648, 2432.6959130108735, 2435.464197876473, 2082.531430428157, 2343.0, 2405.338531903723, 2413.0, 2430.1737409280754, 2378.488624945645, 2403.134871446278, 2409.734929157791, 2411.9517609647883, 2412.179858848, 9145.908540914572, 9164.816703665956, 9232.584533696, 9252.722418327461, 9291.58187881547, 5267.815626453094, 5450.155225939986, 5452.018636645403, 5460.6177567570285, 5522.6134170608075, 3074.2314421394653, 3699.7595156315024, 3750.5686846012786, 3770.6040865709256, 3811.0472381904524, 1103.453790373762, 1104.812181929074, 1105.9944700276528, 1128.554221082676, 1131.796276670199, 12018.879811201863, 12128.0, 12248.571300004458, 12264.877351226374, 12270.81593776094, 4098.426220329156, 4820.39745031871, 4822.132016237111, 4839.1531481991215, 4963.57809586182, 10447.373157610567, 10448.843267350996, 10467.42429166388, 10541.84187237192, 10546.841797373045, 5510.9448905510435, 5512.91730624041, 5513.42109078549, 5523.447655234525, 5527.751251193706, 4476.798544065525, 4648.5119062498625, 4809.759512024385, 4943.8516844494725, 5032.572231360302, 4018.718689691752, 4045.59544045599, 4127.174565086994, 4149.460570125864, 4181.540030596632, 1305.3538498443331, 1311.3181145804128, 1317.4335035934448, 1323.735252949414, 1327.3628658243904, 934.1592566690033, 937.4234845570062, 941.7268991992382, 960.279790157382, 1011.944343061124, 5781.392953739881, 5975.462208401264, 6026.186464827279, 6153.938460615426, 6159.784407545804, 392.4388124981247, 446.48654047845235, 448.6881617275962, 449.47860481840905, 451.1293204116022, 4035.0, 4073.89815254621, 4091.2635725569685, 4114.526829414595, 4209.431726716915, 10729.463526823627, 10954.452277386099, 11071.72320691973, 11075.833862492069, 11095.83356249657, 11007.229493935347, 11018.669439916815, 11021.504032318566, 11026.503807328689, 11033.007029367396, 6972.616506092112, 7933.484323518953, 8318.0, 8415.747527574182, 8454.830903381899, 6071.241094863142, 6153.938460615336, 6161.045038019114, 6176.536759743127, 6237.313895471404, 10110.241731870286, 10386.896131038593, 10393.84409233847, 10402.843957340494, 10403.687889363331, 9067.229285510806, 9720.687707159443, 9725.0, 10156.39061656303, 10345.896541034494, 7121.003059571561, 7341.375983041287, 7531.623418828927, 7694.538327700356, 7867.921320786719, 8416.15838416178, 8760.255378292917, 8760.605772740115, 8761.430507017023, 8761.474311541328, 6744.494162937897, 7680.270374314431, 7809.570473624005, 7843.56860372685, 7946.602669866368, 7513.737019204302, 7658.349040331635, 7670.42471814605, 7700.152983171734, 7785.377169826514, 7132.322429369202, 7278.417726581966, 7279.017332660235, 7288.161861385796, 7291.125064992235, 4333.956660433355, 4989.55094041538, 5073.0, 5082.847514574569, 5130.563902068366, 4780.0, 4955.538116255785, 4974.5771609413605, 5044.344235249394, 5113.335266415343], "value": 4612.637440672823, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.58880511309746, 305.68056381081766, 314.2160310026484, 318.1632307032504, 318.4920052516236, 4532.456105267368, 5255.343082114731, 5432.6468779529405, 5500.724963751812, 5531.695756733378, 5142.717150556718, 5256.053910296145, 5320.339830084958, 5341.252224688546, 5421.376541697706, 3326.301476689892, 3355.748318876094, 3967.0, 4084.611961863618, 4100.698937355428, 3753.8175474725376, 3788.3370410178245, 4016.3774614934587, 4145.3781932710035, 4166.208420400129, 3562.5240457881782, 3985.664802291236, 3988.703671306837, 4090.3455447128467, 4133.173365326931, 3320.621941894111, 3499.91250218744, 4099.979500102495, 4169.395437661552, 4174.979125104385, 3411.669448914925, 3534.5228394166716, 3634.909527141847, 3646.5806432260365, 3647.8723244686576, 5136.43499215086, 5314.847486767865, 5317.703024125029, 5347.465253474621, 5361.437049109865, 4709.882252943669, 4754.310624959361, 4766.261229509397, 4776.331313616097, 4808.4274912921765, 4493.59557639814, 5756.65460072397, 5759.2512973313205, 5763.164341170507, 5807.506361959238, 3350.530925670408, 3619.3123306571665, 3937.212557488485, 3969.9007524811823, 4030.375291829771, 4975.4527002029745, 5706.3722990471015, 5939.287285525765, 6010.609310394811, 6028.367021462727, 3075.8462076896067, 3954.912399090216, 4049.190161967618, 4055.2497787909333, 4087.6321131098343, 4111.97200699825, 4125.773082480492, 4154.376843473503, 4157.667386609064, 4162.75023498585, 3885.9028524286555, 4362.825486980549, 4674.95325046752, 4684.953150468452, 4804.879878003008, 5051.949480505222, 5465.699386533699, 5743.913841292384, 5745.339285982165, 5753.626014309057, 1353.9729205415836, 1368.6304697731557, 1371.7805151175764, 1372.670559065817, 1372.8283964504437, 709.670003448399, 737.4542838299619, 870.4733636150038, 891.7235656946367, 920.3755371768833, 4922.606191504671, 5225.947740522623, 5288.963748956679, 5289.973550132302, 5313.867153321198, 4826.517348265145, 5476.863078422992, 5492.231087647732, 5856.510982832279, 5922.378150294243, 4200.7059505834295, 4641.837535686234, 5144.459831717691, 5219.121116398093, 5306.602004849575, 2654.588538776493, 2694.63622410972, 2915.2071476042506, 3070.7583464071354, 3086.305581244202, 5726.79956201531, 5954.940450595439, 6031.215941927521, 6062.878742425127, 6081.331053584102, 5961.374055724086, 5976.910346344808, 5996.430339117864, 6001.819945401645, 6034.09488576717, 5677.971610142005, 6036.3661815510495, 6042.788502402483, 6063.8467996642385, 6075.544334174866, 4867.853964381075, 5587.44125587438, 5876.297932302622, 5891.612322914256, 5896.174535564938, 5807.332156802022, 5811.593188476766, 5815.870731780314, 5827.94172058274, 5869.325027621878, 4555.594576814442, 5846.0, 6089.54328425361, 6099.939000610115, 6231.937680623136, 3283.5731354924665, 3709.7943168469815, 3726.6087060859304, 3921.3333733264844, 3939.527256729211, 4695.718256904596, 5569.05326094573, 5885.087811389241, 5889.882202356014, 5953.732082056405, 4925.064237794808, 5509.944900550943, 5707.6290041147195, 5729.169270455844, 5753.3958934311295, 4969.509147255881, 5636.603449309545, 5657.179708942263, 5676.858078548069, 5706.686132262766, 4937.851864444073, 5103.524790476542, 5313.149896016619, 5557.388687244481, 5618.157276408571, 4545.6136228420955, 4606.815727371002, 4904.681195722266, 5402.621816472809, 5635.605507614428, 4643.83746568882, 5181.844544663666, 5423.376311724044, 5607.8878422432135, 5639.520640745419], "value": 4123.590862694013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.8976743255942, 744.3896005275673, 768.2240936653985, 805.5126648377732, 836.272442974612, 15296.464623738171, 16619.0, 16633.50099497014, 16637.83362166378, 16639.33442662292, 13861.4455421783, 13951.860481395182, 13958.0, 13968.511102111428, 14349.426022959069, 706.2443185791205, 726.5350175887436, 887.1572006593714, 890.9020007799169, 920.1580553793272, 887.1660638999352, 905.4702998745742, 906.2342320738998, 906.2976193450096, 906.6010955179732, 965.8696076029717, 966.5215718219491, 968.1577027985662, 968.389914353956, 970.0832713086137, 672.5359501943643, 767.4052609227838, 771.3327971304781, 963.7397902566303, 966.333230071252, 567.2144080448567, 687.1204857782047, 705.7706245470191, 706.4807366585555, 708.7731925783751, 15316.770248446282, 15371.53885383429, 15394.307256173499, 15397.846021539754, 15408.845911540853, 14182.574522764333, 14484.565463036126, 14521.128732276096, 14557.344919478648, 14770.778438323434, 9429.434233945916, 11917.404129793562, 12676.556320528829, 13170.934145329307, 13654.453821847113, 4606.147862645421, 4614.861554153381, 4654.976725116353, 4677.415323084614, 4677.438707355105, 88788.0, 111269.0, 113797.99999999917, 116377.0, 118580.0, 10654.573817047387, 11029.0, 11094.833577496343, 11251.324920504632, 11488.253263537843, 21061.78938210629, 21102.577948440947, 21146.788532114482, 21252.36242912715, 21271.89364053201, 7594.354479869163, 7912.525248485109, 7973.760787176394, 8128.309093727099, 8147.185281471805, 3781.3760729479327, 4283.6144746972295, 4380.518143004267, 4437.844675436409, 4453.51011388747, 3948.427478015729, 4241.406203131564, 4245.57544245573, 4274.871753847389, 4274.893127671833, 741.1809950005274, 751.6883039096701, 762.9961850190714, 768.193396933229, 778.7391223939931, 572.1589263782233, 614.4899733221415, 665.9400653941168, 677.8305423644089, 701.2321507948787, 8721.345899057596, 9473.810523789623, 9589.664361747446, 9823.901760982442, 9976.301658883809, 594.3551246897078, 660.8380946668001, 676.736072931557, 677.167084486075, 695.1482999520429, 4751.881202969884, 4795.848996240877, 4824.059308434892, 4826.203676393426, 4838.806447742051, 90033.0, 151548.0, 161946.0, 165473.0, 169917.0, 110833.89166108235, 140857.0, 145472.0, 145908.0, 147281.0, 102438.0, 117930.0, 117980.41009794896, 123290.0, 124767.0, 7634.847303054019, 8905.465672059698, 9008.90991090107, 9090.54547272647, 9169.083091690778, 62839.37160628518, 64661.0, 65006.69986600147, 66103.66948165228, 68016.6599167001, 99223.50388248013, 100232.99767002236, 100896.0, 103919.0, 107469.46265268624, 12459.688507787378, 12548.058895582686, 12646.43091060886, 12718.554850580185, 13157.53948611794, 12799.42402591867, 12823.551175708806, 12894.41975111141, 12953.481860725466, 13087.280199589113, 12120.393980300772, 12316.261024338568, 12549.623511294676, 13015.47938082504, 13047.34763261814, 11281.492332844877, 13328.200307981551, 13552.051356404956, 13949.790753138508, 14184.503542375967, 13708.725825483634, 14217.715645686822, 14570.781438278222, 14819.407223711361, 14829.25853707332, 11229.0, 11590.826137607773, 11911.225770324902, 11984.700382490159, 12039.458224379749, 11197.776044479227, 11530.884691153316, 11679.357635330138, 11838.763224735629, 11956.043516518832], "value": 8034.822444823547, "unit": "score"}], "name": "dromaeo_css", "value": 7635.181408693008, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 128.0, 114.0, 113.0, 114.0, 113.0, 116.0, 120.0, 109.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 132.0, 147.0, 127.0, 158.0, 134.0, 139.0, 144.0, 107.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 185.0, 182.0, 186.0, 183.0, 182.0, 184.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 74.0, 163.0, 82.0, 82.0, 81.0, 68.0, 136.0, 58.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 134.0, 69.0, 88.0, 77.0, 122.0, 69.0, 70.0, 120.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 88.0, 84.0, 86.0, 86.0, 134.0, 85.0, 90.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 112.0, 149.0, 93.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 86.0, 85.0, 84.0, 85.0, 85.0, 84.0, 81.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 55.0, 56.0, 58.0, 58.0, 56.0, 56.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 76.0, 74.0, 80.0, 74.0, 72.0, 72.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 92.0, 106.0, 97.0, 83.0, 89.0, 72.0, 82.0, 72.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 142.0, 133.0, 137.0, 136.0, 144.0, 148.0, 152.0, 197.0, 157.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 160.0, 163.0, 160.0, 159.0, 163.0, 223.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 65.0, 69.0, 80.0, 82.0, 78.0, 65.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1491.0999999999997, "alertThreshold": 2.0}]} 07:38:56 INFO - Return code: 0 07:38:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:56 INFO - # TBPL SUCCESS # 07:38:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:56 INFO - 2016-09-17 07:38:56.581 screenresolution[1715:17810] starting screenresolution argv=screenresolution get 07:38:56 INFO - 2016-09-17 07:38:56.584 screenresolution[1715:17810] Display 0: 1600x1200x32@60 07:38:56 INFO - 2016-09-17 07:38:56.590 screenresolution[1716:17813] starting screenresolution argv=screenresolution list 07:38:56 INFO - Available Modes on Display 0 07:38:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:56 INFO - Intel Iris: 07:38:56 INFO - Chipset Model: Intel Iris 07:38:56 INFO - Type: GPU 07:38:56 INFO - Bus: Built-In 07:38:56 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:56 INFO - Vendor: Intel (0x8086) 07:38:56 INFO - Device ID: 0x0a2e 07:38:56 INFO - Revision ID: 0x0009 07:38:56 INFO - Displays: 07:38:56 INFO - Display: 07:38:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:56 INFO - Display Serial Number: 1600x1200 60 07:38:56 INFO - Main Display: Yes 07:38:56 INFO - Mirror: Off 07:38:56 INFO - Online: Yes 07:38:56 INFO - Rotation: Supported 07:38:56 INFO - Return code: 0 07:38:56 INFO - Running post-action listener: _resource_record_post_action 07:38:56 INFO - [mozharness: 2016-09-17 14:38:56.832151Z] Finished run-tests step (success) 07:38:56 INFO - Running post-run listener: _resource_record_post_run 07:38:56 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31878144; Write bytes: 664987648; Read time: 200; Write time: 1908 07:38:56 INFO - TinderboxPrint: CPU usage
53.2% 07:38:56 INFO - TinderboxPrint: I/O read bytes / time
31,878,144 / 200 07:38:56 INFO - TinderboxPrint: I/O write bytes / time
664,987,648 / 1,908 07:38:56 INFO - TinderboxPrint: CPU idle
2,261.4 (46.8%) 07:38:56 INFO - TinderboxPrint: CPU system
960.4 (19.9%) 07:38:56 INFO - TinderboxPrint: CPU user
1,607.8 (33.3%) 07:38:56 INFO - TinderboxPrint: Swap in / out
392,159,232 / 0 07:38:56 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361910784; Write bytes: 356935680; Read time: 27532; Write time: 767 07:38:56 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29805568; Write bytes: 295780352; Read time: 175; Write time: 1098 07:38:57 INFO - Running post-run listener: _upload_blobber_files 07:38:57 INFO - Blob upload gear active. 07:38:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:57 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:57 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:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:57 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_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_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_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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef6222ebab5c8cb207d5bc9202cf1f9baf1876a4a02c93ad795c2eac86dad81c986f55ad01b578947b88260f7b745453a2ed53ab7d07c0f8bfe614008673d366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ba6a06132077d09d56133e5b7492625acbd2c6c99073ed4433cf21aa340079eaf95fbaafc742d976109bbad20eb13851e0d822432dac69666bbfe8dcb3fb78"} 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef6222ebab5c8cb207d5bc9202cf1f9baf1876a4a02c93ad795c2eac86dad81c986f55ad01b578947b88260f7b745453a2ed53ab7d07c0f8bfe614008673d366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ba6a06132077d09d56133e5b7492625acbd2c6c99073ed4433cf21aa340079eaf95fbaafc742d976109bbad20eb13851e0d822432dac69666bbfe8dcb3fb78"} 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=1258.256583 ========= master_lag: 0.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:39:03.289358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.292742) ========= 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.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef6222ebab5c8cb207d5bc9202cf1f9baf1876a4a02c93ad795c2eac86dad81c986f55ad01b578947b88260f7b745453a2ed53ab7d07c0f8bfe614008673d366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ba6a06132077d09d56133e5b7492625acbd2c6c99073ed4433cf21aa340079eaf95fbaafc742d976109bbad20eb13851e0d822432dac69666bbfe8dcb3fb78"} 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.011107 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef6222ebab5c8cb207d5bc9202cf1f9baf1876a4a02c93ad795c2eac86dad81c986f55ad01b578947b88260f7b745453a2ed53ab7d07c0f8bfe614008673d366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ba6a06132077d09d56133e5b7492625acbd2c6c99073ed4433cf21aa340079eaf95fbaafc742d976109bbad20eb13851e0d822432dac69666bbfe8dcb3fb78"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.339292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.339766) ========= 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.IRkRagS7kx/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.QwXjriJpw5/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.004981 ========= master_lag: 0.72 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:04.067107) ========= ========= Started reboot skipped (results: 3, elapsed: 16 secs) (at 2016-09-17 07:39:04.071133) ========= ========= Finished reboot skipped (results: 3, elapsed: 16 secs) (at 2016-09-17 07:39:20.473596) ========= ========= Total master_lag: 1.44 =========