builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0261 starttime: 1467735983.83 results: success (0) buildid: 20160705070425 builduid: 2fd3ad30233b4024ac7b02eb38056bd3 revision: dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:23.828957) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:23.829423) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:23.829735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:23.853555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:23.854114) ========= 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.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-05 09:26:23-- 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% 147M=0s 2016-07-05 09:26:23 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:24.001853) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:24.002263) ========= 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.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.020526 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:24.051821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:24.052294) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-05 09:26:24,134 truncating revision to first 12 chars 2016-07-05 09:26:24,134 Setting DEBUG logging. 2016-07-05 09:26:24,134 attempt 1/10 2016-07-05 09:26:24,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dbb31bcad5a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 09:26:24,795 unpacking tar archive at: mozilla-central-dbb31bcad5a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.940822 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:25.015859) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:25.016247) ========= script_repo_revision: dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:25.016698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:25.017053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:26:25.036598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-05 09:26:25.037018) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:25 INFO - MultiFileLogger online at 20160705 09:26:25 in /builds/slave/test 09:26:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:26:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:25 INFO - {'append_to_log': False, 09:26:25 INFO - 'base_work_dir': '/builds/slave/test', 09:26:25 INFO - 'blob_upload_branch': 'Firefox', 09:26:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:25 INFO - 'branch': 'Firefox', 09:26:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:25 INFO - 'config_files': ('talos/mac_config.py',), 09:26:25 INFO - 'default_actions': ('clobber', 09:26:25 INFO - 'read-buildbot-config', 09:26:25 INFO - 'download-and-extract', 09:26:25 INFO - 'populate-webroot', 09:26:25 INFO - 'create-virtualenv', 09:26:25 INFO - 'install', 09:26:25 INFO - 'run-tests'), 09:26:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:25 INFO - 'download_minidump_stackwalk': True, 09:26:25 INFO - 'download_symbols': 'ondemand', 09:26:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:25 INFO - 'installer_path': 'installer.exe', 09:26:25 INFO - 'log_level': 'info', 09:26:25 INFO - 'log_name': 'talos', 09:26:25 INFO - 'log_to_console': True, 09:26:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:25 INFO - 'opt_config_files': (), 09:26:25 INFO - 'pip_index': False, 09:26:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:25 INFO - 'cmd': ('bash', 09:26:25 INFO - '-c', 09:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:25 INFO - 'enabled': True, 09:26:25 INFO - 'halt_on_failure': False, 09:26:25 INFO - 'name': 'check_screen_resolution'},), 09:26:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:25 INFO - 'cmd': ('bash', 09:26:25 INFO - '-c', 09:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:25 INFO - 'enabled': True, 09:26:25 INFO - 'halt_on_failure': False, 09:26:25 INFO - 'name': 'check_screen_resolution'},), 09:26:25 INFO - 'run_cmd_checks_enabled': True, 09:26:25 INFO - 'sps_profile': False, 09:26:25 INFO - 'sps_profile_interval': 0, 09:26:25 INFO - 'suite': 'dromaeojs', 09:26:25 INFO - 'system_bits': '32', 09:26:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:25 INFO - 'title': 't-yosemite-r7-0261', 09:26:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:25 INFO - 'use_talos_json': True, 09:26:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:25 INFO - 'work_dir': 'build'} 09:26:25 INFO - ##### 09:26:25 INFO - ##### Running clobber step. 09:26:25 INFO - ##### 09:26:25 INFO - Running pre-action listener: _resource_record_pre_action 09:26:25 INFO - Running main action method: clobber 09:26:25 INFO - rmtree: /builds/slave/test/build 09:26:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:26 INFO - Running post-action listener: _resource_record_post_action 09:26:26 INFO - ##### 09:26:26 INFO - ##### Finished clobber step (success) 09:26:26 INFO - ##### 09:26:26 INFO - ##### 09:26:26 INFO - ##### Running read-buildbot-config step. 09:26:26 INFO - ##### 09:26:26 INFO - Running pre-action listener: _resource_record_pre_action 09:26:26 INFO - Running main action method: read_buildbot_config 09:26:26 INFO - Using buildbot properties: 09:26:26 INFO - { 09:26:26 INFO - "project": "", 09:26:26 INFO - "product": "firefox", 09:26:26 INFO - "script_repo_revision": "production", 09:26:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:26:26 INFO - "repository": "", 09:26:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:26:26 INFO - "buildid": "20160705070425", 09:26:26 INFO - "pgo_build": "False", 09:26:26 INFO - "basedir": "/builds/slave/test", 09:26:26 INFO - "buildnumber": 277, 09:26:26 INFO - "slavename": "t-yosemite-r7-0261", 09:26:26 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:26:26 INFO - "platform": "yosemite_r7", 09:26:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:26:26 INFO - "branch": "mozilla-central", 09:26:26 INFO - "repo_path": "mozilla-central", 09:26:26 INFO - "revision": "dbb31bcad5a1f60a35b5600ea1578d9b9fa55237", 09:26:26 INFO - "stage_platform": "macosx64", 09:26:26 INFO - "builduid": "2fd3ad30233b4024ac7b02eb38056bd3", 09:26:26 INFO - "slavebuilddir": "test" 09:26:26 INFO - } 09:26:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:26:26 INFO - Running post-action listener: _resource_record_post_action 09:26:26 INFO - ##### 09:26:26 INFO - ##### Finished read-buildbot-config step (success) 09:26:26 INFO - ##### 09:26:26 INFO - ##### 09:26:26 INFO - ##### Running download-and-extract step. 09:26:26 INFO - ##### 09:26:26 INFO - Running pre-action listener: _resource_record_pre_action 09:26:26 INFO - Running main action method: download_and_extract 09:26:26 INFO - mkdir: /builds/slave/test/build/tests 09:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:26 INFO - https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:26:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:26:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:26:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:26:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:26:28 INFO - Downloaded 1199 bytes. 09:26:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:26:28 INFO - Using the following test package requirements: 09:26:28 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:26:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:26:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:26:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'jsshell-mac.zip'], 09:26:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:26:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:26:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:26:28 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:26:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:26:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:26:28 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:26:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:26: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')]} 09:26:28 INFO - https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:26:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:32 INFO - Downloaded 18801566 bytes. 09:26:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:26:35 INFO - Return code: 0 09:26:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:35 INFO - https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:26:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:36 INFO - Downloaded 18801566 bytes. 09:26:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:26:39 INFO - Return code: 0 09:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:39 INFO - https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:26:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:26:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:26:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:26:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:26:40 INFO - Downloaded 14403333 bytes. 09:26:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:26:41 INFO - Return code: 0 09:26: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')]} 09:26:41 INFO - https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:26:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:26:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:26:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:26:44 INFO - Downloaded 112538017 bytes. 09:26:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:26:44 INFO - mkdir: /builds/slave/test/properties 09:26:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:44 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:44 INFO - Contents: 09:26:44 INFO - build_url:https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:26:45 INFO - Running post-action listener: _resource_record_post_action 09:26:45 INFO - Running post-action listener: set_extra_try_arguments 09:26:45 INFO - ##### 09:26:45 INFO - ##### Finished download-and-extract step (success) 09:26:45 INFO - ##### 09:26:45 INFO - ##### 09:26:45 INFO - ##### Running populate-webroot step. 09:26:45 INFO - ##### 09:26:45 INFO - Running pre-action listener: _resource_record_pre_action 09:26:45 INFO - Running main action method: populate_webroot 09:26:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:45 INFO - '--tppagecycles', 09:26:45 INFO - '7'], 09:26:45 INFO - 'tests': ['tsvgm']}}, 09:26:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['damp', 'tps']}, 09:26:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'tests': ['damp', 'tps']}, 09:26:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['dromaeo_dom']}, 09:26:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['basic_compositor_video']}, 09:26:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['a11yr', 09:26:45 INFO - 'ts_paint', 09:26:45 INFO - 'tpaint', 09:26:45 INFO - 'sessionrestore', 09:26:45 INFO - 'sessionrestore_no_auto_restore', 09:26:45 INFO - 'tabpaint']}, 09:26:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['tsvgx', 09:26:45 INFO - 'tsvgr_opacity', 09:26:45 INFO - 'tart', 09:26:45 INFO - 'tscrollx', 09:26:45 INFO - 'cart']}, 09:26:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:45 INFO - 'tsvgr_opacity', 09:26:45 INFO - 'tart', 09:26:45 INFO - 'tscrollx', 09:26:45 INFO - 'cart']}, 09:26:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'talos_options': ['--disable-e10s'], 09:26:45 INFO - 'tests': ['tp5o']}, 09:26:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'tests': ['tp5o']}, 09:26:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'talos_options': ['--disable-e10s', 09:26:45 INFO - '--xperf_path', 09:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:45 INFO - 'tests': ['tp5n']}, 09:26:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:45 INFO - 'talos_options': ['--xperf_path', 09:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:45 INFO - 'tests': ['tp5n']}}, 09:26:45 INFO - 'talos.zip': {'path': '', 09:26:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:45 INFO - Running post-action listener: _resource_record_post_action 09:26:45 INFO - ##### 09:26:45 INFO - ##### Finished populate-webroot step (success) 09:26:45 INFO - ##### 09:26:45 INFO - ##### 09:26:45 INFO - ##### Running create-virtualenv step. 09:26:45 INFO - ##### 09:26:45 INFO - Running pre-action listener: _resource_record_pre_action 09:26:45 INFO - Running main action method: create_virtualenv 09:26:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:45 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 09:26:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:45 INFO - Using real prefix '/tools/python27' 09:26:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:48 INFO - Installing pip.................done. 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:48 INFO - 'HOME': '/Users/cltbld', 09:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PAGER': '/bin/cat', 09:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:26:48 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - 'XPC_FLAGS': '0x0', 09:26:48 INFO - 'XPC_SERVICE_NAME': '0', 09:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:49 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 09:26:49 INFO - Cleaning up... 09:26:49 INFO - Return code: 0 09:26:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:49 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')]} 09:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:49 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')]} 09:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:49 INFO - 'HOME': '/Users/cltbld', 09:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:49 INFO - 'LOGNAME': 'cltbld', 09:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:49 INFO - 'MOZ_NO_REMOTE': '1', 09:26:49 INFO - 'NO_EM_RESTART': '1', 09:26:49 INFO - 'PAGER': '/bin/cat', 09:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:49 INFO - 'PWD': '/builds/slave/test', 09:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:49 INFO - 'SHELL': '/bin/bash', 09:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:26:49 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:49 INFO - 'USER': 'cltbld', 09:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:49 INFO - 'XPC_FLAGS': '0x0', 09:26:49 INFO - 'XPC_SERVICE_NAME': '0', 09:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:49 INFO - Downloading/unpacking psutil>=3.1.1 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:52 INFO - Installing collected packages: psutil 09:26:52 INFO - Running setup.py install for psutil 09:26:52 INFO - building 'psutil._psutil_osx' extension 09:26:52 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 09:26:52 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 09:26:52 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 09:26:53 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 09:26:53 INFO - building 'psutil._psutil_posix' extension 09:26:53 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 09:26:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:53 INFO - ^ 09:26:53 INFO - 1 warning generated. 09:26:53 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 09:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:53 INFO - Successfully installed psutil 09:26:53 INFO - Cleaning up... 09:26:53 INFO - Return code: 0 09:26:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:53 INFO - 'HOME': '/Users/cltbld', 09:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:53 INFO - 'LOGNAME': 'cltbld', 09:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:53 INFO - 'MOZ_NO_REMOTE': '1', 09:26:53 INFO - 'NO_EM_RESTART': '1', 09:26:53 INFO - 'PAGER': '/bin/cat', 09:26:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:53 INFO - 'PWD': '/builds/slave/test', 09:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:53 INFO - 'SHELL': '/bin/bash', 09:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:26:53 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:53 INFO - 'USER': 'cltbld', 09:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:53 INFO - 'XPC_FLAGS': '0x0', 09:26:53 INFO - 'XPC_SERVICE_NAME': '0', 09:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:53 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 09:26:53 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 09:26:53 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 09:26:53 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 09:26:53 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 09:26:53 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 09:26:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:56 INFO - Installing collected packages: mozsystemmonitor 09:26:56 INFO - Running setup.py install for mozsystemmonitor 09:26:56 INFO - Successfully installed mozsystemmonitor 09:26:56 INFO - Cleaning up... 09:26:56 INFO - Return code: 0 09:26:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:56 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')]} 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:56 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')]} 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:56 INFO - 'HOME': '/Users/cltbld', 09:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:56 INFO - 'LOGNAME': 'cltbld', 09:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:56 INFO - 'MOZ_NO_REMOTE': '1', 09:26:56 INFO - 'NO_EM_RESTART': '1', 09:26:56 INFO - 'PAGER': '/bin/cat', 09:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:56 INFO - 'PWD': '/builds/slave/test', 09:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:56 INFO - 'SHELL': '/bin/bash', 09:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:26:56 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:56 INFO - 'USER': 'cltbld', 09:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:56 INFO - 'XPC_FLAGS': '0x0', 09:26:56 INFO - 'XPC_SERVICE_NAME': '0', 09:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:56 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 09:26:56 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 09:26:56 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 09:26:56 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 09:26:56 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 09:26:56 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 09:26:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:59 INFO - warning: no files found matching '*.txt' 09:26:59 INFO - no previously-included directories found matching 'build' 09:26:59 INFO - no previously-included directories found matching 'dist' 09:26:59 INFO - no previously-included directories found matching '.git*' 09:26:59 INFO - Installing collected packages: jsonschema, functools32 09:26:59 INFO - Running setup.py install for functools32 09:26:59 INFO - warning: no files found matching '*.txt' 09:26:59 INFO - no previously-included directories found matching 'build' 09:26:59 INFO - no previously-included directories found matching 'dist' 09:26:59 INFO - no previously-included directories found matching '.git*' 09:26:59 INFO - Successfully installed jsonschema functools32 09:26:59 INFO - Cleaning up... 09:26:59 INFO - Return code: 0 09:26:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:59 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')]} 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:59 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')]} 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:59 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 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:26:59 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 09:26:59 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 09:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:59 INFO - 'HOME': '/Users/cltbld', 09:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:59 INFO - 'LOGNAME': 'cltbld', 09:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:59 INFO - 'MOZ_NO_REMOTE': '1', 09:26:59 INFO - 'NO_EM_RESTART': '1', 09:26:59 INFO - 'PAGER': '/bin/cat', 09:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:59 INFO - 'PWD': '/builds/slave/test', 09:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:59 INFO - 'SHELL': '/bin/bash', 09:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:26:59 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:59 INFO - 'USER': 'cltbld', 09:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:59 INFO - 'XPC_FLAGS': '0x0', 09:26:59 INFO - 'XPC_SERVICE_NAME': '0', 09:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:03 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 09:27:03 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 09:27:03 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 09:27:03 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 09:27:03 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 09:27:03 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 09:27:03 INFO - Downloading docopt-0.6.1.tar.gz 09:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:03 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:03 INFO - Running setup.py install for blobuploader 09:27:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:03 INFO - Running setup.py install for requests 09:27:04 INFO - Running setup.py install for docopt 09:27:04 INFO - Successfully installed blobuploader requests docopt 09:27:04 INFO - Cleaning up... 09:27:04 INFO - Return code: 0 09:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:04 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')]} 09:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:04 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')]} 09:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:04 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 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:27:04 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 09:27:04 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 09:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:04 INFO - 'HOME': '/Users/cltbld', 09:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:04 INFO - 'LOGNAME': 'cltbld', 09:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:04 INFO - 'MOZ_NO_REMOTE': '1', 09:27:04 INFO - 'NO_EM_RESTART': '1', 09:27:04 INFO - 'PAGER': '/bin/cat', 09:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:04 INFO - 'PWD': '/builds/slave/test', 09:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:04 INFO - 'SHELL': '/bin/bash', 09:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:27:04 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:04 INFO - 'USER': 'cltbld', 09:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:04 INFO - 'XPC_FLAGS': '0x0', 09:27:04 INFO - 'XPC_SERVICE_NAME': '0', 09:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-9zLen9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ylZ_UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-0cqkcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-3tx3sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-xN5ing-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-PoR08v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-c2gOQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-9g3m2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-RUlPIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-4Yqf0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:05 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-C5KYBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-9PQwRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-8he39c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-9tvmp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-aFny5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-jXmwOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:06 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-hKDUjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:06 INFO - Running setup.py install for manifestparser 09:27:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:06 INFO - Running setup.py install for mozcrash 09:27:06 INFO - Running setup.py install for mozdebug 09:27:07 INFO - Running setup.py install for mozdevice 09:27:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozfile 09:27:07 INFO - Running setup.py install for mozhttpd 09:27:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozinfo 09:27:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozInstall 09:27:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozleak 09:27:07 INFO - Running setup.py install for mozlog 09:27:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for moznetwork 09:27:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozprocess 09:27:08 INFO - Running setup.py install for mozprofile 09:27:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozrunner 09:27:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozscreenshot 09:27:08 INFO - Running setup.py install for moztest 09:27:09 INFO - Running setup.py install for mozversion 09:27:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:09 INFO - Cleaning up... 09:27:09 INFO - Return code: 0 09:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:09 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 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:27:09 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 09:27:09 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 09:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:09 INFO - 'HOME': '/Users/cltbld', 09:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:09 INFO - 'LOGNAME': 'cltbld', 09:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:09 INFO - 'MOZ_NO_REMOTE': '1', 09:27:09 INFO - 'NO_EM_RESTART': '1', 09:27:09 INFO - 'PAGER': '/bin/cat', 09:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:09 INFO - 'PWD': '/builds/slave/test', 09:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:09 INFO - 'SHELL': '/bin/bash', 09:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:27:09 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:09 INFO - 'USER': 'cltbld', 09:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:09 INFO - 'XPC_FLAGS': '0x0', 09:27:09 INFO - 'XPC_SERVICE_NAME': '0', 09:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:09 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ty5o2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:09 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)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:09 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-D1n1P4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:09 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)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:09 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-nEEIth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:09 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)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:09 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-NLMRYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:09 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)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:09 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-TTRCME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:09 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)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-xCNTNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-DQU3_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ssTjrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ZPlSR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-QaXUcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-kE2r4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-DjFAoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-UZUBxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-vdiSZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:10 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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:10 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ERvuzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:11 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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:11 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ypmQ70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:11 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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:11 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Upg4kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:11 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)) 09:27:11 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)) 09:27:11 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)) 09:27:11 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)) 09:27:11 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)) 09:27:11 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)) 09:27:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:11 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 09:27:11 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 09:27:11 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 09:27:11 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 09:27:11 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 09:27:11 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 09:27:13 INFO - Downloading blessings-1.6.tar.gz 09:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:13 INFO - Installing collected packages: blessings 09:27:13 INFO - Running setup.py install for blessings 09:27:13 INFO - Successfully installed blessings 09:27:13 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:14 INFO - Reading from file tmpfile_stdout 09:27:14 INFO - Current package versions: 09:27:14 INFO - blessings == 1.6 09:27:14 INFO - blobuploader == 1.2.4 09:27:14 INFO - docopt == 0.6.1 09:27:14 INFO - functools32 == 3.2.3-2 09:27:14 INFO - jsonschema == 2.5.1 09:27:14 INFO - manifestparser == 1.1 09:27:14 INFO - mozInstall == 1.12 09:27:14 INFO - mozcrash == 0.17 09:27:14 INFO - mozdebug == 0.1 09:27:14 INFO - mozdevice == 0.48 09:27:14 INFO - mozfile == 1.2 09:27:14 INFO - mozhttpd == 0.7 09:27:14 INFO - mozinfo == 0.9 09:27:14 INFO - mozleak == 0.1 09:27:14 INFO - mozlog == 3.2 09:27:14 INFO - moznetwork == 0.27 09:27:14 INFO - mozprocess == 0.22 09:27:14 INFO - mozprofile == 0.28 09:27:14 INFO - mozrunner == 6.11 09:27:14 INFO - mozscreenshot == 0.1 09:27:14 INFO - mozsystemmonitor == 0.3 09:27:14 INFO - moztest == 0.7 09:27:14 INFO - mozversion == 1.4 09:27:14 INFO - psutil == 3.1.1 09:27:14 INFO - requests == 1.2.3 09:27:14 INFO - wsgiref == 0.1.2 09:27:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 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 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:27:14 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 09:27:14 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 09:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:14 INFO - 'HOME': '/Users/cltbld', 09:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:14 INFO - 'LOGNAME': 'cltbld', 09:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:14 INFO - 'MOZ_NO_REMOTE': '1', 09:27:14 INFO - 'NO_EM_RESTART': '1', 09:27:14 INFO - 'PAGER': '/bin/cat', 09:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:14 INFO - 'PWD': '/builds/slave/test', 09:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:14 INFO - 'SHELL': '/bin/bash', 09:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:27:14 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:14 INFO - 'USER': 'cltbld', 09:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:14 INFO - 'XPC_FLAGS': '0x0', 09:27:14 INFO - 'XPC_SERVICE_NAME': '0', 09:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 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)) 09:27:14 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 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 0x1026b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102745618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd68d61600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, '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.mLweRMXqIg/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.gg4T21w1rC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 09:27:14 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 09:27:14 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 09:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:14 INFO - 'HOME': '/Users/cltbld', 09:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:14 INFO - 'LOGNAME': 'cltbld', 09:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:14 INFO - 'MOZ_NO_REMOTE': '1', 09:27:14 INFO - 'NO_EM_RESTART': '1', 09:27:14 INFO - 'PAGER': '/bin/cat', 09:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:14 INFO - 'PWD': '/builds/slave/test', 09:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:14 INFO - 'SHELL': '/bin/bash', 09:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:27:14 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:14 INFO - 'USER': 'cltbld', 09:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:14 INFO - 'XPC_FLAGS': '0x0', 09:27:14 INFO - 'XPC_SERVICE_NAME': '0', 09:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:14 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Running post-action listener: _resource_record_post_action 09:27:14 INFO - Running post-action listener: _start_resource_monitoring 09:27:14 INFO - Starting resource monitoring. 09:27:14 INFO - ##### 09:27:14 INFO - ##### Finished create-virtualenv step (success) 09:27:14 INFO - ##### 09:27:14 INFO - ##### 09:27:14 INFO - ##### Running install step. 09:27:14 INFO - ##### 09:27:14 INFO - Running pre-action listener: _resource_record_pre_action 09:27:14 INFO - Running main action method: install 09:27:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:15 INFO - Reading from file tmpfile_stdout 09:27:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:15 INFO - Reading from file tmpfile_stdout 09:27:15 INFO - Output received: 09:27:15 INFO - Usage: mozinstall [options] installer 09:27:15 INFO - Options: 09:27:15 INFO - -h, --help show this help message and exit 09:27:15 INFO - -d DEST, --destination=DEST 09:27:15 INFO - Directory to install application into. [default: 09:27:15 INFO - "/builds/slave/test"] 09:27:15 INFO - --app=APP Application being installed. [default: firefox] 09:27:15 INFO - mkdir: /builds/slave/test/build/application 09:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:27:49 INFO - Reading from file tmpfile_stdout 09:27:49 INFO - Output received: 09:27:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:27:49 INFO - Running post-action listener: _resource_record_post_action 09:27:49 INFO - ##### 09:27:49 INFO - ##### Finished install step (success) 09:27:49 INFO - ##### 09:27:49 INFO - ##### 09:27:49 INFO - ##### Running run-tests step. 09:27:49 INFO - ##### 09:27:49 INFO - Running pre-action listener: _resource_record_pre_action 09:27:49 INFO - Running main action method: run_tests 09:27:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:49 INFO - 2016-07-05 09:27:49.275 screenresolution[1919:13915] starting screenresolution argv=screenresolution get 09:27:49 INFO - 2016-07-05 09:27:49.277 screenresolution[1919:13915] Display 0: 1600x1200x32@60 09:27:49 INFO - 2016-07-05 09:27:49.283 screenresolution[1920:13917] starting screenresolution argv=screenresolution list 09:27:49 INFO - Available Modes on Display 0 09:27:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:49 INFO - Intel Iris: 09:27:49 INFO - Chipset Model: Intel Iris 09:27:49 INFO - Type: GPU 09:27:49 INFO - Bus: Built-In 09:27:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:49 INFO - Vendor: Intel (0x8086) 09:27:49 INFO - Device ID: 0x0a2e 09:27:49 INFO - Revision ID: 0x0009 09:27:49 INFO - Displays: 09:27:49 INFO - Display: 09:27:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:49 INFO - Display Serial Number: 1600x1200 60 09:27:49 INFO - Main Display: Yes 09:27:49 INFO - Mirror: Off 09:27:49 INFO - Online: Yes 09:27:49 INFO - Rotation: Supported 09:27:49 INFO - Return code: 0 09:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:49 INFO - Python 2.7.3 09:27:49 INFO - Return code: 0 09:27:49 INFO - grabbing minidump binary from tooltool 09:27:49 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')]} 09:27:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102614d30>, '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 0x102379db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10260cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:49 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 09:27:49 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 09:27:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:27:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:49 INFO - Return code: 0 09:27:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0261 --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 09:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mLweRMXqIg/Render', 09:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:49 INFO - 'HOME': '/Users/cltbld', 09:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:49 INFO - 'LOGNAME': 'cltbld', 09:27:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:49 INFO - 'MOZ_NO_REMOTE': '1', 09:27:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:49 INFO - 'NO_EM_RESTART': '1', 09:27:49 INFO - 'PAGER': '/bin/cat', 09:27:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:49 INFO - 'PWD': '/builds/slave/test', 09:27:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:49 INFO - 'SHELL': '/bin/bash', 09:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gg4T21w1rC/Listeners', 09:27:49 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 09:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:49 INFO - 'USER': 'cltbld', 09:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:49 INFO - 'XPC_FLAGS': '0x0', 09:27:49 INFO - 'XPC_SERVICE_NAME': '0', 09:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 09:27:49 INFO - mozversion application_buildid: 20160705070425 09:27:49 INFO - mozversion application_changeset: dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 09:27:49 INFO - mozversion application_display_name: Nightly 09:27:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:49 INFO - mozversion application_name: Firefox 09:27:49 INFO - mozversion application_remotingname: firefox 09:27:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:27:49 INFO - mozversion application_vendor: Mozilla 09:27:49 INFO - mozversion application_version: 50.0a1 09:27:49 INFO - mozversion platform_buildid: 20160705070425 09:27:49 INFO - mozversion platform_changeset: dbb31bcad5a1f60a35b5600ea1578d9b9fa55237 09:27:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:27:49 INFO - mozversion platform_version: 50.0a1 09:27:49 INFO - using testdate: 1467736069 09:27:49 INFO - actual date: 1467736069 09:27:49 INFO - starting webserver on 'localhost:51824' 09:27:49 INFO - SUITE-START | Running 2 tests 09:27:49 INFO - TEST-START | dromaeo_css 09:27:49 INFO - operating with platform_type : mac_ 09:27:49 INFO - Initialising browser for dromaeo_css test... 09:27:49 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpDMw4Qp/profile http://localhost:51824/getInfo.html) 09:27:52 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 09:27:52 INFO - PROCESS | 1930 | colorDepth:24 09:27:52 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 09:27:52 INFO - PROCESS | 1930 | __metrics 09:27:52 INFO - PROCESS | 1930 | 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" 09:27:52 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:52 INFO - TEST-INFO | 1930: exit 0 09:27:52 INFO - Browser initialized. 09:27:52 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:52 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpDMw4Qp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:04 INFO - PROCESS | 1932 | RSS: Main: 243806208 09:28:04 INFO - PROCESS | 1932 | 09:28:56 INFO - PROCESS | 1932 | 1467736136593 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] 09:31:07 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51824/tests/dromaeo/cssquery-ext.html) 09:31:09 INFO - PROCESS | 1932 | RSS: Main: 389378048 09:31:09 INFO - PROCESS | 1932 | 09:34:12 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-ext.html (next: http://localhost:51824/tests/dromaeo/cssquery-jquery.html) 09:34:13 INFO - PROCESS | 1932 | RSS: Main: 374808576 09:34:13 INFO - PROCESS | 1932 | 09:37:15 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51824/tests/dromaeo/cssquery-mootools.html) 09:37:16 INFO - PROCESS | 1932 | RSS: Main: 374927360 09:37:16 INFO - PROCESS | 1932 | 09:40:18 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51824/tests/dromaeo/cssquery-prototype.html) 09:40:18 INFO - PROCESS | 1932 | RSS: Main: 372305920 09:40:18 INFO - PROCESS | 1932 | 09:43:22 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51824/tests/dromaeo/cssquery-yui.html) 09:43:22 INFO - PROCESS | 1932 | RSS: Main: 344100864 09:43:22 INFO - PROCESS | 1932 | 09:46:25 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51824/tests/dromaeo/cssquery-yui.html (next: http://localhost:51824/tests/dromaeo/cssquery-dojo.html) 09:46:26 INFO - PROCESS | 1932 | RSS: Main: 385122304 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | __start_tp_report 09:46:26 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 09:46:26 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 09:46:26 INFO - PROCESS | 1932 | |i|pagename|runs| 09:46:26 INFO - PROCESS | 1932 | |0;dojo.html;3321.236115693389;3324.4348460761667;3345.7286231232138;3355.8825441109534;3397.065806903103;5598;5638.492535671789;5755.65466072036;5808.709564521773;5812.738426770795;5720.542356611471;5756.079027355634;5819.388964158766;5847.473727364536;5887.264091988502;5125.641205115643;5158.329417175762;5208.401033881114;5214.817481388152;5313.256144139824;5274.736263186845;5301.867453313679;5315.7873685052555;5319.734013299339;5348.5453736432255;5121.206213036985;5159.871003224912;5198.9740051299505;5237.1358725810005;5251.50110739483;5223.582113430916;5233.869153271199;5234.26720259164;5242.580593552507;5243.2659427680155;5127.820526281562;5153.613478989091;5168.302279192336;5194.402643695986;5209.973950130263;5671.6597004179885;5745.626534275259;5769.682667453289;5820.708964551755;5845.561582881301;6187.226596675416;6230.532710046719;6253.906191407133;6268.247810262752;6283.277423096357;6113.932424433829;6216.689165541705;6231.719572619198;6234.657093859836;6266.655333956631;5857.238558987389;5904.645721256696;5911.9113213301835;5920.4967577755515;5926.4666180044005;6210.130581718563;6289.811305660837;6299.905501417483;6308.7476500940365;6310.0850376695125;6095.329513753483;6124.142620033199;6140.074827791203;6179.104029915637;6195.628262304186;6255.593386429868;6262.530310226751;6275.811725648238;6277.466415354746;6284.02597597364;6036.939630603638;6040.879182416327;6164.291106522807;6223.37766223374;6261.906071408933;6141.881051037385;6195.411435913581;6196.8760624787255;6225.28409232935;6273.466755325757;4959.801607935642;4962.181240095416;4964.60283177345;4973.875653108701;4994.600431965434;4218.409422680827;4422.557744225616;4497.100579884036;4524.343970124313;4539.977300113478;5017.4731653175895;5737.282839645044;5786.161006654096;5787.450192231731;5794.62334948227;5255.39562950258;6183.443490085914;6262.060690896311;6265.467435267962;6268.561200715906;5181.326427564468;5372.683692495364;5791.986402379567;5800.303963524404;5831.358550559435;5437.483439073281;5484.396716361197;5485.286912701324;5488.012157811632;5489.4236105209175;5762.884742305214;6175.44421002112;6176.073588961512;6232.968835155886;6240.5319601031015;6224;6233.283172435228;6252.155958945484;6260.968695156495;6266.342034086355;6148.95467770489;6332.271788744353;6353.428191462791;6373.203349581302;6383.553151279366;6107.2420975189625;6122.755089796358;6173.81478555644;6180.289266734203;6222.564420490522;6240.126382306572;6242.812715618538;6245.594036387621;6260.029695397221;6260.499160067275;5947.881042379215;6069.575129740876;6166.445019948226;6172.598781079216;6189.133521307111;6091.543134264859;6179.382061793965;6183.196184495987;6187;6192.566520343533;5843.503302219359;6011.308699499614;6012.609180403259;6071.028635418312;6073.937061014393;5930.851728706644;6058;6061.939380606137;6151.6001459904965;6158.3841615838055;5027.924581131359;5934.139549765347;5989.101634754822;5991.400859913973;6022.45797878193 09:46:26 INFO - PROCESS | 1932 | |1;ext.html;526.7392640642882;527.7545941137371;536.6055948877574;557.3590371073268;560.3080195957991;29526.704732952665;29822.70177298229;41780.791096044515;41955.79022104893;42258.154836903246;36122;43039.56960430395;43489.130217395636;43504.347434788506;43601.12797744043;9523.57143928525;9723.416595004323;9726.319157658967;9728.854067188999;9750.609975601003;8590.785230369229;8772.385932984693;11541;11736.061115110771;11754.942055215071;11675.649730508056;11701.356425396641;11769.940705336518;11784.82322765155;11796.2332448391;8670.176333248332;11548.884511154865;11687.415629218547;11763.941180294129;11778.11664125194;11374;11711.590094346657;11712.941435292854;11717.589884354007;11731.7067073323;41291.1741765166;41429;41507.377389339184;41536.58463415357;41549;3578.910527236814;3596.6583174598372;3883.203943191639;4199.9370009449885;4368.258782914119;31851.681483185104;32635.51046734301;32780.344393112246;32910.506342404646;33200;3293.736501079908;3335.8999230023137;3370.5955285365912;3444.8277586120844;3450.258194488172;534227;536301;536961;536968;538139;58780;60380;60395.39604603986;60504.09243861346;60524;35072.36957228218;35156.648433515846;35207.82396088003;36741.44887826685;36777.264454711294;16980.575485612957;17091.059991700575;17184.570385740208;17192.140392980302;17210.225539850737;11823.290602563873;12109.273443593413;12981;13049.28228947398;13165.868341316656;18894.433167005012;19069;19182.328618498286;19189.61620767605;19239.51901202453;7056.671298471492;7292.781216563511;8096.6924426539335;8195.426320157532;8283.544405057779;871.9912800871911;969.3360048366891;998.3061772068488;1099.6865893220395;1139.67519257013;30152.54771178434;30409;30735.385292294475;30918;31107.533386999214;1096.5668560918552;1097.4622435006788;1136.9602063927723;1158.7856246594406;1195.246994393544;12333.075019373582;15473.690526189414;15917.920410398105;15950.361985520682;16037.198140092949;710349.4482527555;714365;716314;717919;718223;57948;59984;60026.39973600208;60177.39822601806;60466.39533604607;1303.361352937054;1336.3117994232978;1420.9360578773774;1436.899417040797;1465.4431316099815;30061.549076764302;31305.217369565944;31581.368372632878;31870.840645796623;32090.19774505656;49662;49664;50316.99366012732;50393;50416.74791626165;538211;539114;539725;540180;542639;23405.531889362457;23653.52692946166;26219.60670589981;26403.943842246863;26417.735822641527;39010.829675109795;47869;48068.519314806406;48272.793180170775;48353;40084.59915400925;40139;40278;40800.3879941807;41177;32698.83650581732;39502;39615;39657.603423966546;40684;37244.19987362375;37581.43627845639;38316;39005.804970976096;39193.41209881797;34636.48045279373;34674;34783.304333912674;35186.47220291646;35314;25095.372615684755;25962.610560841225;26022.219333420027;26069.478610428065;26101.955921762918 09:46:26 INFO - PROCESS | 1932 | |2;jquery.html;321.373322022057;359.04315000523604;359.7893089752982;391.9216156768647;416.841600191927;15876.603084922883;17302.048387338706;20991.895040524774;21456.57086858264;21512;21674.566508669817;22188;22552.774472255274;22775.54448911021;22816.771832281673;5089.796408143669;5959.731812068446;6159.7536098556;6162.352952939944;6220.284667263255;6085.3914608539235;6163.753449862;6180.3201647818705;6191.102290167945;6198.411150940653;5859.912101318484;6095.299040610343;6099.81700548982;6100.542459315569;6110.1140334651445;5926.318473375574;5973.790917317873;5998.850028749294;6068;6092.055731361624;5240.52835244826;5243.10867152585;5269;5271.42014378418;5275.894482110375;27389.863050684817;27423.177304680892;27766.722332776615;27851.303717407227;27882.302942426602;2568.730283320243;2734.913814490282;2801.5097357962277;3124.9218769530717;3220.1144685211752;15696.529104126894;16094.19529023544;16197.02817830934;16331.591710207222;16913;1838.4300866731355;1881.5014021284371;1902.1060101752155;1906.4947788835832;1944.251463186669;94288.58567121498;99653.0069398608;118944.02639933984;128578.3571082148;133645;11280.661580152608;11300.378479183559;11307.890330159273;11439.485223164978;11853.940730296294;13991.020628556107;14113.717725645429;14126.152430853976;14141.080829746033;14145.929270353583;8748.125187481328;8867.822643547093;8869.11308869108;8953.28373730113;8990.730278091667;7438;7476;8808.03111657717;8927.24118449939;9075.591598378089;6719.193696756322;6844.699507093636;6846.212685541127;6846.281140480178;6866.588004719732;1852.397970659541;1864.3754342295213;1873.2600622754208;1887.3862847265664;1891.782445018813;919.2737737187681;969.3650658818485;981.2836629260537;1016.5120742043713;1067.8840611560781;22140.88929555374;22290.442738931655;23054.962526686002;23089.307320780405;23118.884405577865;909.4270609516095;942.119118624075;973.5862258540092;974.7660561465198;995.9900400996042;10780.730481738019;11981;12005.386778627108;12090.395480225954;12103.213291136048;606047;611839;617040;634374;635308;125904.37047814885;125916;127351;148119;149841;1003.4280460137652;1006.1548299428515;1008.8839783424853;1055.3140458701753;1059.8569193158978;19612.8038719611;19631.595504047033;20132.698009529577;20206.29277975264;20296.883671397623;38033.80983095177;38865.6113438862;47296.52703472921;52592.211116832514;59442.40557594369;86375;86886.56556717429;88073;88297.55851220703;90089.54955225444;8119.269265766109;8186.631601578062;8388.58057097155;8522.573871306535;8616.569171541272;11535.76928461443;12763.617091487271;13373.53192638253;13483.393247303698;13614;11492.253003554742;11560.94219528897;11611.477483513094;11626.941865290619;11638.41807909618;11151.776964460827;11444.084473241954;11477;11525.250858694159;11751.236169648946;11709.121815863675;11839.408029598659;11887.16789824704;11928.522859085539;11969.581064662694;8154.469959452616;8229.917700823155;8299.128591498047;8416.2004609562;8445.113263107285;11128.944355278172;11369.2609980351;11427.771444570895;11560.94219528897;11616.825747613624 09:46:26 INFO - PROCESS | 1932 | |3;mootools.html;1421.4314274290289;1569.4742261342453;1588.2217713320472;1807.096451774113;1931.5847092875028;7753.263439973201;8622.568871556421;8842.557872106401;8848.823023539533;8910.97523784765;7728.884066739003;7818.648160832762;8563.957180214105;8729.432586881863;8741.737747867559;2008.317172161465;2332.8367014308965;2369.218158007862;2371.5968285391523;2375.95248095038;1984.9206031758713;2293.3005433342823;2297.4600968772324;2302.3093072078395;2302.3898666853297;2318.9768102319013;2338.3101984914492;2347.4718188407555;2361.456864921069;2363.385519764865;2107.283523601983;2341.8126549876056;2348.7768661977084;2351.1183306260154;2352.094443639203;2024.4129202531396;2334.9299521014395;2335.5795956727784;2338.0530884991604;2338.8947497362487;7505.474616776828;8731.563421828947;8786.780330491725;8820.735377938672;9011.41425807327;4569.771511424416;5326.094563924142;5453.618246722731;5455;5487.972560137174;3108.9067327980197;3624.710023198164;3701.8519259229597;3710.666040056381;3713.795741234231;1097.1990446973755;1102.735343517558;1109.7225693576606;1110.7556337605713;1119.0096764363598;11758.412079396081;11927.88072119285;11937.701557461047;11952.462139203757;11986.940065299617;4400.471943366752;4494.348319493655;4618.076384723069;4640.721556706609;4662.277347011219;9330.160285574331;9330.30022748283;9333.300002499704;9338.95330523343;9355.719328420158;4787.880302992384;5274.973625131926;5281.524662780368;5294.49702278283;5300.469953004668;4456.3538286948215;4599.471060827981;4800.783964721596;4801.375821143229;4964.081644895706;3756.229972855558;3760.2855457462992;3838.5777564467885;3869.090763670535;3877.4377715231135;1067.3329169269207;1129.6949823547595;1176.0944073063863;1187.2045729361348;1187.958421455245;669.3473862983552;808.9322094834879;916.4134953629682;953.270747877874;975.0882924465648;5929.644221346733;6049.21360223177;6183.752649894044;6203.875922481525;6268.811935641938;430.9956900430956;437.0886701227975;446.3438745044779;450.38747303666986;613.3682307223567;3663.7984910829628;3955.129871428281;4147.108371700099;4173.937390939138;4237.618614324726;10499.68500944973;10677.305975111592;10913.345199288067;10914.017738403581;10946.616868409568;10783.299085559414;10875.347479151276;10875.45622718861;10886.510107045202;10905.781884362426;7861.449698521163;8234.547099909563;8369.874451883226;8380.58097095155;8381.245687888118;4989.575886049726;6067.271927368744;6127.724252408563;6198.72105755233;6215.06773983906;8609.569521524025;9725.85411218818;9903.108720215216;10135.189184865341;10152.847707284249;10289.639862604772;10330.896691032993;10349.896501034893;10352.53413596405;10354.326968747007;6762.222344430454;7570.545752363847;7595.202503737249;7740.380769538311;7751.263629955145;8293.792655183668;8383.748487545383;8436;8443.408961372645;8451.02813176468;6203;7495.962520187582;7509.849803004018;7513;7794.610269486617;7349.30181632744;7402.481826272325;7490.325870671666;7536.811579710551;7592.696292148252;7047.894281585678;7057.682404291717;7106.360427561544;7148.070750802362;7182.245864184186;5015.623828212826;5123.3339665843205;5159.716215608178;5181.248718935809;5205.271262023244;4976.676516026447;5161.612879034013;5168.405633352213;5204.895902082013;5209.4009188943755 09:46:26 INFO - PROCESS | 1932 | |4;prototype.html;264.3642040891656;269.3186238815797;312.56865525574705;317.33834954120664;323.9012101309101;5034.370703662043;5078.476457062885;5337.2527846101575;5381.300430943977;5422.83731488055;4461.977690111545;4686.102128637898;4701.412323459567;5352.304200453936;5390.245365648812;4067.3695577185586;4069.3895915612748;4098.344264917607;4099.651529619986;4132.97933510332;3765.642263984917;3839.2513459875336;4117.6940913088765;4132.710710250284;4148.315527937902;3443.5867695876404;4028.436018957348;4033.838646454138;4037.2329257440992;4110.17796440713;3871.651551360377;4037.2531081749676;4053.8175782089875;4077.184563087365;4078.5105787305415;3534.2505459797567;3535.611082780892;3536.469529570559;3545.6454354564594;3562.8218589070702;5051.52010559;5154.252633368178;5199.272101905736;5203.7398130093725;5228.686278823283;4636.35091087248;4639.909621239006;4653.115907977473;4668.906621867543;4690.554397332248;4885.340479035321;5273.077211487975;5571.470710282517;5600.52395546375;5641.71791410432;3422.8801991930163;3846.807659617036;3862.4206369044587;3863.265979463893;3875.2561347393785;4923.458419573844;5861.912071318934;5937.257842769611;5938.524918006592;5952.666506756231;3791.7724936503896;3903.980480097638;3939.783311917815;3957.2785838159916;3962.266980608539;3410.782991055784;3944.8422063117155;3945.5265368156006;3958.2281455116554;3989.3018721723583;3897.649211570972;3932.803359831997;4634.83778067766;4638.567460079516;4658.693946496506;5494.560435165177;5679.858003549944;5717.456841600041;5727.742251598688;5737.913931291034;1306.79744639581;1356.3964036004081;1358.8844948179515;1360.8843248323806;1364.6588352911772;806.5563939833069;807.8303556253135;828.2297463359134;829.1542626520915;833;4433.3596569269575;4477.395551600557;4480.731156130642;4488.498970802779;4506.549345065468;4032.7819699165343;4444.066745983379;4666.836660716858;4799.896023914519;4848.5636292733825;3888.6111388861455;4192.559781222989;4453.487848897354;4459.754713490789;4468.620167285817;2402.3753824005885;2548.6941567011704;2561.244432892301;2570.755778201068;2612.7458819766616;4644.489106198315;4980.003999200175;4999.750162394473;5012.473690262543;5038.622103342264;4095.774732389747;4430.689851710414;4608.308753686907;4769.140035386202;4794.017226468565;4285.378854867694;4401.471823381215;4509.842155524541;4699.882502937454;5020.59835213182;4821.397325334333;4822.734749588806;4860.809101770089;4872.200882131169;4892.385512780703;4146.564610715832;4492.663050271178;4625.7918393673035;4747.62019038483;4823.975880120577;4753.263244197155;5459.808906688406;5480.753366098455;5581.776728930798;5836.299644042572;3459.394605944;3703.2408356287438;3846.8265430031943;3876.670483008863;3973.9667686401167;4514;5118.488151184852;5453.509184173444;5719.942800571941;5995.760169593167;4860.878478038077;4939.728314942713;5186.792528298976;5580.2466666999435;5744.023516002373;4631.3563677478405;5365.758540865592;5626.2685850839125;5635.408282130317;5648.486423541484;5272.077386457431;5509.614326997072;5524.06090964529;5524.502794748492;5526;5314.176302673091;5629.662220266797;5636;5685.971570142123;5721.199032135588;5336.919946200733;5396.595255355943;5565.6104072714525;5581.218629391969;5639.915401268902 09:46:26 INFO - PROCESS | 1932 | |5;yui.html;737.5832654550179;755.2409828122736;801.9077806052304;811.0348685064772;851.9020312664046;15003.774943375844;15495.457658981937;15995.84004159958;16072.598185045379;16099.83900160998;14546.781798273034;14754.040987335815;14771.335289911953;14774.482893098744;14784.704305913876;720.5064530796421;720.7945735465394;745.8135466133467;746.164295988491;887.0641473245723;727.9454040946923;895.2524641923969;902.3322741171552;907.8411278026352;914.5747227539193;955.0783493928358;971.6696323250094;972.9464879431629;973.9464329461877;975.795083032564;958.5494817435793;959.5394210778795;961.1397798969953;962.7352478068552;963.4845357733614;565.8981383351037;705.0833915909298;709.8296408861887;722.2993696114813;724.6811402982671;15066.322015509328;15083.773743393747;15086.924565377212;15108.169050702207;15133.924330378277;13931.791023134661;14305.427782888672;14331.713365732627;14334.283285835667;14386.712265754732;12863.421146048357;12936.700792733996;12970.546030888874;13099.95200383967;13112.934435327857;3750.1187221003042;3889.9805500972598;3903.316919539069;3904.9219015619815;4041.2523683118716;114872;116590;116946.41526792312;116953.83046169515;117132;10857.565697372016;11168.441577921072;11172.720681983015;11222.55109795599;11679.88320116805;21114.472138196666;21410.892945535485;21454.570908582053;21457.46356341104;21697.915104244723;6922.826929326707;7906.130325664172;7995.040595128506;7997.6800927963395;8075.313598344089;4443.488998765119;4511.909761804745;4524.819007239739;4540.250858608359;4717;3528.5765708114677;3640.3993341098953;3645.1069487975087;3649.2154186849953;3657.6159503251774;676.4081428749844;679.8334408069956;685.2633419074515;694.9652517374212;698.4203111417511;590.719408281063;624.8437890527368;644.48119263992;694.1427337238506;714.324962910045;9346.299027572959;9595.47224902623;9641.464887717599;9676.274279429072;9774.364666296668;665.9700313485905;667.7596065416446;694.3438450664074;703.2545501768142;706.5513398991677;4284.507281662586;4650.790714417859;4708.929366059512;4713.010267843792;4714.75952743334;110576.89423105826;153132;154813;156887;158465;131700;133342;134144;134626.99999999805;137396;113209;114634.15845764849;116677.9999999983;117416;118019;8312.584370781558;8796.956015219883;8816.823663526822;8916.598753056258;9041.457512549268;60841;60967.390326096174;62968.68515657393;63243.683781580796;63808.36191638024;81258;89182.55408723175;95884;97257.51371243098;97961.51019244858;11599.304041757521;11678.416079196177;11794.823077653671;11808.76382472363;12337.691557711129;12862.7932418851;12974.221546707227;13061.412236449572;13180.406881690538;13518.05373623837;12139;12151.939240303742;12367.567135150226;12720.045996550482;12749.980001600035;12107.515699371927;12252.448639811051;12352.197107188003;12468.314242716737;12597.748045038865;11825.704357391134;12031.097667674785;12035.819462708241;12149.939250303692;12496.43766030549;10087.798244035224;10256.12822910061;10451.216158788273;10749.14006879432;10914.836277456003;10416.333865814939;10467.424291663727;10640.201984851323;10673.146148308271;10696.304740191863 09:46:26 INFO - PROCESS | 1932 | __end_tp_report 09:46:26 INFO - PROCESS | 1932 | __start_cc_report 09:46:26 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1505 09:46:26 INFO - PROCESS | 1932 | __end_cc_report 09:46:26 INFO - PROCESS | 1932 | __startTimestamp1467737186086__endTimestamp 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | ------- Summary: start ------- 09:46:26 INFO - PROCESS | 1932 | Number of tests: 6 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5744.65 Median:5989.10 stddev:640.12 (10.7%) stddev-sans-first:613.21 09:46:26 INFO - PROCESS | 1932 | Values: 3321.2 3324.4 3345.7 3355.9 3397.1 5598.0 5638.5 5755.7 5808.7 5812.7 5720.5 5756.1 5819.4 5847.5 5887.3 5125.6 5158.3 5208.4 5214.8 5313.3 5274.7 5301.9 5315.8 5319.7 5348.5 5121.2 5159.9 5199.0 5237.1 5251.5 5223.6 5233.9 5234.3 5242.6 5243.3 5127.8 5153.6 5168.3 5194.4 5210.0 5671.7 5745.6 5769.7 5820.7 5845.6 6187.2 6230.5 6253.9 6268.2 6283.3 6113.9 6216.7 6231.7 6234.7 6266.7 5857.2 5904.6 5911.9 5920.5 5926.5 6210.1 6289.8 6299.9 6308.7 6310.1 6095.3 6124.1 6140.1 6179.1 6195.6 6255.6 6262.5 6275.8 6277.5 6284.0 6036.9 6040.9 6164.3 6223.4 6261.9 6141.9 6195.4 6196.9 6225.3 6273.5 4959.8 4962.2 4964.6 4973.9 4994.6 4218.4 4422.6 4497.1 4524.3 4540.0 5017.5 5737.3 5786.2 5787.5 5794.6 5255.4 6183.4 6262.1 6265.5 6268.6 5181.3 5372.7 5792.0 5800.3 5831.4 5437.5 5484.4 5485.3 5488.0 5489.4 5762.9 6175.4 6176.1 6233.0 6240.5 6224.0 6233.3 6252.2 6261.0 6266.3 6149.0 6332.3 6353.4 6373.2 6383.6 6107.2 6122.8 6173.8 6180.3 6222.6 6240.1 6242.8 6245.6 6260.0 6260.5 5947.9 6069.6 6166.4 6172.6 6189.1 6091.5 6179.4 6183.2 6187.0 6192.6 5843.5 6011.3 6012.6 6071.0 6073.9 5930.9 6058.0 6061.9 6151.6 6158.4 5027.9 5934.1 5989.1 5991.4 6022.5 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:72227.67 Median:3587.78 stddev:161466.92 (4500.5%) stddev-sans-first:161830.13 09:46:26 INFO - PROCESS | 1932 | Values: 526.7 527.8 536.6 557.4 560.3 29526.7 29822.7 41780.8 41955.8 42258.2 36122.0 43039.6 43489.1 43504.3 43601.1 9523.6 9723.4 9726.3 9728.9 9750.6 8590.8 8772.4 11541.0 11736.1 11754.9 11675.6 11701.4 11769.9 11784.8 11796.2 8670.2 11548.9 11687.4 11763.9 11778.1 11374.0 11711.6 11712.9 11717.6 11731.7 41291.2 41429.0 41507.4 41536.6 41549.0 3578.9 3596.7 3883.2 4199.9 4368.3 31851.7 32635.5 32780.3 32910.5 33200.0 3293.7 3335.9 3370.6 3444.8 3450.3 534227.0 536301.0 536961.0 536968.0 538139.0 58780.0 60380.0 60395.4 60504.1 60524.0 35072.4 35156.6 35207.8 36741.4 36777.3 16980.6 17091.1 17184.6 17192.1 17210.2 11823.3 12109.3 12981.0 13049.3 13165.9 18894.4 19069.0 19182.3 19189.6 19239.5 7056.7 7292.8 8096.7 8195.4 8283.5 872.0 969.3 998.3 1099.7 1139.7 30152.5 30409.0 30735.4 30918.0 31107.5 1096.6 1097.5 1137.0 1158.8 1195.2 12333.1 15473.7 15917.9 15950.4 16037.2 710349.4 714365.0 716314.0 717919.0 718223.0 57948.0 59984.0 60026.4 60177.4 60466.4 1303.4 1336.3 1420.9 1436.9 1465.4 30061.5 31305.2 31581.4 31870.8 32090.2 49662.0 49664.0 50317.0 50393.0 50416.7 538211.0 539114.0 539725.0 540180.0 542639.0 23405.5 23653.5 26219.6 26403.9 26417.7 39010.8 47869.0 48068.5 48272.8 48353.0 40084.6 40139.0 40278.0 40800.4 41177.0 32698.8 39502.0 39615.0 39657.6 40684.0 37244.2 37581.4 38316.0 39005.8 39193.4 34636.5 34674.0 34783.3 35186.5 35314.0 25095.4 25962.6 26022.2 26069.5 26102.0 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:36438.51 Median:2651.82 stddev:103619.98 (3907.5%) stddev-sans-first:103875.18 09:46:26 INFO - PROCESS | 1932 | Values: 321.4 359.0 359.8 391.9 416.8 15876.6 17302.0 20991.9 21456.6 21512.0 21674.6 22188.0 22552.8 22775.5 22816.8 5089.8 5959.7 6159.8 6162.4 6220.3 6085.4 6163.8 6180.3 6191.1 6198.4 5859.9 6095.3 6099.8 6100.5 6110.1 5926.3 5973.8 5998.9 6068.0 6092.1 5240.5 5243.1 5269.0 5271.4 5275.9 27389.9 27423.2 27766.7 27851.3 27882.3 2568.7 2734.9 2801.5 3124.9 3220.1 15696.5 16094.2 16197.0 16331.6 16913.0 1838.4 1881.5 1902.1 1906.5 1944.3 94288.6 99653.0 118944.0 128578.4 133645.0 11280.7 11300.4 11307.9 11439.5 11853.9 13991.0 14113.7 14126.2 14141.1 14145.9 8748.1 8867.8 8869.1 8953.3 8990.7 7438.0 7476.0 8808.0 8927.2 9075.6 6719.2 6844.7 6846.2 6846.3 6866.6 1852.4 1864.4 1873.3 1887.4 1891.8 919.3 969.4 981.3 1016.5 1067.9 22140.9 22290.4 23055.0 23089.3 23118.9 909.4 942.1 973.6 974.8 996.0 10780.7 11981.0 12005.4 12090.4 12103.2 606047.0 611839.0 617040.0 634374.0 635308.0 125904.4 125916.0 127351.0 148119.0 149841.0 1003.4 1006.2 1008.9 1055.3 1059.9 19612.8 19631.6 20132.7 20206.3 20296.9 38033.8 38865.6 47296.5 52592.2 59442.4 86375.0 86886.6 88073.0 88297.6 90089.5 8119.3 8186.6 8388.6 8522.6 8616.6 11535.8 12763.6 13373.5 13483.4 13614.0 11492.3 11560.9 11611.5 11626.9 11638.4 11151.8 11444.1 11477.0 11525.3 11751.2 11709.1 11839.4 11887.2 11928.5 11969.6 8154.5 8229.9 8299.1 8416.2 8445.1 11128.9 11369.3 11427.8 11560.9 11616.8 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5684.62 Median:4801.08 stddev:3230.46 (67.3%) stddev-sans-first:3223.63 09:46:26 INFO - PROCESS | 1932 | Values: 1421.4 1569.5 1588.2 1807.1 1931.6 7753.3 8622.6 8842.6 8848.8 8911.0 7728.9 7818.6 8564.0 8729.4 8741.7 2008.3 2332.8 2369.2 2371.6 2376.0 1984.9 2293.3 2297.5 2302.3 2302.4 2319.0 2338.3 2347.5 2361.5 2363.4 2107.3 2341.8 2348.8 2351.1 2352.1 2024.4 2334.9 2335.6 2338.1 2338.9 7505.5 8731.6 8786.8 8820.7 9011.4 4569.8 5326.1 5453.6 5455.0 5488.0 3108.9 3624.7 3701.9 3710.7 3713.8 1097.2 1102.7 1109.7 1110.8 1119.0 11758.4 11927.9 11937.7 11952.5 11986.9 4400.5 4494.3 4618.1 4640.7 4662.3 9330.2 9330.3 9333.3 9339.0 9355.7 4787.9 5275.0 5281.5 5294.5 5300.5 4456.4 4599.5 4800.8 4801.4 4964.1 3756.2 3760.3 3838.6 3869.1 3877.4 1067.3 1129.7 1176.1 1187.2 1188.0 669.3 808.9 916.4 953.3 975.1 5929.6 6049.2 6183.8 6203.9 6268.8 431.0 437.1 446.3 450.4 613.4 3663.8 3955.1 4147.1 4173.9 4237.6 10499.7 10677.3 10913.3 10914.0 10946.6 10783.3 10875.3 10875.5 10886.5 10905.8 7861.4 8234.5 8369.9 8380.6 8381.2 4989.6 6067.3 6127.7 6198.7 6215.1 8609.6 9725.9 9903.1 10135.2 10152.8 10289.6 10330.9 10349.9 10352.5 10354.3 6762.2 7570.5 7595.2 7740.4 7751.3 8293.8 8383.7 8436.0 8443.4 8451.0 6203.0 7496.0 7509.8 7513.0 7794.6 7349.3 7402.5 7490.3 7536.8 7592.7 7047.9 7057.7 7106.4 7148.1 7182.2 5015.6 5123.3 5159.7 5181.2 5205.3 4976.7 5161.6 5168.4 5204.9 5209.4 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4329.12 Median:4648.80 stddev:1303.55 (28.0%) stddev-sans-first:1271.00 09:46:26 INFO - PROCESS | 1932 | Values: 264.4 269.3 312.6 317.3 323.9 5034.4 5078.5 5337.3 5381.3 5422.8 4462.0 4686.1 4701.4 5352.3 5390.2 4067.4 4069.4 4098.3 4099.7 4133.0 3765.6 3839.3 4117.7 4132.7 4148.3 3443.6 4028.4 4033.8 4037.2 4110.2 3871.7 4037.3 4053.8 4077.2 4078.5 3534.3 3535.6 3536.5 3545.6 3562.8 5051.5 5154.3 5199.3 5203.7 5228.7 4636.4 4639.9 4653.1 4668.9 4690.6 4885.3 5273.1 5571.5 5600.5 5641.7 3422.9 3846.8 3862.4 3863.3 3875.3 4923.5 5861.9 5937.3 5938.5 5952.7 3791.8 3904.0 3939.8 3957.3 3962.3 3410.8 3944.8 3945.5 3958.2 3989.3 3897.6 3932.8 4634.8 4638.6 4658.7 5494.6 5679.9 5717.5 5727.7 5737.9 1306.8 1356.4 1358.9 1360.9 1364.7 806.6 807.8 828.2 829.2 833.0 4433.4 4477.4 4480.7 4488.5 4506.5 4032.8 4444.1 4666.8 4799.9 4848.6 3888.6 4192.6 4453.5 4459.8 4468.6 2402.4 2548.7 2561.2 2570.8 2612.7 4644.5 4980.0 4999.8 5012.5 5038.6 4095.8 4430.7 4608.3 4769.1 4794.0 4285.4 4401.5 4509.8 4699.9 5020.6 4821.4 4822.7 4860.8 4872.2 4892.4 4146.6 4492.7 4625.8 4747.6 4824.0 4753.3 5459.8 5480.8 5581.8 5836.3 3459.4 3703.2 3846.8 3876.7 3974.0 4514.0 5118.5 5453.5 5719.9 5995.8 4860.9 4939.7 5186.8 5580.2 5744.0 4631.4 5365.8 5626.3 5635.4 5648.5 5272.1 5509.6 5524.1 5524.5 5526.0 5314.2 5629.7 5636.0 5686.0 5721.2 5336.9 5396.6 5565.6 5581.2 5639.9 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:25179.96 Median:3584.49 stddev:40767.54 (1137.3%) stddev-sans-first:40840.59 09:46:26 INFO - PROCESS | 1932 | Values: 737.6 755.2 801.9 811.0 851.9 15003.8 15495.5 15995.8 16072.6 16099.8 14546.8 14754.0 14771.3 14774.5 14784.7 720.5 720.8 745.8 746.2 887.1 727.9 895.3 902.3 907.8 914.6 955.1 971.7 972.9 973.9 975.8 958.5 959.5 961.1 962.7 963.5 565.9 705.1 709.8 722.3 724.7 15066.3 15083.8 15086.9 15108.2 15133.9 13931.8 14305.4 14331.7 14334.3 14386.7 12863.4 12936.7 12970.5 13100.0 13112.9 3750.1 3890.0 3903.3 3904.9 4041.3 114872.0 116590.0 116946.4 116953.8 117132.0 10857.6 11168.4 11172.7 11222.6 11679.9 21114.5 21410.9 21454.6 21457.5 21697.9 6922.8 7906.1 7995.0 7997.7 8075.3 4443.5 4511.9 4524.8 4540.3 4717.0 3528.6 3640.4 3645.1 3649.2 3657.6 676.4 679.8 685.3 695.0 698.4 590.7 624.8 644.5 694.1 714.3 9346.3 9595.5 9641.5 9676.3 9774.4 666.0 667.8 694.3 703.3 706.6 4284.5 4650.8 4708.9 4713.0 4714.8 110576.9 153132.0 154813.0 156887.0 158465.0 131700.0 133342.0 134144.0 134627.0 137396.0 113209.0 114634.2 116678.0 117416.0 118019.0 8312.6 8797.0 8816.8 8916.6 9041.5 60841.0 60967.4 62968.7 63243.7 63808.4 81258.0 89182.6 95884.0 97257.5 97961.5 11599.3 11678.4 11794.8 11808.8 12337.7 12862.8 12974.2 13061.4 13180.4 13518.1 12139.0 12151.9 12367.6 12720.0 12750.0 12107.5 12252.4 12352.2 12468.3 12597.7 11825.7 12031.1 12035.8 12149.9 12496.4 10087.8 10256.1 10451.2 10749.1 10914.8 10416.3 10467.4 10640.2 10673.1 10696.3 09:46:26 INFO - PROCESS | 1932 | -------- Summary: end -------- 09:46:26 INFO - PROCESS | 1932 | 09:46:26 INFO - TEST-INFO | 1932: exit 0 09:46:26 INFO - TEST-OK | dromaeo_css | took 1116777ms 09:46:26 INFO - TEST-START | kraken 09:46:26 INFO - operating with platform_type : mac_ 09:46:26 INFO - Initialising browser for kraken test... 09:46:26 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmp2qaKQv/profile http://localhost:51824/getInfo.html) 09:46:28 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 09:46:28 INFO - PROCESS | 1937 | colorDepth:24 09:46:28 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 09:46:28 INFO - PROCESS | 1937 | __metrics 09:46:28 INFO - PROCESS | 1937 | 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" 09:46:28 INFO - PROCESS | 1937 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:46:29 INFO - TEST-INFO | 1937: exit 0 09:46:29 INFO - Browser initialized. 09:46:29 INFO - Running cycle 1/1 for kraken test... 09:46:29 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmp2qaKQv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:41 INFO - PROCESS | 1938 | RSS: Main: 243630080 09:46:41 INFO - PROCESS | 1938 | 09:46:44 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51824/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:45 INFO - PROCESS | 1938 | RSS: Main: 294363136 09:46:45 INFO - PROCESS | 1938 | 09:46:47 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51824/tests/kraken/driver.html?testName=audio-dft) 09:46:48 INFO - PROCESS | 1938 | RSS: Main: 305016832 09:46:48 INFO - PROCESS | 1938 | 09:46:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51824/tests/kraken/driver.html?testName=audio-fft) 09:46:52 INFO - PROCESS | 1938 | RSS: Main: 392245248 09:46:52 INFO - PROCESS | 1938 | 09:46:54 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51824/tests/kraken/driver.html?testName=audio-oscillator) 09:46:55 INFO - PROCESS | 1938 | RSS: Main: 292544512 09:46:55 INFO - PROCESS | 1938 | 09:46:57 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51824/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:57 INFO - PROCESS | 1938 | RSS: Main: 296751104 09:46:57 INFO - PROCESS | 1938 | 09:47:00 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51824/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:01 INFO - PROCESS | 1938 | RSS: Main: 334229504 09:47:01 INFO - PROCESS | 1938 | 09:47:04 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51824/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:04 INFO - PROCESS | 1938 | RSS: Main: 336384000 09:47:04 INFO - PROCESS | 1938 | 09:47:07 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51824/tests/kraken/driver.html?testName=json-parse-financial) 09:47:08 INFO - PROCESS | 1938 | RSS: Main: 335872000 09:47:08 INFO - PROCESS | 1938 | 09:47:09 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51824/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:10 INFO - PROCESS | 1938 | RSS: Main: 314077184 09:47:10 INFO - PROCESS | 1938 | 09:47:12 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:12 INFO - PROCESS | 1938 | RSS: Main: 321949696 09:47:12 INFO - PROCESS | 1938 | 09:47:14 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:15 INFO - PROCESS | 1938 | RSS: Main: 318930944 09:47:15 INFO - PROCESS | 1938 | 09:47:18 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:19 INFO - PROCESS | 1938 | RSS: Main: 328134656 09:47:19 INFO - PROCESS | 1938 | 09:47:22 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:22 INFO - PROCESS | 1938 | RSS: Main: 330743808 09:47:22 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51824/tests/kraken/driver.html?testName=ai-astar) 09:47:24 INFO - PROCESS | 1938 | RSS: Main: 374456320 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | __start_tp_report 09:47:24 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 09:47:24 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 09:47:24 INFO - PROCESS | 1938 | |i|pagename|runs| 09:47:24 INFO - PROCESS | 1938 | |0;ai-astar;101;112;109;107;114;112;114;114;112;113 09:47:24 INFO - PROCESS | 1938 | |1;audio-beat-detection;143;114;106;155;107;107;129;107;140;105 09:47:24 INFO - PROCESS | 1938 | |2;audio-dft;182;185;183;183;182;184;179;193;260;182 09:47:24 INFO - PROCESS | 1938 | |3;audio-fft;80;74;58;122;80;80;64;63;83;58 09:47:24 INFO - PROCESS | 1938 | |4;audio-oscillator;86;114;125;99;69;68;96;69;68;98 09:47:24 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;87;84;90;85;88;83;88;85;87;86 09:47:24 INFO - PROCESS | 1938 | |6;imaging-darkroom;93;93;93;92;97;92;93;94;94;93 09:47:24 INFO - PROCESS | 1938 | |7;imaging-desaturate;83;85;84;84;84;83;83;85;84;82 09:47:24 INFO - PROCESS | 1938 | |8;json-parse-financial;52;54;54;55;55;54;55;55;53;54 09:47:24 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;65;84;72;74;74;70;70;68;75;104 09:47:24 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;81;85;81;81;82;84;80;85;83;78 09:47:24 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;134;134;139;154;135;135;136;145;242;124 09:47:24 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;162;164;161;164;161;164;162;163;162;160 09:47:24 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;68;67;66;68;67;67;66;64;66;65 09:47:24 INFO - PROCESS | 1938 | __end_tp_report 09:47:24 INFO - PROCESS | 1938 | __start_cc_report 09:47:24 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1988 09:47:24 INFO - PROCESS | 1938 | __end_cc_report 09:47:24 INFO - PROCESS | 1938 | __startTimestamp1467737244588__endTimestamp 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | ------- Summary: start ------- 09:47:24 INFO - PROCESS | 1938 | Number of tests: 14 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:4.13 (3.7%) stddev-sans-first:2.42 09:47:24 INFO - PROCESS | 1938 | Values: 101.0 112.0 109.0 107.0 114.0 112.0 114.0 114.0 112.0 113.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:121.50 stddev:18.80 (15.5%) stddev-sans-first:18.23 09:47:24 INFO - PROCESS | 1938 | Values: 143.0 114.0 106.0 155.0 107.0 107.0 129.0 107.0 140.0 105.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:191.30 Median:183.50 stddev:24.41 (13.3%) stddev-sans-first:25.66 09:47:24 INFO - PROCESS | 1938 | Values: 182.0 185.0 183.0 183.0 182.0 184.0 179.0 193.0 260.0 182.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:76.20 Median:77.00 stddev:18.79 (24.4%) stddev-sans-first:19.88 09:47:24 INFO - PROCESS | 1938 | Values: 80.0 74.0 58.0 122.0 80.0 80.0 64.0 63.0 83.0 58.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:77.50 stddev:20.66 (26.7%) stddev-sans-first:21.88 09:47:24 INFO - PROCESS | 1938 | Values: 86.0 114.0 125.0 99.0 69.0 68.0 96.0 69.0 68.0 98.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.11 (2.4%) stddev-sans-first:2.22 09:47:24 INFO - PROCESS | 1938 | Values: 87.0 84.0 90.0 85.0 88.0 83.0 88.0 85.0 87.0 86.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:1.43 (1.5%) stddev-sans-first:1.51 09:47:24 INFO - PROCESS | 1938 | Values: 93.0 93.0 93.0 92.0 97.0 92.0 93.0 94.0 94.0 93.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.00 stddev:0.95 (1.1%) stddev-sans-first:0.97 09:47:24 INFO - PROCESS | 1938 | Values: 83.0 85.0 84.0 84.0 84.0 83.0 83.0 85.0 84.0 82.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.99 (1.8%) stddev-sans-first:0.71 09:47:24 INFO - PROCESS | 1938 | Values: 52.0 54.0 54.0 55.0 55.0 54.0 55.0 55.0 53.0 54.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:75.60 Median:73.00 stddev:11.20 (15.3%) stddev-sans-first:11.20 09:47:24 INFO - PROCESS | 1938 | Values: 65.0 84.0 72.0 74.0 74.0 70.0 70.0 68.0 75.0 104.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.50 stddev:2.26 (2.7%) stddev-sans-first:2.37 09:47:24 INFO - PROCESS | 1938 | Values: 81.0 85.0 81.0 81.0 82.0 84.0 80.0 85.0 83.0 78.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:137.50 stddev:34.01 (24.7%) stddev-sans-first:35.71 09:47:24 INFO - PROCESS | 1938 | Values: 134.0 134.0 139.0 154.0 135.0 135.0 136.0 145.0 242.0 124.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:162.50 stddev:1.42 (0.9%) stddev-sans-first:1.50 09:47:24 INFO - PROCESS | 1938 | Values: 162.0 164.0 161.0 164.0 161.0 164.0 162.0 163.0 162.0 160.0 09:47:24 INFO - PROCESS | 1938 | 09:47:24 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.20 09:47:24 INFO - PROCESS | 1938 | Values: 68.0 67.0 66.0 68.0 67.0 67.0 66.0 64.0 66.0 65.0 09:47:24 INFO - PROCESS | 1938 | -------- Summary: end -------- 09:47:24 INFO - PROCESS | 1938 | 09:47:25 INFO - TEST-INFO | 1938: exit 0 09:47:25 INFO - TEST-OK | kraken | took 58762ms 09:47:25 INFO - SUITE-END | took 1175s 09:47:25 INFO - Completed test suite (00:19:35) 09:47:25 INFO - javascript benchmark 09:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.236115693389, 3324.4348460761667, 3345.7286231232138, 3355.8825441109534, 3397.065806903103, 5598.0, 5638.492535671789, 5755.65466072036, 5808.709564521773, 5812.738426770795, 5720.542356611471, 5756.079027355634, 5819.388964158766, 5847.473727364536, 5887.264091988502, 5125.641205115643, 5158.329417175762, 5208.401033881114, 5214.817481388152, 5313.256144139824, 5274.736263186845, 5301.867453313679, 5315.7873685052555, 5319.734013299339, 5348.5453736432255, 5121.206213036985, 5159.871003224912, 5198.9740051299505, 5237.1358725810005, 5251.50110739483, 5223.582113430916, 5233.869153271199, 5234.26720259164, 5242.580593552507, 5243.2659427680155, 5127.820526281562, 5153.613478989091, 5168.302279192336, 5194.402643695986, 5209.973950130263, 5671.6597004179885, 5745.626534275259, 5769.682667453289, 5820.708964551755, 5845.561582881301, 6187.226596675416, 6230.532710046719, 6253.906191407133, 6268.247810262752, 6283.277423096357, 6113.932424433829, 6216.689165541705, 6231.719572619198, 6234.657093859836, 6266.655333956631, 5857.238558987389, 5904.645721256696, 5911.9113213301835, 5920.4967577755515, 5926.4666180044005, 6210.130581718563, 6289.811305660837, 6299.905501417483, 6308.7476500940365, 6310.0850376695125, 6095.329513753483, 6124.142620033199, 6140.074827791203, 6179.104029915637, 6195.628262304186, 6255.593386429868, 6262.530310226751, 6275.811725648238, 6277.466415354746, 6284.02597597364, 6036.939630603638, 6040.879182416327, 6164.291106522807, 6223.37766223374, 6261.906071408933, 6141.881051037385, 6195.411435913581, 6196.8760624787255, 6225.28409232935, 6273.466755325757, 4959.801607935642, 4962.181240095416, 4964.60283177345, 4973.875653108701, 4994.600431965434, 4218.409422680827, 4422.557744225616, 4497.100579884036, 4524.343970124313, 4539.977300113478, 5017.4731653175895, 5737.282839645044, 5786.161006654096, 5787.450192231731, 5794.62334948227, 5255.39562950258, 6183.443490085914, 6262.060690896311, 6265.467435267962, 6268.561200715906, 5181.326427564468, 5372.683692495364, 5791.986402379567, 5800.303963524404, 5831.358550559435, 5437.483439073281, 5484.396716361197, 5485.286912701324, 5488.012157811632, 5489.4236105209175, 5762.884742305214, 6175.44421002112, 6176.073588961512, 6232.968835155886, 6240.5319601031015, 6224.0, 6233.283172435228, 6252.155958945484, 6260.968695156495, 6266.342034086355, 6148.95467770489, 6332.271788744353, 6353.428191462791, 6373.203349581302, 6383.553151279366, 6107.2420975189625, 6122.755089796358, 6173.81478555644, 6180.289266734203, 6222.564420490522, 6240.126382306572, 6242.812715618538, 6245.594036387621, 6260.029695397221, 6260.499160067275, 5947.881042379215, 6069.575129740876, 6166.445019948226, 6172.598781079216, 6189.133521307111, 6091.543134264859, 6179.382061793965, 6183.196184495987, 6187.0, 6192.566520343533, 5843.503302219359, 6011.308699499614, 6012.609180403259, 6071.028635418312, 6073.937061014393, 5930.851728706644, 6058.0, 6061.939380606137, 6151.6001459904965, 6158.3841615838055, 5027.924581131359, 5934.139549765347, 5989.101634754822, 5991.400859913973, 6022.45797878193], "value": 5703.922173851987, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.7392640642882, 527.7545941137371, 536.6055948877574, 557.3590371073268, 560.3080195957991, 29526.704732952665, 29822.70177298229, 41780.791096044515, 41955.79022104893, 42258.154836903246, 36122.0, 43039.56960430395, 43489.130217395636, 43504.347434788506, 43601.12797744043, 9523.57143928525, 9723.416595004323, 9726.319157658967, 9728.854067188999, 9750.609975601003, 8590.785230369229, 8772.385932984693, 11541.0, 11736.061115110771, 11754.942055215071, 11675.649730508056, 11701.356425396641, 11769.940705336518, 11784.82322765155, 11796.2332448391, 8670.176333248332, 11548.884511154865, 11687.415629218547, 11763.941180294129, 11778.11664125194, 11374.0, 11711.590094346657, 11712.941435292854, 11717.589884354007, 11731.7067073323, 41291.1741765166, 41429.0, 41507.377389339184, 41536.58463415357, 41549.0, 3578.910527236814, 3596.6583174598372, 3883.203943191639, 4199.9370009449885, 4368.258782914119, 31851.681483185104, 32635.51046734301, 32780.344393112246, 32910.506342404646, 33200.0, 3293.736501079908, 3335.8999230023137, 3370.5955285365912, 3444.8277586120844, 3450.258194488172, 534227.0, 536301.0, 536961.0, 536968.0, 538139.0, 58780.0, 60380.0, 60395.39604603986, 60504.09243861346, 60524.0, 35072.36957228218, 35156.648433515846, 35207.82396088003, 36741.44887826685, 36777.264454711294, 16980.575485612957, 17091.059991700575, 17184.570385740208, 17192.140392980302, 17210.225539850737, 11823.290602563873, 12109.273443593413, 12981.0, 13049.28228947398, 13165.868341316656, 18894.433167005012, 19069.0, 19182.328618498286, 19189.61620767605, 19239.51901202453, 7056.671298471492, 7292.781216563511, 8096.6924426539335, 8195.426320157532, 8283.544405057779, 871.9912800871911, 969.3360048366891, 998.3061772068488, 1099.6865893220395, 1139.67519257013, 30152.54771178434, 30409.0, 30735.385292294475, 30918.0, 31107.533386999214, 1096.5668560918552, 1097.4622435006788, 1136.9602063927723, 1158.7856246594406, 1195.246994393544, 12333.075019373582, 15473.690526189414, 15917.920410398105, 15950.361985520682, 16037.198140092949, 710349.4482527555, 714365.0, 716314.0, 717919.0, 718223.0, 57948.0, 59984.0, 60026.39973600208, 60177.39822601806, 60466.39533604607, 1303.361352937054, 1336.3117994232978, 1420.9360578773774, 1436.899417040797, 1465.4431316099815, 30061.549076764302, 31305.217369565944, 31581.368372632878, 31870.840645796623, 32090.19774505656, 49662.0, 49664.0, 50316.99366012732, 50393.0, 50416.74791626165, 538211.0, 539114.0, 539725.0, 540180.0, 542639.0, 23405.531889362457, 23653.52692946166, 26219.60670589981, 26403.943842246863, 26417.735822641527, 39010.829675109795, 47869.0, 48068.519314806406, 48272.793180170775, 48353.0, 40084.59915400925, 40139.0, 40278.0, 40800.3879941807, 41177.0, 32698.83650581732, 39502.0, 39615.0, 39657.603423966546, 40684.0, 37244.19987362375, 37581.43627845639, 38316.0, 39005.804970976096, 39193.41209881797, 34636.48045279373, 34674.0, 34783.304333912674, 35186.47220291646, 35314.0, 25095.372615684755, 25962.610560841225, 26022.219333420027, 26069.478610428065, 26101.955921762918], "value": 20431.164029586096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.373322022057, 359.04315000523604, 359.7893089752982, 391.9216156768647, 416.841600191927, 15876.603084922883, 17302.048387338706, 20991.895040524774, 21456.57086858264, 21512.0, 21674.566508669817, 22188.0, 22552.774472255274, 22775.54448911021, 22816.771832281673, 5089.796408143669, 5959.731812068446, 6159.7536098556, 6162.352952939944, 6220.284667263255, 6085.3914608539235, 6163.753449862, 6180.3201647818705, 6191.102290167945, 6198.411150940653, 5859.912101318484, 6095.299040610343, 6099.81700548982, 6100.542459315569, 6110.1140334651445, 5926.318473375574, 5973.790917317873, 5998.850028749294, 6068.0, 6092.055731361624, 5240.52835244826, 5243.10867152585, 5269.0, 5271.42014378418, 5275.894482110375, 27389.863050684817, 27423.177304680892, 27766.722332776615, 27851.303717407227, 27882.302942426602, 2568.730283320243, 2734.913814490282, 2801.5097357962277, 3124.9218769530717, 3220.1144685211752, 15696.529104126894, 16094.19529023544, 16197.02817830934, 16331.591710207222, 16913.0, 1838.4300866731355, 1881.5014021284371, 1902.1060101752155, 1906.4947788835832, 1944.251463186669, 94288.58567121498, 99653.0069398608, 118944.02639933984, 128578.3571082148, 133645.0, 11280.661580152608, 11300.378479183559, 11307.890330159273, 11439.485223164978, 11853.940730296294, 13991.020628556107, 14113.717725645429, 14126.152430853976, 14141.080829746033, 14145.929270353583, 8748.125187481328, 8867.822643547093, 8869.11308869108, 8953.28373730113, 8990.730278091667, 7438.0, 7476.0, 8808.03111657717, 8927.24118449939, 9075.591598378089, 6719.193696756322, 6844.699507093636, 6846.212685541127, 6846.281140480178, 6866.588004719732, 1852.397970659541, 1864.3754342295213, 1873.2600622754208, 1887.3862847265664, 1891.782445018813, 919.2737737187681, 969.3650658818485, 981.2836629260537, 1016.5120742043713, 1067.8840611560781, 22140.88929555374, 22290.442738931655, 23054.962526686002, 23089.307320780405, 23118.884405577865, 909.4270609516095, 942.119118624075, 973.5862258540092, 974.7660561465198, 995.9900400996042, 10780.730481738019, 11981.0, 12005.386778627108, 12090.395480225954, 12103.213291136048, 606047.0, 611839.0, 617040.0, 634374.0, 635308.0, 125904.37047814885, 125916.0, 127351.0, 148119.0, 149841.0, 1003.4280460137652, 1006.1548299428515, 1008.8839783424853, 1055.3140458701753, 1059.8569193158978, 19612.8038719611, 19631.595504047033, 20132.698009529577, 20206.29277975264, 20296.883671397623, 38033.80983095177, 38865.6113438862, 47296.52703472921, 52592.211116832514, 59442.40557594369, 86375.0, 86886.56556717429, 88073.0, 88297.55851220703, 90089.54955225444, 8119.269265766109, 8186.631601578062, 8388.58057097155, 8522.573871306535, 8616.569171541272, 11535.76928461443, 12763.617091487271, 13373.53192638253, 13483.393247303698, 13614.0, 11492.253003554742, 11560.94219528897, 11611.477483513094, 11626.941865290619, 11638.41807909618, 11151.776964460827, 11444.084473241954, 11477.0, 11525.250858694159, 11751.236169648946, 11709.121815863675, 11839.408029598659, 11887.16789824704, 11928.522859085539, 11969.581064662694, 8154.469959452616, 8229.917700823155, 8299.128591498047, 8416.2004609562, 8445.113263107285, 11128.944355278172, 11369.2609980351, 11427.771444570895, 11560.94219528897, 11616.825747613624], "value": 10103.694144628858, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.4314274290289, 1569.4742261342453, 1588.2217713320472, 1807.096451774113, 1931.5847092875028, 7753.263439973201, 8622.568871556421, 8842.557872106401, 8848.823023539533, 8910.97523784765, 7728.884066739003, 7818.648160832762, 8563.957180214105, 8729.432586881863, 8741.737747867559, 2008.317172161465, 2332.8367014308965, 2369.218158007862, 2371.5968285391523, 2375.95248095038, 1984.9206031758713, 2293.3005433342823, 2297.4600968772324, 2302.3093072078395, 2302.3898666853297, 2318.9768102319013, 2338.3101984914492, 2347.4718188407555, 2361.456864921069, 2363.385519764865, 2107.283523601983, 2341.8126549876056, 2348.7768661977084, 2351.1183306260154, 2352.094443639203, 2024.4129202531396, 2334.9299521014395, 2335.5795956727784, 2338.0530884991604, 2338.8947497362487, 7505.474616776828, 8731.563421828947, 8786.780330491725, 8820.735377938672, 9011.41425807327, 4569.771511424416, 5326.094563924142, 5453.618246722731, 5455.0, 5487.972560137174, 3108.9067327980197, 3624.710023198164, 3701.8519259229597, 3710.666040056381, 3713.795741234231, 1097.1990446973755, 1102.735343517558, 1109.7225693576606, 1110.7556337605713, 1119.0096764363598, 11758.412079396081, 11927.88072119285, 11937.701557461047, 11952.462139203757, 11986.940065299617, 4400.471943366752, 4494.348319493655, 4618.076384723069, 4640.721556706609, 4662.277347011219, 9330.160285574331, 9330.30022748283, 9333.300002499704, 9338.95330523343, 9355.719328420158, 4787.880302992384, 5274.973625131926, 5281.524662780368, 5294.49702278283, 5300.469953004668, 4456.3538286948215, 4599.471060827981, 4800.783964721596, 4801.375821143229, 4964.081644895706, 3756.229972855558, 3760.2855457462992, 3838.5777564467885, 3869.090763670535, 3877.4377715231135, 1067.3329169269207, 1129.6949823547595, 1176.0944073063863, 1187.2045729361348, 1187.958421455245, 669.3473862983552, 808.9322094834879, 916.4134953629682, 953.270747877874, 975.0882924465648, 5929.644221346733, 6049.21360223177, 6183.752649894044, 6203.875922481525, 6268.811935641938, 430.9956900430956, 437.0886701227975, 446.3438745044779, 450.38747303666986, 613.3682307223567, 3663.7984910829628, 3955.129871428281, 4147.108371700099, 4173.937390939138, 4237.618614324726, 10499.68500944973, 10677.305975111592, 10913.345199288067, 10914.017738403581, 10946.616868409568, 10783.299085559414, 10875.347479151276, 10875.45622718861, 10886.510107045202, 10905.781884362426, 7861.449698521163, 8234.547099909563, 8369.874451883226, 8380.58097095155, 8381.245687888118, 4989.575886049726, 6067.271927368744, 6127.724252408563, 6198.72105755233, 6215.06773983906, 8609.569521524025, 9725.85411218818, 9903.108720215216, 10135.189184865341, 10152.847707284249, 10289.639862604772, 10330.896691032993, 10349.896501034893, 10352.53413596405, 10354.326968747007, 6762.222344430454, 7570.545752363847, 7595.202503737249, 7740.380769538311, 7751.263629955145, 8293.792655183668, 8383.748487545383, 8436.0, 8443.408961372645, 8451.02813176468, 6203.0, 7495.962520187582, 7509.849803004018, 7513.0, 7794.610269486617, 7349.30181632744, 7402.481826272325, 7490.325870671666, 7536.811579710551, 7592.696292148252, 7047.894281585678, 7057.682404291717, 7106.360427561544, 7148.070750802362, 7182.245864184186, 5015.623828212826, 5123.3339665843205, 5159.716215608178, 5181.248718935809, 5205.271262023244, 4976.676516026447, 5161.612879034013, 5168.405633352213, 5204.895902082013, 5209.4009188943755], "value": 4478.2414616049255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.3642040891656, 269.3186238815797, 312.56865525574705, 317.33834954120664, 323.9012101309101, 5034.370703662043, 5078.476457062885, 5337.2527846101575, 5381.300430943977, 5422.83731488055, 4461.977690111545, 4686.102128637898, 4701.412323459567, 5352.304200453936, 5390.245365648812, 4067.3695577185586, 4069.3895915612748, 4098.344264917607, 4099.651529619986, 4132.97933510332, 3765.642263984917, 3839.2513459875336, 4117.6940913088765, 4132.710710250284, 4148.315527937902, 3443.5867695876404, 4028.436018957348, 4033.838646454138, 4037.2329257440992, 4110.17796440713, 3871.651551360377, 4037.2531081749676, 4053.8175782089875, 4077.184563087365, 4078.5105787305415, 3534.2505459797567, 3535.611082780892, 3536.469529570559, 3545.6454354564594, 3562.8218589070702, 5051.52010559, 5154.252633368178, 5199.272101905736, 5203.7398130093725, 5228.686278823283, 4636.35091087248, 4639.909621239006, 4653.115907977473, 4668.906621867543, 4690.554397332248, 4885.340479035321, 5273.077211487975, 5571.470710282517, 5600.52395546375, 5641.71791410432, 3422.8801991930163, 3846.807659617036, 3862.4206369044587, 3863.265979463893, 3875.2561347393785, 4923.458419573844, 5861.912071318934, 5937.257842769611, 5938.524918006592, 5952.666506756231, 3791.7724936503896, 3903.980480097638, 3939.783311917815, 3957.2785838159916, 3962.266980608539, 3410.782991055784, 3944.8422063117155, 3945.5265368156006, 3958.2281455116554, 3989.3018721723583, 3897.649211570972, 3932.803359831997, 4634.83778067766, 4638.567460079516, 4658.693946496506, 5494.560435165177, 5679.858003549944, 5717.456841600041, 5727.742251598688, 5737.913931291034, 1306.79744639581, 1356.3964036004081, 1358.8844948179515, 1360.8843248323806, 1364.6588352911772, 806.5563939833069, 807.8303556253135, 828.2297463359134, 829.1542626520915, 833.0, 4433.3596569269575, 4477.395551600557, 4480.731156130642, 4488.498970802779, 4506.549345065468, 4032.7819699165343, 4444.066745983379, 4666.836660716858, 4799.896023914519, 4848.5636292733825, 3888.6111388861455, 4192.559781222989, 4453.487848897354, 4459.754713490789, 4468.620167285817, 2402.3753824005885, 2548.6941567011704, 2561.244432892301, 2570.755778201068, 2612.7458819766616, 4644.489106198315, 4980.003999200175, 4999.750162394473, 5012.473690262543, 5038.622103342264, 4095.774732389747, 4430.689851710414, 4608.308753686907, 4769.140035386202, 4794.017226468565, 4285.378854867694, 4401.471823381215, 4509.842155524541, 4699.882502937454, 5020.59835213182, 4821.397325334333, 4822.734749588806, 4860.809101770089, 4872.200882131169, 4892.385512780703, 4146.564610715832, 4492.663050271178, 4625.7918393673035, 4747.62019038483, 4823.975880120577, 4753.263244197155, 5459.808906688406, 5480.753366098455, 5581.776728930798, 5836.299644042572, 3459.394605944, 3703.2408356287438, 3846.8265430031943, 3876.670483008863, 3973.9667686401167, 4514.0, 5118.488151184852, 5453.509184173444, 5719.942800571941, 5995.760169593167, 4860.878478038077, 4939.728314942713, 5186.792528298976, 5580.2466666999435, 5744.023516002373, 4631.3563677478405, 5365.758540865592, 5626.2685850839125, 5635.408282130317, 5648.486423541484, 5272.077386457431, 5509.614326997072, 5524.06090964529, 5524.502794748492, 5526.0, 5314.176302673091, 5629.662220266797, 5636.0, 5685.971570142123, 5721.199032135588, 5336.919946200733, 5396.595255355943, 5565.6104072714525, 5581.218629391969, 5639.915401268902], "value": 3914.286681501418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.5832654550179, 755.2409828122736, 801.9077806052304, 811.0348685064772, 851.9020312664046, 15003.774943375844, 15495.457658981937, 15995.84004159958, 16072.598185045379, 16099.83900160998, 14546.781798273034, 14754.040987335815, 14771.335289911953, 14774.482893098744, 14784.704305913876, 720.5064530796421, 720.7945735465394, 745.8135466133467, 746.164295988491, 887.0641473245723, 727.9454040946923, 895.2524641923969, 902.3322741171552, 907.8411278026352, 914.5747227539193, 955.0783493928358, 971.6696323250094, 972.9464879431629, 973.9464329461877, 975.795083032564, 958.5494817435793, 959.5394210778795, 961.1397798969953, 962.7352478068552, 963.4845357733614, 565.8981383351037, 705.0833915909298, 709.8296408861887, 722.2993696114813, 724.6811402982671, 15066.322015509328, 15083.773743393747, 15086.924565377212, 15108.169050702207, 15133.924330378277, 13931.791023134661, 14305.427782888672, 14331.713365732627, 14334.283285835667, 14386.712265754732, 12863.421146048357, 12936.700792733996, 12970.546030888874, 13099.95200383967, 13112.934435327857, 3750.1187221003042, 3889.9805500972598, 3903.316919539069, 3904.9219015619815, 4041.2523683118716, 114872.0, 116590.0, 116946.41526792312, 116953.83046169515, 117132.0, 10857.565697372016, 11168.441577921072, 11172.720681983015, 11222.55109795599, 11679.88320116805, 21114.472138196666, 21410.892945535485, 21454.570908582053, 21457.46356341104, 21697.915104244723, 6922.826929326707, 7906.130325664172, 7995.040595128506, 7997.6800927963395, 8075.313598344089, 4443.488998765119, 4511.909761804745, 4524.819007239739, 4540.250858608359, 4717.0, 3528.5765708114677, 3640.3993341098953, 3645.1069487975087, 3649.2154186849953, 3657.6159503251774, 676.4081428749844, 679.8334408069956, 685.2633419074515, 694.9652517374212, 698.4203111417511, 590.719408281063, 624.8437890527368, 644.48119263992, 694.1427337238506, 714.324962910045, 9346.299027572959, 9595.47224902623, 9641.464887717599, 9676.274279429072, 9774.364666296668, 665.9700313485905, 667.7596065416446, 694.3438450664074, 703.2545501768142, 706.5513398991677, 4284.507281662586, 4650.790714417859, 4708.929366059512, 4713.010267843792, 4714.75952743334, 110576.89423105826, 153132.0, 154813.0, 156887.0, 158465.0, 131700.0, 133342.0, 134144.0, 134626.99999999805, 137396.0, 113209.0, 114634.15845764849, 116677.9999999983, 117416.0, 118019.0, 8312.584370781558, 8796.956015219883, 8816.823663526822, 8916.598753056258, 9041.457512549268, 60841.0, 60967.390326096174, 62968.68515657393, 63243.683781580796, 63808.36191638024, 81258.0, 89182.55408723175, 95884.0, 97257.51371243098, 97961.51019244858, 11599.304041757521, 11678.416079196177, 11794.823077653671, 11808.76382472363, 12337.691557711129, 12862.7932418851, 12974.221546707227, 13061.412236449572, 13180.406881690538, 13518.05373623837, 12139.0, 12151.939240303742, 12367.567135150226, 12720.045996550482, 12749.980001600035, 12107.515699371927, 12252.448639811051, 12352.197107188003, 12468.314242716737, 12597.748045038865, 11825.704357391134, 12031.097667674785, 12035.819462708241, 12149.939250303692, 12496.43766030549, 10087.798244035224, 10256.12822910061, 10451.216158788273, 10749.14006879432, 10914.836277456003, 10416.333865814939, 10467.424291663727, 10640.201984851323, 10673.146148308271, 10696.304740191863], "value": 7838.843522461062, "unit": "score"}], "name": "dromaeo_css", "value": 7381.902218958891, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 109.0, 107.0, 114.0, 112.0, 114.0, 114.0, 112.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 106.0, 155.0, 107.0, 107.0, 129.0, 107.0, 140.0, 105.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 183.0, 183.0, 182.0, 184.0, 179.0, 193.0, 260.0, 182.0], "value": 191.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 122.0, 80.0, 80.0, 64.0, 63.0, 83.0, 58.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 114.0, 125.0, 99.0, 69.0, 68.0, 96.0, 69.0, 68.0, 98.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 90.0, 85.0, 88.0, 83.0, 88.0, 85.0, 87.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 97.0, 92.0, 93.0, 94.0, 94.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 84.0, 84.0, 83.0, 83.0, 85.0, 84.0, 82.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 84.0, 72.0, 74.0, 74.0, 70.0, 70.0, 68.0, 75.0, 104.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 81.0, 81.0, 82.0, 84.0, 80.0, 85.0, 83.0, 78.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 139.0, 154.0, 135.0, 135.0, 136.0, 145.0, 242.0, 124.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 164.0, 161.0, 164.0, 162.0, 163.0, 162.0, 160.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 68.0, 67.0, 67.0, 66.0, 64.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1440.4, "alertThreshold": 2.0}]} 09:47:25 INFO - Return code: 0 09:47:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:25 INFO - # TBPL SUCCESS # 09:47:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:25 INFO - 2016-07-05 09:47:25.890 screenresolution[1942:15800] starting screenresolution argv=screenresolution get 09:47:25 INFO - 2016-07-05 09:47:25.893 screenresolution[1942:15800] Display 0: 1600x1200x32@60 09:47:25 INFO - 2016-07-05 09:47:25.899 screenresolution[1943:15803] starting screenresolution argv=screenresolution list 09:47:25 INFO - Available Modes on Display 0 09:47:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:26 INFO - Intel Iris: 09:47:26 INFO - Chipset Model: Intel Iris 09:47:26 INFO - Type: GPU 09:47:26 INFO - Bus: Built-In 09:47:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:26 INFO - Vendor: Intel (0x8086) 09:47:26 INFO - Device ID: 0x0a2e 09:47:26 INFO - Revision ID: 0x0009 09:47:26 INFO - Displays: 09:47:26 INFO - Display: 09:47:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:26 INFO - Display Serial Number: 1600x1200 60 09:47:26 INFO - Main Display: Yes 09:47:26 INFO - Mirror: Off 09:47:26 INFO - Online: Yes 09:47:26 INFO - Rotation: Supported 09:47:26 INFO - Return code: 0 09:47:26 INFO - Running post-action listener: _resource_record_post_action 09:47:26 INFO - ##### 09:47:26 INFO - ##### Finished run-tests step (success) 09:47:26 INFO - ##### 09:47:26 INFO - Running post-run listener: _resource_record_post_run 09:47:26 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 864256; Write bytes: 705423872; Read time: 7; Write time: 1887 09:47:26 INFO - TinderboxPrint: CPU usage
53.7% 09:47:26 INFO - TinderboxPrint: I/O read bytes / time
864,256 / 7 09:47:26 INFO - TinderboxPrint: I/O write bytes / time
705,423,872 / 1,887 09:47:26 INFO - TinderboxPrint: CPU idle
2,241.4 (46.3%) 09:47:26 INFO - TinderboxPrint: CPU system
966.8 (20.0%) 09:47:26 INFO - TinderboxPrint: CPU user
1,633.4 (33.7%) 09:47:26 INFO - TinderboxPrint: Swap in / out
407,351,296 / 0 09:47:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402651648; Write bytes: 398735360; Read time: 29634; Write time: 791 09:47:26 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 823296; Write bytes: 292844032; Read time: 6; Write time: 1055 09:47:26 INFO - Running post-run listener: _upload_blobber_files 09:47:26 INFO - Blob upload gear active. 09:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:26 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 09:47:26 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:47:26 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:47:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:27 INFO - (blobuploader) - INFO - Done attempting. 09:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:47:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:29 INFO - (blobuploader) - INFO - Done attempting. 09:47:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:31 INFO - (blobuploader) - INFO - Done attempting. 09:47:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:31 INFO - Return code: 0 09:47:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e00ffa1aa5f929ca94c9a7b742364756db39c97cbc44f75a8a66789b94e1ddaeb3a99fca75ad91cd41901b93282644740d109d82f8ec1573aa792e40044134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa2e3b40c5c1f065415bdfad58cccd4e68b5906ac912cb874b09e148e87720f3ce9ba23276ed9c9772224006c7a92c1e4db9e328b1c97a5c6e274219512f84b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92dcf2c22da96ba146efe08729acbce0549327bf193a36904d0dd546bf7e94f30598eeb3c6c11e1fba86c9137ac69194cb709a829bf9201128748517478169f"} 09:47:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:31 INFO - Contents: 09:47:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e00ffa1aa5f929ca94c9a7b742364756db39c97cbc44f75a8a66789b94e1ddaeb3a99fca75ad91cd41901b93282644740d109d82f8ec1573aa792e40044134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa2e3b40c5c1f065415bdfad58cccd4e68b5906ac912cb874b09e148e87720f3ce9ba23276ed9c9772224006c7a92c1e4db9e328b1c97a5c6e274219512f84b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92dcf2c22da96ba146efe08729acbce0549327bf193a36904d0dd546bf7e94f30598eeb3c6c11e1fba86c9137ac69194cb709a829bf9201128748517478169f"} 09:47:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:31 INFO - Copying logs to upload dir... 09:47:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.681484 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-05 09:47:31.755999) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:47:31.761520) ========= 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.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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/Firefox/sha512/33e00ffa1aa5f929ca94c9a7b742364756db39c97cbc44f75a8a66789b94e1ddaeb3a99fca75ad91cd41901b93282644740d109d82f8ec1573aa792e40044134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa2e3b40c5c1f065415bdfad58cccd4e68b5906ac912cb874b09e148e87720f3ce9ba23276ed9c9772224006c7a92c1e4db9e328b1c97a5c6e274219512f84b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92dcf2c22da96ba146efe08729acbce0549327bf193a36904d0dd546bf7e94f30598eeb3c6c11e1fba86c9137ac69194cb709a829bf9201128748517478169f"} build_url:https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010640 build_url: 'https://queue.taskcluster.net/v1/task/MFe_9riEQ6KKwjUhzh8Cqg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e00ffa1aa5f929ca94c9a7b742364756db39c97cbc44f75a8a66789b94e1ddaeb3a99fca75ad91cd41901b93282644740d109d82f8ec1573aa792e40044134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3aa2e3b40c5c1f065415bdfad58cccd4e68b5906ac912cb874b09e148e87720f3ce9ba23276ed9c9772224006c7a92c1e4db9e328b1c97a5c6e274219512f84b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92dcf2c22da96ba146efe08729acbce0549327bf193a36904d0dd546bf7e94f30598eeb3c6c11e1fba86c9137ac69194cb709a829bf9201128748517478169f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:47:31.797602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:47:31.798230) ========= 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.mLweRMXqIg/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.gg4T21w1rC/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.005181 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:47:31.850095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:47:31.850617) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:47:31.850952) ========= ========= Total master_lag: 0.19 =========