builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1464994325.82 results: success (0) buildid: 20160603145726 builduid: 735eabd89e7642b190025e5aec037e93 revision: 15bb21e207853ad8c1c4761205dfbd60118224c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:05.816331) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:05.832153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:05.832445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:05.863996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:05.864292) ========= 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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-06-03 15:52:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 207M=0s 2016-06-03 15:52:06 (207 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.317262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:06.202701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:06.203017) ========= 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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.027724 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:06.251287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:52:06.251636) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 15bb21e207853ad8c1c4761205dfbd60118224c7 --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 15bb21e207853ad8c1c4761205dfbd60118224c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-06-03 15:52:06,329 truncating revision to first 12 chars 2016-06-03 15:52:06,329 Setting DEBUG logging. 2016-06-03 15:52:06,329 attempt 1/10 2016-06-03 15:52:06,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/15bb21e20785?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 15:52:07,403 unpacking tar archive at: mozilla-aurora-15bb21e20785/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331135 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:52:07.602879) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:07.603191) ========= script_repo_revision: 15bb21e207853ad8c1c4761205dfbd60118224c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:07.604624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:07.604897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:07.622978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-06-03 15:52:07.623261) ========= /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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:52:07 INFO - MultiFileLogger online at 20160603 15:52:07 in /builds/slave/test 15:52:07 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 15:52:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:52:07 INFO - {'append_to_log': False, 15:52:07 INFO - 'base_work_dir': '/builds/slave/test', 15:52:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:52:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:52:07 INFO - 'branch': 'Mozilla-Aurora', 15:52:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:52:07 INFO - 'config_files': ('talos/mac_config.py',), 15:52:07 INFO - 'default_actions': ('clobber', 15:52:07 INFO - 'read-buildbot-config', 15:52:07 INFO - 'download-and-extract', 15:52:07 INFO - 'populate-webroot', 15:52:07 INFO - 'create-virtualenv', 15:52:07 INFO - 'install', 15:52:07 INFO - 'run-tests'), 15:52:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:52:07 INFO - 'download_minidump_stackwalk': True, 15:52:07 INFO - 'download_symbols': 'ondemand', 15:52:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:52:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:52:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:52:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:52:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:52:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:52:07 INFO - 'installer_path': 'installer.exe', 15:52:07 INFO - 'log_level': 'info', 15:52:07 INFO - 'log_name': 'talos', 15:52:07 INFO - 'log_to_console': True, 15:52:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:52:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:52:07 INFO - 'opt_config_files': (), 15:52:07 INFO - 'pip_index': False, 15:52:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:52:07 INFO - 'cmd': ('bash', 15:52:07 INFO - '-c', 15:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:52:07 INFO - 'enabled': True, 15:52:07 INFO - 'halt_on_failure': False, 15:52:07 INFO - 'name': 'check_screen_resolution'},), 15:52:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:52:07 INFO - 'cmd': ('bash', 15:52:07 INFO - '-c', 15:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:52:07 INFO - 'enabled': True, 15:52:07 INFO - 'halt_on_failure': False, 15:52:07 INFO - 'name': 'check_screen_resolution'},), 15:52:07 INFO - 'run_cmd_checks_enabled': True, 15:52:07 INFO - 'sps_profile': False, 15:52:07 INFO - 'sps_profile_interval': 0, 15:52:07 INFO - 'suite': 'dromaeojs', 15:52:07 INFO - 'system_bits': '32', 15:52:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:52:07 INFO - 'title': 't-yosemite-r7-0081', 15:52:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:52:07 INFO - 'use_talos_json': True, 15:52:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:52:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:52:07 INFO - 'work_dir': 'build'} 15:52:07 INFO - ##### 15:52:07 INFO - ##### Running clobber step. 15:52:07 INFO - ##### 15:52:07 INFO - Running pre-action listener: _resource_record_pre_action 15:52:07 INFO - Running main action method: clobber 15:52:07 INFO - rmtree: /builds/slave/test/build 15:52:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:52:09 INFO - Running post-action listener: _resource_record_post_action 15:52:09 INFO - ##### 15:52:09 INFO - ##### Running read-buildbot-config step. 15:52:09 INFO - ##### 15:52:09 INFO - Running pre-action listener: _resource_record_pre_action 15:52:09 INFO - Running main action method: read_buildbot_config 15:52:09 INFO - Using buildbot properties: 15:52:09 INFO - { 15:52:09 INFO - "project": "", 15:52:09 INFO - "product": "firefox", 15:52:09 INFO - "script_repo_revision": "production", 15:52:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:52:09 INFO - "repository": "", 15:52:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:52:09 INFO - "buildid": "20160603145726", 15:52:09 INFO - "pgo_build": "False", 15:52:09 INFO - "basedir": "/builds/slave/test", 15:52:09 INFO - "buildnumber": 21, 15:52:09 INFO - "slavename": "t-yosemite-r7-0081", 15:52:09 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:52:09 INFO - "platform": "yosemite_r7", 15:52:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:52:09 INFO - "branch": "mozilla-aurora", 15:52:09 INFO - "repo_path": "releases/mozilla-aurora", 15:52:09 INFO - "revision": "15bb21e207853ad8c1c4761205dfbd60118224c7", 15:52:09 INFO - "stage_platform": "macosx64", 15:52:09 INFO - "builduid": "735eabd89e7642b190025e5aec037e93", 15:52:09 INFO - "slavebuilddir": "test" 15:52:09 INFO - } 15:52:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:52:09 INFO - Running post-action listener: _resource_record_post_action 15:52:09 INFO - ##### 15:52:09 INFO - ##### Running download-and-extract step. 15:52:09 INFO - ##### 15:52:09 INFO - Running pre-action listener: _resource_record_pre_action 15:52:09 INFO - Running main action method: download_and_extract 15:52:09 INFO - mkdir: /builds/slave/test/build/tests 15:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:09 INFO - https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:52:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:52:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:52:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:52:14 INFO - Downloaded 1199 bytes. 15:52:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:52:14 INFO - Using the following test package requirements: 15:52:14 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:52:14 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:52:14 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:52:14 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'jsshell-mac.zip'], 15:52:14 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:52:14 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:52:14 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:52:14 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:52:14 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:52:14 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:52:14 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:52:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:52: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')]} 15:52:14 INFO - https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:52:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:52:16 INFO - Downloaded 18463790 bytes. 15:52:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:52:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:52:19 INFO - Return code: 0 15:52:19 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:19 INFO - https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:52:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:52:49 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 15:52:49 INFO - retry: attempt #1 caught exception: timed out 15:52:49 INFO - retry: Failed, sleeping 30 seconds before retrying 15:53:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 15:53:21 INFO - Downloaded 18463790 bytes. 15:53:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:53:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:53:25 INFO - Return code: 0 15:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:25 INFO - https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:53:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:53:25 INFO - Downloaded 10840145 bytes. 15:53:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:53:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:53:26 INFO - Return code: 0 15:53: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')]} 15:53:26 INFO - https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:53:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:53:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:53:30 INFO - Downloaded 114163558 bytes. 15:53:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:30 INFO - mkdir: /builds/slave/test/properties 15:53:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:53:30 INFO - Writing to file /builds/slave/test/properties/build_url 15:53:30 INFO - Contents: 15:53:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:53:31 INFO - Running post-action listener: _resource_record_post_action 15:53:31 INFO - Running post-action listener: set_extra_try_arguments 15:53:31 INFO - ##### 15:53:31 INFO - ##### Running populate-webroot step. 15:53:31 INFO - ##### 15:53:31 INFO - Running pre-action listener: _resource_record_pre_action 15:53:31 INFO - Running main action method: populate_webroot 15:53:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:53:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:53:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:53:31 INFO - '--tppagecycles', 15:53:31 INFO - '7'], 15:53:31 INFO - 'tests': ['tsvgm']}}, 15:53:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:53:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:53:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:53:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:53:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['damp', 'tps']}, 15:53:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'tests': ['damp', 'tps']}, 15:53:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['dromaeo_dom']}, 15:53:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:53:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'other-e10s': {'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['a11yr', 15:53:31 INFO - 'ts_paint', 15:53:31 INFO - 'tpaint', 15:53:31 INFO - 'sessionrestore', 15:53:31 INFO - 'sessionrestore_no_auto_restore', 15:53:31 INFO - 'tabpaint']}, 15:53:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['tsvgx', 15:53:31 INFO - 'tsvgr_opacity', 15:53:31 INFO - 'tart', 15:53:31 INFO - 'tscrollx', 15:53:31 INFO - 'cart']}, 15:53:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:53:31 INFO - 'tsvgr_opacity', 15:53:31 INFO - 'tart', 15:53:31 INFO - 'tscrollx', 15:53:31 INFO - 'cart']}, 15:53:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'talos_options': ['--disable-e10s'], 15:53:31 INFO - 'tests': ['tp5o']}, 15:53:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'tests': ['tp5o']}, 15:53:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'talos_options': ['--disable-e10s', 15:53:31 INFO - '--xperf_path', 15:53:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:31 INFO - 'tests': ['tp5n']}, 15:53:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:31 INFO - 'talos_options': ['--xperf_path', 15:53:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:31 INFO - 'tests': ['tp5n']}}, 15:53:31 INFO - 'talos.zip': {'path': '', 15:53:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:53:31 INFO - Running post-action listener: _resource_record_post_action 15:53:31 INFO - ##### 15:53:31 INFO - ##### Running create-virtualenv step. 15:53:31 INFO - ##### 15:53:31 INFO - Running pre-action listener: _resource_record_pre_action 15:53:31 INFO - Running main action method: create_virtualenv 15:53:31 INFO - Creating virtualenv /builds/slave/test/build/venv 15:53:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:53:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:53:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:53:31 INFO - Using real prefix '/tools/python27' 15:53:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:53:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:53:35 INFO - Installing pip.................done. 15:53:35 INFO - Return code: 0 15:53:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:35 INFO - 'HOME': '/Users/cltbld', 15:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:35 INFO - 'LOGNAME': 'cltbld', 15:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:35 INFO - 'MOZ_NO_REMOTE': '1', 15:53:35 INFO - 'NO_EM_RESTART': '1', 15:53:35 INFO - 'PAGER': '/bin/cat', 15:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:35 INFO - 'PWD': '/builds/slave/test', 15:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:35 INFO - 'SHELL': '/bin/bash', 15:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:35 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:35 INFO - 'USER': 'cltbld', 15:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:35 INFO - 'XPC_FLAGS': '0x0', 15:53:35 INFO - 'XPC_SERVICE_NAME': '0', 15:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:53:35 INFO - Cleaning up... 15:53:35 INFO - Return code: 0 15:53:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:35 INFO - 'HOME': '/Users/cltbld', 15:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:35 INFO - 'LOGNAME': 'cltbld', 15:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:35 INFO - 'MOZ_NO_REMOTE': '1', 15:53:35 INFO - 'NO_EM_RESTART': '1', 15:53:35 INFO - 'PAGER': '/bin/cat', 15:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:35 INFO - 'PWD': '/builds/slave/test', 15:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:35 INFO - 'SHELL': '/bin/bash', 15:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:35 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:35 INFO - 'USER': 'cltbld', 15:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:35 INFO - 'XPC_FLAGS': '0x0', 15:53:35 INFO - 'XPC_SERVICE_NAME': '0', 15:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:35 INFO - Downloading/unpacking psutil>=0.7.1 15:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:53:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:53:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:53:38 INFO - Installing collected packages: psutil 15:53:38 INFO - Running setup.py install for psutil 15:53:38 INFO - building 'psutil._psutil_osx' extension 15:53:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:53:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:53:39 INFO - building 'psutil._psutil_posix' extension 15:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:53:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:53:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:53:39 INFO - ^ 15:53:39 INFO - 1 warning generated. 15:53:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:53:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:53:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:53:39 INFO - Successfully installed psutil 15:53:39 INFO - Cleaning up... 15:53:39 INFO - Return code: 0 15:53:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:39 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:39 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.0.0'] in /builds/slave/test/build 15:53:39 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.0.0 15:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:39 INFO - 'HOME': '/Users/cltbld', 15:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:39 INFO - 'LOGNAME': 'cltbld', 15:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:39 INFO - 'MOZ_NO_REMOTE': '1', 15:53:39 INFO - 'NO_EM_RESTART': '1', 15:53:39 INFO - 'PAGER': '/bin/cat', 15:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:39 INFO - 'PWD': '/builds/slave/test', 15:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:39 INFO - 'SHELL': '/bin/bash', 15:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:39 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:39 INFO - 'USER': 'cltbld', 15:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:39 INFO - 'XPC_FLAGS': '0x0', 15:53:39 INFO - 'XPC_SERVICE_NAME': '0', 15:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:53:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:53:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:53:42 INFO - Installing collected packages: mozsystemmonitor 15:53:42 INFO - Running setup.py install for mozsystemmonitor 15:53:42 INFO - Successfully installed mozsystemmonitor 15:53:42 INFO - Cleaning up... 15:53:42 INFO - Return code: 0 15:53:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:42 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 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:53:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:42 INFO - 'HOME': '/Users/cltbld', 15:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:42 INFO - 'LOGNAME': 'cltbld', 15:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:42 INFO - 'MOZ_NO_REMOTE': '1', 15:53:42 INFO - 'NO_EM_RESTART': '1', 15:53:42 INFO - 'PAGER': '/bin/cat', 15:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:42 INFO - 'PWD': '/builds/slave/test', 15:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:42 INFO - 'SHELL': '/bin/bash', 15:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:42 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:42 INFO - 'USER': 'cltbld', 15:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:42 INFO - 'XPC_FLAGS': '0x0', 15:53:42 INFO - 'XPC_SERVICE_NAME': '0', 15:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:43 INFO - Downloading/unpacking blobuploader==1.2.4 15:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:45 INFO - Downloading blobuploader-1.2.4.tar.gz 15:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:53:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:53:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:46 INFO - Downloading docopt-0.6.1.tar.gz 15:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:53:46 INFO - Installing collected packages: blobuploader, requests, docopt 15:53:46 INFO - Running setup.py install for blobuploader 15:53:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:53:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:53:46 INFO - Running setup.py install for requests 15:53:47 INFO - Running setup.py install for docopt 15:53:47 INFO - Successfully installed blobuploader requests docopt 15:53:47 INFO - Cleaning up... 15:53:47 INFO - Return code: 0 15:53:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:47 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 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:47 INFO - 'HOME': '/Users/cltbld', 15:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:47 INFO - 'LOGNAME': 'cltbld', 15:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:47 INFO - 'MOZ_NO_REMOTE': '1', 15:53:47 INFO - 'NO_EM_RESTART': '1', 15:53:47 INFO - 'PAGER': '/bin/cat', 15:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:47 INFO - 'PWD': '/builds/slave/test', 15:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:47 INFO - 'SHELL': '/bin/bash', 15:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:47 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:47 INFO - 'USER': 'cltbld', 15:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:47 INFO - 'XPC_FLAGS': '0x0', 15:53:47 INFO - 'XPC_SERVICE_NAME': '0', 15:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:53:47 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-m8w3Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-fBHfgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-E8fxre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-2eghKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-lQjwue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PrpCxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-UHSLv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PGRppx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-SRg7qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-LOQRbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:53:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-rxN_h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-aewgz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xdzEKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-rk3BEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-h99HZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-JpCmys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:53:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-pWGMxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:53:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:53:49 INFO - Running setup.py install for manifestparser 15:53:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:53:49 INFO - Running setup.py install for mozcrash 15:53:50 INFO - Running setup.py install for mozdebug 15:53:50 INFO - Running setup.py install for mozdevice 15:53:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:53:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:53:50 INFO - Running setup.py install for mozfile 15:53:50 INFO - Running setup.py install for mozhttpd 15:53:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:53:50 INFO - Running setup.py install for mozinfo 15:53:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:53:50 INFO - Running setup.py install for mozInstall 15:53:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Running setup.py install for mozleak 15:53:51 INFO - Running setup.py install for mozlog 15:53:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Running setup.py install for moznetwork 15:53:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Running setup.py install for mozprocess 15:53:51 INFO - Running setup.py install for mozprofile 15:53:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:53:51 INFO - Running setup.py install for mozrunner 15:53:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:53:52 INFO - Running setup.py install for mozscreenshot 15:53:52 INFO - Running setup.py install for moztest 15:53:52 INFO - Running setup.py install for mozversion 15:53:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:53:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:53:52 INFO - Cleaning up... 15:53:52 INFO - Return code: 0 15:53:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:52 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 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:52 INFO - 'HOME': '/Users/cltbld', 15:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:52 INFO - 'LOGNAME': 'cltbld', 15:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:52 INFO - 'MOZ_NO_REMOTE': '1', 15:53:52 INFO - 'NO_EM_RESTART': '1', 15:53:52 INFO - 'PAGER': '/bin/cat', 15:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:52 INFO - 'PWD': '/builds/slave/test', 15:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:52 INFO - 'SHELL': '/bin/bash', 15:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:52 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:52 INFO - 'USER': 'cltbld', 15:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:52 INFO - 'XPC_FLAGS': '0x0', 15:53:52 INFO - 'XPC_SERVICE_NAME': '0', 15:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:53:52 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Xg4Mdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:53:52 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-29CX9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:53:52 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-OGjuZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-C9bYqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-SP6wOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-56K_3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-APBTga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-bumxPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-WoOIEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-MpmXCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-q44mrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:53:53 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-y_KyKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:53:54 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-t8YraD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:53:54 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-zymu1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:54 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-mcwXvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:53:54 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-227_qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:53:54 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xwQn1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:57 INFO - Downloading blessings-1.6.tar.gz 15:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:53:57 INFO - Installing collected packages: blessings 15:53:57 INFO - Running setup.py install for blessings 15:53:57 INFO - Successfully installed blessings 15:53:57 INFO - Cleaning up... 15:53:57 INFO - Return code: 0 15:53:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:53:57 INFO - Reading from file tmpfile_stdout 15:53:57 INFO - Current package versions: 15:53:57 INFO - blessings == 1.6 15:53:57 INFO - blobuploader == 1.2.4 15:53:57 INFO - docopt == 0.6.1 15:53:57 INFO - manifestparser == 1.1 15:53:57 INFO - mozInstall == 1.12 15:53:57 INFO - mozcrash == 0.17 15:53:57 INFO - mozdebug == 0.1 15:53:57 INFO - mozdevice == 0.48 15:53:57 INFO - mozfile == 1.2 15:53:57 INFO - mozhttpd == 0.7 15:53:57 INFO - mozinfo == 0.9 15:53:57 INFO - mozleak == 0.1 15:53:57 INFO - mozlog == 3.1 15:53:57 INFO - moznetwork == 0.27 15:53:57 INFO - mozprocess == 0.22 15:53:57 INFO - mozprofile == 0.28 15:53:57 INFO - mozrunner == 6.11 15:53:57 INFO - mozscreenshot == 0.1 15:53:57 INFO - mozsystemmonitor == 0.0 15:53:57 INFO - moztest == 0.7 15:53:57 INFO - mozversion == 1.4 15:53:57 INFO - psutil == 3.1.1 15:53:57 INFO - requests == 1.2.3 15:53:57 INFO - wsgiref == 0.1.2 15:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 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 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:57 INFO - 'HOME': '/Users/cltbld', 15:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:57 INFO - 'LOGNAME': 'cltbld', 15:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:57 INFO - 'MOZ_NO_REMOTE': '1', 15:53:57 INFO - 'NO_EM_RESTART': '1', 15:53:57 INFO - 'PAGER': '/bin/cat', 15:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:57 INFO - 'PWD': '/builds/slave/test', 15:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:57 INFO - 'SHELL': '/bin/bash', 15:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:57 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:57 INFO - 'USER': 'cltbld', 15:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:57 INFO - 'XPC_FLAGS': '0x0', 15:53:57 INFO - 'XPC_SERVICE_NAME': '0', 15:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:53:57 INFO - Cleaning up... 15:53:57 INFO - Return code: 0 15:53:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:57 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 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:57 INFO - 'HOME': '/Users/cltbld', 15:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:57 INFO - 'LOGNAME': 'cltbld', 15:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:57 INFO - 'MOZ_NO_REMOTE': '1', 15:53:57 INFO - 'NO_EM_RESTART': '1', 15:53:57 INFO - 'PAGER': '/bin/cat', 15:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:57 INFO - 'PWD': '/builds/slave/test', 15:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:57 INFO - 'SHELL': '/bin/bash', 15:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:53:58 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:58 INFO - 'USER': 'cltbld', 15:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:58 INFO - 'XPC_FLAGS': '0x0', 15:53:58 INFO - 'XPC_SERVICE_NAME': '0', 15:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:58 INFO - Downloading/unpacking jsonschema 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:00 INFO - Downloading/unpacking functools32 (from jsonschema) 15:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:01 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:01 INFO - warning: no files found matching '*.txt' 15:54:01 INFO - no previously-included directories found matching 'build' 15:54:01 INFO - no previously-included directories found matching 'dist' 15:54:01 INFO - no previously-included directories found matching '.git*' 15:54:01 INFO - Installing collected packages: jsonschema, functools32 15:54:01 INFO - Running setup.py install for functools32 15:54:01 INFO - warning: no files found matching '*.txt' 15:54:01 INFO - no previously-included directories found matching 'build' 15:54:01 INFO - no previously-included directories found matching 'dist' 15:54:01 INFO - no previously-included directories found matching '.git*' 15:54:01 INFO - Successfully installed jsonschema functools32 15:54:01 INFO - Cleaning up... 15:54:01 INFO - Return code: 0 15:54:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10103da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c14bf490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, '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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:01 INFO - 'HOME': '/Users/cltbld', 15:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:01 INFO - 'LOGNAME': 'cltbld', 15:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:01 INFO - 'MOZ_NO_REMOTE': '1', 15:54:01 INFO - 'NO_EM_RESTART': '1', 15:54:01 INFO - 'PAGER': '/bin/cat', 15:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:01 INFO - 'PWD': '/builds/slave/test', 15:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:01 INFO - 'SHELL': '/bin/bash', 15:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:54:01 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:01 INFO - 'USER': 'cltbld', 15:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:01 INFO - 'XPC_FLAGS': '0x0', 15:54:01 INFO - 'XPC_SERVICE_NAME': '0', 15:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:01 INFO - Downloading/unpacking flake8 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:54:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:54:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:54:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:05 INFO - no previously-included directories found matching 'docs/_build' 15:54:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:54:05 INFO - Running setup.py install for pep8 15:54:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:05 INFO - no previously-included directories found matching 'docs/_build' 15:54:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:54:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:54:05 INFO - Cleaning up... 15:54:05 INFO - Return code: 0 15:54:05 INFO - Running post-action listener: _resource_record_post_action 15:54:05 INFO - Running post-action listener: _start_resource_monitoring 15:54:05 INFO - Starting resource monitoring. 15:54:05 INFO - ##### 15:54:05 INFO - ##### Running install step. 15:54:05 INFO - ##### 15:54:05 INFO - Running pre-action listener: _resource_record_pre_action 15:54:05 INFO - Running main action method: install 15:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:06 INFO - Reading from file tmpfile_stdout 15:54:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:06 INFO - Reading from file tmpfile_stdout 15:54:06 INFO - Output received: 15:54:06 INFO - Usage: mozinstall [options] installer 15:54:06 INFO - Options: 15:54:06 INFO - -h, --help show this help message and exit 15:54:06 INFO - -d DEST, --destination=DEST 15:54:06 INFO - Directory to install application into. [default: 15:54:06 INFO - "/builds/slave/test"] 15:54:06 INFO - --app=APP Application being installed. [default: firefox] 15:54:06 INFO - mkdir: /builds/slave/test/build/application 15:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:54:37 INFO - Reading from file tmpfile_stdout 15:54:37 INFO - Output received: 15:54:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:54:37 INFO - Running post-action listener: _resource_record_post_action 15:54:37 INFO - ##### 15:54:37 INFO - ##### Running run-tests step. 15:54:37 INFO - ##### 15:54:37 INFO - Running pre-action listener: _resource_record_pre_action 15:54:37 INFO - Running main action method: run_tests 15:54:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:54:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:54:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:54:37 INFO - 2016-06-03 15:54:37.548 screenresolution[1645:7904] starting screenresolution argv=screenresolution get 15:54:37 INFO - 2016-06-03 15:54:37.550 screenresolution[1645:7904] Display 0: 1600x1200x32@60 15:54:37 INFO - 2016-06-03 15:54:37.556 screenresolution[1646:7907] starting screenresolution argv=screenresolution list 15:54:37 INFO - Available Modes on Display 0 15:54:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:54:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:54:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:54:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:54:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:54:37 INFO - Intel Iris: 15:54:37 INFO - Chipset Model: Intel Iris 15:54:37 INFO - Type: GPU 15:54:37 INFO - Bus: Built-In 15:54:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:54:37 INFO - Vendor: Intel (0x8086) 15:54:37 INFO - Device ID: 0x0a2e 15:54:37 INFO - Revision ID: 0x0009 15:54:37 INFO - Displays: 15:54:37 INFO - Display: 15:54:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:54:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:54:37 INFO - Display Serial Number: 1600x1200 60 15:54:37 INFO - Main Display: Yes 15:54:37 INFO - Mirror: Off 15:54:37 INFO - Online: Yes 15:54:37 INFO - Rotation: Supported 15:54:37 INFO - Return code: 0 15:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:54:37 INFO - Python 2.7.3 15:54:37 INFO - Return code: 0 15:54:37 INFO - grabbing minidump binary from tooltool 15:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100edce00>, '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 0x100d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ed7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:54:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:54:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:54:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:54:37 INFO - Return code: 0 15:54:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:54:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:54:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:54:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:54:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:54:38 INFO - Return code: 0 15:54:38 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://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:54:38 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://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uD2qVxXoJV/Render', 15:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:38 INFO - 'HOME': '/Users/cltbld', 15:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:38 INFO - 'LOGNAME': 'cltbld', 15:54:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:38 INFO - 'MOZ_NO_REMOTE': '1', 15:54:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:38 INFO - 'NO_EM_RESTART': '1', 15:54:38 INFO - 'PAGER': '/bin/cat', 15:54:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:38 INFO - 'PWD': '/builds/slave/test', 15:54:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:38 INFO - 'SHELL': '/bin/bash', 15:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pg2JWcP7FG/Listeners', 15:54:38 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 15:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:38 INFO - 'USER': 'cltbld', 15:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:38 INFO - 'XPC_FLAGS': '0x0', 15:54:38 INFO - 'XPC_SERVICE_NAME': '0', 15:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:38 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://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:54:39 INFO - mozversion application_buildid: 20160603145726 15:54:39 INFO - mozversion application_changeset: 15bb21e207853ad8c1c4761205dfbd60118224c7 15:54:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:54:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:54:39 INFO - mozversion application_name: Firefox 15:54:39 INFO - mozversion application_remotingname: firefox-dev 15:54:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:54:39 INFO - mozversion application_vendor: Mozilla 15:54:39 INFO - mozversion application_version: 48.0a2 15:54:39 INFO - mozversion platform_buildid: 20160603145726 15:54:39 INFO - mozversion platform_changeset: 15bb21e207853ad8c1c4761205dfbd60118224c7 15:54:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:54:39 INFO - mozversion platform_version: 48.0a2 15:54:39 INFO - using testdate: 1464994479 15:54:39 INFO - actual date: 1464994479 15:54:39 INFO - starting webserver on 'localhost:49276' 15:54:39 INFO - SUITE-START | Running 2 tests 15:54:39 INFO - TEST-START | dromaeo_css 15:54:39 INFO - operating with platform_type : mac_ 15:54:39 INFO - Initialising browser for dromaeo_css test... 15:54:39 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpuO0FXT/profile http://localhost:49276/getInfo.html) 15:54:41 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 15:54:41 INFO - PROCESS | 1664 | colorDepth:24 15:54:41 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 15:54:41 INFO - PROCESS | 1664 | __metrics 15:54:41 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:54:41 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:54:42 INFO - TEST-INFO | 1664: exit 0 15:54:42 INFO - Browser initialized. 15:54:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:54:42 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpuO0FXT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:54 INFO - PROCESS | 1666 | RSS: Main: 211636224 15:54:54 INFO - PROCESS | 1666 | 15:55:45 INFO - PROCESS | 1666 | 1464994545984 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:57:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 15:57:58 INFO - PROCESS | 1666 | RSS: Main: 364191744 15:57:58 INFO - PROCESS | 1666 | 16:01:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 16:01:02 INFO - PROCESS | 1666 | RSS: Main: 359563264 16:01:02 INFO - PROCESS | 1666 | 16:04:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 16:04:04 INFO - PROCESS | 1666 | RSS: Main: 362205184 16:04:04 INFO - PROCESS | 1666 | 16:07:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 16:07:07 INFO - PROCESS | 1666 | RSS: Main: 357687296 16:07:07 INFO - PROCESS | 1666 | 16:10:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 16:10:11 INFO - PROCESS | 1666 | RSS: Main: 324251648 16:10:11 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 16:13:14 INFO - PROCESS | 1666 | RSS: Main: 366825472 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | __start_tp_report 16:13:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 16:13:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 16:13:14 INFO - PROCESS | 1666 | |i|pagename|runs| 16:13:14 INFO - PROCESS | 1666 | |0;dojo.html;3134.1851118709137;3430.897073087808;3433.7081348085394;3443.7589368744184;3496.5629296337956;5718.485336319731;5730.25506684131;5733.627314224573;5733.770649174034;5774.105013722869;5720.084786434171;5784.942150578493;5785.421457854212;5791.739371728272;5805.622634528752;5197.986392653434;5212.843614691566;5266.499682530172;5328.147496400557;5331.6534425262425;5320.042392369372;5320.388155362125;5329.333833270841;5334.626576139672;5338.145896656536;5232.529072383485;5285.577153827704;5297.549708274782;5302.522772950434;5335.759890804904;5307.071262529042;5314.99015187117;5337.70642614656;5346;5358.437364076754;5106.770195341219;5154.433012368637;5161.5354618084175;5230.607704422184;5240.8689782755355;5519.668819870821;5545.251391062196;5588.245586845765;5592.86017849557;5621.4659607337235;6263.71813268404;6278.995360742305;6281.214848143982;6293.779717709858;6304.936950630481;5318.308619879391;5743.712814359265;5813.302403711582;6287.748490060392;6306.085617585424;5770.509506691937;5792.855178620526;5869.6771677558145;5891.823245302648;5899.646021238739;6195.2255968004;6205.782797602153;6235.314115447297;6248.4064013918605;6261.029540421242;6153.138560601519;6159.630422174684;6172.105044768573;6174.38256174379;6178;6047.395260474005;6169.352218017133;6169.722362493609;6178.505719542516;6184.165137706352;6116.974953844038;6142.652254521207;6249.34381889895;6255.56211065221;6264.154339164154;6195.566310358322;6263.123162757218;6271.122042913996;6271.247450305901;6274.498040156776;4905.607551395879;4930.605551555867;4931.284963680247;4949.90100197994;4971.502849715;4371.956280437218;4394.033312671208;4457.197704413172;4486.3494793254795;4509.616682582017;5714.485696287354;5717.742701578439;5718.1708652246025;5722.055860783007;5723.141528770634;6214.40963108504;6255.030470277115;6271.62370257786;6280.905786413207;6284.937150628435;5599.720013999284;5643.746031428595;5674.77300907959;5755.395683453261;5759.308882934075;5327.200919862052;5347.652402593819;5360.678359298455;5370.140777475585;5370.543503802221;6315.96842015787;6321.810345689545;6327.841803954938;6336.271328797063;6342.270638876588;6143.662098584442;6160.969195153995;6193.040078787795;6209.627422354673;6224.470919971853;6223.3465486123305;6237.625742455467;6284.025975973731;6287.18266625354;6310.242770867525;6167.691615419121;6187.288461826767;6219.284782249918;6222.128901953821;6222.751089956351;6211.627302361872;6289.7484100635465;6291.433770960635;6307.24313082433;6309.716062777278;5984.162217289496;6054.878902421839;6077.179580756541;6125.907024958145;6131.866048757656;6112.74665817949;6126.620139581256;6140.017597184429;6149.323574406902;6151.046587778901;5959.195508606283;6004;6007.948608993496;6014.48876845473;6021.488173505301;5732.16883551891;5983.970080149571;6070.575059745952;6131.927227014103;6144.648177400964;5982.551308651781;5987.730552125252;5997.940020599738;6008.128821320972;6037.305709843425 16:13:14 INFO - PROCESS | 1666 | |1;ext.html;499.24613833111994;516.7236924795756;518.4478530365161;532.936047674279;582.6707910030835;30393.544096838537;37550.87347379579;38486.422703659395;38650.03374915628;38694.80652596733;41491.17017659645;41682.791586042025;41877.79061104698;41954;41980.79009604955;8541.701040463586;9502.572384242709;9669.854952175723;9682.806343873119;9695.612175512972;8607.18231767981;11354.20520563561;11524.366159861203;11558.595449159282;11576.421178941062;9621.615135394612;11469.713257168554;11479.368634725086;11485.942570287138;11583.247088939192;8668.52323122228;9844;11201.495932683049;11285.266457680265;11673.649790506213;11077.279976801483;11197;11202.103831693445;11209.215354925158;11230.045446137088;37075.258494830225;37387.626123738686;37500.87497375083;37592;37593;3186.203449137716;3199.2001999500126;3318.784279021856;3403.914902127442;3507.018034950218;25538.97844086234;26981.865090674422;27987.580186297222;28145.577816332567;28688;2749.3951330707414;2758.531049721532;2797.608334833125;2798.3423895384567;2834.957475637867;371286;371588;371638;371865.12539498386;372240;60781.39218607846;60842.08736868862;60896.69551652302;60931;61138.388616114156;67560.66219668968;69136.6543167291;69214.65392673004;69255.30744692589;69308.6534567324;17038.48884533466;17392.043437611053;17404.738928916075;17416.825831741775;17425.215865285838;12302.200356976768;12507.874921250854;13212.405441755143;13359.666008349868;13502.257375844423;18895.62208755817;18952.90523547401;18963.620727585647;18973;19021.619567608846;7759.107702614158;7853.8821917671285;7934.76195714118;7942.014496375906;8010.879836802453;933.8933363963589;963.4700914496999;1005.4872015272264;1088.7604726960055;1201.963941081769;27401.725982740318;28969.275768105967;29237.117301863083;29262.414751705273;29300;1089.4008295437477;1119.4738472917716;1158.9478473468714;1181.4388165621433;1274.6558429224058;14868.661820435997;14998;15559.988600741142;15977.760333595006;16247.756283655755;548503;641505;649348;652482;652851;60874;61062.4734381644;61221.77556448846;61244;61430.38569614336;1345.0584590786489;1467.9779803302958;1504.7818066380314;1514.7424937760386;1521.6424140327015;28286.575701364083;28502.71737771847;28904.132876013755;29315.560266596443;29332.266693331985;47540.04919901652;48134.03731925412;48230.75884620554;48307.5169248303;48369.51630483791;364481;364510;365103;365537;365740.51389229717;25008;26436.471270574864;26460.88312934999;26461.280016798846;26464.6181843635;43640.345394819735;44237.11525769531;44298.778506107265;45914.08171836611;45937.31094033659;31120.53319200259;33988.83005584956;36168.457473137394;37377;37549.24901502009;36524.45213321749;36722.63277367299;36967;37123.44314835226;37308.81345593255;36499.905002849955;36646.63353366539;36999;37291.06772330713;37722.62277377301;31645.05064848058;31964.84017579897;32071.839640801645;32327.51508727324;32525.349493009533;24290.271291861274;24308.149214777397;24322;24806.37984050413;24831.751682482944 16:13:14 INFO - PROCESS | 1666 | |2;jquery.html;348.8866118511484;399.2454261445867;405.8295515883329;406.0186290900406;416.7666106980091;18206.726899096524;19685.803141968576;20326.796732032675;20355.592888142248;20395.694064589006;19679.901600492012;19864.70202946957;20136.49658758532;21042.053107610154;21115.894420527875;5919.37846526115;6009.609375390607;6064.666443345614;6098.420650038262;6123.234595675541;5850.561207909403;6026.728797204137;6030.457258846703;6064.787732429366;6071.301800292978;5750.453706897838;6023.909641355383;6028.4272994065495;6029.276486821587;6083.634981901078;5046.924296135561;6019.5485338599565;6047.606905551125;6053.75784968603;6084.178635884187;5323.813666521692;5330.626856120073;5336.91994620081;5511;5829.387914268983;13327.733445331136;13512.729745405037;13571.592852214351;13590.048696591242;17430.912845435818;2147.5597502512105;2196.066671664536;2222.822174226058;2239.6416573348347;2249.156566287642;9461.52692365379;9620.278479114095;9702.126808587262;9805.411675299505;10136.797264054678;1711.247051297425;1724.6033412315237;1725.663495618355;1728.9221985010704;1770.4954088084835;103570.48214758953;110291.44854275677;117330.41334793357;119388.20917686242;119581.99999999913;10315.535800888978;10900.890991090146;11658.591949281734;11667.35829529367;11794.646160615195;14919.328630211667;14970.401183952737;14976.026558273677;15008.549743507712;15018;8451.3519863626;8730.607122679494;8744.213020828156;8781.297496200288;8867.246284065797;8789.077146899612;8805.339599530062;8813.118688131135;8864.689735859343;8902.465852048897;4221.598948099926;4253.680973926906;4615.14619795339;4656.089943785134;4659.324397962277;1784.4111443223705;1900.8879805314045;1918.3477617610079;1995.7747111970339;2022.1304838919407;758.971593490823;934.1405906565975;985.8077674853334;1027.7276521721749;1029.613894789454;15960.680786384208;21262.060286450505;21263.255786047415;21269;21425.78574214238;857.4512312120248;984.2913102566141;997.5411310797115;1003.1373019203479;1057.3127467146417;8959.865602015974;10587.364758114538;11066.778664426667;11107.222494425312;11141.275817071864;514843;592027.039864798;603748;630070.8496457489;630503;74810.25189748142;97900;125531.3723431377;131084;134967;1021.4943602916455;1022.1362948308667;1043.4313299251926;1045.3675526306474;1046.6859942017213;18311.267549298413;18531.90734046375;18755.531111722317;18832.52918677044;18912.52718681989;47038.294425584325;50426;51553;52545.21182182347;56563.434365657464;52220.95558088893;62810.37189628045;71417.28582714107;72385;75864.62067689626;7574.848503030019;8145.348372130097;8281.503109813431;8308.335333173452;8337.430045535586;11098.389588572705;12665.81001284963;12834.871651283367;12909.741805164032;12916.547920822726;9932.652357167464;10683.839742404025;11011.934510125431;11067;11131.276467029616;10820.783584328427;10823.729406764895;10853.620123295646;10921.672349829518;11055.447227638426;9902.108810207115;10613.9469302653;11338;11434.599789007345;11451.312921224753;7981.680732770624;8036.4776289541;8290.212429819157;8302.460340077876;8345.874811877706;8102.878456823035;11084.667459976214;11140;11198.44007799623;11212.607558735404 16:13:14 INFO - PROCESS | 1666 | |3;mootools.html;1440.431029743252;1549.442200807709;1572.7247731646967;1795.4523870219587;1929.0740444586597;7942.682292708285;8524.531150786705;8654.567271636424;8677.002144753355;8684.565771711405;8450.746477605666;8713.869291960626;8721.258693011087;8728.214460698537;8740.082291359411;2458.0536493450063;2484.043643197373;2486.042873493709;2486.614574740918;2490;2424.442378253003;2480.119557557068;2480.9751902481016;2487.875606219691;2507.7993760499207;2448.0942051440993;2480.355107672;2486.577281862079;2498.375406148463;2499.4126380300613;2443.0960544598433;2485.7887079598254;2491.9875400623064;2498.812589055819;2498.9875050624814;2063.9690404643943;2426.9878650606634;2473.727889932106;2475.3687809608614;2497.7127630322384;8422.957885210595;8807.823843523158;8870.512121833297;8875.467471951639;8883.422577532505;5398.91901621476;5563.610547261694;5570.526505247059;5592.189132575753;5616.971915140439;3590.730695197871;3659.79871107089;3705.314516814371;3715.5727091384565;3719.7396182267253;1041.463646222196;1052.8578641883325;1059.2373491086405;1064.7391389109641;1088.5808963549011;11919.463624136935;12053.879461205363;12067.094967877445;12078.999999999913;12098.395080245953;3819.8090095495113;4586.403767510203;4727.456342520654;4759.571638552547;4760.166970780169;9388.530573471437;9403.247740180768;9414.482203478738;9418.246540276761;9429.905700942903;5406.91889621656;5417.052015897281;5420.701861397582;5432.782688692487;5437.619366644297;4261.488621365394;4370.38814565961;4376.956230437719;4378.168148051924;4977.328060711758;3602.8378722957004;3834.731568790158;3908.550516690617;3908.921821563553;3918.1967696622123;1174.8825117488354;1212.8362671039474;1215;1215.3194211241732;1230.1204638683384;844.1642773654161;863.710656929923;892.7144911327667;933.6638810028385;948.8908775490769;5766.192733017381;5803.303603567599;5885.264341957255;6024.668643224665;6032.577719559677;956.0821611253136;977.0913050862624;991.1228562722043;998.7902540466583;1007.924405669563;3866.0141663876093;3949.2299001694396;3976.8608098716404;3984.382420724792;4031.657309128698;10409.219308551888;10676.145908327315;10874;10921.836172457419;10952;10630.30902991303;10719.19606029551;10723.356598604109;10747.89252107469;10755.300905441121;8282.544460054754;8287.715404112372;8295.62669679866;8301.418900677016;8377.41358104939;5506.724663766876;5587.413321601336;5640.605157638925;5806.883862322646;5838.978178818775;9262.259019278306;9456.007119252654;9639.036096390586;9655.22758179358;9667.419954802734;9374.390664606779;9849.113579777855;9876.308658394131;9879.20966322678;9979.850302245617;7070.964645176741;7080.681369338289;7133.072700548895;7147.571145731273;7182.96408517954;7142.250063743232;7343.963280183565;8070.959645201737;8289.751307460785;8321.916780832356;7375.37309328713;7382.114146302478;7394.519356241827;7486.326230639269;7516.098068231847;7252.202257751537;7252.419806415369;7340.559566426032;7366.889496657447;7406.444516661164;6844.076049733222;6967;6975;6979.616121113388;6985.44116470671;4828.7102773833685;4904.043711476371;4941.061198372297;5012.172991456515;5056.255591820816;4973.975130124326;4992.850214493571;5020.673656212334;5069.822556210515;5210.947890521046 16:13:14 INFO - PROCESS | 1666 | |4;prototype.html;323.76203490434534;330.3607519449865;332.6208122740076;339.623018449521;342.8679958216086;6512.283648798634;6521.445677117445;6521.8369540761505;6585.736570537185;6593.208814942202;5244.292020577221;5589.74846131924;6222.817664643714;6510.08858759774;6541.672916354175;4619.653525985556;4730.290456431529;4755.953690188153;4823.638227132961;4851.975740121295;4780.139574876521;4826.9034619307595;4833.3958255218095;4849.345338379327;4861.1006963711825;4769;4801.5438533339275;4806.303086052538;4810.759462026902;4865.391826021747;4519.050999290153;4673.462551806551;4721.032188401369;4780.832670856538;4783.976080119577;4109.835606575733;4122.2579935611575;4124.793760311973;4131.35963925592;4139.730917490384;4959;5771.307443106812;6228.906566401508;6247.593906396115;6251.593646412968;5692.260006199209;5709.657420554739;5709.885802283931;5710.65736055838;5719.999000174995;6948.200956889921;6958;6962;6970.30296970304;6975.546589471668;3824.464574959508;4361.629261512743;4535.024969631512;4552.203364411214;4579.107074120548;7096.858062838766;7305.52514086588;7311.524750891228;7323.011393461868;7354.852902941965;4595.1269258841385;4605.378273932976;4613.984923316865;4618.907621847544;4637.652176086807;3879.6120387960977;4485.058137791035;4670.159371313196;4683.461401938753;4687.015726697432;5549.613666123606;5550.833474995676;5552.111662134039;5564.443555644484;5570.220169176318;6734.326567343226;6923.896141557881;6991.93008069923;6993.510454268254;7005.894911576331;1603.1503303249297;1605.675317862768;1616.426168710091;1629.771831943529;1630.4293497276096;863.1929146248278;962.5572236771163;978.256525040974;1019.273903809125;1036.3937096798306;6381.329960354189;6526.923057695427;6530.967345163243;6537.215534135934;6557.245916719543;7208;7228.277172282729;7250.891236631454;7251.5286506376915;7253.419726421874;6557.639329836905;6562.639054851934;6564.015397690289;6576.210854697371;6580.407763301326;3682.9815850920572;3778.3576791944884;3832.6550610445192;3836.1752223271574;3840.020794697334;7573.015507983927;7580.848383032308;7581.649688596553;7586.165521792709;7586.962065189638;7455.030845989987;7484.32641062307;7507.361874240642;7513.774586762406;7524.247575242542;7268.854622907618;7563.9621801890635;7567.886481702779;7577.810554736066;7587.165411804697;6251.437370636482;7002.964985175042;7169.820754481179;7248.275172482709;7280.162781280009;7130.358267755927;7152.177499587614;7152.6781294842895;7161.785146445615;7171.856562868609;7542.057242844645;7543.622818858926;7560.886586701094;7564.130125035691;7578.242175782378;4610.815567377267;4624.930626040679;4629.513901040342;4634.189016922092;4639.0721855628335;6964.512484126062;7204.639768011683;7212.098487689039;7217.783466496014;7228.49400541957;5699.7435115419075;6962.060121883481;6979.755708550177;6993.300669932966;6993.790186294419;5660.330202590329;6884.793456196322;6969.965150174216;6994.860102798018;6999.860002799814;6814.829629259309;6830.282820303796;6836.555623884432;6837.760678376233;6838.418734407432;6646.2356828965285;6986.441084713312;7001.964990175016;7008.053912721921;7017.087778588751;5693.3168019837885;6547.901781473377;6643.2360278568585;6712.83217919556;6873.5875847449315 16:13:14 INFO - PROCESS | 1666 | |5;yui.html;697.6337422853003;724.3046675191815;751.8082888863339;752.9171791102978;779.699815571005;13955.232462214573;15233;15267.694646107071;15271.389144434237;15285.847141528582;11631.767364652702;13698.52055178069;13859.514916977907;13927.651808704788;13943.302834858267;726.415235735233;741.8664640364732;743.9256074392572;744.3077937518106;745.2063552316793;734.2694019450654;912.9634814607408;934.7569631895722;940.3840484482674;943.8301105800953;975.8243516167087;984.3995162950595;986.5363279258772;987.77775000625;989.9158571521393;759.502525845572;973.1436336024324;978.2760757039775;978.706388083572;978.8531720241949;725.1262229014026;736.0835759479429;737.2406421385981;738.7525179064966;738.9556626602367;14699.118052916752;14723.263836808224;14766.114033157937;14788.556343309718;14806;12432.875671243262;12446.377681115999;12562;12816.935915320457;12993.155444896052;10009.449480278654;10127.13919316859;10216.744581385525;10321.380717156995;10711.143108551298;3505.4566542186003;3508.4561892906386;3543.0433782878736;3551.5915669698056;3666.9083272918124;71152;71572;73320.63339683268;80446;80544;9681.37071090377;9699.272554558436;9710.611575537041;9735.415875047378;9755.658551950648;20866.26968056111;21215;22430.43923901883;22502.774972250394;22539.549209015728;7545.396368290523;7598.354139898171;7655.157932627407;7804.843903121906;7932.127465978738;3973.8211780469946;4065.6747460203114;4088.2232375848494;4216.578342165821;4338.219120558329;3658.926821463609;3703.2963736889906;3703.5555733312176;3704.4258139987805;3706.05495598626;643.0707627478249;665.6638397609177;670.4435318685479;672.1497305908031;674.6458109492555;559.4964531921304;595.238095238096;596.2755252368394;616.6608365399013;622.3372108704216;7797.532148071134;8195.42632015765;8233.259006689306;8237.79405514855;8282.917170828336;503.34313720594974;514.2954152810598;619.8326451858063;621.6394491195095;623.2085251730277;3805.980970095132;3806.011065144761;3806.333891568964;3903.843846246118;3950.229705207457;88090;91161;91268.08731912728;91371;91474;70222.64888675626;70965;72097.27902721011;72111.63944180246;72703;65048.674756625915;65226.347736522024;65338.673306633165;65662;65824.67087664627;7709.614519274126;7811.570363629828;7813.218678132142;7877.7636670899965;7900.367970562456;44588.77705611581;44919;45663.771681141385;46242.53757462382;46700.59898203059;65211;66598.66802663823;66990;67272;67877.30306742371;10393.532291047073;10840;11021.063209627271;11264.774704506028;11756.823647645464;11702.824457633313;11879.112965308233;12044;12336;12690.682732931751;11019.173561983043;11307.773844523228;11390;11717.121215909012;11804.055675546108;11579.826302605636;11591.88408115908;11638.41807909618;11675.591354302558;11848.111391645833;11256.437178141225;11314;11532.481038353126;11608.941955290169;11884.940575297069;9586.712398628053;9616.326857119935;9636.132748052709;9640.469774162208;9835;9166.312526560669;9168.587413566538;9239.76900577464;9241.306901982243;9410.38832475887 16:13:14 INFO - PROCESS | 1666 | __end_tp_report 16:13:14 INFO - PROCESS | 1666 | __start_cc_report 16:13:14 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1498 16:13:14 INFO - PROCESS | 1666 | __end_cc_report 16:13:14 INFO - PROCESS | 1666 | __startTimestamp1464995594329__endTimestamp 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | ------- Summary: start ------- 16:13:14 INFO - PROCESS | 1666 | Number of tests: 6 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5751.72 Median:5984.16 stddev:624.96 (10.4%) stddev-sans-first:592.18 16:13:14 INFO - PROCESS | 1666 | Values: 3134.2 3430.9 3433.7 3443.8 3496.6 5718.5 5730.3 5733.6 5733.8 5774.1 5720.1 5784.9 5785.4 5791.7 5805.6 5198.0 5212.8 5266.5 5328.1 5331.7 5320.0 5320.4 5329.3 5334.6 5338.1 5232.5 5285.6 5297.5 5302.5 5335.8 5307.1 5315.0 5337.7 5346.0 5358.4 5106.8 5154.4 5161.5 5230.6 5240.9 5519.7 5545.3 5588.2 5592.9 5621.5 6263.7 6279.0 6281.2 6293.8 6304.9 5318.3 5743.7 5813.3 6287.7 6306.1 5770.5 5792.9 5869.7 5891.8 5899.6 6195.2 6205.8 6235.3 6248.4 6261.0 6153.1 6159.6 6172.1 6174.4 6178.0 6047.4 6169.4 6169.7 6178.5 6184.2 6117.0 6142.7 6249.3 6255.6 6264.2 6195.6 6263.1 6271.1 6271.2 6274.5 4905.6 4930.6 4931.3 4949.9 4971.5 4372.0 4394.0 4457.2 4486.3 4509.6 5714.5 5717.7 5718.2 5722.1 5723.1 6214.4 6255.0 6271.6 6280.9 6284.9 5599.7 5643.7 5674.8 5755.4 5759.3 5327.2 5347.7 5360.7 5370.1 5370.5 6316.0 6321.8 6327.8 6336.3 6342.3 6143.7 6161.0 6193.0 6209.6 6224.5 6223.3 6237.6 6284.0 6287.2 6310.2 6167.7 6187.3 6219.3 6222.1 6222.8 6211.6 6289.7 6291.4 6307.2 6309.7 5984.2 6054.9 6077.2 6125.9 6131.9 6112.7 6126.6 6140.0 6149.3 6151.0 5959.2 6004.0 6007.9 6014.5 6021.5 5732.2 5984.0 6070.6 6131.9 6144.6 5982.6 5987.7 5997.9 6008.1 6037.3 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:60527.35 Median:3455.47 stddev:125987.48 (3646.0%) stddev-sans-first:126260.29 16:13:14 INFO - PROCESS | 1666 | Values: 499.2 516.7 518.4 532.9 582.7 30393.5 37550.9 38486.4 38650.0 38694.8 41491.2 41682.8 41877.8 41954.0 41980.8 8541.7 9502.6 9669.9 9682.8 9695.6 8607.2 11354.2 11524.4 11558.6 11576.4 9621.6 11469.7 11479.4 11485.9 11583.2 8668.5 9844.0 11201.5 11285.3 11673.6 11077.3 11197.0 11202.1 11209.2 11230.0 37075.3 37387.6 37500.9 37592.0 37593.0 3186.2 3199.2 3318.8 3403.9 3507.0 25539.0 26981.9 27987.6 28145.6 28688.0 2749.4 2758.5 2797.6 2798.3 2835.0 371286.0 371588.0 371638.0 371865.1 372240.0 60781.4 60842.1 60896.7 60931.0 61138.4 67560.7 69136.7 69214.7 69255.3 69308.7 17038.5 17392.0 17404.7 17416.8 17425.2 12302.2 12507.9 13212.4 13359.7 13502.3 18895.6 18952.9 18963.6 18973.0 19021.6 7759.1 7853.9 7934.8 7942.0 8010.9 933.9 963.5 1005.5 1088.8 1202.0 27401.7 28969.3 29237.1 29262.4 29300.0 1089.4 1119.5 1158.9 1181.4 1274.7 14868.7 14998.0 15560.0 15977.8 16247.8 548503.0 641505.0 649348.0 652482.0 652851.0 60874.0 61062.5 61221.8 61244.0 61430.4 1345.1 1468.0 1504.8 1514.7 1521.6 28286.6 28502.7 28904.1 29315.6 29332.3 47540.0 48134.0 48230.8 48307.5 48369.5 364481.0 364510.0 365103.0 365537.0 365740.5 25008.0 26436.5 26460.9 26461.3 26464.6 43640.3 44237.1 44298.8 45914.1 45937.3 31120.5 33988.8 36168.5 37377.0 37549.2 36524.5 36722.6 36967.0 37123.4 37308.8 36499.9 36646.6 36999.0 37291.1 37722.6 31645.1 31964.8 32071.8 32327.5 32525.3 24290.3 24308.1 24322.0 24806.4 24831.8 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33671.72 Median:2209.44 stddev:99031.03 (4482.2%) stddev-sans-first:99277.22 16:13:14 INFO - PROCESS | 1666 | Values: 348.9 399.2 405.8 406.0 416.8 18206.7 19685.8 20326.8 20355.6 20395.7 19679.9 19864.7 20136.5 21042.1 21115.9 5919.4 6009.6 6064.7 6098.4 6123.2 5850.6 6026.7 6030.5 6064.8 6071.3 5750.5 6023.9 6028.4 6029.3 6083.6 5046.9 6019.5 6047.6 6053.8 6084.2 5323.8 5330.6 5336.9 5511.0 5829.4 13327.7 13512.7 13571.6 13590.0 17430.9 2147.6 2196.1 2222.8 2239.6 2249.2 9461.5 9620.3 9702.1 9805.4 10136.8 1711.2 1724.6 1725.7 1728.9 1770.5 103570.5 110291.4 117330.4 119388.2 119582.0 10315.5 10900.9 11658.6 11667.4 11794.6 14919.3 14970.4 14976.0 15008.5 15018.0 8451.4 8730.6 8744.2 8781.3 8867.2 8789.1 8805.3 8813.1 8864.7 8902.5 4221.6 4253.7 4615.1 4656.1 4659.3 1784.4 1900.9 1918.3 1995.8 2022.1 759.0 934.1 985.8 1027.7 1029.6 15960.7 21262.1 21263.3 21269.0 21425.8 857.5 984.3 997.5 1003.1 1057.3 8959.9 10587.4 11066.8 11107.2 11141.3 514843.0 592027.0 603748.0 630070.8 630503.0 74810.3 97900.0 125531.4 131084.0 134967.0 1021.5 1022.1 1043.4 1045.4 1046.7 18311.3 18531.9 18755.5 18832.5 18912.5 47038.3 50426.0 51553.0 52545.2 56563.4 52221.0 62810.4 71417.3 72385.0 75864.6 7574.8 8145.3 8281.5 8308.3 8337.4 11098.4 12665.8 12834.9 12909.7 12916.5 9932.7 10683.8 11011.9 11067.0 11131.3 10820.8 10823.7 10853.6 10921.7 11055.4 9902.1 10613.9 11338.0 11434.6 11451.3 7981.7 8036.5 8290.2 8302.5 8345.9 8102.9 11084.7 11140.0 11198.4 11212.6 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5675.74 Median:5304.93 stddev:3144.94 (59.3%) stddev-sans-first:3137.65 16:13:14 INFO - PROCESS | 1666 | Values: 1440.4 1549.4 1572.7 1795.5 1929.1 7942.7 8524.5 8654.6 8677.0 8684.6 8450.7 8713.9 8721.3 8728.2 8740.1 2458.1 2484.0 2486.0 2486.6 2490.0 2424.4 2480.1 2481.0 2487.9 2507.8 2448.1 2480.4 2486.6 2498.4 2499.4 2443.1 2485.8 2492.0 2498.8 2499.0 2064.0 2427.0 2473.7 2475.4 2497.7 8423.0 8807.8 8870.5 8875.5 8883.4 5398.9 5563.6 5570.5 5592.2 5617.0 3590.7 3659.8 3705.3 3715.6 3719.7 1041.5 1052.9 1059.2 1064.7 1088.6 11919.5 12053.9 12067.1 12079.0 12098.4 3819.8 4586.4 4727.5 4759.6 4760.2 9388.5 9403.2 9414.5 9418.2 9429.9 5406.9 5417.1 5420.7 5432.8 5437.6 4261.5 4370.4 4377.0 4378.2 4977.3 3602.8 3834.7 3908.6 3908.9 3918.2 1174.9 1212.8 1215.0 1215.3 1230.1 844.2 863.7 892.7 933.7 948.9 5766.2 5803.3 5885.3 6024.7 6032.6 956.1 977.1 991.1 998.8 1007.9 3866.0 3949.2 3976.9 3984.4 4031.7 10409.2 10676.1 10874.0 10921.8 10952.0 10630.3 10719.2 10723.4 10747.9 10755.3 8282.5 8287.7 8295.6 8301.4 8377.4 5506.7 5587.4 5640.6 5806.9 5839.0 9262.3 9456.0 9639.0 9655.2 9667.4 9374.4 9849.1 9876.3 9879.2 9979.9 7071.0 7080.7 7133.1 7147.6 7183.0 7142.3 7344.0 8071.0 8289.8 8321.9 7375.4 7382.1 7394.5 7486.3 7516.1 7252.2 7252.4 7340.6 7366.9 7406.4 6844.1 6967.0 6975.0 6979.6 6985.4 4828.7 4904.0 4941.1 5012.2 5056.3 4974.0 4992.9 5020.7 5069.8 5210.9 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5677.34 Median:6534.09 stddev:1828.98 (28.0%) stddev-sans-first:1789.42 16:13:14 INFO - PROCESS | 1666 | Values: 323.8 330.4 332.6 339.6 342.9 6512.3 6521.4 6521.8 6585.7 6593.2 5244.3 5589.7 6222.8 6510.1 6541.7 4619.7 4730.3 4756.0 4823.6 4852.0 4780.1 4826.9 4833.4 4849.3 4861.1 4769.0 4801.5 4806.3 4810.8 4865.4 4519.1 4673.5 4721.0 4780.8 4784.0 4109.8 4122.3 4124.8 4131.4 4139.7 4959.0 5771.3 6228.9 6247.6 6251.6 5692.3 5709.7 5709.9 5710.7 5720.0 6948.2 6958.0 6962.0 6970.3 6975.5 3824.5 4361.6 4535.0 4552.2 4579.1 7096.9 7305.5 7311.5 7323.0 7354.9 4595.1 4605.4 4614.0 4618.9 4637.7 3879.6 4485.1 4670.2 4683.5 4687.0 5549.6 5550.8 5552.1 5564.4 5570.2 6734.3 6923.9 6991.9 6993.5 7005.9 1603.2 1605.7 1616.4 1629.8 1630.4 863.2 962.6 978.3 1019.3 1036.4 6381.3 6526.9 6531.0 6537.2 6557.2 7208.0 7228.3 7250.9 7251.5 7253.4 6557.6 6562.6 6564.0 6576.2 6580.4 3683.0 3778.4 3832.7 3836.2 3840.0 7573.0 7580.8 7581.6 7586.2 7587.0 7455.0 7484.3 7507.4 7513.8 7524.2 7268.9 7564.0 7567.9 7577.8 7587.2 6251.4 7003.0 7169.8 7248.3 7280.2 7130.4 7152.2 7152.7 7161.8 7171.9 7542.1 7543.6 7560.9 7564.1 7578.2 4610.8 4624.9 4629.5 4634.2 4639.1 6964.5 7204.6 7212.1 7217.8 7228.5 5699.7 6962.1 6979.8 6993.3 6993.8 5660.3 6884.8 6970.0 6994.9 6999.9 6814.8 6830.3 6836.6 6837.8 6838.4 6646.2 6986.4 7002.0 7008.1 7017.1 5693.3 6547.9 6643.2 6712.8 6873.6 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17660.42 Median:32845.87 stddev:24344.43 (74.1%) stddev-sans-first:24379.40 16:13:14 INFO - PROCESS | 1666 | Values: 697.6 724.3 751.8 752.9 779.7 13955.2 15233.0 15267.7 15271.4 15285.8 11631.8 13698.5 13859.5 13927.7 13943.3 726.4 741.9 743.9 744.3 745.2 734.3 913.0 934.8 940.4 943.8 975.8 984.4 986.5 987.8 989.9 759.5 973.1 978.3 978.7 978.9 725.1 736.1 737.2 738.8 739.0 14699.1 14723.3 14766.1 14788.6 14806.0 12432.9 12446.4 12562.0 12816.9 12993.2 10009.4 10127.1 10216.7 10321.4 10711.1 3505.5 3508.5 3543.0 3551.6 3666.9 71152.0 71572.0 73320.6 80446.0 80544.0 9681.4 9699.3 9710.6 9735.4 9755.7 20866.3 21215.0 22430.4 22502.8 22539.5 7545.4 7598.4 7655.2 7804.8 7932.1 3973.8 4065.7 4088.2 4216.6 4338.2 3658.9 3703.3 3703.6 3704.4 3706.1 643.1 665.7 670.4 672.1 674.6 559.5 595.2 596.3 616.7 622.3 7797.5 8195.4 8233.3 8237.8 8282.9 503.3 514.3 619.8 621.6 623.2 3806.0 3806.0 3806.3 3903.8 3950.2 88090.0 91161.0 91268.1 91371.0 91474.0 70222.6 70965.0 72097.3 72111.6 72703.0 65048.7 65226.3 65338.7 65662.0 65824.7 7709.6 7811.6 7813.2 7877.8 7900.4 44588.8 44919.0 45663.8 46242.5 46700.6 65211.0 66598.7 66990.0 67272.0 67877.3 10393.5 10840.0 11021.1 11264.8 11756.8 11702.8 11879.1 12044.0 12336.0 12690.7 11019.2 11307.8 11390.0 11717.1 11804.1 11579.8 11591.9 11638.4 11675.6 11848.1 11256.4 11314.0 11532.5 11608.9 11884.9 9586.7 9616.3 9636.1 9640.5 9835.0 9166.3 9168.6 9239.8 9241.3 9410.4 16:13:14 INFO - PROCESS | 1666 | -------- Summary: end -------- 16:13:14 INFO - PROCESS | 1666 | 16:13:14 INFO - TEST-INFO | 1666: exit 0 16:13:14 INFO - TEST-OK | dromaeo_css | took 1115703ms 16:13:14 INFO - TEST-START | kraken 16:13:14 INFO - operating with platform_type : mac_ 16:13:14 INFO - Initialising browser for kraken test... 16:13:14 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpNDMx11/profile http://localhost:49276/getInfo.html) 16:13:17 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 16:13:17 INFO - PROCESS | 1672 | colorDepth:24 16:13:17 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 16:13:17 INFO - PROCESS | 1672 | __metrics 16:13:17 INFO - PROCESS | 1672 | 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" 16:13:17 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:13:17 INFO - TEST-INFO | 1672: exit 0 16:13:17 INFO - Browser initialized. 16:13:17 INFO - Running cycle 1/1 for kraken test... 16:13:17 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpNDMx11/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:29 INFO - PROCESS | 1674 | RSS: Main: 211173376 16:13:29 INFO - PROCESS | 1674 | 16:13:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 16:13:33 INFO - PROCESS | 1674 | RSS: Main: 271732736 16:13:33 INFO - PROCESS | 1674 | 16:13:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 16:13:36 INFO - PROCESS | 1674 | RSS: Main: 286253056 16:13:36 INFO - PROCESS | 1674 | 16:13:39 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 16:13:40 INFO - PROCESS | 1674 | RSS: Main: 372027392 16:13:40 INFO - PROCESS | 1674 | 16:13:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 16:13:43 INFO - PROCESS | 1674 | RSS: Main: 279801856 16:13:43 INFO - PROCESS | 1674 | 16:13:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:13:45 INFO - PROCESS | 1674 | RSS: Main: 281190400 16:13:45 INFO - PROCESS | 1674 | 16:13:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 16:13:49 INFO - PROCESS | 1674 | RSS: Main: 318738432 16:13:49 INFO - PROCESS | 1674 | 16:13:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 16:13:52 INFO - PROCESS | 1674 | RSS: Main: 319606784 16:13:52 INFO - PROCESS | 1674 | 16:13:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 16:13:56 INFO - PROCESS | 1674 | RSS: Main: 317628416 16:13:56 INFO - PROCESS | 1674 | 16:13:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:13:58 INFO - PROCESS | 1674 | RSS: Main: 310419456 16:13:58 INFO - PROCESS | 1674 | 16:14:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:00 INFO - PROCESS | 1674 | RSS: Main: 316534784 16:14:00 INFO - PROCESS | 1674 | 16:14:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:03 INFO - PROCESS | 1674 | RSS: Main: 319168512 16:14:03 INFO - PROCESS | 1674 | 16:14:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:07 INFO - PROCESS | 1674 | RSS: Main: 324018176 16:14:07 INFO - PROCESS | 1674 | 16:14:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:10 INFO - PROCESS | 1674 | RSS: Main: 321884160 16:14:10 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 16:14:12 INFO - PROCESS | 1674 | RSS: Main: 385855488 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | __start_tp_report 16:14:12 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 16:14:12 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 16:14:12 INFO - PROCESS | 1674 | |i|pagename|runs| 16:14:12 INFO - PROCESS | 1674 | |0;ai-astar;112;114;118;113;113;111;114;115;113;134 16:14:12 INFO - PROCESS | 1674 | |1;audio-beat-detection;154;125;141;166;119;142;139;117;168;118 16:14:12 INFO - PROCESS | 1674 | |2;audio-dft;186;186;185;185;185;186;185;184;181;184 16:14:12 INFO - PROCESS | 1674 | |3;audio-fft;69;78;74;155;80;81;88;69;134;73 16:14:12 INFO - PROCESS | 1674 | |4;audio-oscillator;93;91;142;77;79;85;120;80;76;125 16:14:12 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;85;88;84;87;87;88;86;88;84 16:14:12 INFO - PROCESS | 1674 | |6;imaging-darkroom;95;93;95;95;135;116;94;94;94;94 16:14:12 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;83;83;84;84;85;83;84;90;84 16:14:12 INFO - PROCESS | 1674 | |8;json-parse-financial;59;64;63;63;65;64;63;82;58;58 16:14:12 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;66;69;72;74;70;69;68;70;70 16:14:12 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;82;80;81;81;81;81;81;80;79;86 16:14:12 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;131;130;131;127;130;145;138;127;146;176 16:14:12 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;165;150;161;216;207;161;204;167;165;166 16:14:12 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;68;69;69;69;71;90;96;85;68;67 16:14:12 INFO - PROCESS | 1674 | __end_tp_report 16:14:12 INFO - PROCESS | 1674 | __start_cc_report 16:14:12 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2083 16:14:12 INFO - PROCESS | 1674 | __end_cc_report 16:14:12 INFO - PROCESS | 1674 | __startTimestamp1464995652638__endTimestamp 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | ------- Summary: start ------- 16:14:12 INFO - PROCESS | 1674 | Number of tests: 14 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:115.70 Median:114.00 stddev:6.70 (5.9%) stddev-sans-first:6.97 16:14:12 INFO - PROCESS | 1674 | Values: 112.0 114.0 118.0 113.0 113.0 111.0 114.0 115.0 113.0 134.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:141.50 stddev:19.23 (13.6%) stddev-sans-first:19.61 16:14:12 INFO - PROCESS | 1674 | Values: 154.0 125.0 141.0 166.0 119.0 142.0 139.0 117.0 168.0 118.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.49 (0.8%) stddev-sans-first:1.51 16:14:12 INFO - PROCESS | 1674 | Values: 186.0 186.0 185.0 185.0 185.0 186.0 185.0 184.0 181.0 184.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:90.10 Median:76.00 stddev:29.66 (39.0%) stddev-sans-first:30.46 16:14:12 INFO - PROCESS | 1674 | Values: 69.0 78.0 74.0 155.0 80.0 81.0 88.0 69.0 134.0 73.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:79.50 stddev:23.54 (29.6%) stddev-sans-first:24.93 16:14:12 INFO - PROCESS | 1674 | Values: 93.0 91.0 142.0 77.0 79.0 85.0 120.0 80.0 76.0 125.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.58 (1.8%) stddev-sans-first:1.66 16:14:12 INFO - PROCESS | 1674 | Values: 87.0 85.0 88.0 84.0 87.0 87.0 88.0 86.0 88.0 84.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:94.00 stddev:13.93 (14.8%) stddev-sans-first:14.63 16:14:12 INFO - PROCESS | 1674 | Values: 95.0 93.0 95.0 95.0 135.0 116.0 94.0 94.0 94.0 94.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:2.07 (2.5%) stddev-sans-first:2.19 16:14:12 INFO - PROCESS | 1674 | Values: 84.0 83.0 83.0 84.0 84.0 85.0 83.0 84.0 90.0 84.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:63.50 stddev:6.87 (10.8%) stddev-sans-first:7.06 16:14:12 INFO - PROCESS | 1674 | Values: 59.0 64.0 63.0 63.0 65.0 64.0 63.0 82.0 58.0 58.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:2.28 16:14:12 INFO - PROCESS | 1674 | Values: 63.0 66.0 69.0 72.0 74.0 70.0 69.0 68.0 70.0 70.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:1.87 (2.3%) stddev-sans-first:1.96 16:14:12 INFO - PROCESS | 1674 | Values: 82.0 80.0 81.0 81.0 81.0 81.0 81.0 80.0 79.0 86.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:134.50 stddev:15.00 (11.2%) stddev-sans-first:15.69 16:14:12 INFO - PROCESS | 1674 | Values: 131.0 130.0 131.0 127.0 130.0 145.0 138.0 127.0 146.0 176.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:166.50 stddev:23.32 (14.0%) stddev-sans-first:24.38 16:14:12 INFO - PROCESS | 1674 | Values: 165.0 150.0 161.0 216.0 207.0 161.0 204.0 167.0 165.0 166.0 16:14:12 INFO - PROCESS | 1674 | 16:14:12 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:70.00 stddev:10.81 (15.4%) stddev-sans-first:11.15 16:14:12 INFO - PROCESS | 1674 | Values: 68.0 69.0 69.0 69.0 71.0 90.0 96.0 85.0 68.0 67.0 16:14:12 INFO - PROCESS | 1674 | -------- Summary: end -------- 16:14:12 INFO - PROCESS | 1674 | 16:14:13 INFO - TEST-INFO | 1674: exit 0 16:14:13 INFO - TEST-OK | kraken | took 58665ms 16:14:13 INFO - SUITE-END | took 1174s 16:14:13 INFO - Completed test suite (00:19:34) 16:14:13 INFO - javascript benchmark 16:14:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.1851118709137, 3430.897073087808, 3433.7081348085394, 3443.7589368744184, 3496.5629296337956, 5718.485336319731, 5730.25506684131, 5733.627314224573, 5733.770649174034, 5774.105013722869, 5720.084786434171, 5784.942150578493, 5785.421457854212, 5791.739371728272, 5805.622634528752, 5197.986392653434, 5212.843614691566, 5266.499682530172, 5328.147496400557, 5331.6534425262425, 5320.042392369372, 5320.388155362125, 5329.333833270841, 5334.626576139672, 5338.145896656536, 5232.529072383485, 5285.577153827704, 5297.549708274782, 5302.522772950434, 5335.759890804904, 5307.071262529042, 5314.99015187117, 5337.70642614656, 5346.0, 5358.437364076754, 5106.770195341219, 5154.433012368637, 5161.5354618084175, 5230.607704422184, 5240.8689782755355, 5519.668819870821, 5545.251391062196, 5588.245586845765, 5592.86017849557, 5621.4659607337235, 6263.71813268404, 6278.995360742305, 6281.214848143982, 6293.779717709858, 6304.936950630481, 5318.308619879391, 5743.712814359265, 5813.302403711582, 6287.748490060392, 6306.085617585424, 5770.509506691937, 5792.855178620526, 5869.6771677558145, 5891.823245302648, 5899.646021238739, 6195.2255968004, 6205.782797602153, 6235.314115447297, 6248.4064013918605, 6261.029540421242, 6153.138560601519, 6159.630422174684, 6172.105044768573, 6174.38256174379, 6178.0, 6047.395260474005, 6169.352218017133, 6169.722362493609, 6178.505719542516, 6184.165137706352, 6116.974953844038, 6142.652254521207, 6249.34381889895, 6255.56211065221, 6264.154339164154, 6195.566310358322, 6263.123162757218, 6271.122042913996, 6271.247450305901, 6274.498040156776, 4905.607551395879, 4930.605551555867, 4931.284963680247, 4949.90100197994, 4971.502849715, 4371.956280437218, 4394.033312671208, 4457.197704413172, 4486.3494793254795, 4509.616682582017, 5714.485696287354, 5717.742701578439, 5718.1708652246025, 5722.055860783007, 5723.141528770634, 6214.40963108504, 6255.030470277115, 6271.62370257786, 6280.905786413207, 6284.937150628435, 5599.720013999284, 5643.746031428595, 5674.77300907959, 5755.395683453261, 5759.308882934075, 5327.200919862052, 5347.652402593819, 5360.678359298455, 5370.140777475585, 5370.543503802221, 6315.96842015787, 6321.810345689545, 6327.841803954938, 6336.271328797063, 6342.270638876588, 6143.662098584442, 6160.969195153995, 6193.040078787795, 6209.627422354673, 6224.470919971853, 6223.3465486123305, 6237.625742455467, 6284.025975973731, 6287.18266625354, 6310.242770867525, 6167.691615419121, 6187.288461826767, 6219.284782249918, 6222.128901953821, 6222.751089956351, 6211.627302361872, 6289.7484100635465, 6291.433770960635, 6307.24313082433, 6309.716062777278, 5984.162217289496, 6054.878902421839, 6077.179580756541, 6125.907024958145, 6131.866048757656, 6112.74665817949, 6126.620139581256, 6140.017597184429, 6149.323574406902, 6151.046587778901, 5959.195508606283, 6004.0, 6007.948608993496, 6014.48876845473, 6021.488173505301, 5732.16883551891, 5983.970080149571, 6070.575059745952, 6131.927227014103, 6144.648177400964, 5982.551308651781, 5987.730552125252, 5997.940020599738, 6008.128821320972, 6037.305709843425], "value": 5712.0144841515175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.24613833111994, 516.7236924795756, 518.4478530365161, 532.936047674279, 582.6707910030835, 30393.544096838537, 37550.87347379579, 38486.422703659395, 38650.03374915628, 38694.80652596733, 41491.17017659645, 41682.791586042025, 41877.79061104698, 41954.0, 41980.79009604955, 8541.701040463586, 9502.572384242709, 9669.854952175723, 9682.806343873119, 9695.612175512972, 8607.18231767981, 11354.20520563561, 11524.366159861203, 11558.595449159282, 11576.421178941062, 9621.615135394612, 11469.713257168554, 11479.368634725086, 11485.942570287138, 11583.247088939192, 8668.52323122228, 9844.0, 11201.495932683049, 11285.266457680265, 11673.649790506213, 11077.279976801483, 11197.0, 11202.103831693445, 11209.215354925158, 11230.045446137088, 37075.258494830225, 37387.626123738686, 37500.87497375083, 37592.0, 37593.0, 3186.203449137716, 3199.2001999500126, 3318.784279021856, 3403.914902127442, 3507.018034950218, 25538.97844086234, 26981.865090674422, 27987.580186297222, 28145.577816332567, 28688.0, 2749.3951330707414, 2758.531049721532, 2797.608334833125, 2798.3423895384567, 2834.957475637867, 371286.0, 371588.0, 371638.0, 371865.12539498386, 372240.0, 60781.39218607846, 60842.08736868862, 60896.69551652302, 60931.0, 61138.388616114156, 67560.66219668968, 69136.6543167291, 69214.65392673004, 69255.30744692589, 69308.6534567324, 17038.48884533466, 17392.043437611053, 17404.738928916075, 17416.825831741775, 17425.215865285838, 12302.200356976768, 12507.874921250854, 13212.405441755143, 13359.666008349868, 13502.257375844423, 18895.62208755817, 18952.90523547401, 18963.620727585647, 18973.0, 19021.619567608846, 7759.107702614158, 7853.8821917671285, 7934.76195714118, 7942.014496375906, 8010.879836802453, 933.8933363963589, 963.4700914496999, 1005.4872015272264, 1088.7604726960055, 1201.963941081769, 27401.725982740318, 28969.275768105967, 29237.117301863083, 29262.414751705273, 29300.0, 1089.4008295437477, 1119.4738472917716, 1158.9478473468714, 1181.4388165621433, 1274.6558429224058, 14868.661820435997, 14998.0, 15559.988600741142, 15977.760333595006, 16247.756283655755, 548503.0, 641505.0, 649348.0, 652482.0, 652851.0, 60874.0, 61062.4734381644, 61221.77556448846, 61244.0, 61430.38569614336, 1345.0584590786489, 1467.9779803302958, 1504.7818066380314, 1514.7424937760386, 1521.6424140327015, 28286.575701364083, 28502.71737771847, 28904.132876013755, 29315.560266596443, 29332.266693331985, 47540.04919901652, 48134.03731925412, 48230.75884620554, 48307.5169248303, 48369.51630483791, 364481.0, 364510.0, 365103.0, 365537.0, 365740.51389229717, 25008.0, 26436.471270574864, 26460.88312934999, 26461.280016798846, 26464.6181843635, 43640.345394819735, 44237.11525769531, 44298.778506107265, 45914.08171836611, 45937.31094033659, 31120.53319200259, 33988.83005584956, 36168.457473137394, 37377.0, 37549.24901502009, 36524.45213321749, 36722.63277367299, 36967.0, 37123.44314835226, 37308.81345593255, 36499.905002849955, 36646.63353366539, 36999.0, 37291.06772330713, 37722.62277377301, 31645.05064848058, 31964.84017579897, 32071.839640801645, 32327.51508727324, 32525.349493009533, 24290.271291861274, 24308.149214777397, 24322.0, 24806.37984050413, 24831.751682482944], "value": 19676.268569147327, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.8866118511484, 399.2454261445867, 405.8295515883329, 406.0186290900406, 416.7666106980091, 18206.726899096524, 19685.803141968576, 20326.796732032675, 20355.592888142248, 20395.694064589006, 19679.901600492012, 19864.70202946957, 20136.49658758532, 21042.053107610154, 21115.894420527875, 5919.37846526115, 6009.609375390607, 6064.666443345614, 6098.420650038262, 6123.234595675541, 5850.561207909403, 6026.728797204137, 6030.457258846703, 6064.787732429366, 6071.301800292978, 5750.453706897838, 6023.909641355383, 6028.4272994065495, 6029.276486821587, 6083.634981901078, 5046.924296135561, 6019.5485338599565, 6047.606905551125, 6053.75784968603, 6084.178635884187, 5323.813666521692, 5330.626856120073, 5336.91994620081, 5511.0, 5829.387914268983, 13327.733445331136, 13512.729745405037, 13571.592852214351, 13590.048696591242, 17430.912845435818, 2147.5597502512105, 2196.066671664536, 2222.822174226058, 2239.6416573348347, 2249.156566287642, 9461.52692365379, 9620.278479114095, 9702.126808587262, 9805.411675299505, 10136.797264054678, 1711.247051297425, 1724.6033412315237, 1725.663495618355, 1728.9221985010704, 1770.4954088084835, 103570.48214758953, 110291.44854275677, 117330.41334793357, 119388.20917686242, 119581.99999999913, 10315.535800888978, 10900.890991090146, 11658.591949281734, 11667.35829529367, 11794.646160615195, 14919.328630211667, 14970.401183952737, 14976.026558273677, 15008.549743507712, 15018.0, 8451.3519863626, 8730.607122679494, 8744.213020828156, 8781.297496200288, 8867.246284065797, 8789.077146899612, 8805.339599530062, 8813.118688131135, 8864.689735859343, 8902.465852048897, 4221.598948099926, 4253.680973926906, 4615.14619795339, 4656.089943785134, 4659.324397962277, 1784.4111443223705, 1900.8879805314045, 1918.3477617610079, 1995.7747111970339, 2022.1304838919407, 758.971593490823, 934.1405906565975, 985.8077674853334, 1027.7276521721749, 1029.613894789454, 15960.680786384208, 21262.060286450505, 21263.255786047415, 21269.0, 21425.78574214238, 857.4512312120248, 984.2913102566141, 997.5411310797115, 1003.1373019203479, 1057.3127467146417, 8959.865602015974, 10587.364758114538, 11066.778664426667, 11107.222494425312, 11141.275817071864, 514843.0, 592027.039864798, 603748.0, 630070.8496457489, 630503.0, 74810.25189748142, 97900.0, 125531.3723431377, 131084.0, 134967.0, 1021.4943602916455, 1022.1362948308667, 1043.4313299251926, 1045.3675526306474, 1046.6859942017213, 18311.267549298413, 18531.90734046375, 18755.531111722317, 18832.52918677044, 18912.52718681989, 47038.294425584325, 50426.0, 51553.0, 52545.21182182347, 56563.434365657464, 52220.95558088893, 62810.37189628045, 71417.28582714107, 72385.0, 75864.62067689626, 7574.848503030019, 8145.348372130097, 8281.503109813431, 8308.335333173452, 8337.430045535586, 11098.389588572705, 12665.81001284963, 12834.871651283367, 12909.741805164032, 12916.547920822726, 9932.652357167464, 10683.839742404025, 11011.934510125431, 11067.0, 11131.276467029616, 10820.783584328427, 10823.729406764895, 10853.620123295646, 10921.672349829518, 11055.447227638426, 9902.108810207115, 10613.9469302653, 11338.0, 11434.599789007345, 11451.312921224753, 7981.680732770624, 8036.4776289541, 8290.212429819157, 8302.460340077876, 8345.874811877706, 8102.878456823035, 11084.667459976214, 11140.0, 11198.44007799623, 11212.607558735404], "value": 9309.431264046658, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.431029743252, 1549.442200807709, 1572.7247731646967, 1795.4523870219587, 1929.0740444586597, 7942.682292708285, 8524.531150786705, 8654.567271636424, 8677.002144753355, 8684.565771711405, 8450.746477605666, 8713.869291960626, 8721.258693011087, 8728.214460698537, 8740.082291359411, 2458.0536493450063, 2484.043643197373, 2486.042873493709, 2486.614574740918, 2490.0, 2424.442378253003, 2480.119557557068, 2480.9751902481016, 2487.875606219691, 2507.7993760499207, 2448.0942051440993, 2480.355107672, 2486.577281862079, 2498.375406148463, 2499.4126380300613, 2443.0960544598433, 2485.7887079598254, 2491.9875400623064, 2498.812589055819, 2498.9875050624814, 2063.9690404643943, 2426.9878650606634, 2473.727889932106, 2475.3687809608614, 2497.7127630322384, 8422.957885210595, 8807.823843523158, 8870.512121833297, 8875.467471951639, 8883.422577532505, 5398.91901621476, 5563.610547261694, 5570.526505247059, 5592.189132575753, 5616.971915140439, 3590.730695197871, 3659.79871107089, 3705.314516814371, 3715.5727091384565, 3719.7396182267253, 1041.463646222196, 1052.8578641883325, 1059.2373491086405, 1064.7391389109641, 1088.5808963549011, 11919.463624136935, 12053.879461205363, 12067.094967877445, 12078.999999999913, 12098.395080245953, 3819.8090095495113, 4586.403767510203, 4727.456342520654, 4759.571638552547, 4760.166970780169, 9388.530573471437, 9403.247740180768, 9414.482203478738, 9418.246540276761, 9429.905700942903, 5406.91889621656, 5417.052015897281, 5420.701861397582, 5432.782688692487, 5437.619366644297, 4261.488621365394, 4370.38814565961, 4376.956230437719, 4378.168148051924, 4977.328060711758, 3602.8378722957004, 3834.731568790158, 3908.550516690617, 3908.921821563553, 3918.1967696622123, 1174.8825117488354, 1212.8362671039474, 1215.0, 1215.3194211241732, 1230.1204638683384, 844.1642773654161, 863.710656929923, 892.7144911327667, 933.6638810028385, 948.8908775490769, 5766.192733017381, 5803.303603567599, 5885.264341957255, 6024.668643224665, 6032.577719559677, 956.0821611253136, 977.0913050862624, 991.1228562722043, 998.7902540466583, 1007.924405669563, 3866.0141663876093, 3949.2299001694396, 3976.8608098716404, 3984.382420724792, 4031.657309128698, 10409.219308551888, 10676.145908327315, 10874.0, 10921.836172457419, 10952.0, 10630.30902991303, 10719.19606029551, 10723.356598604109, 10747.89252107469, 10755.300905441121, 8282.544460054754, 8287.715404112372, 8295.62669679866, 8301.418900677016, 8377.41358104939, 5506.724663766876, 5587.413321601336, 5640.605157638925, 5806.883862322646, 5838.978178818775, 9262.259019278306, 9456.007119252654, 9639.036096390586, 9655.22758179358, 9667.419954802734, 9374.390664606779, 9849.113579777855, 9876.308658394131, 9879.20966322678, 9979.850302245617, 7070.964645176741, 7080.681369338289, 7133.072700548895, 7147.571145731273, 7182.96408517954, 7142.250063743232, 7343.963280183565, 8070.959645201737, 8289.751307460785, 8321.916780832356, 7375.37309328713, 7382.114146302478, 7394.519356241827, 7486.326230639269, 7516.098068231847, 7252.202257751537, 7252.419806415369, 7340.559566426032, 7366.889496657447, 7406.444516661164, 6844.076049733222, 6967.0, 6975.0, 6979.616121113388, 6985.44116470671, 4828.7102773833685, 4904.043711476371, 4941.061198372297, 5012.172991456515, 5056.255591820816, 4973.975130124326, 4992.850214493571, 5020.673656212334, 5069.822556210515, 5210.947890521046], "value": 4591.015486640556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.76203490434534, 330.3607519449865, 332.6208122740076, 339.623018449521, 342.8679958216086, 6512.283648798634, 6521.445677117445, 6521.8369540761505, 6585.736570537185, 6593.208814942202, 5244.292020577221, 5589.74846131924, 6222.817664643714, 6510.08858759774, 6541.672916354175, 4619.653525985556, 4730.290456431529, 4755.953690188153, 4823.638227132961, 4851.975740121295, 4780.139574876521, 4826.9034619307595, 4833.3958255218095, 4849.345338379327, 4861.1006963711825, 4769.0, 4801.5438533339275, 4806.303086052538, 4810.759462026902, 4865.391826021747, 4519.050999290153, 4673.462551806551, 4721.032188401369, 4780.832670856538, 4783.976080119577, 4109.835606575733, 4122.2579935611575, 4124.793760311973, 4131.35963925592, 4139.730917490384, 4959.0, 5771.307443106812, 6228.906566401508, 6247.593906396115, 6251.593646412968, 5692.260006199209, 5709.657420554739, 5709.885802283931, 5710.65736055838, 5719.999000174995, 6948.200956889921, 6958.0, 6962.0, 6970.30296970304, 6975.546589471668, 3824.464574959508, 4361.629261512743, 4535.024969631512, 4552.203364411214, 4579.107074120548, 7096.858062838766, 7305.52514086588, 7311.524750891228, 7323.011393461868, 7354.852902941965, 4595.1269258841385, 4605.378273932976, 4613.984923316865, 4618.907621847544, 4637.652176086807, 3879.6120387960977, 4485.058137791035, 4670.159371313196, 4683.461401938753, 4687.015726697432, 5549.613666123606, 5550.833474995676, 5552.111662134039, 5564.443555644484, 5570.220169176318, 6734.326567343226, 6923.896141557881, 6991.93008069923, 6993.510454268254, 7005.894911576331, 1603.1503303249297, 1605.675317862768, 1616.426168710091, 1629.771831943529, 1630.4293497276096, 863.1929146248278, 962.5572236771163, 978.256525040974, 1019.273903809125, 1036.3937096798306, 6381.329960354189, 6526.923057695427, 6530.967345163243, 6537.215534135934, 6557.245916719543, 7208.0, 7228.277172282729, 7250.891236631454, 7251.5286506376915, 7253.419726421874, 6557.639329836905, 6562.639054851934, 6564.015397690289, 6576.210854697371, 6580.407763301326, 3682.9815850920572, 3778.3576791944884, 3832.6550610445192, 3836.1752223271574, 3840.020794697334, 7573.015507983927, 7580.848383032308, 7581.649688596553, 7586.165521792709, 7586.962065189638, 7455.030845989987, 7484.32641062307, 7507.361874240642, 7513.774586762406, 7524.247575242542, 7268.854622907618, 7563.9621801890635, 7567.886481702779, 7577.810554736066, 7587.165411804697, 6251.437370636482, 7002.964985175042, 7169.820754481179, 7248.275172482709, 7280.162781280009, 7130.358267755927, 7152.177499587614, 7152.6781294842895, 7161.785146445615, 7171.856562868609, 7542.057242844645, 7543.622818858926, 7560.886586701094, 7564.130125035691, 7578.242175782378, 4610.815567377267, 4624.930626040679, 4629.513901040342, 4634.189016922092, 4639.0721855628335, 6964.512484126062, 7204.639768011683, 7212.098487689039, 7217.783466496014, 7228.49400541957, 5699.7435115419075, 6962.060121883481, 6979.755708550177, 6993.300669932966, 6993.790186294419, 5660.330202590329, 6884.793456196322, 6969.965150174216, 6994.860102798018, 6999.860002799814, 6814.829629259309, 6830.282820303796, 6836.555623884432, 6837.760678376233, 6838.418734407432, 6646.2356828965285, 6986.441084713312, 7001.964990175016, 7008.053912721921, 7017.087778588751, 5693.3168019837885, 6547.901781473377, 6643.2360278568585, 6712.83217919556, 6873.5875847449315], "value": 5063.677614863378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.6337422853003, 724.3046675191815, 751.8082888863339, 752.9171791102978, 779.699815571005, 13955.232462214573, 15233.0, 15267.694646107071, 15271.389144434237, 15285.847141528582, 11631.767364652702, 13698.52055178069, 13859.514916977907, 13927.651808704788, 13943.302834858267, 726.415235735233, 741.8664640364732, 743.9256074392572, 744.3077937518106, 745.2063552316793, 734.2694019450654, 912.9634814607408, 934.7569631895722, 940.3840484482674, 943.8301105800953, 975.8243516167087, 984.3995162950595, 986.5363279258772, 987.77775000625, 989.9158571521393, 759.502525845572, 973.1436336024324, 978.2760757039775, 978.706388083572, 978.8531720241949, 725.1262229014026, 736.0835759479429, 737.2406421385981, 738.7525179064966, 738.9556626602367, 14699.118052916752, 14723.263836808224, 14766.114033157937, 14788.556343309718, 14806.0, 12432.875671243262, 12446.377681115999, 12562.0, 12816.935915320457, 12993.155444896052, 10009.449480278654, 10127.13919316859, 10216.744581385525, 10321.380717156995, 10711.143108551298, 3505.4566542186003, 3508.4561892906386, 3543.0433782878736, 3551.5915669698056, 3666.9083272918124, 71152.0, 71572.0, 73320.63339683268, 80446.0, 80544.0, 9681.37071090377, 9699.272554558436, 9710.611575537041, 9735.415875047378, 9755.658551950648, 20866.26968056111, 21215.0, 22430.43923901883, 22502.774972250394, 22539.549209015728, 7545.396368290523, 7598.354139898171, 7655.157932627407, 7804.843903121906, 7932.127465978738, 3973.8211780469946, 4065.6747460203114, 4088.2232375848494, 4216.578342165821, 4338.219120558329, 3658.926821463609, 3703.2963736889906, 3703.5555733312176, 3704.4258139987805, 3706.05495598626, 643.0707627478249, 665.6638397609177, 670.4435318685479, 672.1497305908031, 674.6458109492555, 559.4964531921304, 595.238095238096, 596.2755252368394, 616.6608365399013, 622.3372108704216, 7797.532148071134, 8195.42632015765, 8233.259006689306, 8237.79405514855, 8282.917170828336, 503.34313720594974, 514.2954152810598, 619.8326451858063, 621.6394491195095, 623.2085251730277, 3805.980970095132, 3806.011065144761, 3806.333891568964, 3903.843846246118, 3950.229705207457, 88090.0, 91161.0, 91268.08731912728, 91371.0, 91474.0, 70222.64888675626, 70965.0, 72097.27902721011, 72111.63944180246, 72703.0, 65048.674756625915, 65226.347736522024, 65338.673306633165, 65662.0, 65824.67087664627, 7709.614519274126, 7811.570363629828, 7813.218678132142, 7877.7636670899965, 7900.367970562456, 44588.77705611581, 44919.0, 45663.771681141385, 46242.53757462382, 46700.59898203059, 65211.0, 66598.66802663823, 66990.0, 67272.0, 67877.30306742371, 10393.532291047073, 10840.0, 11021.063209627271, 11264.774704506028, 11756.823647645464, 11702.824457633313, 11879.112965308233, 12044.0, 12336.0, 12690.682732931751, 11019.173561983043, 11307.773844523228, 11390.0, 11717.121215909012, 11804.055675546108, 11579.826302605636, 11591.88408115908, 11638.41807909618, 11675.591354302558, 11848.111391645833, 11256.437178141225, 11314.0, 11532.481038353126, 11608.941955290169, 11884.940575297069, 9586.712398628053, 9616.326857119935, 9636.132748052709, 9640.469774162208, 9835.0, 9166.312526560669, 9168.587413566538, 9239.76900577464, 9241.306901982243, 9410.38832475887], "value": 6842.329755169068, "unit": "score"}], "name": "dromaeo_css", "value": 7416.719827942957, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 118.0, 113.0, 113.0, 111.0, 114.0, 115.0, 113.0, 134.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 141.0, 166.0, 119.0, 142.0, 139.0, 117.0, 168.0, 118.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 185.0, 185.0, 186.0, 185.0, 184.0, 181.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 74.0, 155.0, 80.0, 81.0, 88.0, 69.0, 134.0, 73.0], "value": 90.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 142.0, 77.0, 79.0, 85.0, 120.0, 80.0, 76.0, 125.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 87.0, 87.0, 88.0, 86.0, 88.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 95.0, 95.0, 135.0, 116.0, 94.0, 94.0, 94.0, 94.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 84.0, 85.0, 83.0, 84.0, 90.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 63.0, 63.0, 65.0, 64.0, 63.0, 82.0, 58.0, 58.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 69.0, 72.0, 74.0, 70.0, 69.0, 68.0, 70.0, 70.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 81.0, 81.0, 81.0, 81.0, 80.0, 79.0, 86.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 131.0, 127.0, 130.0, 145.0, 138.0, 127.0, 146.0, 176.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 150.0, 161.0, 216.0, 207.0, 161.0, 204.0, 167.0, 165.0, 166.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 71.0, 90.0, 96.0, 85.0, 68.0, 67.0], "value": 75.2, "unit": "score"}], "name": "kraken", "value": 1501.1999999999998, "alertThreshold": 2.0}]} 16:14:13 INFO - Return code: 0 16:14:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:14:13 ERROR - # TBPL SUCCESS # 16:14:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:13 INFO - 2016-06-03 16:14:13.954 screenresolution[1678:10096] starting screenresolution argv=screenresolution get 16:14:13 INFO - 2016-06-03 16:14:13.956 screenresolution[1678:10096] Display 0: 1600x1200x32@60 16:14:13 INFO - 2016-06-03 16:14:13.962 screenresolution[1679:10099] starting screenresolution argv=screenresolution list 16:14:13 INFO - Available Modes on Display 0 16:14:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:14 INFO - Intel Iris: 16:14:14 INFO - Chipset Model: Intel Iris 16:14:14 INFO - Type: GPU 16:14:14 INFO - Bus: Built-In 16:14:14 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:14 INFO - Vendor: Intel (0x8086) 16:14:14 INFO - Device ID: 0x0a2e 16:14:14 INFO - Revision ID: 0x0009 16:14:14 INFO - Displays: 16:14:14 INFO - Display: 16:14:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:14 INFO - Display Serial Number: 1600x1200 60 16:14:14 INFO - Main Display: Yes 16:14:14 INFO - Mirror: Off 16:14:14 INFO - Online: Yes 16:14:14 INFO - Rotation: Supported 16:14:14 INFO - Return code: 0 16:14:14 INFO - Running post-action listener: _resource_record_post_action 16:14:14 INFO - Running post-run listener: _resource_record_post_run 16:14:15 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32779776; Write bytes: 650510848; Read time: 330; Write time: 1771 16:14:15 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347099648; Write bytes: 345839616; Read time: 26511; Write time: 651 16:14:15 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31792640; Write bytes: 294713856; Read time: 314; Write time: 1091 16:14:15 INFO - Running post-run listener: _upload_blobber_files 16:14:15 INFO - Blob upload gear active. 16:14:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:14:15 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 16:14:15 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'] 16:14:15 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 16:14:15 INFO - (blobuploader) - INFO - Open directory for files ... 16:14:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:14:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:14:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:16 INFO - (blobuploader) - INFO - Done attempting. 16:14:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:14:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:14:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:17 INFO - (blobuploader) - INFO - Done attempting. 16:14:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:14:17 INFO - Return code: 0 16:14:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:14:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:14:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84d1475c9efe09c7cb69c28aab041b27c95f015365dffda9db920b9615b69e2fb5b9b4d884c22e0bc8a550330144a7335fb630c95ce365fcfbb5e9540a29d318", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1911b83a4973563aa7cb4af77c47600f63157917e893e2755447c2f0a9b7c247371ec3f5f5cd2deecf655a21c46ebdc802e4d1d6b6a05af528d3d7509469b758"} 16:14:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:14:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:14:17 INFO - Contents: 16:14:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84d1475c9efe09c7cb69c28aab041b27c95f015365dffda9db920b9615b69e2fb5b9b4d884c22e0bc8a550330144a7335fb630c95ce365fcfbb5e9540a29d318", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1911b83a4973563aa7cb4af77c47600f63157917e893e2755447c2f0a9b7c247371ec3f5f5cd2deecf655a21c46ebdc802e4d1d6b6a05af528d3d7509469b758"} 16:14:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:14:17 INFO - Copying logs to upload dir... 16:14:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:14:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1330.202863 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-06-03 16:14:17.861486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 16:14:17.864991) ========= 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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/84d1475c9efe09c7cb69c28aab041b27c95f015365dffda9db920b9615b69e2fb5b9b4d884c22e0bc8a550330144a7335fb630c95ce365fcfbb5e9540a29d318", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1911b83a4973563aa7cb4af77c47600f63157917e893e2755447c2f0a9b7c247371ec3f5f5cd2deecf655a21c46ebdc802e4d1d6b6a05af528d3d7509469b758"} build_url:https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/Z115wfHsSH6K7VmnCGOdvA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84d1475c9efe09c7cb69c28aab041b27c95f015365dffda9db920b9615b69e2fb5b9b4d884c22e0bc8a550330144a7335fb630c95ce365fcfbb5e9540a29d318", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1911b83a4973563aa7cb4af77c47600f63157917e893e2755447c2f0a9b7c247371ec3f5f5cd2deecf655a21c46ebdc802e4d1d6b6a05af528d3d7509469b758"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 16:14:17.896334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 16:14:17.896654) ========= 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.uD2qVxXoJV/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.pg2JWcP7FG/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005116 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 16:14:17.945292) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 16:14:17.945619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 16:14:17.945963) ========= ========= Total master_lag: 0.16 =========