builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1467598443.5 results: success (0) buildid: 20160703171925 builduid: 9272a0a913a44a7cb598a1f7947d2d6f revision: dbcb78d8ca7469fa8e303eaeeecb17b51757df01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:03.499557) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:03.507056) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:03.507389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:03.618048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:03.618436) ========= 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.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-07-03 19:14:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.3M=0.001s 2016-07-03 19:14:04 (11.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.504664 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:04.146651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:04.146972) ========= 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.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.026523 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:04.198491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 19:14:04.198877) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dbcb78d8ca7469fa8e303eaeeecb17b51757df01 --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-beta --rev dbcb78d8ca7469fa8e303eaeeecb17b51757df01 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-07-03 19:14:04,281 truncating revision to first 12 chars 2016-07-03 19:14:04,281 Setting DEBUG logging. 2016-07-03 19:14:04,281 attempt 1/10 2016-07-03 19:14:04,281 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/dbcb78d8ca74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-03 19:14:05,061 unpacking tar archive at: mozilla-beta-dbcb78d8ca74/testing/mozharness/ program finished with exit code 0 elapsedTime=1.044480 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 19:14:05.271241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:05.271762) ========= script_repo_revision: dbcb78d8ca7469fa8e303eaeeecb17b51757df01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:05.272432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:05.272920) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 19:14:05.293382) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-03 19:14:05.293850) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:05 INFO - MultiFileLogger online at 20160703 19:14:05 in /builds/slave/test 19:14:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:14:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:05 INFO - {'append_to_log': False, 19:14:05 INFO - 'base_work_dir': '/builds/slave/test', 19:14:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:14:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:05 INFO - 'branch': 'Mozilla-Beta', 19:14:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:05 INFO - 'config_files': ('talos/mac_config.py',), 19:14:05 INFO - 'default_actions': ('clobber', 19:14:05 INFO - 'read-buildbot-config', 19:14:05 INFO - 'download-and-extract', 19:14:05 INFO - 'populate-webroot', 19:14:05 INFO - 'create-virtualenv', 19:14:05 INFO - 'install', 19:14:05 INFO - 'run-tests'), 19:14:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:05 INFO - 'download_minidump_stackwalk': True, 19:14:05 INFO - 'download_symbols': 'ondemand', 19:14:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:05 INFO - 'installer_path': 'installer.exe', 19:14:05 INFO - 'log_level': 'info', 19:14:05 INFO - 'log_name': 'talos', 19:14:05 INFO - 'log_to_console': True, 19:14:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:14:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:14:05 INFO - 'opt_config_files': (), 19:14:05 INFO - 'pip_index': False, 19:14:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:05 INFO - 'cmd': ('bash', 19:14:05 INFO - '-c', 19:14:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:05 INFO - 'enabled': True, 19:14:05 INFO - 'halt_on_failure': False, 19:14:05 INFO - 'name': 'check_screen_resolution'},), 19:14:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:05 INFO - 'cmd': ('bash', 19:14:05 INFO - '-c', 19:14:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:05 INFO - 'enabled': True, 19:14:05 INFO - 'halt_on_failure': False, 19:14:05 INFO - 'name': 'check_screen_resolution'},), 19:14:05 INFO - 'run_cmd_checks_enabled': True, 19:14:05 INFO - 'sps_profile': False, 19:14:05 INFO - 'sps_profile_interval': 0, 19:14:05 INFO - 'suite': 'dromaeojs', 19:14:05 INFO - 'system_bits': '32', 19:14:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:05 INFO - 'title': 't-yosemite-r7-0286', 19:14:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:05 INFO - 'use_talos_json': True, 19:14:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:05 INFO - 'work_dir': 'build'} 19:14:05 INFO - ##### 19:14:05 INFO - ##### Running clobber step. 19:14:05 INFO - ##### 19:14:05 INFO - Running pre-action listener: _resource_record_pre_action 19:14:05 INFO - Running main action method: clobber 19:14:05 INFO - rmtree: /builds/slave/test/build 19:14:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:14:07 INFO - Running post-action listener: _resource_record_post_action 19:14:07 INFO - ##### 19:14:07 INFO - ##### Running read-buildbot-config step. 19:14:07 INFO - ##### 19:14:07 INFO - Running pre-action listener: _resource_record_pre_action 19:14:07 INFO - Running main action method: read_buildbot_config 19:14:07 INFO - Using buildbot properties: 19:14:07 INFO - { 19:14:07 INFO - "project": "", 19:14:07 INFO - "product": "firefox", 19:14:07 INFO - "script_repo_revision": "production", 19:14:07 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 19:14:07 INFO - "repository": "", 19:14:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 19:14:07 INFO - "buildid": "20160703171925", 19:14:07 INFO - "pgo_build": "False", 19:14:07 INFO - "basedir": "/builds/slave/test", 19:14:07 INFO - "buildnumber": 17, 19:14:07 INFO - "slavename": "t-yosemite-r7-0286", 19:14:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:14:07 INFO - "platform": "yosemite_r7", 19:14:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:14:07 INFO - "branch": "mozilla-beta", 19:14:07 INFO - "repo_path": "releases/mozilla-beta", 19:14:07 INFO - "revision": "dbcb78d8ca7469fa8e303eaeeecb17b51757df01", 19:14:07 INFO - "stage_platform": "macosx64", 19:14:07 INFO - "builduid": "9272a0a913a44a7cb598a1f7947d2d6f", 19:14:07 INFO - "slavebuilddir": "test" 19:14:07 INFO - } 19:14:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 19:14:07 INFO - Running post-action listener: _resource_record_post_action 19:14:07 INFO - ##### 19:14:07 INFO - ##### Running download-and-extract step. 19:14:07 INFO - ##### 19:14:07 INFO - Running pre-action listener: _resource_record_pre_action 19:14:07 INFO - Running main action method: download_and_extract 19:14:07 INFO - mkdir: /builds/slave/test/build/tests 19:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:07 INFO - https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:14:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 19:14:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 19:14:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 19:14:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 19:14:16 INFO - Downloaded 1165 bytes. 19:14:16 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 19:14:16 INFO - Using the following test package requirements: 19:14:16 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 19:14:16 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 19:14:16 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 19:14:16 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:14:16 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 19:14:16 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 19:14:16 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 19:14:16 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 19:14:16 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 19:14:16 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 19:14:16 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 19:14:16 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 19:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:16 INFO - https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:14:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 19:14:19 INFO - Downloaded 18351370 bytes. 19:14:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:14:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:14:22 INFO - Return code: 0 19:14:22 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:14:22 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')]} 19:14:22 INFO - https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:14:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 19:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 19:14:23 INFO - Downloaded 18351370 bytes. 19:14:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:14:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:14:27 INFO - Return code: 0 19:14:27 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')]} 19:14:27 INFO - https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:14:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 19:14:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 19:14:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 19:14:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 19:14:28 INFO - Downloaded 10840145 bytes. 19:14:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:14:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:14:28 INFO - Return code: 0 19:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:28 INFO - https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 19:14:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 19:14:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 19:14:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 19:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 19:14:31 INFO - Downloaded 86194133 bytes. 19:14:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 19:14:31 INFO - mkdir: /builds/slave/test/properties 19:14:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:14:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:14:31 INFO - Contents: 19:14:31 INFO - build_url:https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 19:14:32 INFO - Running post-action listener: _resource_record_post_action 19:14:32 INFO - Running post-action listener: set_extra_try_arguments 19:14:32 INFO - ##### 19:14:32 INFO - ##### Running populate-webroot step. 19:14:32 INFO - ##### 19:14:32 INFO - Running pre-action listener: _resource_record_pre_action 19:14:32 INFO - Running main action method: populate_webroot 19:14:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:14:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:14:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:14:32 INFO - '--tppagecycles', 19:14:32 INFO - '7'], 19:14:32 INFO - 'tests': ['tsvgm']}}, 19:14:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:14:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:14:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:14:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:14:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['damp', 'tps']}, 19:14:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'tests': ['damp', 'tps']}, 19:14:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['dromaeo_dom']}, 19:14:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:14:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['a11yr', 19:14:32 INFO - 'ts_paint', 19:14:32 INFO - 'tpaint', 19:14:32 INFO - 'sessionrestore', 19:14:32 INFO - 'sessionrestore_no_auto_restore', 19:14:32 INFO - 'tabpaint']}, 19:14:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['tsvgx', 19:14:32 INFO - 'tsvgr_opacity', 19:14:32 INFO - 'tart', 19:14:32 INFO - 'tscrollx', 19:14:32 INFO - 'cart']}, 19:14:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:14:32 INFO - 'tsvgr_opacity', 19:14:32 INFO - 'tart', 19:14:32 INFO - 'tscrollx', 19:14:32 INFO - 'cart']}, 19:14:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'talos_options': ['--disable-e10s'], 19:14:32 INFO - 'tests': ['tp5o']}, 19:14:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'tests': ['tp5o']}, 19:14:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'talos_options': ['--disable-e10s', 19:14:32 INFO - '--xperf_path', 19:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:32 INFO - 'tests': ['tp5n']}, 19:14:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:32 INFO - 'talos_options': ['--xperf_path', 19:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:32 INFO - 'tests': ['tp5n']}}, 19:14:32 INFO - 'talos.zip': {'path': '', 19:14:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:14:32 INFO - Running post-action listener: _resource_record_post_action 19:14:32 INFO - ##### 19:14:32 INFO - ##### Running create-virtualenv step. 19:14:32 INFO - ##### 19:14:32 INFO - Running pre-action listener: _resource_record_pre_action 19:14:32 INFO - Running main action method: create_virtualenv 19:14:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:14:32 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 19:14:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:14:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:14:32 INFO - Using real prefix '/tools/python27' 19:14:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:14:33 INFO - Installing distribute.............................................................................................................................................................................................done. 19:14:36 INFO - Installing pip.................done. 19:14:36 INFO - Return code: 0 19:14:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:14:36 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')]} 19:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:36 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')]} 19:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:36 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:36 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 19:14:36 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 19:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:36 INFO - 'HOME': '/Users/cltbld', 19:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:36 INFO - 'LOGNAME': 'cltbld', 19:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:36 INFO - 'MOZ_NO_REMOTE': '1', 19:14:36 INFO - 'NO_EM_RESTART': '1', 19:14:36 INFO - 'PAGER': '/bin/cat', 19:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:36 INFO - 'PWD': '/builds/slave/test', 19:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:36 INFO - 'SHELL': '/bin/bash', 19:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:36 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:36 INFO - 'USER': 'cltbld', 19:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:36 INFO - 'XPC_FLAGS': '0x0', 19:14:36 INFO - 'XPC_SERVICE_NAME': '0', 19:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:36 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 19:14:36 INFO - Cleaning up... 19:14:36 INFO - Return code: 0 19:14:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:14:36 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')]} 19:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:36 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')]} 19:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:36 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:36 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 19:14:36 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 19:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:36 INFO - 'HOME': '/Users/cltbld', 19:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:36 INFO - 'LOGNAME': 'cltbld', 19:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:36 INFO - 'MOZ_NO_REMOTE': '1', 19:14:36 INFO - 'NO_EM_RESTART': '1', 19:14:36 INFO - 'PAGER': '/bin/cat', 19:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:36 INFO - 'PWD': '/builds/slave/test', 19:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:36 INFO - 'SHELL': '/bin/bash', 19:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:36 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:36 INFO - 'USER': 'cltbld', 19:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:36 INFO - 'XPC_FLAGS': '0x0', 19:14:36 INFO - 'XPC_SERVICE_NAME': '0', 19:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:36 INFO - Downloading/unpacking psutil>=0.7.1 19:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:14:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:39 INFO - Installing collected packages: psutil 19:14:39 INFO - Running setup.py install for psutil 19:14:39 INFO - building 'psutil._psutil_osx' extension 19:14: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:14:40 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 19:14:40 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 19:14:40 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 19:14:40 INFO - building 'psutil._psutil_posix' extension 19:14:40 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 19:14:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:14:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:14:40 INFO - ^ 19:14:40 INFO - 1 warning generated. 19:14:40 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 19:14:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:40 INFO - Successfully installed psutil 19:14:40 INFO - Cleaning up... 19:14:41 INFO - Return code: 0 19:14:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:41 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:41 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 19:14:41 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 19:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:41 INFO - 'HOME': '/Users/cltbld', 19:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:41 INFO - 'LOGNAME': 'cltbld', 19:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:41 INFO - 'MOZ_NO_REMOTE': '1', 19:14:41 INFO - 'NO_EM_RESTART': '1', 19:14:41 INFO - 'PAGER': '/bin/cat', 19:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:41 INFO - 'PWD': '/builds/slave/test', 19:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:41 INFO - 'SHELL': '/bin/bash', 19:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:41 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:41 INFO - 'USER': 'cltbld', 19:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:41 INFO - 'XPC_FLAGS': '0x0', 19:14:41 INFO - 'XPC_SERVICE_NAME': '0', 19:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:14:41 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 19:14:41 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 19:14:41 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 19:14:41 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 19:14:41 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 19:14:41 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 19:14:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:14:43 INFO - Installing collected packages: mozsystemmonitor 19:14:43 INFO - Running setup.py install for mozsystemmonitor 19:14:43 INFO - Successfully installed mozsystemmonitor 19:14:43 INFO - Cleaning up... 19:14:44 INFO - Return code: 0 19:14:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:14:44 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')]} 19:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:44 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')]} 19:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:44 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:44 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 19:14:44 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 19:14:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:44 INFO - 'HOME': '/Users/cltbld', 19:14:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:44 INFO - 'LOGNAME': 'cltbld', 19:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:44 INFO - 'MOZ_NO_REMOTE': '1', 19:14:44 INFO - 'NO_EM_RESTART': '1', 19:14:44 INFO - 'PAGER': '/bin/cat', 19:14:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:44 INFO - 'PWD': '/builds/slave/test', 19:14:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:44 INFO - 'SHELL': '/bin/bash', 19:14:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:44 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:44 INFO - 'USER': 'cltbld', 19:14:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:44 INFO - 'XPC_FLAGS': '0x0', 19:14:44 INFO - 'XPC_SERVICE_NAME': '0', 19:14:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:46 INFO - Downloading blobuploader-1.2.4.tar.gz 19:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:14:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:14:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:47 INFO - Downloading docopt-0.6.1.tar.gz 19:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:14:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:14:48 INFO - Running setup.py install for blobuploader 19:14:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:14:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:14:48 INFO - Running setup.py install for requests 19:14:48 INFO - Running setup.py install for docopt 19:14:48 INFO - Successfully installed blobuploader requests docopt 19:14:48 INFO - Cleaning up... 19:14:48 INFO - Return code: 0 19:14:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:48 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:48 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 19:14:48 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 19:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:48 INFO - 'HOME': '/Users/cltbld', 19:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:48 INFO - 'LOGNAME': 'cltbld', 19:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:48 INFO - 'MOZ_NO_REMOTE': '1', 19:14:48 INFO - 'NO_EM_RESTART': '1', 19:14:48 INFO - 'PAGER': '/bin/cat', 19:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:48 INFO - 'PWD': '/builds/slave/test', 19:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:48 INFO - 'SHELL': '/bin/bash', 19:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:48 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:48 INFO - 'USER': 'cltbld', 19:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:48 INFO - 'XPC_FLAGS': '0x0', 19:14:48 INFO - 'XPC_SERVICE_NAME': '0', 19:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-V2KZ9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-_8hJQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-GF07hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-CgmeyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4fD18t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-yhPmV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ac4uOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-JnOgjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-fBy3kM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-UbL6Yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-xxbaoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-X7Ygy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-TF6Q81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-gSSMJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ArN9us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-EOjJU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-51ih5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:14:50 INFO - Running setup.py install for manifestparser 19:14:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:14:51 INFO - Running setup.py install for mozcrash 19:14:51 INFO - Running setup.py install for mozdebug 19:14:51 INFO - Running setup.py install for mozdevice 19:14:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:14:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:14:51 INFO - Running setup.py install for mozfile 19:14:51 INFO - Running setup.py install for mozhttpd 19:14:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:14:51 INFO - Running setup.py install for mozinfo 19:14:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:14:51 INFO - Running setup.py install for mozInstall 19:14:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Running setup.py install for mozleak 19:14:52 INFO - Running setup.py install for mozlog 19:14:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Running setup.py install for moznetwork 19:14:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Running setup.py install for mozprocess 19:14:52 INFO - Running setup.py install for mozprofile 19:14:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:14:52 INFO - Running setup.py install for mozrunner 19:14:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:14:53 INFO - Running setup.py install for mozscreenshot 19:14:53 INFO - Running setup.py install for moztest 19:14:53 INFO - Running setup.py install for mozversion 19:14:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:14:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:14:53 INFO - Cleaning up... 19:14:53 INFO - Return code: 0 19:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:53 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')]} 19:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:53 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')]} 19:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:53 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:53 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 19:14:53 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 19:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:53 INFO - 'HOME': '/Users/cltbld', 19:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:53 INFO - 'LOGNAME': 'cltbld', 19:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:53 INFO - 'MOZ_NO_REMOTE': '1', 19:14:53 INFO - 'NO_EM_RESTART': '1', 19:14:53 INFO - 'PAGER': '/bin/cat', 19:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:53 INFO - 'PWD': '/builds/slave/test', 19:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:53 INFO - 'SHELL': '/bin/bash', 19:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:53 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:53 INFO - 'USER': 'cltbld', 19:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:53 INFO - 'XPC_FLAGS': '0x0', 19:14:53 INFO - 'XPC_SERVICE_NAME': '0', 19:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Poofhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:53 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)) 19:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-KcMk1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4cWhCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-wyeay3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-e26aQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eY4WXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-6br3L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FlLbCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FzWaOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-iYAPFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-VZileJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:54 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)) 19:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-CMxQ6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:55 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)) 19:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Ng9RI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:55 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)) 19:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-bWwHXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:55 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)) 19:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-nF6JkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:55 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)) 19:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ArUG0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:55 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)) 19:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-gmdiNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:55 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)) 19:14:55 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)) 19:14:55 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)) 19:14:55 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)) 19:14:55 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)) 19:14:55 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)) 19:14:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:14:55 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 19:14:55 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 19:14:55 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 19:14:55 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 19:14:55 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 19:14:55 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 19:14:57 INFO - Downloading blessings-1.6.tar.gz 19:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:14:58 INFO - Installing collected packages: blessings 19:14:58 INFO - Running setup.py install for blessings 19:14:58 INFO - Successfully installed blessings 19:14:58 INFO - Cleaning up... 19:14:58 INFO - Return code: 0 19:14:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:58 INFO - Reading from file tmpfile_stdout 19:14:58 INFO - Current package versions: 19:14:58 INFO - blessings == 1.6 19:14:58 INFO - blobuploader == 1.2.4 19:14:58 INFO - docopt == 0.6.1 19:14:58 INFO - manifestparser == 1.1 19:14:58 INFO - mozInstall == 1.12 19:14:58 INFO - mozcrash == 0.17 19:14:58 INFO - mozdebug == 0.1 19:14:58 INFO - mozdevice == 0.48 19:14:58 INFO - mozfile == 1.2 19:14:58 INFO - mozhttpd == 0.7 19:14:58 INFO - mozinfo == 0.9 19:14:58 INFO - mozleak == 0.1 19:14:58 INFO - mozlog == 3.1 19:14:58 INFO - moznetwork == 0.27 19:14:58 INFO - mozprocess == 0.22 19:14:58 INFO - mozprofile == 0.28 19:14:58 INFO - mozrunner == 6.11 19:14:58 INFO - mozscreenshot == 0.1 19:14:58 INFO - mozsystemmonitor == 0.0 19:14:58 INFO - moztest == 0.7 19:14:58 INFO - mozversion == 1.4 19:14:58 INFO - psutil == 3.1.1 19:14:58 INFO - requests == 1.2.3 19:14:58 INFO - wsgiref == 0.1.2 19:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:58 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')]} 19:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:58 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')]} 19:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:58 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:58 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 19:14:58 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 19:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:58 INFO - 'HOME': '/Users/cltbld', 19:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:58 INFO - 'LOGNAME': 'cltbld', 19:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:58 INFO - 'MOZ_NO_REMOTE': '1', 19:14:58 INFO - 'NO_EM_RESTART': '1', 19:14:58 INFO - 'PAGER': '/bin/cat', 19:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:58 INFO - 'PWD': '/builds/slave/test', 19:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:58 INFO - 'SHELL': '/bin/bash', 19:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:58 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:58 INFO - 'USER': 'cltbld', 19:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:58 INFO - 'XPC_FLAGS': '0x0', 19:14:58 INFO - 'XPC_SERVICE_NAME': '0', 19:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 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)) 19:14:58 INFO - Cleaning up... 19:14:58 INFO - Return code: 0 19:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:14:58 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')]} 19:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:58 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')]} 19:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:58 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:14:58 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 19:14:58 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 19:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:58 INFO - 'HOME': '/Users/cltbld', 19:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:58 INFO - 'LOGNAME': 'cltbld', 19:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:58 INFO - 'MOZ_NO_REMOTE': '1', 19:14:58 INFO - 'NO_EM_RESTART': '1', 19:14:58 INFO - 'PAGER': '/bin/cat', 19:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:58 INFO - 'PWD': '/builds/slave/test', 19:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:58 INFO - 'SHELL': '/bin/bash', 19:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:14:58 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:58 INFO - 'USER': 'cltbld', 19:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:58 INFO - 'XPC_FLAGS': '0x0', 19:14:58 INFO - 'XPC_SERVICE_NAME': '0', 19:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:59 INFO - Downloading/unpacking jsonschema 19:14:59 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 19:14:59 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 19:14:59 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 19:14:59 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 19:14:59 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 19:14:59 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 19:15:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:01 INFO - Downloading/unpacking functools32 (from jsonschema) 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15: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 19:15:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:02 INFO - warning: no files found matching '*.txt' 19:15:02 INFO - no previously-included directories found matching 'build' 19:15:02 INFO - no previously-included directories found matching 'dist' 19:15:02 INFO - no previously-included directories found matching '.git*' 19:15:02 INFO - Installing collected packages: jsonschema, functools32 19:15:02 INFO - Running setup.py install for functools32 19:15:02 INFO - warning: no files found matching '*.txt' 19:15:02 INFO - no previously-included directories found matching 'build' 19:15:02 INFO - no previously-included directories found matching 'dist' 19:15:02 INFO - no previously-included directories found matching '.git*' 19:15:02 INFO - Successfully installed jsonschema functools32 19:15:02 INFO - Cleaning up... 19:15:02 INFO - Return code: 0 19:15:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:15:02 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')]} 19:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:02 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')]} 19:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:02 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 0x10bf8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf9e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82ba55cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, '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.wPaP9cBzGE/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.ippjlMHVch/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 19:15:02 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 19:15:02 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 19:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:02 INFO - 'HOME': '/Users/cltbld', 19:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:02 INFO - 'LOGNAME': 'cltbld', 19:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:02 INFO - 'MOZ_NO_REMOTE': '1', 19:15:02 INFO - 'NO_EM_RESTART': '1', 19:15:02 INFO - 'PAGER': '/bin/cat', 19:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:02 INFO - 'PWD': '/builds/slave/test', 19:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:02 INFO - 'SHELL': '/bin/bash', 19:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:15:02 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:02 INFO - 'USER': 'cltbld', 19:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:02 INFO - 'XPC_FLAGS': '0x0', 19:15:02 INFO - 'XPC_SERVICE_NAME': '0', 19:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:02 INFO - Downloading/unpacking flake8 19:15:02 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 19:15:02 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 19:15:02 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 19:15:02 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 19:15:02 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 19:15:02 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 19:15:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:15:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:15:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:05 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 19:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:15:06 INFO - no previously-included directories found matching 'docs/_build' 19:15:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:15:06 INFO - Running setup.py install for pep8 19:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:15:06 INFO - no previously-included directories found matching 'docs/_build' 19:15:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:15:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:15:06 INFO - Cleaning up... 19:15:06 INFO - Return code: 0 19:15:06 INFO - Running post-action listener: _resource_record_post_action 19:15:06 INFO - Running post-action listener: _start_resource_monitoring 19:15:06 INFO - Starting resource monitoring. 19:15:06 INFO - ##### 19:15:06 INFO - ##### Running install step. 19:15:06 INFO - ##### 19:15:06 INFO - Running pre-action listener: _resource_record_pre_action 19:15:06 INFO - Running main action method: install 19:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:06 INFO - Reading from file tmpfile_stdout 19:15:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:07 INFO - Reading from file tmpfile_stdout 19:15:07 INFO - Output received: 19:15:07 INFO - Usage: mozinstall [options] installer 19:15:07 INFO - Options: 19:15:07 INFO - -h, --help show this help message and exit 19:15:07 INFO - -d DEST, --destination=DEST 19:15:07 INFO - Directory to install application into. [default: 19:15:07 INFO - "/builds/slave/test"] 19:15:07 INFO - --app=APP Application being installed. [default: firefox] 19:15:07 INFO - mkdir: /builds/slave/test/build/application 19:15:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:15:28 INFO - Reading from file tmpfile_stdout 19:15:28 INFO - Output received: 19:15:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:15:28 INFO - Running post-action listener: _resource_record_post_action 19:15:28 INFO - ##### 19:15:28 INFO - ##### Running run-tests step. 19:15:28 INFO - ##### 19:15:28 INFO - Running pre-action listener: _resource_record_pre_action 19:15:28 INFO - Running main action method: run_tests 19:15:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:15:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:15:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:15:28 INFO - 2016-07-03 19:15:28.872 screenresolution[1649:8296] starting screenresolution argv=screenresolution get 19:15:28 INFO - 2016-07-03 19:15:28.874 screenresolution[1649:8296] Display 0: 1600x1200x32@60 19:15:28 INFO - 2016-07-03 19:15:28.880 screenresolution[1650:8299] starting screenresolution argv=screenresolution list 19:15:28 INFO - Available Modes on Display 0 19:15:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:15:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:15:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:15:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:15:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:15:29 INFO - Intel Iris: 19:15:29 INFO - Chipset Model: Intel Iris 19:15:29 INFO - Type: GPU 19:15:29 INFO - Bus: Built-In 19:15:29 INFO - VRAM (Dynamic, Max): 1536 MB 19:15:29 INFO - Vendor: Intel (0x8086) 19:15:29 INFO - Device ID: 0x0a2e 19:15:29 INFO - Revision ID: 0x0009 19:15:29 INFO - Displays: 19:15:29 INFO - Display: 19:15:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:15:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:15:29 INFO - Display Serial Number: 1600x1200 60 19:15:29 INFO - Main Display: Yes 19:15:29 INFO - Mirror: Off 19:15:29 INFO - Online: Yes 19:15:29 INFO - Rotation: Supported 19:15:29 INFO - Return code: 0 19:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:29 INFO - Python 2.7.3 19:15:29 INFO - Return code: 0 19:15:29 INFO - grabbing minidump binary from tooltool 19:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:29 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 0x10be2de00>, '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 0x10bc96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be28b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:29 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 19:15:29 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 19:15:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:29 INFO - Return code: 0 19:15:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:15:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:15:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:15:30 INFO - Return code: 0 19:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 19:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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 19:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPaP9cBzGE/Render', 19:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:30 INFO - 'HOME': '/Users/cltbld', 19:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:30 INFO - 'LOGNAME': 'cltbld', 19:15:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:30 INFO - 'MOZ_NO_REMOTE': '1', 19:15:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:30 INFO - 'NO_EM_RESTART': '1', 19:15:30 INFO - 'PAGER': '/bin/cat', 19:15:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:30 INFO - 'PWD': '/builds/slave/test', 19:15:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:30 INFO - 'SHELL': '/bin/bash', 19:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ippjlMHVch/Listeners', 19:15:30 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 19:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:30 INFO - 'USER': 'cltbld', 19:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:30 INFO - 'XPC_FLAGS': '0x0', 19:15:30 INFO - 'XPC_SERVICE_NAME': '0', 19:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 19:15:30 INFO - mozversion application_buildid: 20160703171925 19:15:30 INFO - mozversion application_changeset: dbcb78d8ca7469fa8e303eaeeecb17b51757df01 19:15:30 INFO - mozversion application_display_name: Firefox 19:15:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:30 INFO - mozversion application_name: Firefox 19:15:30 INFO - mozversion application_remotingname: firefox 19:15:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:15:30 INFO - mozversion application_vendor: Mozilla 19:15:30 INFO - mozversion application_version: 48.0 19:15:30 INFO - mozversion platform_buildid: 20160703171925 19:15:30 INFO - mozversion platform_changeset: dbcb78d8ca7469fa8e303eaeeecb17b51757df01 19:15:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:15:30 INFO - mozversion platform_version: 48.0 19:15:30 INFO - using testdate: 1467598530 19:15:30 INFO - actual date: 1467598530 19:15:30 INFO - starting webserver on 'localhost:49285' 19:15:30 INFO - SUITE-START | Running 2 tests 19:15:30 INFO - TEST-START | dromaeo_css 19:15:30 INFO - operating with platform_type : mac_ 19:15:30 INFO - Initialising browser for dromaeo_css test... 19:15:30 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpim6aPr/profile http://localhost:49285/getInfo.html) 19:15:32 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 19:15:32 INFO - PROCESS | 1668 | colorDepth:24 19:15:32 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 19:15:32 INFO - PROCESS | 1668 | __metrics 19:15:32 INFO - PROCESS | 1668 | 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" 19:15:32 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:15:33 INFO - TEST-INFO | 1668: exit 0 19:15:33 INFO - Browser initialized. 19:15:33 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:33 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpim6aPr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:45 INFO - PROCESS | 1672 | RSS: Main: 161800192 19:15:45 INFO - PROCESS | 1672 | 19:16:36 INFO - PROCESS | 1672 | 1467598596501 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] 19:18:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49285/tests/dromaeo/cssquery-ext.html) 19:18:47 INFO - PROCESS | 1672 | RSS: Main: 305287168 19:18:47 INFO - PROCESS | 1672 | 19:21:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-ext.html (next: http://localhost:49285/tests/dromaeo/cssquery-jquery.html) 19:21:51 INFO - PROCESS | 1672 | RSS: Main: 304603136 19:21:51 INFO - PROCESS | 1672 | 19:24:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49285/tests/dromaeo/cssquery-mootools.html) 19:24:53 INFO - PROCESS | 1672 | RSS: Main: 306741248 19:24:53 INFO - PROCESS | 1672 | 19:27:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49285/tests/dromaeo/cssquery-prototype.html) 19:27:56 INFO - PROCESS | 1672 | RSS: Main: 302370816 19:27:56 INFO - PROCESS | 1672 | 19:30:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49285/tests/dromaeo/cssquery-yui.html) 19:31:00 INFO - PROCESS | 1672 | RSS: Main: 262766592 19:31:00 INFO - PROCESS | 1672 | 19:34:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-yui.html (next: http://localhost:49285/tests/dromaeo/cssquery-dojo.html) 19:34:03 INFO - PROCESS | 1672 | RSS: Main: 311676928 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | __start_tp_report 19:34:03 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 19:34:03 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 19:34:03 INFO - PROCESS | 1672 | |i|pagename|runs| 19:34:03 INFO - PROCESS | 1672 | |0;dojo.html;3531.77043492173;3590.3357878792426;3590.407582748846;3590.4614307853835;3675.209829886574;5789.623674461168;5829.970850145749;5843.707814609268;5853.063509838426;5858.88282234355;5833.591648584601;5854.268216472941;5865.325487568931;5868.3838196989345;5873.500752436048;5209.973950130244;5232.764525596357;5267.1309233976535;5287.656302340355;5310.123829568117;5283.260343551906;5334.093204155303;5343.572514198855;5344.118220493614;5363.168708850115;5256.316678831747;5328.280682107905;5333.2533445317495;5344.759485823128;5349.010433069875;4463.772462572819;5323.307969963899;5323.893522129536;5334.59990500714;5355.919661205085;5147.562457191143;5180.274761533388;5251.973740131313;5272.209168624699;5278.683279003272;5739.741711622987;5741.770329186827;5748.223989761371;5771.624844385144;5778.075507918755;5156.355455568054;6256.937430625727;6271.278802937675;6272.341404152589;6272.87454250917;5985.311689155759;6130.846728831815;6229.221347331583;6247.781327653511;6264;5956.344802071812;5957.195778569924;5996.8500787480225;6004.54965877561;6021.668808215502;6311.368863113652;6341.207349081365;6342.334054924258;6347.365263473615;6350.301466838644;6099.4815440688035;6107.097787096225;6270.216222972129;6275.6862156891975;6286.874262514724;6200.193974783339;6269.937300627026;6275.843103922439;6286.591371560834;6288.937110628926;6201.96899015502;6229.127922090911;6250.249970003629;6277.937220627827;6294.370562943761;6198.2252218472695;6233.096201050822;6240.65676387794;6263.467605253604;6281.748730050838;4967.130752118437;5006.799728010911;5026.52248036436;5084.46613105626;5093.694378337312;4177.101923086551;4295.484541854998;4509.864704058883;4522;4533.320001999727;5824.388439213906;6010.549208809357;6012.068129439944;6016.278046634345;6047.758089676452;6189.566730328924;6204.224471941007;6237.781677641352;6269.341719119518;6273.37266273369;5950.137230101697;6003.219581454383;6007.549433792483;6020.367861374581;6023.126646636213;5383.300170977748;5516.475934786189;5528.917066244009;5530.198121272473;5537.390887002426;6482.967585162044;6554.246261679968;6566.212054553484;6566.540342176002;6568.80293591193;6289.119523266655;6293.181886354928;6320.083587879823;6327.493800495858;6343.841403964938;6358.1734374531015;6370.331115233019;6520.282768895323;6544.640044797391;6557.082008518716;6210.130581718653;6246.9062964054665;6249.718762655601;6260.28006779208;6283.717232724447;6251.093591429309;6255.437010669061;6256.593321434092;6257.030160325156;6259.687015649109;6100.359462256399;6136.948001697639;6230.127782110418;6233.407826256498;6274.247090349005;6230.345813689497;6245.750169993149;6250.812475625738;6258;6280.623162610258;6059.848503787264;6103.603265787887;6133.601315914451;6136.355682653257;6143.539234557336;4653.697509661996;6127.479164270909;6134.4172303631085;6211.720472578654;6242.812715618538;6104.145419641342;6128.662923539248;6173.074038894022;6190.473809726043;6192.752289908533 19:34:03 INFO - PROCESS | 1672 | |1;ext.html;548.9670619762813;553.1398675060279;553.2590556812507;578.8437121977069;592.899207134787;31515;39844;40303.59696403035;40535.79732101336;40646.593534064654;43785.840345167235;43945.78027109868;44006;44201.11597768051;44374.55625443745;8997.370184087116;9127.726168214931;9651.372660777062;9869.210463162966;9880.110790028895;11841.407929603529;12115.03079753622;12127.090468214876;12155.331456769874;12157.331346775924;11797.469113889852;11900.702482437922;11909.225900316495;11912.166148369617;11926.821097683542;11705.648830535098;11830.526778928832;11837;11842.171048026641;11844.289342639426;11635.709107272301;11726.237794543327;11766.117541184445;11769.882301176964;11784.823227651592;42227.788861055495;42551;42553.36169957422;42670;42681.35977960333;3301.273719781668;3700.2599480104086;3825.981777262237;3928.7642741435607;3976.6619837313865;29011.274718132216;29040;29112.8544357279;29171.854140729374;29375.55936660952;2672.1048448769684;2711.3221694576355;2743.890244390222;2769.418422131355;2810.732980366882;518687;520560.39719801536;521078;521482;524057;59281.110783338285;68201;69067.30932690609;69078.96381554281;69191.96212056823;64215.6789216051;64305.71388572203;64691.67654161699;64733.67633161898;64734.676326618064;17163.88434751737;17258.654826903643;17285.135743212788;17298.308067677153;17323.826761732475;12638.684032899251;12817.551385701456;13580.660483487993;13955.441782328797;13993.930030349784;17990.460286191435;18046.097695115455;18472.891626502453;18568.628627427377;18600.906995464935;6113.480354169857;6114.755409783559;7269.273072692794;7308.415326773846;7350.191478937312;1012.4380968562518;1062.5112448273726;1082.3073233130804;1128.3624752015085;1131.219458573598;30083.398332033237;30657.08028759141;30720;31465.213369665482;31634.525482117788;1092.9835253214587;1133.6429024857143;1183.7099910521806;1229.16416836552;1285.6464472270013;15633.68732625364;15973.920130399507;15996.76004859928;16052.678946421007;16298.67402651964;602627;608871;611733;614863;615874;63454.36545634484;63545;63720;63797.681011595574;63924.68037659782;1391.1096897985296;1529.296523599157;1567.130242715304;1572.4653617770014;1577.5740550051191;26256.34359141037;29205.85397073001;29533.261668457602;29717.257068572595;30076.5488517668;48900.5109948896;62454.687726561075;63315.366846330944;63341;63373.683131585894;424722;429522;430001;430368;432905;25126.12058577941;28036.57945130784;28144.71855281421;28661;28909.56635650425;46168.538314616424;46456.535434646576;46812.7659361701;46989.76505117452;48070.5192948066;37156.44265335968;38258.61741382551;38439.807800960814;38508.61491385163;38994.63518776829;37757.05607359838;38154.61845381511;38159;38491;38908.02729931774;37638;37937.62062379341;38049;38448.80775596104;38540;32068.83965580157;32822.5076623846;33861.66138338585;33891.830540847135;34050;24585.385365366008;24847.378815529755;24987.305222282925;25220.74779252184;25334.619980700674 19:34:03 INFO - PROCESS | 1672 | |2;jquery.html;411.4507132977476;414.3116799043897;415.0868090201557;416.6958120571982;424.51605170106063;19682;20745.688814667774;21450;21455.463613409654;21646.567068658616;20950;21301.25445609404;22030.669539956874;22081.889590552066;22100.22649207278;5669.291338582688;6264.780732674358;6268.279147898004;6284.905726414107;6287.654179020152;5123.743812809364;6241.375862413768;6242.219722534661;6253.4684551812925;6264;5692.715364231793;6010.459058684717;6016.2479690038745;6017.39826017397;6020.488258498011;5748.2527271454655;5892.204552385416;5984.222051133346;6099.451049405575;6107.4197951194565;5949.077892926603;5955.34491205967;5973.253343332083;5981.401859814027;5994.340622531518;16080.678386432322;26719.931202751868;27096.729032709616;27100.728992710017;27155.1853444397;2292.942676433086;2331.5453486570127;2703.931946880971;2823.181277429543;2870.6555213374318;9826.361286516425;9850.704478865573;10153.796924061551;10195.745106372326;10501.737456563571;1735.6355165415277;1822.2164469278205;1858.3310008396966;1884.9434516964513;1995.3016444244543;106680.46659766651;114534;114828.42585787101;116508.41745791302;116549;9878.703638890845;11512.251703639236;11768.411579420994;11807.527698891949;11838.230515016669;15160.545183644508;15169.86226033052;15225.543233703007;15225.695486090217;15230.857685673618;7534.020577325023;8803.647854085764;9177.174054335143;9188.356815023018;9230.584623691815;9213.493257870747;9220;9229.446233226028;9308.208802251884;9310.115539023782;4059.6752259819145;4799.784009719501;4863.537963893425;4896.828610998598;4940.110780059552;1990.4526255279625;2026.8175864172294;2028;2058.1458694641856;2093.2987449204384;887.7913690282778;974.7563109222552;976.7411635916487;984.1683777208248;987.6148302162162;18203.54491137732;20128.49678758043;21638.02628881704;21893.452663683536;21926.45183870416;968.5254225429629;997.3941953454931;1020.2959957629212;1034.7413146713322;1074.3231763988638;9682.90317096834;11173.888261117447;11569.595064172714;11690.415479226005;11719.531218751154;487375.57307629246;537908;539179;672097;675888;90564.547177265;108071.91928080619;118444.8155518451;127308;127753;1037.2169012395593;1062.829947208443;1079.5681727309138;1079.7030816525519;1080.4381721504774;19157.233710651977;19513.70729439031;19982.400527984184;20038.599228015068;20272.391828245178;33593.328133436706;56094.43905560892;56232;57039;57153.14270285866;81600.59199703964;84518;85348;85745.57127214324;87156;8109.553974531457;8170.182981701782;8260.256576907866;8264.049634292134;8503.829923401374;10621.946890265808;12936.870631293566;13036.087473876738;13101.213927164401;13116.868831311565;10752.51613677402;11351.318920864775;11363.261388009752;11469.598564050551;11494.367809770543;10916.290441121302;11031.503582338653;11143.498542565441;11277.379744114152;11464.656060318204;11166.665000050012;11438.714032149262;11558.94220528892;11701.06391488696;11723.706907327385;7994.800129996564;8068.677252909818;8103.311218546254;8389.406012857782;8629.22336989674;10968.780624387628;11203.775924481302;11359.54561817518;11555.364454955059;11729.824052639047 19:34:03 INFO - PROCESS | 1672 | |3;mootools.html;1484.5026915983144;1528.258794484675;1654.3796076471326;1902.5053486093611;2020.4746765840878;8162.91837081629;8803.823923521526;8976.922769267689;8981.05698901614;8986.820263594724;8636.697715579956;8910.866337004934;8917.286617070635;8924.687635932727;8939.910600893989;2195.8462907596477;2515.8490490570534;2552.068494999321;2554.3231043773417;2557.1689201009654;2471.122751423246;2538.4288535079645;2541.606051062088;2543.987280063597;2550.6174073888974;2499.7375275596073;2528.734482879299;2547.757962993522;2548.808839337057;2558.872056397182;2503.7621425964503;2525.5454018276705;2529.962050569243;2539.7587229213286;2552.808539359546;2163.3077415227135;2438.987805060963;2464.581021226396;2493.0900221419233;2543.6438898554215;8593.398462107654;8782.516961567111;8969.192772650493;9035.277177825825;9053;5557.638753481051;5659.971700141514;5691.686957217351;5717.285339332583;5719.857003574903;3648.562172539312;3697.6150383003082;3760.7555508891833;3788.753731007503;3795.62043795621;1064.3720205079042;1074.4949873559415;1075.424647813416;1097.2812807611028;1109.9278546894427;12268.079894007897;12394.070444716683;12404.007679385628;12404.875951240463;12415.317157556241;4695.765211739399;4698.718076915396;4827.203511420576;4828.46886842447;4856.4415092264135;9590.232781377474;9617.951910240545;9626.614935402506;9630.085141911508;9631.181349585224;5504.697241651667;5504.752286147133;5542.418046105182;5548.4451554845;5549.361823390281;4491.932621010687;4576.336431217454;4927.457979622239;5022.748862556858;5150.562202212779;3907.941380879289;3972.6027397260623;4018.557958624549;4034.1931613677384;4038.6567141793275;1225.993870030644;1241.8013117901091;1242.3788105947026;1254.3352023427599;1256.2274201366276;944.9007854175212;963.3834346018554;966.4539535162611;981.3081777346953;1019.5412064570972;5940.168376427304;6065.8180254592435;6100.511959043266;6126.081087836771;6295.307516173218;868.9304855611535;972.6595691508057;973.8216757723234;979.4515071559949;1026.481626778487;4000.3599424092004;4061.248668996245;4077.4699289092227;4097.97951010249;4124.546299907008;10666.679999600025;11021.944890275498;11040.779184416428;11078.723031924266;11162;10830.620928267474;10967.945160274148;11020.173486988508;11049.226554141133;11058.557657693764;8149.307308878693;8525.360597955054;8659.567021648892;8663.48019118855;8664.350173736995;5943.732532036155;5965.761369545169;6138.416850399205;6150.138980542817;6278.246610406781;9507.477088760184;9845.163161131097;9992.050755178247;10015.499225038573;10034.69895903124;9985.301028927905;10030.49847507607;10147.79704405893;10159.695209143738;10301.227407944285;6722.899156512754;7044.964775176091;7241.927580724126;7520.285572870568;7781.221877812173;6709.396154346132;7710.383169346551;8498.70254541088;8563.914360856561;8588.141185881612;7415.1101867776215;7426.777196684108;7487.438442116972;7661.731839385595;7753.922460775546;7366.337029667356;7389.224131466119;7434.3309102181065;7462.067241594801;7488.588127653032;7170.318819712119;7176.354128128493;7192.748253811092;7213.9278607213255;7215.927840721735;4879.780409881635;4946.282788995503;5000.87497812543;5101.0308041472;5142.871428214175;5032.84901452957;5045.621578381562;5068.6451948363265;5158.819441319536;5310.495502927216 19:34:03 INFO - PROCESS | 1672 | |4;prototype.html;333.0541262813609;339.20456529438474;343.1489905035512;346.62910685566453;350.4812876942127;5997.2803263608375;6216.880961426941;6722.899156512656;6734.29289924557;6766.526343155975;6678.065070890079;6683.832904177398;6692.665366731669;6697.430718388943;6724.865502689955;4263;4769.594584460317;4883.145449546333;4887.6089912807065;4923.532264434891;4879.048585525824;4909.263610458442;4984.675996060263;5005.324281222025;5016.573591244749;4931.235658472942;4936.284238785374;4944;4945.282933974571;4950.6782059166035;4717.386739723831;4762.761861906908;4896.730679812609;4926.11329960607;4938.950610493885;4258.5528519505315;4263.765492897889;4264.552222016675;4268.935965960513;4268.996785755345;5270.631055826094;6456.289808121103;6473.74105035798;6489.22129344477;6498.870022599569;5795.362510123883;5816.563757718188;5843.948089343917;5844.912326315109;5866.090755932837;6863.142107236596;7099.751508697171;7110.217876033632;7118.679659415338;7151.499395042297;4634;4675.13510000651;4675.597320803745;4687.812487500496;4689.179393606105;7550.395968322521;7632.503887247367;7646.770596882103;7649.196834332371;7665.808354791119;4726.243800991825;4737.170995075848;4738.407699037621;4739.3838800955655;4740.573348398591;4791.784369703371;4799.544043315879;4810.855674329776;4817.27740838877;4822.480918510658;5525.364583072917;5780.306363236438;5781.82654520365;5782.6819524925695;5803.767849285981;7100.431965442752;7116.537425067458;7130;7159.713611455483;7176.569405835667;1638.9344426222817;1665.7334826427714;1669.699454098274;1670.0313817985539;1672.272561435755;992.7666998255404;1008.3496144986443;1036.5750042482546;1044.7335929337971;1065.9733506662396;5322.467753224724;6696;6723.831904202434;6840.110785597841;6897.862042759117;7555.659995300225;7556.65995030225;7568.5837278950185;7572;7579.5073320234005;5582.190582365534;6461.127747754087;6842.452603791685;6857.725690972405;6875.346842049998;3854.807259637007;3964.72246942717;4000.3999400089638;4000.7799571023293;4005.899852503711;7935.246151615703;7942.6425810838655;7951.204879512118;7951.363890888715;7983.760487185394;7692.230776922375;7703.499272547267;7707.92292077072;7747.341475974493;7750.806229844186;7902.565358905315;7916.366690664733;7928.405369597303;7940.642671079931;7953;7248.420126389981;7540.622968851645;7540.811479713051;7542.811429714301;7547.433942454448;7385.335319821242;7414.110306763007;7445.218252083453;7447.441441891771;7459.589722565311;7848.704963680929;7976.680932762624;7978.680852765824;7987;8013.038435387791;4204.4324016257415;4741.980474198064;4759.119562880878;4762.714237145782;4762.880927976828;7222.96388518054;7496.137944136494;7497.887531687138;7512.774616761506;7535.284148005931;7288;7309.817254568678;7328.450366222448;7334.96332518334;7340.0457940467395;7269.709211631475;7278.490505664553;7290.088738907637;7301.306375894282;7306.707731690885;7143.678534465857;7152.892706609509;7166.462515311268;7173.892391614026;7174.928250717426;6956.095707557985;7271.636418179176;7286.526375785557;7292.525985810906;7293.124825021031;6780.762673306411;6897.275786042594;6970.721171153097;6994.720211191496;7138.072050633385 19:34:03 INFO - PROCESS | 1672 | |5;yui.html;713.3152173913044;727.2763600217784;760.7185341423674;768.561919705768;811.2617518058568;15175.696486070272;15473.767893481607;15521.767173492379;15526.844731552681;15549.922250388761;14134.787978180335;14220.502282420117;14228.857711422883;14236.430542778277;14239.145651260931;765.7128576783706;779.0378882080626;942.8114377124568;943.4669411782355;971.946542940138;948.981020379592;958.2573505533232;959.7312752429297;961.5817119552981;963.4604621411996;1034.4724190662741;1035.1770342577631;1037.4138611684375;1037.8858325584179;1040.8230600797845;816.3754727633366;1000.554753134859;1019.0421004255976;1022.2128960700219;1030.386919782728;763.107164617399;764.0907320288838;764.4877931785717;767.3630886364305;767.7965339185121;14553.908456865778;14635;14639.853601464061;14652.267386630732;14655.34050967709;12898.355082245851;13147.671308217276;13241.940644748396;13289.3355332233;13296;10333.58665653373;10358.585656573729;10382.636607718769;10664.986826251494;11164;3534.452159915217;3550.786952782841;3593.964060359389;3659.743817932746;3809.5428548574073;81675.18324816794;82159;82428;83896;84205.57897210475;8403.747887563382;9657.613695452244;9712.660056897974;9807.019298070136;9826;21886.45283867916;22480.550388992127;22529;22542.436439088826;22545.759983200755;7798.454108212371;7856;7969.72105976288;8161.55111468863;8261.504309741435;4309.956900431018;4314.762688052125;4363.258246098171;4367.192069467159;4480.014396832692;3712.4988126603103;3775.716821238418;3778.319902417591;3779.1874746929543;3786.6970642348547;646.12127506591;651.6611362091686;674.7166190200128;675.7195763758084;710.5559025608994;538.5045757902739;577.9190913272146;588.9646621202655;604.1421181921594;630.501903496242;8759.518226497476;8804.47173169612;8819.206271435602;9128.954355228181;9155.028072488865;507.1024287011968;509.8368522072975;524.0279281932037;585.0989476206679;632.4466092169351;3709.4435834624483;4064.227796718614;4104.8152833122585;4134.73124246923;4143.215669968403;88717;95189;97500;97549;97722.5113874426;73457.89813152807;77044.61477692575;79781;80456.79314810282;83450.58274708588;69242.65378673175;70024.64987675131;70323.64838175777;72144.63927680328;72145;8305.792355191168;8563.785905352166;8621.482711037357;8777.736667899719;8933.46399216049;43358.91602709958;45705.085898282516;46293;47825.043499130516;47833.52166478291;66334;67297.66351168213;70267.29732702812;70458.59082818417;70577.64711176412;11712.180147389601;11787.587434439753;11929.880701192877;12035.217710848767;12474;11942.522299107939;12195.882565501252;12249.816252756395;12948.482060717466;13030.478780849038;11607.419629018346;11938.403079845799;11974.161808673309;12134.271943683407;12342.197757145757;11881.346525941157;11894.881051189377;11921.463534141158;12160.635180944586;12186.903178713603;11718.70703232426;11724.413779311171;11818.881811181778;11880.82178767302;12116.394180290774;9707.271954603575;9884.962078981604;10116.696499105039;10426.635067772591;10492.108170805566;8623.8706419405;9531.38046027006;9794.363366381162;10118.291719579558;10119.949400252952 19:34:03 INFO - PROCESS | 1672 | __end_tp_report 19:34:03 INFO - PROCESS | 1672 | __start_cc_report 19:34:03 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1315 19:34:03 INFO - PROCESS | 1672 | __end_cc_report 19:34:03 INFO - PROCESS | 1672 | __startTimestamp1467599643061__endTimestamp 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | ------- Summary: start ------- 19:34:03 INFO - PROCESS | 1672 | Number of tests: 6 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5831.93 Median:6104.15 stddev:642.54 (10.5%) stddev-sans-first:618.66 19:34:03 INFO - PROCESS | 1672 | Values: 3531.8 3590.3 3590.4 3590.5 3675.2 5789.6 5830.0 5843.7 5853.1 5858.9 5833.6 5854.3 5865.3 5868.4 5873.5 5210.0 5232.8 5267.1 5287.7 5310.1 5283.3 5334.1 5343.6 5344.1 5363.2 5256.3 5328.3 5333.3 5344.8 5349.0 4463.8 5323.3 5323.9 5334.6 5355.9 5147.6 5180.3 5252.0 5272.2 5278.7 5739.7 5741.8 5748.2 5771.6 5778.1 5156.4 6256.9 6271.3 6272.3 6272.9 5985.3 6130.8 6229.2 6247.8 6264.0 5956.3 5957.2 5996.9 6004.5 6021.7 6311.4 6341.2 6342.3 6347.4 6350.3 6099.5 6107.1 6270.2 6275.7 6286.9 6200.2 6269.9 6275.8 6286.6 6288.9 6202.0 6229.1 6250.2 6277.9 6294.4 6198.2 6233.1 6240.7 6263.5 6281.7 4967.1 5006.8 5026.5 5084.5 5093.7 4177.1 4295.5 4509.9 4522.0 4533.3 5824.4 6010.5 6012.1 6016.3 6047.8 6189.6 6204.2 6237.8 6269.3 6273.4 5950.1 6003.2 6007.5 6020.4 6023.1 5383.3 5516.5 5528.9 5530.2 5537.4 6483.0 6554.2 6566.2 6566.5 6568.8 6289.1 6293.2 6320.1 6327.5 6343.8 6358.2 6370.3 6520.3 6544.6 6557.1 6210.1 6246.9 6249.7 6260.3 6283.7 6251.1 6255.4 6256.6 6257.0 6259.7 6100.4 6136.9 6230.1 6233.4 6274.2 6230.3 6245.8 6250.8 6258.0 6280.6 6059.8 6103.6 6133.6 6136.4 6143.5 4653.7 6127.5 6134.4 6211.7 6242.8 6104.1 6128.7 6173.1 6190.5 6192.8 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:67180.75 Median:33970.92 stddev:140021.55 (412.2%) stddev-sans-first:140324.97 19:34:03 INFO - PROCESS | 1672 | Values: 549.0 553.1 553.3 578.8 592.9 31515.0 39844.0 40303.6 40535.8 40646.6 43785.8 43945.8 44006.0 44201.1 44374.6 8997.4 9127.7 9651.4 9869.2 9880.1 11841.4 12115.0 12127.1 12155.3 12157.3 11797.5 11900.7 11909.2 11912.2 11926.8 11705.6 11830.5 11837.0 11842.2 11844.3 11635.7 11726.2 11766.1 11769.9 11784.8 42227.8 42551.0 42553.4 42670.0 42681.4 3301.3 3700.3 3826.0 3928.8 3976.7 29011.3 29040.0 29112.9 29171.9 29375.6 2672.1 2711.3 2743.9 2769.4 2810.7 518687.0 520560.4 521078.0 521482.0 524057.0 59281.1 68201.0 69067.3 69079.0 69192.0 64215.7 64305.7 64691.7 64733.7 64734.7 17163.9 17258.7 17285.1 17298.3 17323.8 12638.7 12817.6 13580.7 13955.4 13993.9 17990.5 18046.1 18472.9 18568.6 18600.9 6113.5 6114.8 7269.3 7308.4 7350.2 1012.4 1062.5 1082.3 1128.4 1131.2 30083.4 30657.1 30720.0 31465.2 31634.5 1093.0 1133.6 1183.7 1229.2 1285.6 15633.7 15973.9 15996.8 16052.7 16298.7 602627.0 608871.0 611733.0 614863.0 615874.0 63454.4 63545.0 63720.0 63797.7 63924.7 1391.1 1529.3 1567.1 1572.5 1577.6 26256.3 29205.9 29533.3 29717.3 30076.5 48900.5 62454.7 63315.4 63341.0 63373.7 424722.0 429522.0 430001.0 430368.0 432905.0 25126.1 28036.6 28144.7 28661.0 28909.6 46168.5 46456.5 46812.8 46989.8 48070.5 37156.4 38258.6 38439.8 38508.6 38994.6 37757.1 38154.6 38159.0 38491.0 38908.0 37638.0 37937.6 38049.0 38448.8 38540.0 32068.8 32822.5 33861.7 33891.8 34050.0 24585.4 24847.4 24987.3 25220.7 25334.6 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:34464.67 Median:14525.74 stddev:97864.69 (673.7%) stddev-sans-first:98105.82 19:34:03 INFO - PROCESS | 1672 | Values: 411.5 414.3 415.1 416.7 424.5 19682.0 20745.7 21450.0 21455.5 21646.6 20950.0 21301.3 22030.7 22081.9 22100.2 5669.3 6264.8 6268.3 6284.9 6287.7 5123.7 6241.4 6242.2 6253.5 6264.0 5692.7 6010.5 6016.2 6017.4 6020.5 5748.3 5892.2 5984.2 6099.5 6107.4 5949.1 5955.3 5973.3 5981.4 5994.3 16080.7 26719.9 27096.7 27100.7 27155.2 2292.9 2331.5 2703.9 2823.2 2870.7 9826.4 9850.7 10153.8 10195.7 10501.7 1735.6 1822.2 1858.3 1884.9 1995.3 106680.5 114534.0 114828.4 116508.4 116549.0 9878.7 11512.3 11768.4 11807.5 11838.2 15160.5 15169.9 15225.5 15225.7 15230.9 7534.0 8803.6 9177.2 9188.4 9230.6 9213.5 9220.0 9229.4 9308.2 9310.1 4059.7 4799.8 4863.5 4896.8 4940.1 1990.5 2026.8 2028.0 2058.1 2093.3 887.8 974.8 976.7 984.2 987.6 18203.5 20128.5 21638.0 21893.5 21926.5 968.5 997.4 1020.3 1034.7 1074.3 9682.9 11173.9 11569.6 11690.4 11719.5 487375.6 537908.0 539179.0 672097.0 675888.0 90564.5 108071.9 118444.8 127308.0 127753.0 1037.2 1062.8 1079.6 1079.7 1080.4 19157.2 19513.7 19982.4 20038.6 20272.4 33593.3 56094.4 56232.0 57039.0 57153.1 81600.6 84518.0 85348.0 85745.6 87156.0 8109.6 8170.2 8260.3 8264.0 8503.8 10621.9 12936.9 13036.1 13101.2 13116.9 10752.5 11351.3 11363.3 11469.6 11494.4 10916.3 11031.5 11143.5 11277.4 11464.7 11166.7 11438.7 11558.9 11701.1 11723.7 7994.8 8068.7 8103.3 8389.4 8629.2 10968.8 11203.8 11359.5 11555.4 11729.8 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5831.38 Median:5039.24 stddev:3227.09 (64.0%) stddev-sans-first:3219.61 19:34:03 INFO - PROCESS | 1672 | Values: 1484.5 1528.3 1654.4 1902.5 2020.5 8162.9 8803.8 8976.9 8981.1 8986.8 8636.7 8910.9 8917.3 8924.7 8939.9 2195.8 2515.8 2552.1 2554.3 2557.2 2471.1 2538.4 2541.6 2544.0 2550.6 2499.7 2528.7 2547.8 2548.8 2558.9 2503.8 2525.5 2530.0 2539.8 2552.8 2163.3 2439.0 2464.6 2493.1 2543.6 8593.4 8782.5 8969.2 9035.3 9053.0 5557.6 5660.0 5691.7 5717.3 5719.9 3648.6 3697.6 3760.8 3788.8 3795.6 1064.4 1074.5 1075.4 1097.3 1109.9 12268.1 12394.1 12404.0 12404.9 12415.3 4695.8 4698.7 4827.2 4828.5 4856.4 9590.2 9618.0 9626.6 9630.1 9631.2 5504.7 5504.8 5542.4 5548.4 5549.4 4491.9 4576.3 4927.5 5022.7 5150.6 3907.9 3972.6 4018.6 4034.2 4038.7 1226.0 1241.8 1242.4 1254.3 1256.2 944.9 963.4 966.5 981.3 1019.5 5940.2 6065.8 6100.5 6126.1 6295.3 868.9 972.7 973.8 979.5 1026.5 4000.4 4061.2 4077.5 4098.0 4124.5 10666.7 11021.9 11040.8 11078.7 11162.0 10830.6 10967.9 11020.2 11049.2 11058.6 8149.3 8525.4 8659.6 8663.5 8664.4 5943.7 5965.8 6138.4 6150.1 6278.2 9507.5 9845.2 9992.1 10015.5 10034.7 9985.3 10030.5 10147.8 10159.7 10301.2 6722.9 7045.0 7241.9 7520.3 7781.2 6709.4 7710.4 8498.7 8563.9 8588.1 7415.1 7426.8 7487.4 7661.7 7753.9 7366.3 7389.2 7434.3 7462.1 7488.6 7170.3 7176.4 7192.7 7213.9 7215.9 4879.8 4946.3 5000.9 5101.0 5142.9 5032.8 5045.6 5068.6 5158.8 5310.5 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5914.18 Median:6724.35 stddev:1920.62 (28.6%) stddev-sans-first:1879.77 19:34:03 INFO - PROCESS | 1672 | Values: 333.1 339.2 343.1 346.6 350.5 5997.3 6216.9 6722.9 6734.3 6766.5 6678.1 6683.8 6692.7 6697.4 6724.9 4263.0 4769.6 4883.1 4887.6 4923.5 4879.0 4909.3 4984.7 5005.3 5016.6 4931.2 4936.3 4944.0 4945.3 4950.7 4717.4 4762.8 4896.7 4926.1 4939.0 4258.6 4263.8 4264.6 4268.9 4269.0 5270.6 6456.3 6473.7 6489.2 6498.9 5795.4 5816.6 5843.9 5844.9 5866.1 6863.1 7099.8 7110.2 7118.7 7151.5 4634.0 4675.1 4675.6 4687.8 4689.2 7550.4 7632.5 7646.8 7649.2 7665.8 4726.2 4737.2 4738.4 4739.4 4740.6 4791.8 4799.5 4810.9 4817.3 4822.5 5525.4 5780.3 5781.8 5782.7 5803.8 7100.4 7116.5 7130.0 7159.7 7176.6 1638.9 1665.7 1669.7 1670.0 1672.3 992.8 1008.3 1036.6 1044.7 1066.0 5322.5 6696.0 6723.8 6840.1 6897.9 7555.7 7556.7 7568.6 7572.0 7579.5 5582.2 6461.1 6842.5 6857.7 6875.3 3854.8 3964.7 4000.4 4000.8 4005.9 7935.2 7942.6 7951.2 7951.4 7983.8 7692.2 7703.5 7707.9 7747.3 7750.8 7902.6 7916.4 7928.4 7940.6 7953.0 7248.4 7540.6 7540.8 7542.8 7547.4 7385.3 7414.1 7445.2 7447.4 7459.6 7848.7 7976.7 7978.7 7987.0 8013.0 4204.4 4742.0 4759.1 4762.7 4762.9 7223.0 7496.1 7497.9 7512.8 7535.3 7288.0 7309.8 7328.5 7335.0 7340.0 7269.7 7278.5 7290.1 7301.3 7306.7 7143.7 7152.9 7166.5 7173.9 7174.9 6956.1 7271.6 7286.5 7292.5 7293.1 6780.8 6897.3 6970.7 6994.7 7138.1 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:18655.12 Median:45999.04 stddev:26065.14 (56.7%) stddev-sans-first:26103.44 19:34:03 INFO - PROCESS | 1672 | Values: 713.3 727.3 760.7 768.6 811.3 15175.7 15473.8 15521.8 15526.8 15549.9 14134.8 14220.5 14228.9 14236.4 14239.1 765.7 779.0 942.8 943.5 971.9 949.0 958.3 959.7 961.6 963.5 1034.5 1035.2 1037.4 1037.9 1040.8 816.4 1000.6 1019.0 1022.2 1030.4 763.1 764.1 764.5 767.4 767.8 14553.9 14635.0 14639.9 14652.3 14655.3 12898.4 13147.7 13241.9 13289.3 13296.0 10333.6 10358.6 10382.6 10665.0 11164.0 3534.5 3550.8 3594.0 3659.7 3809.5 81675.2 82159.0 82428.0 83896.0 84205.6 8403.7 9657.6 9712.7 9807.0 9826.0 21886.5 22480.6 22529.0 22542.4 22545.8 7798.5 7856.0 7969.7 8161.6 8261.5 4310.0 4314.8 4363.3 4367.2 4480.0 3712.5 3775.7 3778.3 3779.2 3786.7 646.1 651.7 674.7 675.7 710.6 538.5 577.9 589.0 604.1 630.5 8759.5 8804.5 8819.2 9129.0 9155.0 507.1 509.8 524.0 585.1 632.4 3709.4 4064.2 4104.8 4134.7 4143.2 88717.0 95189.0 97500.0 97549.0 97722.5 73457.9 77044.6 79781.0 80456.8 83450.6 69242.7 70024.6 70323.6 72144.6 72145.0 8305.8 8563.8 8621.5 8777.7 8933.5 43358.9 45705.1 46293.0 47825.0 47833.5 66334.0 67297.7 70267.3 70458.6 70577.6 11712.2 11787.6 11929.9 12035.2 12474.0 11942.5 12195.9 12249.8 12948.5 13030.5 11607.4 11938.4 11974.2 12134.3 12342.2 11881.3 11894.9 11921.5 12160.6 12186.9 11718.7 11724.4 11818.9 11880.8 12116.4 9707.3 9885.0 10116.7 10426.6 10492.1 8623.9 9531.4 9794.4 10118.3 10119.9 19:34:03 INFO - PROCESS | 1672 | -------- Summary: end -------- 19:34:03 INFO - PROCESS | 1672 | 19:34:03 INFO - TEST-INFO | 1672: exit 0 19:34:03 INFO - TEST-OK | dromaeo_css | took 1113073ms 19:34:03 INFO - TEST-START | kraken 19:34:03 INFO - operating with platform_type : mac_ 19:34:03 INFO - Initialising browser for kraken test... 19:34:03 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp7EQkMV/profile http://localhost:49285/getInfo.html) 19:34:05 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 19:34:05 INFO - PROCESS | 1678 | colorDepth:24 19:34:05 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 19:34:05 INFO - PROCESS | 1678 | __metrics 19:34:05 INFO - PROCESS | 1678 | 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" 19:34:05 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:34:06 INFO - TEST-INFO | 1678: exit 0 19:34:06 INFO - Browser initialized. 19:34:06 INFO - Running cycle 1/1 for kraken test... 19:34:06 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp7EQkMV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:18 INFO - PROCESS | 1681 | RSS: Main: 161427456 19:34:18 INFO - PROCESS | 1681 | 19:34:20 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection) 19:34:21 INFO - PROCESS | 1681 | RSS: Main: 223961088 19:34:21 INFO - PROCESS | 1681 | 19:34:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-dft) 19:34:25 INFO - PROCESS | 1681 | RSS: Main: 239271936 19:34:25 INFO - PROCESS | 1681 | 19:34:28 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-fft) 19:34:28 INFO - PROCESS | 1681 | RSS: Main: 324091904 19:34:28 INFO - PROCESS | 1681 | 19:34:30 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator) 19:34:31 INFO - PROCESS | 1681 | RSS: Main: 232013824 19:34:31 INFO - PROCESS | 1681 | 19:34:33 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:34 INFO - PROCESS | 1681 | RSS: Main: 232001536 19:34:34 INFO - PROCESS | 1681 | 19:34:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom) 19:34:37 INFO - PROCESS | 1681 | RSS: Main: 269791232 19:34:37 INFO - PROCESS | 1681 | 19:34:40 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate) 19:34:41 INFO - PROCESS | 1681 | RSS: Main: 270077952 19:34:41 INFO - PROCESS | 1681 | 19:34:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial) 19:34:44 INFO - PROCESS | 1681 | RSS: Main: 268939264 19:34:44 INFO - PROCESS | 1681 | 19:34:45 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:34:46 INFO - PROCESS | 1681 | RSS: Main: 260771840 19:34:46 INFO - PROCESS | 1681 | 19:34:48 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:34:48 INFO - PROCESS | 1681 | RSS: Main: 268087296 19:34:48 INFO - PROCESS | 1681 | 19:34:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:34:51 INFO - PROCESS | 1681 | RSS: Main: 270901248 19:34:51 INFO - PROCESS | 1681 | 19:34:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:34:55 INFO - PROCESS | 1681 | RSS: Main: 278192128 19:34:55 INFO - PROCESS | 1681 | 19:34:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:34:58 INFO - PROCESS | 1681 | RSS: Main: 275394560 19:34:58 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49285/tests/kraken/driver.html?testName=ai-astar) 19:35:00 INFO - PROCESS | 1681 | RSS: Main: 336031744 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | __start_tp_report 19:35:00 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 19:35:00 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 19:35:00 INFO - PROCESS | 1681 | |i|pagename|runs| 19:35:00 INFO - PROCESS | 1681 | |0;ai-astar;115;112;118;111;112;111;113;113;112;133 19:35:00 INFO - PROCESS | 1681 | |1;audio-beat-detection;155;124;139;163;116;136;116;151;152;120 19:35:00 INFO - PROCESS | 1681 | |2;audio-dft;184;186;186;185;186;185;184;185;184;185 19:35:00 INFO - PROCESS | 1681 | |3;audio-fft;73;77;71;154;65;73;82;88;161;55 19:35:00 INFO - PROCESS | 1681 | |4;audio-oscillator;92;91;136;79;86;91;124;79;76;127 19:35:00 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;87;85;88;86;88;84;89;85;89;84 19:35:00 INFO - PROCESS | 1681 | |6;imaging-darkroom;93;94;95;94;106;148;121;94;94;94 19:35:00 INFO - PROCESS | 1681 | |7;imaging-desaturate;86;84;83;86;82;85;86;83;82;83 19:35:00 INFO - PROCESS | 1681 | |8;json-parse-financial;61;67;63;63;62;64;65;83;59;58 19:35:00 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;61;63;67;65;75;72;71;65;66;66 19:35:00 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;81;78;79;80;81;78;79;79;80;83 19:35:00 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;133;137;127;136;142;119;142;134;155;135 19:35:00 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;161;164;163;165;166;154;163;161;162;158 19:35:00 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;69;68;68;67;66;67;66;69;69;65 19:35:00 INFO - PROCESS | 1681 | __end_tp_report 19:35:00 INFO - PROCESS | 1681 | __start_cc_report 19:35:00 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1849 19:35:00 INFO - PROCESS | 1681 | __end_cc_report 19:35:00 INFO - PROCESS | 1681 | __startTimestamp1467599700428__endTimestamp 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | ------- Summary: start ------- 19:35:00 INFO - PROCESS | 1681 | Number of tests: 14 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:115.00 Median:113.00 stddev:6.67 (5.9%) stddev-sans-first:7.07 19:35:00 INFO - PROCESS | 1681 | Values: 115.0 112.0 118.0 111.0 112.0 111.0 113.0 113.0 112.0 133.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:145.00 stddev:17.53 (12.1%) stddev-sans-first:17.37 19:35:00 INFO - PROCESS | 1681 | Values: 155.0 124.0 139.0 163.0 116.0 136.0 116.0 151.0 152.0 120.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:0.82 (0.4%) stddev-sans-first:0.78 19:35:00 INFO - PROCESS | 1681 | Values: 184.0 186.0 186.0 185.0 186.0 185.0 184.0 185.0 184.0 185.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:89.90 Median:73.00 stddev:36.76 (50.4%) stddev-sans-first:38.48 19:35:00 INFO - PROCESS | 1681 | Values: 73.0 77.0 71.0 154.0 65.0 73.0 82.0 88.0 161.0 55.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:82.50 stddev:22.22 (26.9%) stddev-sans-first:23.46 19:35:00 INFO - PROCESS | 1681 | Values: 92.0 91.0 136.0 79.0 86.0 91.0 124.0 79.0 76.0 127.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.96 (2.2%) stddev-sans-first:2.07 19:35:00 INFO - PROCESS | 1681 | Values: 87.0 85.0 88.0 86.0 88.0 84.0 89.0 85.0 89.0 84.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:94.00 stddev:18.03 (19.2%) stddev-sans-first:18.74 19:35:00 INFO - PROCESS | 1681 | Values: 93.0 94.0 95.0 94.0 106.0 148.0 121.0 94.0 94.0 94.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.50 stddev:1.63 (1.9%) stddev-sans-first:1.56 19:35:00 INFO - PROCESS | 1681 | Values: 86.0 84.0 83.0 86.0 82.0 85.0 86.0 83.0 82.0 83.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:63.50 stddev:7.03 (11.1%) stddev-sans-first:7.34 19:35:00 INFO - PROCESS | 1681 | Values: 61.0 67.0 63.0 63.0 62.0 64.0 65.0 83.0 59.0 58.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:66.50 stddev:4.31 (6.5%) stddev-sans-first:3.96 19:35:00 INFO - PROCESS | 1681 | Values: 61.0 63.0 67.0 65.0 75.0 72.0 71.0 65.0 66.0 66.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:1.55 (1.9%) stddev-sans-first:1.58 19:35:00 INFO - PROCESS | 1681 | Values: 81.0 78.0 79.0 80.0 81.0 78.0 79.0 79.0 80.0 83.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:136.50 stddev:9.53 (7.0%) stddev-sans-first:10.05 19:35:00 INFO - PROCESS | 1681 | Values: 133.0 137.0 127.0 136.0 142.0 119.0 142.0 134.0 155.0 135.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.70 Median:163.00 stddev:3.53 (2.2%) stddev-sans-first:3.73 19:35:00 INFO - PROCESS | 1681 | Values: 161.0 164.0 163.0 165.0 166.0 154.0 163.0 161.0 162.0 158.0 19:35:00 INFO - PROCESS | 1681 | 19:35:00 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.43 (2.1%) stddev-sans-first:1.39 19:35:00 INFO - PROCESS | 1681 | Values: 69.0 68.0 68.0 67.0 66.0 67.0 66.0 69.0 69.0 65.0 19:35:00 INFO - PROCESS | 1681 | -------- Summary: end -------- 19:35:00 INFO - PROCESS | 1681 | 19:35:01 INFO - TEST-INFO | 1681: exit 0 19:35:01 INFO - TEST-OK | kraken | took 57540ms 19:35:01 INFO - SUITE-END | took 1170s 19:35:01 INFO - Completed test suite (00:19:31) 19:35:01 INFO - javascript benchmark 19:35:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3531.77043492173, 3590.3357878792426, 3590.407582748846, 3590.4614307853835, 3675.209829886574, 5789.623674461168, 5829.970850145749, 5843.707814609268, 5853.063509838426, 5858.88282234355, 5833.591648584601, 5854.268216472941, 5865.325487568931, 5868.3838196989345, 5873.500752436048, 5209.973950130244, 5232.764525596357, 5267.1309233976535, 5287.656302340355, 5310.123829568117, 5283.260343551906, 5334.093204155303, 5343.572514198855, 5344.118220493614, 5363.168708850115, 5256.316678831747, 5328.280682107905, 5333.2533445317495, 5344.759485823128, 5349.010433069875, 4463.772462572819, 5323.307969963899, 5323.893522129536, 5334.59990500714, 5355.919661205085, 5147.562457191143, 5180.274761533388, 5251.973740131313, 5272.209168624699, 5278.683279003272, 5739.741711622987, 5741.770329186827, 5748.223989761371, 5771.624844385144, 5778.075507918755, 5156.355455568054, 6256.937430625727, 6271.278802937675, 6272.341404152589, 6272.87454250917, 5985.311689155759, 6130.846728831815, 6229.221347331583, 6247.781327653511, 6264.0, 5956.344802071812, 5957.195778569924, 5996.8500787480225, 6004.54965877561, 6021.668808215502, 6311.368863113652, 6341.207349081365, 6342.334054924258, 6347.365263473615, 6350.301466838644, 6099.4815440688035, 6107.097787096225, 6270.216222972129, 6275.6862156891975, 6286.874262514724, 6200.193974783339, 6269.937300627026, 6275.843103922439, 6286.591371560834, 6288.937110628926, 6201.96899015502, 6229.127922090911, 6250.249970003629, 6277.937220627827, 6294.370562943761, 6198.2252218472695, 6233.096201050822, 6240.65676387794, 6263.467605253604, 6281.748730050838, 4967.130752118437, 5006.799728010911, 5026.52248036436, 5084.46613105626, 5093.694378337312, 4177.101923086551, 4295.484541854998, 4509.864704058883, 4522.0, 4533.320001999727, 5824.388439213906, 6010.549208809357, 6012.068129439944, 6016.278046634345, 6047.758089676452, 6189.566730328924, 6204.224471941007, 6237.781677641352, 6269.341719119518, 6273.37266273369, 5950.137230101697, 6003.219581454383, 6007.549433792483, 6020.367861374581, 6023.126646636213, 5383.300170977748, 5516.475934786189, 5528.917066244009, 5530.198121272473, 5537.390887002426, 6482.967585162044, 6554.246261679968, 6566.212054553484, 6566.540342176002, 6568.80293591193, 6289.119523266655, 6293.181886354928, 6320.083587879823, 6327.493800495858, 6343.841403964938, 6358.1734374531015, 6370.331115233019, 6520.282768895323, 6544.640044797391, 6557.082008518716, 6210.130581718653, 6246.9062964054665, 6249.718762655601, 6260.28006779208, 6283.717232724447, 6251.093591429309, 6255.437010669061, 6256.593321434092, 6257.030160325156, 6259.687015649109, 6100.359462256399, 6136.948001697639, 6230.127782110418, 6233.407826256498, 6274.247090349005, 6230.345813689497, 6245.750169993149, 6250.812475625738, 6258.0, 6280.623162610258, 6059.848503787264, 6103.603265787887, 6133.601315914451, 6136.355682653257, 6143.539234557336, 4653.697509661996, 6127.479164270909, 6134.4172303631085, 6211.720472578654, 6242.812715618538, 6104.145419641342, 6128.662923539248, 6173.074038894022, 6190.473809726043, 6192.752289908533], "value": 5793.093411655546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.9670619762813, 553.1398675060279, 553.2590556812507, 578.8437121977069, 592.899207134787, 31515.0, 39844.0, 40303.59696403035, 40535.79732101336, 40646.593534064654, 43785.840345167235, 43945.78027109868, 44006.0, 44201.11597768051, 44374.55625443745, 8997.370184087116, 9127.726168214931, 9651.372660777062, 9869.210463162966, 9880.110790028895, 11841.407929603529, 12115.03079753622, 12127.090468214876, 12155.331456769874, 12157.331346775924, 11797.469113889852, 11900.702482437922, 11909.225900316495, 11912.166148369617, 11926.821097683542, 11705.648830535098, 11830.526778928832, 11837.0, 11842.171048026641, 11844.289342639426, 11635.709107272301, 11726.237794543327, 11766.117541184445, 11769.882301176964, 11784.823227651592, 42227.788861055495, 42551.0, 42553.36169957422, 42670.0, 42681.35977960333, 3301.273719781668, 3700.2599480104086, 3825.981777262237, 3928.7642741435607, 3976.6619837313865, 29011.274718132216, 29040.0, 29112.8544357279, 29171.854140729374, 29375.55936660952, 2672.1048448769684, 2711.3221694576355, 2743.890244390222, 2769.418422131355, 2810.732980366882, 518687.0, 520560.39719801536, 521078.0, 521482.0, 524057.0, 59281.110783338285, 68201.0, 69067.30932690609, 69078.96381554281, 69191.96212056823, 64215.6789216051, 64305.71388572203, 64691.67654161699, 64733.67633161898, 64734.676326618064, 17163.88434751737, 17258.654826903643, 17285.135743212788, 17298.308067677153, 17323.826761732475, 12638.684032899251, 12817.551385701456, 13580.660483487993, 13955.441782328797, 13993.930030349784, 17990.460286191435, 18046.097695115455, 18472.891626502453, 18568.628627427377, 18600.906995464935, 6113.480354169857, 6114.755409783559, 7269.273072692794, 7308.415326773846, 7350.191478937312, 1012.4380968562518, 1062.5112448273726, 1082.3073233130804, 1128.3624752015085, 1131.219458573598, 30083.398332033237, 30657.08028759141, 30720.0, 31465.213369665482, 31634.525482117788, 1092.9835253214587, 1133.6429024857143, 1183.7099910521806, 1229.16416836552, 1285.6464472270013, 15633.68732625364, 15973.920130399507, 15996.76004859928, 16052.678946421007, 16298.67402651964, 602627.0, 608871.0, 611733.0, 614863.0, 615874.0, 63454.36545634484, 63545.0, 63720.0, 63797.681011595574, 63924.68037659782, 1391.1096897985296, 1529.296523599157, 1567.130242715304, 1572.4653617770014, 1577.5740550051191, 26256.34359141037, 29205.85397073001, 29533.261668457602, 29717.257068572595, 30076.5488517668, 48900.5109948896, 62454.687726561075, 63315.366846330944, 63341.0, 63373.683131585894, 424722.0, 429522.0, 430001.0, 430368.0, 432905.0, 25126.12058577941, 28036.57945130784, 28144.71855281421, 28661.0, 28909.56635650425, 46168.538314616424, 46456.535434646576, 46812.7659361701, 46989.76505117452, 48070.5192948066, 37156.44265335968, 38258.61741382551, 38439.807800960814, 38508.61491385163, 38994.63518776829, 37757.05607359838, 38154.61845381511, 38159.0, 38491.0, 38908.02729931774, 37638.0, 37937.62062379341, 38049.0, 38448.80775596104, 38540.0, 32068.83965580157, 32822.5076623846, 33861.66138338585, 33891.830540847135, 34050.0, 24585.385365366008, 24847.378815529755, 24987.305222282925, 25220.74779252184, 25334.619980700674], "value": 20753.52325789256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.4507132977476, 414.3116799043897, 415.0868090201557, 416.6958120571982, 424.51605170106063, 19682.0, 20745.688814667774, 21450.0, 21455.463613409654, 21646.567068658616, 20950.0, 21301.25445609404, 22030.669539956874, 22081.889590552066, 22100.22649207278, 5669.291338582688, 6264.780732674358, 6268.279147898004, 6284.905726414107, 6287.654179020152, 5123.743812809364, 6241.375862413768, 6242.219722534661, 6253.4684551812925, 6264.0, 5692.715364231793, 6010.459058684717, 6016.2479690038745, 6017.39826017397, 6020.488258498011, 5748.2527271454655, 5892.204552385416, 5984.222051133346, 6099.451049405575, 6107.4197951194565, 5949.077892926603, 5955.34491205967, 5973.253343332083, 5981.401859814027, 5994.340622531518, 16080.678386432322, 26719.931202751868, 27096.729032709616, 27100.728992710017, 27155.1853444397, 2292.942676433086, 2331.5453486570127, 2703.931946880971, 2823.181277429543, 2870.6555213374318, 9826.361286516425, 9850.704478865573, 10153.796924061551, 10195.745106372326, 10501.737456563571, 1735.6355165415277, 1822.2164469278205, 1858.3310008396966, 1884.9434516964513, 1995.3016444244543, 106680.46659766651, 114534.0, 114828.42585787101, 116508.41745791302, 116549.0, 9878.703638890845, 11512.251703639236, 11768.411579420994, 11807.527698891949, 11838.230515016669, 15160.545183644508, 15169.86226033052, 15225.543233703007, 15225.695486090217, 15230.857685673618, 7534.020577325023, 8803.647854085764, 9177.174054335143, 9188.356815023018, 9230.584623691815, 9213.493257870747, 9220.0, 9229.446233226028, 9308.208802251884, 9310.115539023782, 4059.6752259819145, 4799.784009719501, 4863.537963893425, 4896.828610998598, 4940.110780059552, 1990.4526255279625, 2026.8175864172294, 2028.0, 2058.1458694641856, 2093.2987449204384, 887.7913690282778, 974.7563109222552, 976.7411635916487, 984.1683777208248, 987.6148302162162, 18203.54491137732, 20128.49678758043, 21638.02628881704, 21893.452663683536, 21926.45183870416, 968.5254225429629, 997.3941953454931, 1020.2959957629212, 1034.7413146713322, 1074.3231763988638, 9682.90317096834, 11173.888261117447, 11569.595064172714, 11690.415479226005, 11719.531218751154, 487375.57307629246, 537908.0, 539179.0, 672097.0, 675888.0, 90564.547177265, 108071.91928080619, 118444.8155518451, 127308.0, 127753.0, 1037.2169012395593, 1062.829947208443, 1079.5681727309138, 1079.7030816525519, 1080.4381721504774, 19157.233710651977, 19513.70729439031, 19982.400527984184, 20038.599228015068, 20272.391828245178, 33593.328133436706, 56094.43905560892, 56232.0, 57039.0, 57153.14270285866, 81600.59199703964, 84518.0, 85348.0, 85745.57127214324, 87156.0, 8109.553974531457, 8170.182981701782, 8260.256576907866, 8264.049634292134, 8503.829923401374, 10621.946890265808, 12936.870631293566, 13036.087473876738, 13101.213927164401, 13116.868831311565, 10752.51613677402, 11351.318920864775, 11363.261388009752, 11469.598564050551, 11494.367809770543, 10916.290441121302, 11031.503582338653, 11143.498542565441, 11277.379744114152, 11464.656060318204, 11166.665000050012, 11438.714032149262, 11558.94220528892, 11701.06391488696, 11723.706907327385, 7994.800129996564, 8068.677252909818, 8103.311218546254, 8389.406012857782, 8629.22336989674, 10968.780624387628, 11203.775924481302, 11359.54561817518, 11555.364454955059, 11729.824052639047], "value": 9839.364542075424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.5026915983144, 1528.258794484675, 1654.3796076471326, 1902.5053486093611, 2020.4746765840878, 8162.91837081629, 8803.823923521526, 8976.922769267689, 8981.05698901614, 8986.820263594724, 8636.697715579956, 8910.866337004934, 8917.286617070635, 8924.687635932727, 8939.910600893989, 2195.8462907596477, 2515.8490490570534, 2552.068494999321, 2554.3231043773417, 2557.1689201009654, 2471.122751423246, 2538.4288535079645, 2541.606051062088, 2543.987280063597, 2550.6174073888974, 2499.7375275596073, 2528.734482879299, 2547.757962993522, 2548.808839337057, 2558.872056397182, 2503.7621425964503, 2525.5454018276705, 2529.962050569243, 2539.7587229213286, 2552.808539359546, 2163.3077415227135, 2438.987805060963, 2464.581021226396, 2493.0900221419233, 2543.6438898554215, 8593.398462107654, 8782.516961567111, 8969.192772650493, 9035.277177825825, 9053.0, 5557.638753481051, 5659.971700141514, 5691.686957217351, 5717.285339332583, 5719.857003574903, 3648.562172539312, 3697.6150383003082, 3760.7555508891833, 3788.753731007503, 3795.62043795621, 1064.3720205079042, 1074.4949873559415, 1075.424647813416, 1097.2812807611028, 1109.9278546894427, 12268.079894007897, 12394.070444716683, 12404.007679385628, 12404.875951240463, 12415.317157556241, 4695.765211739399, 4698.718076915396, 4827.203511420576, 4828.46886842447, 4856.4415092264135, 9590.232781377474, 9617.951910240545, 9626.614935402506, 9630.085141911508, 9631.181349585224, 5504.697241651667, 5504.752286147133, 5542.418046105182, 5548.4451554845, 5549.361823390281, 4491.932621010687, 4576.336431217454, 4927.457979622239, 5022.748862556858, 5150.562202212779, 3907.941380879289, 3972.6027397260623, 4018.557958624549, 4034.1931613677384, 4038.6567141793275, 1225.993870030644, 1241.8013117901091, 1242.3788105947026, 1254.3352023427599, 1256.2274201366276, 944.9007854175212, 963.3834346018554, 966.4539535162611, 981.3081777346953, 1019.5412064570972, 5940.168376427304, 6065.8180254592435, 6100.511959043266, 6126.081087836771, 6295.307516173218, 868.9304855611535, 972.6595691508057, 973.8216757723234, 979.4515071559949, 1026.481626778487, 4000.3599424092004, 4061.248668996245, 4077.4699289092227, 4097.97951010249, 4124.546299907008, 10666.679999600025, 11021.944890275498, 11040.779184416428, 11078.723031924266, 11162.0, 10830.620928267474, 10967.945160274148, 11020.173486988508, 11049.226554141133, 11058.557657693764, 8149.307308878693, 8525.360597955054, 8659.567021648892, 8663.48019118855, 8664.350173736995, 5943.732532036155, 5965.761369545169, 6138.416850399205, 6150.138980542817, 6278.246610406781, 9507.477088760184, 9845.163161131097, 9992.050755178247, 10015.499225038573, 10034.69895903124, 9985.301028927905, 10030.49847507607, 10147.79704405893, 10159.695209143738, 10301.227407944285, 6722.899156512754, 7044.964775176091, 7241.927580724126, 7520.285572870568, 7781.221877812173, 6709.396154346132, 7710.383169346551, 8498.70254541088, 8563.914360856561, 8588.141185881612, 7415.1101867776215, 7426.777196684108, 7487.438442116972, 7661.731839385595, 7753.922460775546, 7366.337029667356, 7389.224131466119, 7434.3309102181065, 7462.067241594801, 7488.588127653032, 7170.318819712119, 7176.354128128493, 7192.748253811092, 7213.9278607213255, 7215.927840721735, 4879.780409881635, 4946.282788995503, 5000.87497812543, 5101.0308041472, 5142.871428214175, 5032.84901452957, 5045.621578381562, 5068.6451948363265, 5158.819441319536, 5310.495502927216], "value": 4716.885935309486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.0541262813609, 339.20456529438474, 343.1489905035512, 346.62910685566453, 350.4812876942127, 5997.2803263608375, 6216.880961426941, 6722.899156512656, 6734.29289924557, 6766.526343155975, 6678.065070890079, 6683.832904177398, 6692.665366731669, 6697.430718388943, 6724.865502689955, 4263.0, 4769.594584460317, 4883.145449546333, 4887.6089912807065, 4923.532264434891, 4879.048585525824, 4909.263610458442, 4984.675996060263, 5005.324281222025, 5016.573591244749, 4931.235658472942, 4936.284238785374, 4944.0, 4945.282933974571, 4950.6782059166035, 4717.386739723831, 4762.761861906908, 4896.730679812609, 4926.11329960607, 4938.950610493885, 4258.5528519505315, 4263.765492897889, 4264.552222016675, 4268.935965960513, 4268.996785755345, 5270.631055826094, 6456.289808121103, 6473.74105035798, 6489.22129344477, 6498.870022599569, 5795.362510123883, 5816.563757718188, 5843.948089343917, 5844.912326315109, 5866.090755932837, 6863.142107236596, 7099.751508697171, 7110.217876033632, 7118.679659415338, 7151.499395042297, 4634.0, 4675.13510000651, 4675.597320803745, 4687.812487500496, 4689.179393606105, 7550.395968322521, 7632.503887247367, 7646.770596882103, 7649.196834332371, 7665.808354791119, 4726.243800991825, 4737.170995075848, 4738.407699037621, 4739.3838800955655, 4740.573348398591, 4791.784369703371, 4799.544043315879, 4810.855674329776, 4817.27740838877, 4822.480918510658, 5525.364583072917, 5780.306363236438, 5781.82654520365, 5782.6819524925695, 5803.767849285981, 7100.431965442752, 7116.537425067458, 7130.0, 7159.713611455483, 7176.569405835667, 1638.9344426222817, 1665.7334826427714, 1669.699454098274, 1670.0313817985539, 1672.272561435755, 992.7666998255404, 1008.3496144986443, 1036.5750042482546, 1044.7335929337971, 1065.9733506662396, 5322.467753224724, 6696.0, 6723.831904202434, 6840.110785597841, 6897.862042759117, 7555.659995300225, 7556.65995030225, 7568.5837278950185, 7572.0, 7579.5073320234005, 5582.190582365534, 6461.127747754087, 6842.452603791685, 6857.725690972405, 6875.346842049998, 3854.807259637007, 3964.72246942717, 4000.3999400089638, 4000.7799571023293, 4005.899852503711, 7935.246151615703, 7942.6425810838655, 7951.204879512118, 7951.363890888715, 7983.760487185394, 7692.230776922375, 7703.499272547267, 7707.92292077072, 7747.341475974493, 7750.806229844186, 7902.565358905315, 7916.366690664733, 7928.405369597303, 7940.642671079931, 7953.0, 7248.420126389981, 7540.622968851645, 7540.811479713051, 7542.811429714301, 7547.433942454448, 7385.335319821242, 7414.110306763007, 7445.218252083453, 7447.441441891771, 7459.589722565311, 7848.704963680929, 7976.680932762624, 7978.680852765824, 7987.0, 8013.038435387791, 4204.4324016257415, 4741.980474198064, 4759.119562880878, 4762.714237145782, 4762.880927976828, 7222.96388518054, 7496.137944136494, 7497.887531687138, 7512.774616761506, 7535.284148005931, 7288.0, 7309.817254568678, 7328.450366222448, 7334.96332518334, 7340.0457940467395, 7269.709211631475, 7278.490505664553, 7290.088738907637, 7301.306375894282, 7306.707731690885, 7143.678534465857, 7152.892706609509, 7166.462515311268, 7173.892391614026, 7174.928250717426, 6956.095707557985, 7271.636418179176, 7286.526375785557, 7292.525985810906, 7293.124825021031, 6780.762673306411, 6897.275786042594, 6970.721171153097, 6994.720211191496, 7138.072050633385], "value": 5268.4838575219965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.3152173913044, 727.2763600217784, 760.7185341423674, 768.561919705768, 811.2617518058568, 15175.696486070272, 15473.767893481607, 15521.767173492379, 15526.844731552681, 15549.922250388761, 14134.787978180335, 14220.502282420117, 14228.857711422883, 14236.430542778277, 14239.145651260931, 765.7128576783706, 779.0378882080626, 942.8114377124568, 943.4669411782355, 971.946542940138, 948.981020379592, 958.2573505533232, 959.7312752429297, 961.5817119552981, 963.4604621411996, 1034.4724190662741, 1035.1770342577631, 1037.4138611684375, 1037.8858325584179, 1040.8230600797845, 816.3754727633366, 1000.554753134859, 1019.0421004255976, 1022.2128960700219, 1030.386919782728, 763.107164617399, 764.0907320288838, 764.4877931785717, 767.3630886364305, 767.7965339185121, 14553.908456865778, 14635.0, 14639.853601464061, 14652.267386630732, 14655.34050967709, 12898.355082245851, 13147.671308217276, 13241.940644748396, 13289.3355332233, 13296.0, 10333.58665653373, 10358.585656573729, 10382.636607718769, 10664.986826251494, 11164.0, 3534.452159915217, 3550.786952782841, 3593.964060359389, 3659.743817932746, 3809.5428548574073, 81675.18324816794, 82159.0, 82428.0, 83896.0, 84205.57897210475, 8403.747887563382, 9657.613695452244, 9712.660056897974, 9807.019298070136, 9826.0, 21886.45283867916, 22480.550388992127, 22529.0, 22542.436439088826, 22545.759983200755, 7798.454108212371, 7856.0, 7969.72105976288, 8161.55111468863, 8261.504309741435, 4309.956900431018, 4314.762688052125, 4363.258246098171, 4367.192069467159, 4480.014396832692, 3712.4988126603103, 3775.716821238418, 3778.319902417591, 3779.1874746929543, 3786.6970642348547, 646.12127506591, 651.6611362091686, 674.7166190200128, 675.7195763758084, 710.5559025608994, 538.5045757902739, 577.9190913272146, 588.9646621202655, 604.1421181921594, 630.501903496242, 8759.518226497476, 8804.47173169612, 8819.206271435602, 9128.954355228181, 9155.028072488865, 507.1024287011968, 509.8368522072975, 524.0279281932037, 585.0989476206679, 632.4466092169351, 3709.4435834624483, 4064.227796718614, 4104.8152833122585, 4134.73124246923, 4143.215669968403, 88717.0, 95189.0, 97500.0, 97549.0, 97722.5113874426, 73457.89813152807, 77044.61477692575, 79781.0, 80456.79314810282, 83450.58274708588, 69242.65378673175, 70024.64987675131, 70323.64838175777, 72144.63927680328, 72145.0, 8305.792355191168, 8563.785905352166, 8621.482711037357, 8777.736667899719, 8933.46399216049, 43358.91602709958, 45705.085898282516, 46293.0, 47825.043499130516, 47833.52166478291, 66334.0, 67297.66351168213, 70267.29732702812, 70458.59082818417, 70577.64711176412, 11712.180147389601, 11787.587434439753, 11929.880701192877, 12035.217710848767, 12474.0, 11942.522299107939, 12195.882565501252, 12249.816252756395, 12948.482060717466, 13030.478780849038, 11607.419629018346, 11938.403079845799, 11974.161808673309, 12134.271943683407, 12342.197757145757, 11881.346525941157, 11894.881051189377, 11921.463534141158, 12160.635180944586, 12186.903178713603, 11718.70703232426, 11724.413779311171, 11818.881811181778, 11880.82178767302, 12116.394180290774, 9707.271954603575, 9884.962078981604, 10116.696499105039, 10426.635067772591, 10492.108170805566, 8623.8706419405, 9531.38046027006, 9794.363366381162, 10118.291719579558, 10119.949400252952], "value": 7139.568953563721, "unit": "score"}], "name": "dromaeo_css", "value": 7709.0932212097005, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 118.0, 111.0, 112.0, 111.0, 113.0, 113.0, 112.0, 133.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 139.0, 163.0, 116.0, 136.0, 116.0, 151.0, 152.0, 120.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 185.0, 186.0, 185.0, 184.0, 185.0, 184.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 71.0, 154.0, 65.0, 73.0, 82.0, 88.0, 161.0, 55.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 136.0, 79.0, 86.0, 91.0, 124.0, 79.0, 76.0, 127.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 88.0, 84.0, 89.0, 85.0, 89.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 106.0, 148.0, 121.0, 94.0, 94.0, 94.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 86.0, 82.0, 85.0, 86.0, 83.0, 82.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 63.0, 63.0, 62.0, 64.0, 65.0, 83.0, 59.0, 58.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 67.0, 65.0, 75.0, 72.0, 71.0, 65.0, 66.0, 66.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 79.0, 80.0, 81.0, 78.0, 79.0, 79.0, 80.0, 83.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 137.0, 127.0, 136.0, 142.0, 119.0, 142.0, 134.0, 155.0, 135.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 163.0, 165.0, 166.0, 154.0, 163.0, 161.0, 162.0, 158.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 67.0, 66.0, 67.0, 66.0, 69.0, 69.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1475.5, "alertThreshold": 2.0}]} 19:35:01 INFO - Return code: 0 19:35:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:35:01 ERROR - # TBPL SUCCESS # 19:35:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:35:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:35:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:35:01 INFO - 2016-07-03 19:35:01.729 screenresolution[1685:10436] starting screenresolution argv=screenresolution get 19:35:01 INFO - 2016-07-03 19:35:01.731 screenresolution[1685:10436] Display 0: 1600x1200x32@60 19:35:01 INFO - 2016-07-03 19:35:01.737 screenresolution[1686:10439] starting screenresolution argv=screenresolution list 19:35:01 INFO - Available Modes on Display 0 19:35:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:35:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:35:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:35:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:35:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:35:01 INFO - Intel Iris: 19:35:01 INFO - Chipset Model: Intel Iris 19:35:01 INFO - Type: GPU 19:35:01 INFO - Bus: Built-In 19:35:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:35:01 INFO - Vendor: Intel (0x8086) 19:35:01 INFO - Device ID: 0x0a2e 19:35:01 INFO - Revision ID: 0x0009 19:35:01 INFO - Displays: 19:35:01 INFO - Display: 19:35:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:35:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:35:01 INFO - Display Serial Number: 1600x1200 60 19:35:01 INFO - Main Display: Yes 19:35:01 INFO - Mirror: Off 19:35:01 INFO - Online: Yes 19:35:01 INFO - Rotation: Supported 19:35:01 INFO - Return code: 0 19:35:01 INFO - Running post-action listener: _resource_record_post_action 19:35:01 INFO - Running post-run listener: _resource_record_post_run 19:35:03 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 31686144; Write bytes: 494795776; Read time: 201; Write time: 1524 19:35:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 193430016; Write bytes: 187361280; Read time: 17667; Write time: 421 19:35:03 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 29904384; Write bytes: 297694208; Read time: 187; Write time: 1074 19:35:03 INFO - Running post-run listener: _upload_blobber_files 19:35:03 INFO - Blob upload gear active. 19:35:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:35:03 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 19:35:03 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:35:03 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:35:03 INFO - (blobuploader) - INFO - Open directory for files ... 19:35:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:35:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:35:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:04 INFO - (blobuploader) - INFO - Done attempting. 19:35:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:35:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:35:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:05 INFO - (blobuploader) - INFO - Done attempting. 19:35:05 INFO - (blobuploader) - INFO - Iteration through files over. 19:35:05 INFO - Return code: 0 19:35:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:35:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:35:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be898d96483ce084e3118143b9f01c6dd39c33639712bf888025e7b6e7beeba3d21930b711767e0612a74f10158869569ffba5046f02483a63841b63dfb9d94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89888617245a1e879165bc24b132b853ac18469bf57f2450061ba06b1e0c2a6e71f429e00fcda9daf24dab02b4d335001ae7d8a4c18931b6239b7eaa8cd75678"} 19:35:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:35:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:35:05 INFO - Contents: 19:35:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be898d96483ce084e3118143b9f01c6dd39c33639712bf888025e7b6e7beeba3d21930b711767e0612a74f10158869569ffba5046f02483a63841b63dfb9d94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89888617245a1e879165bc24b132b853ac18469bf57f2450061ba06b1e0c2a6e71f429e00fcda9daf24dab02b4d335001ae7d8a4c18931b6239b7eaa8cd75678"} 19:35:05 INFO - Running post-run listener: copy_logs_to_upload_dir 19:35:05 INFO - Copying logs to upload dir... 19:35:05 INFO - mkdir: /builds/slave/test/build/upload/logs 19:35:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.252710 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-03 19:35:05.585193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 19:35:05.588597) ========= 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.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-Beta/sha512/7be898d96483ce084e3118143b9f01c6dd39c33639712bf888025e7b6e7beeba3d21930b711767e0612a74f10158869569ffba5046f02483a63841b63dfb9d94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89888617245a1e879165bc24b132b853ac18469bf57f2450061ba06b1e0c2a6e71f429e00fcda9daf24dab02b4d335001ae7d8a4c18931b6239b7eaa8cd75678"} build_url:https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010835 build_url: 'https://queue.taskcluster.net/v1/task/QpnO2g9LRTa1MLnrSnfe4A/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be898d96483ce084e3118143b9f01c6dd39c33639712bf888025e7b6e7beeba3d21930b711767e0612a74f10158869569ffba5046f02483a63841b63dfb9d94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89888617245a1e879165bc24b132b853ac18469bf57f2450061ba06b1e0c2a6e71f429e00fcda9daf24dab02b4d335001ae7d8a4c18931b6239b7eaa8cd75678"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 19:35:05.621564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:35:05.621911) ========= 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.wPaP9cBzGE/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.ippjlMHVch/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005067 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 19:35:05.671116) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 19:35:05.671430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 19:35:05.671774) ========= ========= Total master_lag: 0.18 =========