builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0047 starttime: 1467648972.91 results: success (0) buildid: 20160704065143 builduid: 77f35740e5f448a98c1c8a8c40608da9 revision: 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:12.906596) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:12.907013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:12.907285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:12.931538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:12.931866) ========= 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.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-04 09:16:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.3M=0.001s 2016-07-04 09:16:13 (19.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:13.088816) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:13.089151) ========= 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.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.029954 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:13.140641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 09:16:13.141018) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-04 09:16:13,214 truncating revision to first 12 chars 2016-07-04 09:16:13,214 Setting DEBUG logging. 2016-07-04 09:16:13,214 attempt 1/10 2016-07-04 09:16:13,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3e04c4853a8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 09:16:14,256 unpacking tar archive at: mozilla-beta-3e04c4853a8b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299788 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 09:16:14.463165) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:14.463505) ========= script_repo_revision: 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:14.463906) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:14.464260) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:14.482619) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-04 09:16:14.483043) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:14 INFO - MultiFileLogger online at 20160704 09:16:14 in /builds/slave/test 09:16:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:16:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:14 INFO - {'append_to_log': False, 09:16:14 INFO - 'base_work_dir': '/builds/slave/test', 09:16:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:16:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:14 INFO - 'branch': 'Mozilla-Beta', 09:16:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:14 INFO - 'config_files': ('talos/mac_config.py',), 09:16:14 INFO - 'default_actions': ('clobber', 09:16:14 INFO - 'read-buildbot-config', 09:16:14 INFO - 'download-and-extract', 09:16:14 INFO - 'populate-webroot', 09:16:14 INFO - 'create-virtualenv', 09:16:14 INFO - 'install', 09:16:14 INFO - 'run-tests'), 09:16:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:14 INFO - 'download_minidump_stackwalk': True, 09:16:14 INFO - 'download_symbols': 'ondemand', 09:16:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:14 INFO - 'installer_path': 'installer.exe', 09:16:14 INFO - 'log_level': 'info', 09:16:14 INFO - 'log_name': 'talos', 09:16:14 INFO - 'log_to_console': True, 09:16:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:14 INFO - 'opt_config_files': (), 09:16:14 INFO - 'pip_index': False, 09:16:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:14 INFO - 'cmd': ('bash', 09:16:14 INFO - '-c', 09:16:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:14 INFO - 'enabled': True, 09:16:14 INFO - 'halt_on_failure': False, 09:16:14 INFO - 'name': 'check_screen_resolution'},), 09:16:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:14 INFO - 'cmd': ('bash', 09:16:14 INFO - '-c', 09:16:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:14 INFO - 'enabled': True, 09:16:14 INFO - 'halt_on_failure': False, 09:16:14 INFO - 'name': 'check_screen_resolution'},), 09:16:14 INFO - 'run_cmd_checks_enabled': True, 09:16:14 INFO - 'sps_profile': False, 09:16:14 INFO - 'sps_profile_interval': 0, 09:16:14 INFO - 'suite': 'dromaeojs', 09:16:14 INFO - 'system_bits': '32', 09:16:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:14 INFO - 'title': 't-yosemite-r7-0047', 09:16:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:14 INFO - 'use_talos_json': True, 09:16:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:14 INFO - 'work_dir': 'build'} 09:16:14 INFO - ##### 09:16:14 INFO - ##### Running clobber step. 09:16:14 INFO - ##### 09:16:14 INFO - Running pre-action listener: _resource_record_pre_action 09:16:14 INFO - Running main action method: clobber 09:16:14 INFO - rmtree: /builds/slave/test/build 09:16:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:17 INFO - Running post-action listener: _resource_record_post_action 09:16:17 INFO - ##### 09:16:17 INFO - ##### Running read-buildbot-config step. 09:16:17 INFO - ##### 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: read_buildbot_config 09:16:17 INFO - Using buildbot properties: 09:16:17 INFO - { 09:16:17 INFO - "project": "", 09:16:17 INFO - "product": "firefox", 09:16:17 INFO - "script_repo_revision": "production", 09:16:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:16:17 INFO - "repository": "", 09:16:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:16:17 INFO - "buildid": "20160704065143", 09:16:17 INFO - "pgo_build": "False", 09:16:17 INFO - "basedir": "/builds/slave/test", 09:16:17 INFO - "buildnumber": 11, 09:16:17 INFO - "slavename": "t-yosemite-r7-0047", 09:16:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:16:17 INFO - "platform": "yosemite_r7", 09:16:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:16:17 INFO - "branch": "mozilla-beta", 09:16:17 INFO - "repo_path": "releases/mozilla-beta", 09:16:17 INFO - "revision": "3e04c4853a8bc95b6b485c42fb7e1e2d7500316b", 09:16:17 INFO - "stage_platform": "macosx64", 09:16:17 INFO - "builduid": "77f35740e5f448a98c1c8a8c40608da9", 09:16:17 INFO - "slavebuilddir": "test" 09:16:17 INFO - } 09:16:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:16:17 INFO - Running post-action listener: _resource_record_post_action 09:16:17 INFO - ##### 09:16:17 INFO - ##### Running download-and-extract step. 09:16:17 INFO - ##### 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: download_and_extract 09:16:17 INFO - mkdir: /builds/slave/test/build/tests 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:17 INFO - https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:16:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:16:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:16:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 09:16:19 INFO - Downloaded 1165 bytes. 09:16:19 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:16:19 INFO - Using the following test package requirements: 09:16:19 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:16:19 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:16:19 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:16:19 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:16:19 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:16:19 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:16:19 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:16:19 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:16:19 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:16:19 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:16:19 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:16:19 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:19 INFO - https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:16:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:16:20 INFO - Downloaded 18351373 bytes. 09:16:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:16:24 INFO - Return code: 0 09:16:24 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:24 INFO - https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:16:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:16:25 INFO - Downloaded 18351373 bytes. 09:16:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:16:28 INFO - Return code: 0 09:16: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:16:28 INFO - https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:16:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:16:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:16:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 09:16:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 09:16:30 INFO - Downloaded 10840145 bytes. 09:16:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:16:30 INFO - Return code: 0 09:16:30 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:16:30 INFO - https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:16:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:16:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:16:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 09:16:32 INFO - Downloaded 86196126 bytes. 09:16:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:16:32 INFO - mkdir: /builds/slave/test/properties 09:16:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:32 INFO - Contents: 09:16:32 INFO - build_url:https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:16:33 INFO - Running post-action listener: _resource_record_post_action 09:16:33 INFO - Running post-action listener: set_extra_try_arguments 09:16:33 INFO - ##### 09:16:33 INFO - ##### Running populate-webroot step. 09:16:33 INFO - ##### 09:16:33 INFO - Running pre-action listener: _resource_record_pre_action 09:16:33 INFO - Running main action method: populate_webroot 09:16:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:33 INFO - '--tppagecycles', 09:16:33 INFO - '7'], 09:16:33 INFO - 'tests': ['tsvgm']}}, 09:16:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['damp', 'tps']}, 09:16:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'tests': ['damp', 'tps']}, 09:16:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['dromaeo_dom']}, 09:16:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['a11yr', 09:16:33 INFO - 'ts_paint', 09:16:33 INFO - 'tpaint', 09:16:33 INFO - 'sessionrestore', 09:16:33 INFO - 'sessionrestore_no_auto_restore', 09:16:33 INFO - 'tabpaint']}, 09:16:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['tsvgx', 09:16:33 INFO - 'tsvgr_opacity', 09:16:33 INFO - 'tart', 09:16:33 INFO - 'tscrollx', 09:16:33 INFO - 'cart']}, 09:16:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:33 INFO - 'tsvgr_opacity', 09:16:33 INFO - 'tart', 09:16:33 INFO - 'tscrollx', 09:16:33 INFO - 'cart']}, 09:16:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'talos_options': ['--disable-e10s'], 09:16:33 INFO - 'tests': ['tp5o']}, 09:16:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'tests': ['tp5o']}, 09:16:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'talos_options': ['--disable-e10s', 09:16:33 INFO - '--xperf_path', 09:16:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:33 INFO - 'tests': ['tp5n']}, 09:16:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:33 INFO - 'talos_options': ['--xperf_path', 09:16:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:33 INFO - 'tests': ['tp5n']}}, 09:16:33 INFO - 'talos.zip': {'path': '', 09:16:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:33 INFO - Running post-action listener: _resource_record_post_action 09:16:33 INFO - ##### 09:16:33 INFO - ##### Running create-virtualenv step. 09:16:33 INFO - ##### 09:16:33 INFO - Running pre-action listener: _resource_record_pre_action 09:16:33 INFO - Running main action method: create_virtualenv 09:16:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:33 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:16:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:33 INFO - Using real prefix '/tools/python27' 09:16:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:34 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:37 INFO - Installing pip.................done. 09:16:37 INFO - Return code: 0 09:16:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:37 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:16:37 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:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:37 INFO - 'HOME': '/Users/cltbld', 09:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:37 INFO - 'LOGNAME': 'cltbld', 09:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:37 INFO - 'MOZ_NO_REMOTE': '1', 09:16:37 INFO - 'NO_EM_RESTART': '1', 09:16:37 INFO - 'PAGER': '/bin/cat', 09:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:37 INFO - 'PWD': '/builds/slave/test', 09:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:37 INFO - 'SHELL': '/bin/bash', 09:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:37 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:37 INFO - 'USER': 'cltbld', 09:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:37 INFO - 'XPC_FLAGS': '0x0', 09:16:37 INFO - 'XPC_SERVICE_NAME': '0', 09:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:37 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:16:37 INFO - Cleaning up... 09:16:37 INFO - Return code: 0 09:16:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:37 INFO - 'HOME': '/Users/cltbld', 09:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:37 INFO - 'LOGNAME': 'cltbld', 09:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:37 INFO - 'MOZ_NO_REMOTE': '1', 09:16:37 INFO - 'NO_EM_RESTART': '1', 09:16:37 INFO - 'PAGER': '/bin/cat', 09:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:37 INFO - 'PWD': '/builds/slave/test', 09:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:37 INFO - 'SHELL': '/bin/bash', 09:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:37 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:37 INFO - 'USER': 'cltbld', 09:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:37 INFO - 'XPC_FLAGS': '0x0', 09:16:37 INFO - 'XPC_SERVICE_NAME': '0', 09:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:37 INFO - Downloading/unpacking psutil>=0.7.1 09:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:40 INFO - Installing collected packages: psutil 09:16:40 INFO - Running setup.py install for psutil 09:16:40 INFO - building 'psutil._psutil_osx' extension 09:16:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:16:41 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:16:41 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:16:41 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:16:41 INFO - building 'psutil._psutil_posix' extension 09:16:41 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:16:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:41 INFO - ^ 09:16:41 INFO - 1 warning generated. 09:16:41 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:16:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:42 INFO - Successfully installed psutil 09:16:42 INFO - Cleaning up... 09:16:42 INFO - Return code: 0 09:16:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:42 INFO - 'HOME': '/Users/cltbld', 09:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:42 INFO - 'LOGNAME': 'cltbld', 09:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:42 INFO - 'MOZ_NO_REMOTE': '1', 09:16:42 INFO - 'NO_EM_RESTART': '1', 09:16:42 INFO - 'PAGER': '/bin/cat', 09:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:42 INFO - 'PWD': '/builds/slave/test', 09:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:42 INFO - 'SHELL': '/bin/bash', 09:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:42 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:42 INFO - 'USER': 'cltbld', 09:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:42 INFO - 'XPC_FLAGS': '0x0', 09:16:42 INFO - 'XPC_SERVICE_NAME': '0', 09:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:16:42 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:16:42 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:16:42 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:16:42 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:16:42 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:16:42 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:16:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:16:44 INFO - Installing collected packages: mozsystemmonitor 09:16:44 INFO - Running setup.py install for mozsystemmonitor 09:16:45 INFO - Successfully installed mozsystemmonitor 09:16:45 INFO - Cleaning up... 09:16:45 INFO - Return code: 0 09:16:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:45 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:16:45 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:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:45 INFO - 'HOME': '/Users/cltbld', 09:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PAGER': '/bin/cat', 09:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:45 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - 'XPC_FLAGS': '0x0', 09:16:45 INFO - 'XPC_SERVICE_NAME': '0', 09:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:48 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:16:48 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:16:48 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:16:48 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:16:48 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:16:48 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:16:49 INFO - Downloading docopt-0.6.1.tar.gz 09:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:49 INFO - Running setup.py install for blobuploader 09:16:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:49 INFO - Running setup.py install for requests 09:16:49 INFO - Running setup.py install for docopt 09:16:49 INFO - Successfully installed blobuploader requests docopt 09:16:49 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:50 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:16:50 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:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:50 INFO - 'HOME': '/Users/cltbld', 09:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PAGER': '/bin/cat', 09:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:50 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - 'XPC_FLAGS': '0x0', 09:16:50 INFO - 'XPC_SERVICE_NAME': '0', 09:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-affceZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-9SjfXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-RuDsNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-v4qaUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-E_Iu1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-itbEMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:50 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-2PLpm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-CR_vRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-0tYqu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-6PtmpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-7muHH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-NOWJ45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-v6Bk8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-CEpp6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-BPMXWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:51 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-pYXLCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:52 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-S0aAUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:52 INFO - Running setup.py install for manifestparser 09:16:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:52 INFO - Running setup.py install for mozcrash 09:16:52 INFO - Running setup.py install for mozdebug 09:16:52 INFO - Running setup.py install for mozdevice 09:16:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:52 INFO - Running setup.py install for mozfile 09:16:52 INFO - Running setup.py install for mozhttpd 09:16:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:52 INFO - Running setup.py install for mozinfo 09:16:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Running setup.py install for mozInstall 09:16:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Running setup.py install for mozleak 09:16:53 INFO - Running setup.py install for mozlog 09:16:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Running setup.py install for moznetwork 09:16:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:53 INFO - Running setup.py install for mozprocess 09:16:53 INFO - Running setup.py install for mozprofile 09:16:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:54 INFO - Running setup.py install for mozrunner 09:16:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:54 INFO - Running setup.py install for mozscreenshot 09:16:54 INFO - Running setup.py install for moztest 09:16:54 INFO - Running setup.py install for mozversion 09:16:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:54 INFO - Cleaning up... 09:16:54 INFO - Return code: 0 09:16:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:54 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:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:54 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:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:54 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:54 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:16:54 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:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:54 INFO - 'HOME': '/Users/cltbld', 09:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:54 INFO - 'LOGNAME': 'cltbld', 09:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:54 INFO - 'MOZ_NO_REMOTE': '1', 09:16:54 INFO - 'NO_EM_RESTART': '1', 09:16:54 INFO - 'PAGER': '/bin/cat', 09:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:54 INFO - 'PWD': '/builds/slave/test', 09:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:54 INFO - 'SHELL': '/bin/bash', 09:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:54 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:54 INFO - 'USER': 'cltbld', 09:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:54 INFO - 'XPC_FLAGS': '0x0', 09:16:54 INFO - 'XPC_SERVICE_NAME': '0', 09:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:54 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-VorI_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-srHXx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-eC4Jyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-eKQwlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-WXozY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ifvrVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-lFeFPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-7h7ThG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-fVG28q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:55 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:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ut03sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:55 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-gW3kiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-4uneKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-xT0i6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-nLnjVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-z_7lg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-uEXiJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:56 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:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:56 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-X2kUy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:56 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:16:56 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:16:56 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:16:56 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:16:56 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:16:56 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:16:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16: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:16: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:16: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:16: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:16: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:16: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:16:59 INFO - Downloading blessings-1.6.tar.gz 09:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:59 INFO - Installing collected packages: blessings 09:16:59 INFO - Running setup.py install for blessings 09:16:59 INFO - Successfully installed blessings 09:16:59 INFO - Cleaning up... 09:16:59 INFO - Return code: 0 09:16:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:59 INFO - Reading from file tmpfile_stdout 09:16:59 INFO - Current package versions: 09:16:59 INFO - blessings == 1.6 09:16:59 INFO - blobuploader == 1.2.4 09:16:59 INFO - docopt == 0.6.1 09:16:59 INFO - manifestparser == 1.1 09:16:59 INFO - mozInstall == 1.12 09:16:59 INFO - mozcrash == 0.17 09:16:59 INFO - mozdebug == 0.1 09:16:59 INFO - mozdevice == 0.48 09:16:59 INFO - mozfile == 1.2 09:16:59 INFO - mozhttpd == 0.7 09:16:59 INFO - mozinfo == 0.9 09:16:59 INFO - mozleak == 0.1 09:16:59 INFO - mozlog == 3.1 09:16:59 INFO - moznetwork == 0.27 09:16:59 INFO - mozprocess == 0.22 09:16:59 INFO - mozprofile == 0.28 09:16:59 INFO - mozrunner == 6.11 09:16:59 INFO - mozscreenshot == 0.1 09:16:59 INFO - mozsystemmonitor == 0.0 09:16:59 INFO - moztest == 0.7 09:16:59 INFO - mozversion == 1.4 09:16:59 INFO - psutil == 3.1.1 09:16:59 INFO - requests == 1.2.3 09:16:59 INFO - wsgiref == 0.1.2 09:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16: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:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:59 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:16:59 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:16:59 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:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:59 INFO - 'HOME': '/Users/cltbld', 09:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:59 INFO - 'LOGNAME': 'cltbld', 09:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:59 INFO - 'MOZ_NO_REMOTE': '1', 09:16:59 INFO - 'NO_EM_RESTART': '1', 09:16:59 INFO - 'PAGER': '/bin/cat', 09:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:59 INFO - 'PWD': '/builds/slave/test', 09:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:59 INFO - 'SHELL': '/bin/bash', 09:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:16:59 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:59 INFO - 'USER': 'cltbld', 09:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:59 INFO - 'XPC_FLAGS': '0x0', 09:16:59 INFO - 'XPC_SERVICE_NAME': '0', 09:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 INFO - Cleaning up... 09:17:00 INFO - Return code: 0 09:17:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:00 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:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 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:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 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 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:17:00 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:17:00 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:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:00 INFO - 'HOME': '/Users/cltbld', 09:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:00 INFO - 'LOGNAME': 'cltbld', 09:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:00 INFO - 'MOZ_NO_REMOTE': '1', 09:17:00 INFO - 'NO_EM_RESTART': '1', 09:17:00 INFO - 'PAGER': '/bin/cat', 09:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:00 INFO - 'PWD': '/builds/slave/test', 09:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:00 INFO - 'SHELL': '/bin/bash', 09:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:17:00 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:00 INFO - 'USER': 'cltbld', 09:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:00 INFO - 'XPC_FLAGS': '0x0', 09:17:00 INFO - 'XPC_SERVICE_NAME': '0', 09:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:00 INFO - Downloading/unpacking jsonschema 09:17: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:17: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:17: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:17: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:17: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:17: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:17:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:02 INFO - Downloading/unpacking functools32 (from jsonschema) 09:17: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:17: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:17: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:17: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:17: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:17: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:17:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:03 INFO - warning: no files found matching '*.txt' 09:17:03 INFO - no previously-included directories found matching 'build' 09:17:03 INFO - no previously-included directories found matching 'dist' 09:17:03 INFO - no previously-included directories found matching '.git*' 09:17:03 INFO - Installing collected packages: jsonschema, functools32 09:17:03 INFO - Running setup.py install for functools32 09:17:03 INFO - warning: no files found matching '*.txt' 09:17:03 INFO - no previously-included directories found matching 'build' 09:17:03 INFO - no previously-included directories found matching 'dist' 09:17:03 INFO - no previously-included directories found matching '.git*' 09:17:03 INFO - Successfully installed jsonschema functools32 09:17:03 INFO - Cleaning up... 09:17:03 INFO - Return code: 0 09:17:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:17:03 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:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:03 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:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1098eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098fd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa16a470ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, '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.tD0mSwNNuA/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.44jCZGbJFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:03 INFO - 'HOME': '/Users/cltbld', 09:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:03 INFO - 'LOGNAME': 'cltbld', 09:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:03 INFO - 'MOZ_NO_REMOTE': '1', 09:17:03 INFO - 'NO_EM_RESTART': '1', 09:17:03 INFO - 'PAGER': '/bin/cat', 09:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:03 INFO - 'PWD': '/builds/slave/test', 09:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:03 INFO - 'SHELL': '/bin/bash', 09:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:17:03 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:03 INFO - 'USER': 'cltbld', 09:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:03 INFO - 'XPC_FLAGS': '0x0', 09:17:03 INFO - 'XPC_SERVICE_NAME': '0', 09:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:03 INFO - Downloading/unpacking flake8 09:17: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:17: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:17: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:17: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:17: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:17: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:17:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:17:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:17:06 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:17:06 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:17:06 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:17:06 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:17:06 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:17:06 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:17:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:17:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:17:06 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:17:06 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:17:06 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:17:06 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:17:06 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:17:06 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:17:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:17:07 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:17:07 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:17:07 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:17:07 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:17:07 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:17:07 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:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:17:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:17:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:17:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:17:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:17:07 INFO - no previously-included directories found matching 'docs/_build' 09:17:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:17:07 INFO - Running setup.py install for pep8 09:17:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:17:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:17:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:17:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:17:07 INFO - no previously-included directories found matching 'docs/_build' 09:17:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:17:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:17:07 INFO - Cleaning up... 09:17:07 INFO - Return code: 0 09:17:07 INFO - Running post-action listener: _resource_record_post_action 09:17:07 INFO - Running post-action listener: _start_resource_monitoring 09:17:07 INFO - Starting resource monitoring. 09:17:07 INFO - ##### 09:17:07 INFO - ##### Running install step. 09:17:07 INFO - ##### 09:17:07 INFO - Running pre-action listener: _resource_record_pre_action 09:17:07 INFO - Running main action method: install 09:17:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:08 INFO - Reading from file tmpfile_stdout 09:17:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:08 INFO - Reading from file tmpfile_stdout 09:17:08 INFO - Output received: 09:17:08 INFO - Usage: mozinstall [options] installer 09:17:08 INFO - Options: 09:17:08 INFO - -h, --help show this help message and exit 09:17:08 INFO - -d DEST, --destination=DEST 09:17:08 INFO - Directory to install application into. [default: 09:17:08 INFO - "/builds/slave/test"] 09:17:08 INFO - --app=APP Application being installed. [default: firefox] 09:17:08 INFO - mkdir: /builds/slave/test/build/application 09:17:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:17:30 INFO - Reading from file tmpfile_stdout 09:17:30 INFO - Output received: 09:17:30 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:17:30 INFO - Running post-action listener: _resource_record_post_action 09:17:30 INFO - ##### 09:17:30 INFO - ##### Running run-tests step. 09:17:30 INFO - ##### 09:17:30 INFO - Running pre-action listener: _resource_record_pre_action 09:17:30 INFO - Running main action method: run_tests 09:17:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:30 INFO - 2016-07-04 09:17:30.465 screenresolution[1639:7468] starting screenresolution argv=screenresolution get 09:17:30 INFO - 2016-07-04 09:17:30.468 screenresolution[1639:7468] Display 0: 1600x1200x32@60 09:17:30 INFO - 2016-07-04 09:17:30.473 screenresolution[1640:7471] starting screenresolution argv=screenresolution list 09:17:30 INFO - Available Modes on Display 0 09:17:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:30 INFO - Intel Iris: 09:17:30 INFO - Chipset Model: Intel Iris 09:17:30 INFO - Type: GPU 09:17:30 INFO - Bus: Built-In 09:17:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:30 INFO - Vendor: Intel (0x8086) 09:17:30 INFO - Device ID: 0x0a2e 09:17:30 INFO - Revision ID: 0x0009 09:17:30 INFO - Displays: 09:17:30 INFO - Display: 09:17:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:30 INFO - Display Serial Number: 1600x1200 60 09:17:30 INFO - Main Display: Yes 09:17:30 INFO - Mirror: Off 09:17:30 INFO - Online: Yes 09:17:30 INFO - Rotation: Supported 09:17:30 INFO - Return code: 0 09:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:30 INFO - Python 2.7.3 09:17:30 INFO - Return code: 0 09:17:30 INFO - grabbing minidump binary from tooltool 09:17:30 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:17:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10978ce00>, '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 0x1095f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109787b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:30 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:17:30 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:17:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:30 INFO - Return code: 0 09:17:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:17:31 INFO - Return code: 0 09:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0047 --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:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tD0mSwNNuA/Render', 09:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:31 INFO - 'HOME': '/Users/cltbld', 09:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:31 INFO - 'LOGNAME': 'cltbld', 09:17:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:31 INFO - 'MOZ_NO_REMOTE': '1', 09:17:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:31 INFO - 'NO_EM_RESTART': '1', 09:17:31 INFO - 'PAGER': '/bin/cat', 09:17:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:31 INFO - 'PWD': '/builds/slave/test', 09:17:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:31 INFO - 'SHELL': '/bin/bash', 09:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.44jCZGbJFS/Listeners', 09:17:31 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 09:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:31 INFO - 'USER': 'cltbld', 09:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:31 INFO - 'XPC_FLAGS': '0x0', 09:17:31 INFO - 'XPC_SERVICE_NAME': '0', 09:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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:17:32 INFO - mozversion application_buildid: 20160704065143 09:17:32 INFO - mozversion application_changeset: 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b 09:17:32 INFO - mozversion application_display_name: Firefox 09:17:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:32 INFO - mozversion application_name: Firefox 09:17:32 INFO - mozversion application_remotingname: firefox 09:17:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:17:32 INFO - mozversion application_vendor: Mozilla 09:17:32 INFO - mozversion application_version: 48.0 09:17:32 INFO - mozversion platform_buildid: 20160704065143 09:17:32 INFO - mozversion platform_changeset: 3e04c4853a8bc95b6b485c42fb7e1e2d7500316b 09:17:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:17:32 INFO - mozversion platform_version: 48.0 09:17:32 INFO - using testdate: 1467649052 09:17:32 INFO - actual date: 1467649052 09:17:32 INFO - starting webserver on 'localhost:49279' 09:17:32 INFO - SUITE-START | Running 2 tests 09:17:32 INFO - TEST-START | dromaeo_css 09:17:32 INFO - operating with platform_type : mac_ 09:17:32 INFO - Initialising browser for dromaeo_css test... 09:17:32 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp8PIJN0/profile http://localhost:49279/getInfo.html) 09:17:34 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:17:34 INFO - PROCESS | 1658 | colorDepth:24 09:17:34 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:17:34 INFO - PROCESS | 1658 | __metrics 09:17:34 INFO - PROCESS | 1658 | 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:17:34 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:17:35 INFO - TEST-INFO | 1658: exit 0 09:17:35 INFO - Browser initialized. 09:17:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp8PIJN0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:46 INFO - PROCESS | 1661 | RSS: Main: 162160640 09:17:46 INFO - PROCESS | 1661 | 09:18:38 INFO - PROCESS | 1661 | 1467649118220 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:20:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 09:20:49 INFO - PROCESS | 1661 | RSS: Main: 255311872 09:20:49 INFO - PROCESS | 1661 | 09:23:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 09:23:52 INFO - PROCESS | 1661 | RSS: Main: 301326336 09:23:52 INFO - PROCESS | 1661 | 09:26:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 09:26:55 INFO - PROCESS | 1661 | RSS: Main: 299872256 09:26:55 INFO - PROCESS | 1661 | 09:29:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 09:29:57 INFO - PROCESS | 1661 | RSS: Main: 303910912 09:29:57 INFO - PROCESS | 1661 | 09:33:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 09:33:01 INFO - PROCESS | 1661 | RSS: Main: 272064512 09:33:01 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 09:36:04 INFO - PROCESS | 1661 | RSS: Main: 314925056 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | __start_tp_report 09:36:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:36:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:36:04 INFO - PROCESS | 1661 | |i|pagename|runs| 09:36:04 INFO - PROCESS | 1661 | |0;dojo.html;3542.4863394807753;3613.7831730096214;3617.801020943847;3626.4560315952613;3696.353138200816;5634.3520495143075;5695.658260504372;5731.484166425021;5749.770009199632;5773.7113144342775;4711.882202944928;5174.793008279665;5251.081060814352;5720.427957204278;5727;5214.765335559909;5234.790608375661;5242.187460943541;5247.186686063647;5264.552513036378;5326.946730532683;5372;5372.409035006146;5379.300690910176;5380.946190538103;5263.499967503081;5317.601179911503;5328.5470734987575;5345.7594408251725;5356.9196462052905;5326.973365133169;5370;5370.516653501204;5372.140457526777;5408.486193811582;5167.199084141964;5212.218167274939;5216.4522725114075;5307.893842123174;5311.787528498855;5604.775808967636;5633.15502674598;5672.262605861211;5674.404187560288;5686.5735069869515;6313.589616674947;6357.93642063578;6357.936420635827;6371.585846919936;6376.649284289362;6077.939220607782;6152;6237.313895471494;6384.776532821375;6421.165248517709;5865.88268234633;5882.441168089024;5905.2323197984415;5906.26171728534;5942.930272550982;6365.809025729235;6367.681615919186;6370.58591191571;6372.267189273293;6381.585196962275;6072.969635151796;6107.795737660903;6190.3190649028575;6193.38066193377;6218.502519798405;6368.713407896656;6379.681015949184;6387.872242555216;6391.776287829904;6396.776112836028;6222.502199824004;6292.779752708633;6317.5893566918;6317.873642527124;6318.905216421757;6152.384761523901;6204.13142160098;6269.435750782452;6334.081558174131;6338.30278669346;4966.428860680996;4995.675281106716;4996.5003499650475;5003.824866129668;5024.773885175176;4534.546545345439;4544.659150563721;4547.704399214041;4552.635789136861;4578.587927086578;4620.237660785932;5869.148973398833;5942.10868369748;6033.064874944391;6040.637561746222;6322.810315690536;6380.170577824853;6386.776462823779;6390.041493775878;6412;5967.492763115184;5983.102534619842;5994.880102397928;5995.160677505153;6003.429674180946;5360.061989151883;5389.7035663038905;5410.458954104637;5436.21174929633;5447.536959358499;6461.030845373231;6483.189601299838;6489.123968264224;6548.148740663683;6551.4103730664465;6239.688015599293;6405.455536279468;6412.711427985659;6415.3263907289065;6415.454686351713;6299.653519056313;6374.553781235269;6374.553781235454;6381.8085457436355;6392.648404337806;6269.686515674107;6297.590656607306;6301.40136687014;6302.960011598035;6318.8736225274315;4556.840510582114;6217.751289948352;6370.55406121567;6377;6398.072279519351;6135.601185922901;6160.353162918008;6180.134781130735;6182.505399568114;6203.906941395973;6175.96912015437;6180.598261113013;6186.628802271878;6208.503319734501;6219;5003.974980125076;5872.530197584098;5937.614055086406;6055.424734650202;6058.878822423615;5091.516305950929;6003.219581454383;6114.847128821815;6124.356942521149;6189.6286222826775;6045.486133678686;6047.365026672136;6054.394560544087;6062;6072.1498990140535 09:36:04 INFO - PROCESS | 1661 | |1;ext.html;518.968861868288;532.0980937311257;575.715021064573;578.144346367376;578.8003138917074;24459.143929962454;39080.41379379308;39549.406758898636;39691;39700.80149599255;37889.62110378895;39977;42733.145337093236;43114.7844260779;43246.13507729844;8975.416597921147;9141.908580914172;9682.322237443399;9860.556274967643;9875.95062024689;9182;9346;11743.41282935854;12034.638960831146;12048.337341446217;11657.475413606408;11683.474243659013;11725.648230553097;11734;11743.119266055037;11724.648260552154;11741.060715142768;11811.763764724657;11816.822747658793;11824.172307938448;11519;11564.653060408202;11649.825252621218;11651.126165537618;11659.242149260272;41393.96515087117;41873.1625367494;42014.57985420168;42027;42043.94890127771;3391.2030672791875;3489.476578513218;3518.4018716817945;3602.4596310553366;3793.468914351993;28781.712182878113;29267.829286828714;29655.70344296551;29759;30506;2761.3234757484547;2770.5567109262624;2771.1547977866744;2803.887844486218;2804.8597570121615;333615;334804;335099;335203;335399.99999999756;38197.42703859444;38845;68047.65976170088;68510.65744671244;68554.65722671355;37214.44178337327;37224.25551488955;37531.81234093812;37534.436983445274;37595;16203.432879849384;16373.42693005739;16395.180240988142;16397.508074757778;16422.1788910554;12886.742265154644;13039.739205215843;13612.795808062887;13677.837383822487;13720.313984300745;8555.700550480704;8671.479711217347;8672.089430609822;8695.304375649934;8768.991565969995;2996.3258266889775;3456.6370531094376;3475.5134281200653;3481.2863363010524;3482.89551313461;1006.3006210683651;1015.9224386130381;1028.6502589119586;1141.6004398460639;1158.7276989907366;26484.47031059368;29813.552796708067;30329.848350758104;31057.68942310593;31869.8406507966;1077.472038701031;1082.7726177502655;1109.7891400634014;1183.6863231243726;1211.878812118781;15888;15995.600109997344;16079.276432560562;16234.594135146715;16307.266173022243;633318;634986;641096;642155;644346;64039;64520;64532;64562.70874582482;64954.67522662356;1330.381372661717;1390.2978995607357;1544.609851133966;1591.7851090102688;1622.5781296862665;29008.12975610735;29853.104406867827;30011.399772004876;30727.539086913268;30907.536386954664;50373.748131260574;50786;50824.74587627038;50853.745731271105;50886.491135088174;459756;464385;464803;464974;465075;26901.865490673204;29384;29479;29499;29526.40947181087;47218;49161;49535.504644953086;51097.48902510927;51301.74349128231;34382.31235375329;39235.019124522114;40160.59839401569;41081.38377924393;41283.7935810319;38447.423288651255;38491.807540962116;38561.8431447057;38789.6121038786;39731.801340994265;38351.42472862965;39630.40554391629;39684.2063158741;40043.599564003984;40097.799511003424;31357;32037.519437208928;33417.66582334146;34478;34989.650103498636;24312.392190194678;25927.611085834105;26051.465482357245;26085;26302.342441439116 09:36:04 INFO - PROCESS | 1661 | |2;jquery.html;401.7248184993278;404.72074268754557;407.56594227148094;410.1796407185629;418.51243301553694;20615.896920515414;20954.057067431964;21669.241576544824;21686.566268674636;21710.89144554277;20728.8963555182;21243.04406301716;21282.57434851302;21294.36116916491;21316.680249796267;5651.717414129298;5801.970990145054;5923.378045305246;5950.196723442345;5953.107033944922;4922.80308787648;5731.226284451587;5856.590038697272;5890.381509941459;6135.938640613582;6107.175531303284;6115.633062016271;6132.938670613304;6133.570650054498;6152.354002829706;5929.199558059671;6083.574149809515;6086.208792856923;6678.332166783332;6693.431058360045;5915.053591425351;5925.525957923399;5933.821985340447;5934.317553481362;5946.464818166342;13078.476860925552;13437.731245375038;13673.521426750116;13691.246981416018;13704.3833027514;1734.731116676917;2004.8295894848955;2074.5954538864926;2126.851120421571;2190.134896715804;9792.55933482995;9865.260105492045;9876.40741555509;10034.89965100347;15296.235188240655;1867.3370953311514;1875.2592725873203;1895.1471837673103;1915.3870761356372;1932.6424611446787;124844;128223;128768.99999999907;129286.0607090885;142050;18057.729134063;20282.898585506977;20754.377368678965;21458.034388452557;21552;36157;36248.09379765527;36426;37015;37101.628983710354;11546.711332216593;11561.959423651911;11848.88151118495;11927.463264153133;12014.939925300318;9869.756004917954;9923.751906202402;9927.553260103312;9944.403335799876;9994.650187243557;9920.751981200528;9969;9986.25515431713;10028.398296102258;10115.848262276071;3106.8601912913973;3124.500079987191;3265;3801.334766415866;4054.9797251013556;738.9543795529373;985.6845809340942;989.7228775942604;1055.2613170780485;1078.2075174746556;19433;20200.49498762543;20293;20469.795302047085;20694.379168624964;994.5474808961905;1023.9744006399751;1028.7325295423243;1035.7203555040096;1038.2576457832686;9400.106989836091;10897.83653245202;10927.726806829893;10947.288426252268;10987.835182472269;560742;657092;668704;681722;681924;102230;103746;118777;139714;148930.25534872257;920.1994264989502;1018.6231094495084;1046.1787496814964;1054.926155169146;1055.8785739640039;18046.368377106737;18124.912505249726;18263.81736182621;18769.530761731065;19069.427917162506;53157.734211328694;53827.46172538224;55650.16524752051;56320.436795633155;56542.7172864133;76894;81430.59284703538;81588.59205703934;82690;86237;8172.713955011546;8175.468594541336;8217.95891020565;8325.458845175044;8519.148085191679;10539.42033188182;12047.457864396298;12602.621921342374;12673.873261267638;13077.476900923857;10942.890571094187;11330.376829274139;11563.884361156617;11581.59464418775;11642.01042911362;10999.01008909202;11020.614278500214;11170.162237832032;11374.488148033193;11496.597619083292;5891.558133139946;8096.959515202387;10192.694219173436;11438.771224575628;11789.823152652545;8454.66181352739;8459.365547584079;8543.700970466003;8583.742487725378;8809.691660791841;10577.682669519927;11261.380624065756;11408.429578521207;11468.082553395541;11689.88310116888 09:36:04 INFO - PROCESS | 1661 | |3;mootools.html;1489.7318482673115;1606.5019843848404;1627.1538799824095;1743.4769569129257;1923.1922592511144;8120.756377308683;8921.64313427463;9065.456072635638;9067.957184923736;9082.54587270637;8686.087960764124;8925.598348074336;8926.330525210602;8927.06265842087;8930.19628233459;2494.9750502495017;2524.772770450659;2526.406294520786;2528.582783840664;2529.190658989124;2460.434100156965;2531.9240422787348;2539.5555777738914;2541.9110331138413;2549.146036077916;2519.1812660885284;2543.529447052292;2565.28172111809;2567.1785028790796;2567.2939941516047;2511.0834545390985;2547.4522977559823;2559.667243258383;2561.078011915709;2566.6021766626204;2497.687789026372;2537.7969762419148;2541.453587478701;2544.185860524633;2555.399481121935;8821.073787252375;9045;9082.000979892207;9095.454272743591;9104;5523.392426833045;5698.40166782486;5710.2862142232225;5713.171590119409;5732.5987180897355;3587.443946188345;3649.5802982656805;3701.5373078365205;3725.3853114236117;3750.456183853326;1039.682896716501;1049.1187402581868;1062.1608928946123;1073.1682945717055;1084;12065.819012714815;12128.636140915698;12331.075169362333;12386.009119270346;12408.875911240862;4620.029793743352;4652.95347046532;4770.904581908342;4773;4786.7845946932475;9641.469719165372;9646.035396460298;9648.614055437703;9660.613575457044;9669.951650241845;5481.095619222783;5493.285872836505;5495.450454954473;5495.972520137454;5498.1752737089755;4140.627343539096;4464.665150113755;4659.6505262105475;5061.544460998454;5108.923366149511;3913.8434462621744;3914.549826769901;3978.5026871641044;4006.338954072604;4026.859059932947;1126.549380247891;1128.7968165730247;1198;1204.9457774399998;1221.3221661977689;854.2610641794927;940.9341346105707;942.7360339105062;955.3599088610508;960.1406740966795;6021.397860213943;6036.396360363929;6142.539309551801;6221.09794079865;6260.46786023184;473.3562355196929;474.14416977356206;477.2292747213295;478.5668969582533;489.649900321272;3935.58676338986;4081.1225586499045;4082.6121518455698;4124.154548317588;4137.234611596864;10990.615328463466;11418.714532136763;11500.597479088192;11525.423728813526;11533.904279093473;10758.0855627271;11149.16381271408;11259.437028148559;11260.042896353738;11315.094792416587;8490.915090849136;8568.014678312074;8711.21599056088;8754.34342424308;8761.25529330014;5978.461938425563;6010.639361638316;6077.331493535619;6124.295705993868;6148.692565371803;9680.031996800264;9794.608215671293;10007.749806254902;10028.448435336126;10181.898181018096;10151.54318055704;10345.63790267337;10351.378917264989;10515.421651809223;10542.367457952547;6665.700043497957;6835.7949261522235;6843.076184715199;7178.24628414009;7249.456290778107;7768.572728499987;8033.357331413356;8109.8378032440205;8640.611172497378;8662.133786621287;6992.825179370557;7152.785416437515;7579.54522728638;7711.652975616223;7743.451309738188;6172.259328880563;7553.7356192533;7679.500832445873;7787.9096926431475;7898.01274840645;7095.787126386217;7222.205557388578;7288.3076107769675;7289.817754556178;7298.160711518243;5283.418823929442;5309.495597918191;5345.812896548602;5349.358077030781;5369.3556773187465;5293.037734719638;5304.549113325411;5308.124159513792;5542.0301447247375;5590.021746194481 09:36:04 INFO - PROCESS | 1661 | |4;prototype.html;326.22033340316665;332.6490552467147;340.70192566322305;342.7241070937894;343.48648770088704;6397.968010159954;6455.386738259869;6655.835228834952;6692.799216023521;6697.63163026034;6679.365460281278;6703.128593282866;6703.497237707179;6711.194656641198;6745.966270168642;5051.797928082881;5055.165897626887;5056.129232184097;5059.924101138486;5067.847964561069;4314.460692413449;4788.832390866321;4882.658213925027;5062.848114556569;5065.518775716301;4303.24693178694;4730.763461826912;4918.942427378113;4956.801727930879;4968.950310496903;4983.476734942815;4990.850274491771;4991.550760431561;4998.550130488237;5001.224810154414;3735.34631439497;4316.438862947828;4324.870253892388;4332.805023773938;4333.046729719456;6362;6392.7123279452535;6409.615423074631;6411.455026322768;6421.711023003976;5043.672161309503;5584.413636568183;5869.530437565028;5881.206037184968;5885.411458854123;5931.317898441691;6920.930790692129;7095.432365410755;7113.928860711379;7123.964380178066;4782.282657601388;4797.880052998668;4799.18413869643;4805;4807.471178170398;7131.643417829087;7312.707491700326;7477.140128885193;7479.5886226257535;7506.249375062504;3873.7288389813007;4679.766011699401;4716.646251531149;4819.445763737145;4846.006568653417;4914.0908931847725;4941.48114447985;4962.478939711351;4972.378452693413;4972.676776009548;5830.067189249699;5838.299404071454;5838.357780644125;5842.620229685057;5872.324682661463;7157.355837974503;7167.749128780468;7178.2103968563415;7191;7207.13514378278;1581.612504936275;1646.8353164683676;1652.1491431912575;1707.556035430797;1736.6787144378332;1028.9691309260584;1031.4584842957506;1046.670298855858;1049.0506091986765;1056.3556230699267;5642.012647786621;6709.127813384326;6877;6905.75829845953;6910.136232970879;7346.118465784033;7360.895865620199;7362.079740032496;7364.3372096511575;7364.926350736531;6224.78616669745;6320.968395157995;6878.174619045646;6906.171259448898;6912.792616221522;3868.4390763339156;3900.7854567999034;3906.257811015898;3906.3554513504955;3906.6093390661276;7675.424343174284;7971.481853679492;7973.0033745781775;8013.318867896294;8066.717664881701;7636;7704.537727736354;7705.653245603961;7706.267904549059;7707.075150981918;8015.639296231683;8020.077691065486;8039.437239393186;8047.396445266745;8051.114377418597;7150.427965762622;7415.740449084256;7482.70069197226;7487.2138425464545;7488.438367122597;7291.125064992235;7332.81667958284;7346.449016323904;7352.228016058237;7353.411727061929;8006.719764808437;8018.919810802051;8028.839423211386;8030.799230019296;8036.839263214586;3918.647321741057;4612.930806037979;4674.11191873543;4739.620830333496;4740.431148262231;7243.710251589878;7360.484766066324;7409.518381305198;7447.739329123455;7471.775846724607;7170.820729481805;7210;7219.169795473587;7240.927590724025;7242.529235599669;7196.280371962762;7207.063081799332;7215.386692131227;7220.3140701633265;7225.096862892138;6817.386435220854;6976.965115174392;7040.119985001875;7051.541649792747;7053.647317634201;7125.857482850418;7174.928250717426;7183.676734547063;7194.820129496805;7198.604076775828;5660.745266463102;6512.274247324368;7020.22777494465;7028.683709232994;7036.964815175891 09:36:04 INFO - PROCESS | 1661 | |5;yui.html;645.6578013652764;695.9421679047847;698.1552321690754;721.9855602887942;731.8170457385651;15281.923590382046;15316.69366612667;15335.233238338067;15359.84640153598;15445.6138596535;14098.71802563948;14129.858701412983;14143.009989300754;14145.241677872818;14159.29203539824;756.5385115079811;920.8388532006906;946.668665966913;948.2650945517211;949.5252373813094;928.1692884868039;938.723076692374;943.005129588284;945.1729736480579;945.9432434053945;803.7106641609016;999.7450650084219;1001.3191030099566;1002.5638847101498;1005.1757558801786;970.5244430229208;1006.8741407324085;1007.4056306778963;1007.9869730920415;1010.0202803280879;751.3463286940342;751.4964973467735;751.999840212516;755.0938873351945;756.092688773474;15130.621734456616;15189.088654680754;15231.086134831945;15231.923840380727;15268.31292591825;12920.741585168338;12994.610161695164;13218.867811321956;13442.462301507929;13471.393787279596;10119.696409107739;10243.641472548425;10260.435676037889;10544.261901666887;10684.465776711211;3540.150363912642;3545.2377738786026;3591.6587924147166;3651.2879988402274;3818.6754125899333;78878.42243155105;79262.8110578336;80325.59837200835;83029;83504.58247708723;9603.711888643213;9804.411735295906;9902.356346837432;10074.496275186211;10128.848067278997;22860.4284892879;22893.656595151086;22910.7708922912;22938;22986.425339366317;7651.69393224276;7676.347510461674;7781.844363112706;7875.409344299201;7875.488093273919;4113.835446582103;4122.196171746541;4196.727212731163;4213.136307057046;4256.787160641956;3768.981155094262;3836.8273427695262;3837.712171587142;3837.788921609338;3840.9615903840604;597.375742349246;602.1569802276847;604.7460066771966;608.4919092557675;688.3563867783638;581.5318668471837;596.4393470138143;617.4350469320522;627.3788948940517;651.7230177174682;8086.393520485987;8249.29880960125;8379.503459015936;8388.035375931846;8546.838366692748;595.7319206357157;598.7006496751624;612.9671503516499;623.0313595752679;624.3226099681805;3695.2609478104487;3941.881743547698;3952.822123004472;4062.7765472899273;4191.036061705825;92625.073749263;93397;93713.59429608562;97974;98726.50636746913;80548.98627534359;84839.72740408899;85199;86769.13230867736;87064;73476.63261683764;74758.87861682079;74886;76825;78665;7596.050493688289;7749.845003100019;8536.74389768308;8719.738407847775;8721.040685524715;49632;50052.749736251084;52690.73654631702;52772.20841687454;52943.47056529386;63318.733625328154;70414.29585704078;72967.63516182386;73491;75395.62302188454;9136.314776391664;11118.722031949266;11233.32600044;11310.208285419942;11673.824892626788;12047.939760301493;12217.144799863925;12299.385030748248;12347.19743216688;12765.61703148907;8470.435564818612;11786.646400607995;12021.879781202077;12027.579034733742;12149.75700486003;11469.082473402275;11591;11735.941320293345;11778.352190629597;11990.220635658654;11424.771504569695;11478.88521114778;11642.6507204784;11709.824352634547;12157.209781364183;8188.344932405274;9702.805943881223;10029.949850250701;10036.19710423179;10251.7437064074;9617.278704097082;9883.159931405911;9890.950545247228;9989.550470228713;10035.749106272402 09:36:04 INFO - PROCESS | 1661 | __end_tp_report 09:36:04 INFO - PROCESS | 1661 | __start_cc_report 09:36:04 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1144 09:36:04 INFO - PROCESS | 1661 | __end_cc_report 09:36:04 INFO - PROCESS | 1661 | __startTimestamp1467650164702__endTimestamp 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:36:04 INFO - PROCESS | 1661 | Number of tests: 6 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5802.96 Median:6047.37 stddev:650.72 (10.8%) stddev-sans-first:628.09 09:36:04 INFO - PROCESS | 1661 | Values: 3542.5 3613.8 3617.8 3626.5 3696.4 5634.4 5695.7 5731.5 5749.8 5773.7 4711.9 5174.8 5251.1 5720.4 5727.0 5214.8 5234.8 5242.2 5247.2 5264.6 5326.9 5372.0 5372.4 5379.3 5380.9 5263.5 5317.6 5328.5 5345.8 5356.9 5327.0 5370.0 5370.5 5372.1 5408.5 5167.2 5212.2 5216.5 5307.9 5311.8 5604.8 5633.2 5672.3 5674.4 5686.6 6313.6 6357.9 6357.9 6371.6 6376.6 6077.9 6152.0 6237.3 6384.8 6421.2 5865.9 5882.4 5905.2 5906.3 5942.9 6365.8 6367.7 6370.6 6372.3 6381.6 6073.0 6107.8 6190.3 6193.4 6218.5 6368.7 6379.7 6387.9 6391.8 6396.8 6222.5 6292.8 6317.6 6317.9 6318.9 6152.4 6204.1 6269.4 6334.1 6338.3 4966.4 4995.7 4996.5 5003.8 5024.8 4534.5 4544.7 4547.7 4552.6 4578.6 4620.2 5869.1 5942.1 6033.1 6040.6 6322.8 6380.2 6386.8 6390.0 6412.0 5967.5 5983.1 5994.9 5995.2 6003.4 5360.1 5389.7 5410.5 5436.2 5447.5 6461.0 6483.2 6489.1 6548.1 6551.4 6239.7 6405.5 6412.7 6415.3 6415.5 6299.7 6374.6 6374.6 6381.8 6392.6 6269.7 6297.6 6301.4 6303.0 6318.9 4556.8 6217.8 6370.6 6377.0 6398.1 6135.6 6160.4 6180.1 6182.5 6203.9 6176.0 6180.6 6186.6 6208.5 6219.0 5004.0 5872.5 5937.6 6055.4 6058.9 5091.5 6003.2 6114.8 6124.4 6189.6 6045.5 6047.4 6054.4 6062.0 6072.1 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:62096.33 Median:18967.32 stddev:132358.10 (697.8%) stddev-sans-first:132648.65 09:36:04 INFO - PROCESS | 1661 | Values: 519.0 532.1 575.7 578.1 578.8 24459.1 39080.4 39549.4 39691.0 39700.8 37889.6 39977.0 42733.1 43114.8 43246.1 8975.4 9141.9 9682.3 9860.6 9876.0 9182.0 9346.0 11743.4 12034.6 12048.3 11657.5 11683.5 11725.6 11734.0 11743.1 11724.6 11741.1 11811.8 11816.8 11824.2 11519.0 11564.7 11649.8 11651.1 11659.2 41394.0 41873.2 42014.6 42027.0 42043.9 3391.2 3489.5 3518.4 3602.5 3793.5 28781.7 29267.8 29655.7 29759.0 30506.0 2761.3 2770.6 2771.2 2803.9 2804.9 333615.0 334804.0 335099.0 335203.0 335400.0 38197.4 38845.0 68047.7 68510.7 68554.7 37214.4 37224.3 37531.8 37534.4 37595.0 16203.4 16373.4 16395.2 16397.5 16422.2 12886.7 13039.7 13612.8 13677.8 13720.3 8555.7 8671.5 8672.1 8695.3 8769.0 2996.3 3456.6 3475.5 3481.3 3482.9 1006.3 1015.9 1028.7 1141.6 1158.7 26484.5 29813.6 30329.8 31057.7 31869.8 1077.5 1082.8 1109.8 1183.7 1211.9 15888.0 15995.6 16079.3 16234.6 16307.3 633318.0 634986.0 641096.0 642155.0 644346.0 64039.0 64520.0 64532.0 64562.7 64954.7 1330.4 1390.3 1544.6 1591.8 1622.6 29008.1 29853.1 30011.4 30727.5 30907.5 50373.7 50786.0 50824.7 50853.7 50886.5 459756.0 464385.0 464803.0 464974.0 465075.0 26901.9 29384.0 29479.0 29499.0 29526.4 47218.0 49161.0 49535.5 51097.5 51301.7 34382.3 39235.0 40160.6 41081.4 41283.8 38447.4 38491.8 38561.8 38789.6 39731.8 38351.4 39630.4 39684.2 40043.6 40097.8 31357.0 32037.5 33417.7 34478.0 34989.7 24312.4 25927.6 26051.5 26085.0 26302.3 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:37733.36 Median:21505.02 stddev:108410.14 (504.1%) stddev-sans-first:108678.02 09:36:04 INFO - PROCESS | 1661 | Values: 401.7 404.7 407.6 410.2 418.5 20615.9 20954.1 21669.2 21686.6 21710.9 20728.9 21243.0 21282.6 21294.4 21316.7 5651.7 5802.0 5923.4 5950.2 5953.1 4922.8 5731.2 5856.6 5890.4 6135.9 6107.2 6115.6 6132.9 6133.6 6152.4 5929.2 6083.6 6086.2 6678.3 6693.4 5915.1 5925.5 5933.8 5934.3 5946.5 13078.5 13437.7 13673.5 13691.2 13704.4 1734.7 2004.8 2074.6 2126.9 2190.1 9792.6 9865.3 9876.4 10034.9 15296.2 1867.3 1875.3 1895.1 1915.4 1932.6 124844.0 128223.0 128769.0 129286.1 142050.0 18057.7 20282.9 20754.4 21458.0 21552.0 36157.0 36248.1 36426.0 37015.0 37101.6 11546.7 11562.0 11848.9 11927.5 12014.9 9869.8 9923.8 9927.6 9944.4 9994.7 9920.8 9969.0 9986.3 10028.4 10115.8 3106.9 3124.5 3265.0 3801.3 4055.0 739.0 985.7 989.7 1055.3 1078.2 19433.0 20200.5 20293.0 20469.8 20694.4 994.5 1024.0 1028.7 1035.7 1038.3 9400.1 10897.8 10927.7 10947.3 10987.8 560742.0 657092.0 668704.0 681722.0 681924.0 102230.0 103746.0 118777.0 139714.0 148930.3 920.2 1018.6 1046.2 1054.9 1055.9 18046.4 18124.9 18263.8 18769.5 19069.4 53157.7 53827.5 55650.2 56320.4 56542.7 76894.0 81430.6 81588.6 82690.0 86237.0 8172.7 8175.5 8218.0 8325.5 8519.1 10539.4 12047.5 12602.6 12673.9 13077.5 10942.9 11330.4 11563.9 11581.6 11642.0 10999.0 11020.6 11170.2 11374.5 11496.6 5891.6 8097.0 10192.7 11438.8 11789.8 8454.7 8459.4 8543.7 8583.7 8809.7 10577.7 11261.4 11408.4 11468.1 11689.9 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5853.81 Median:2775.60 stddev:3291.29 (118.6%) stddev-sans-first:3284.18 09:36:04 INFO - PROCESS | 1661 | Values: 1489.7 1606.5 1627.2 1743.5 1923.2 8120.8 8921.6 9065.5 9068.0 9082.5 8686.1 8925.6 8926.3 8927.1 8930.2 2495.0 2524.8 2526.4 2528.6 2529.2 2460.4 2531.9 2539.6 2541.9 2549.1 2519.2 2543.5 2565.3 2567.2 2567.3 2511.1 2547.5 2559.7 2561.1 2566.6 2497.7 2537.8 2541.5 2544.2 2555.4 8821.1 9045.0 9082.0 9095.5 9104.0 5523.4 5698.4 5710.3 5713.2 5732.6 3587.4 3649.6 3701.5 3725.4 3750.5 1039.7 1049.1 1062.2 1073.2 1084.0 12065.8 12128.6 12331.1 12386.0 12408.9 4620.0 4653.0 4770.9 4773.0 4786.8 9641.5 9646.0 9648.6 9660.6 9670.0 5481.1 5493.3 5495.5 5496.0 5498.2 4140.6 4464.7 4659.7 5061.5 5108.9 3913.8 3914.5 3978.5 4006.3 4026.9 1126.5 1128.8 1198.0 1204.9 1221.3 854.3 940.9 942.7 955.4 960.1 6021.4 6036.4 6142.5 6221.1 6260.5 473.4 474.1 477.2 478.6 489.6 3935.6 4081.1 4082.6 4124.2 4137.2 10990.6 11418.7 11500.6 11525.4 11533.9 10758.1 11149.2 11259.4 11260.0 11315.1 8490.9 8568.0 8711.2 8754.3 8761.3 5978.5 6010.6 6077.3 6124.3 6148.7 9680.0 9794.6 10007.7 10028.4 10181.9 10151.5 10345.6 10351.4 10515.4 10542.4 6665.7 6835.8 6843.1 7178.2 7249.5 7768.6 8033.4 8109.8 8640.6 8662.1 6992.8 7152.8 7579.5 7711.7 7743.5 6172.3 7553.7 7679.5 7787.9 7898.0 7095.8 7222.2 7288.3 7289.8 7298.2 5283.4 5309.5 5345.8 5349.4 5369.4 5293.0 5304.5 5308.1 5542.0 5590.0 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5887.22 Median:6695.22 stddev:1902.58 (28.4%) stddev-sans-first:1861.57 09:36:04 INFO - PROCESS | 1661 | Values: 326.2 332.6 340.7 342.7 343.5 6398.0 6455.4 6655.8 6692.8 6697.6 6679.4 6703.1 6703.5 6711.2 6746.0 5051.8 5055.2 5056.1 5059.9 5067.8 4314.5 4788.8 4882.7 5062.8 5065.5 4303.2 4730.8 4918.9 4956.8 4969.0 4983.5 4990.9 4991.6 4998.6 5001.2 3735.3 4316.4 4324.9 4332.8 4333.0 6362.0 6392.7 6409.6 6411.5 6421.7 5043.7 5584.4 5869.5 5881.2 5885.4 5931.3 6920.9 7095.4 7113.9 7124.0 4782.3 4797.9 4799.2 4805.0 4807.5 7131.6 7312.7 7477.1 7479.6 7506.2 3873.7 4679.8 4716.6 4819.4 4846.0 4914.1 4941.5 4962.5 4972.4 4972.7 5830.1 5838.3 5838.4 5842.6 5872.3 7157.4 7167.7 7178.2 7191.0 7207.1 1581.6 1646.8 1652.1 1707.6 1736.7 1029.0 1031.5 1046.7 1049.1 1056.4 5642.0 6709.1 6877.0 6905.8 6910.1 7346.1 7360.9 7362.1 7364.3 7364.9 6224.8 6321.0 6878.2 6906.2 6912.8 3868.4 3900.8 3906.3 3906.4 3906.6 7675.4 7971.5 7973.0 8013.3 8066.7 7636.0 7704.5 7705.7 7706.3 7707.1 8015.6 8020.1 8039.4 8047.4 8051.1 7150.4 7415.7 7482.7 7487.2 7488.4 7291.1 7332.8 7346.4 7352.2 7353.4 8006.7 8018.9 8028.8 8030.8 8036.8 3918.6 4612.9 4674.1 4739.6 4740.4 7243.7 7360.5 7409.5 7447.7 7471.8 7170.8 7210.0 7219.2 7240.9 7242.5 7196.3 7207.1 7215.4 7220.3 7225.1 6817.4 6977.0 7040.1 7051.5 7053.6 7125.9 7174.9 7183.7 7194.8 7198.6 5660.7 6512.3 7020.2 7028.7 7037.0 09:36:04 INFO - PROCESS | 1661 | 09:36:04 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:19034.47 Median:26762.50 stddev:26949.77 (100.7%) stddev-sans-first:26990.00 09:36:04 INFO - PROCESS | 1661 | Values: 645.7 695.9 698.2 722.0 731.8 15281.9 15316.7 15335.2 15359.8 15445.6 14098.7 14129.9 14143.0 14145.2 14159.3 756.5 920.8 946.7 948.3 949.5 928.2 938.7 943.0 945.2 945.9 803.7 999.7 1001.3 1002.6 1005.2 970.5 1006.9 1007.4 1008.0 1010.0 751.3 751.5 752.0 755.1 756.1 15130.6 15189.1 15231.1 15231.9 15268.3 12920.7 12994.6 13218.9 13442.5 13471.4 10119.7 10243.6 10260.4 10544.3 10684.5 3540.2 3545.2 3591.7 3651.3 3818.7 78878.4 79262.8 80325.6 83029.0 83504.6 9603.7 9804.4 9902.4 10074.5 10128.8 22860.4 22893.7 22910.8 22938.0 22986.4 7651.7 7676.3 7781.8 7875.4 7875.5 4113.8 4122.2 4196.7 4213.1 4256.8 3769.0 3836.8 3837.7 3837.8 3841.0 597.4 602.2 604.7 608.5 688.4 581.5 596.4 617.4 627.4 651.7 8086.4 8249.3 8379.5 8388.0 8546.8 595.7 598.7 613.0 623.0 624.3 3695.3 3941.9 3952.8 4062.8 4191.0 92625.1 93397.0 93713.6 97974.0 98726.5 80549.0 84839.7 85199.0 86769.1 87064.0 73476.6 74758.9 74886.0 76825.0 78665.0 7596.1 7749.8 8536.7 8719.7 8721.0 49632.0 50052.7 52690.7 52772.2 52943.5 63318.7 70414.3 72967.6 73491.0 75395.6 9136.3 11118.7 11233.3 11310.2 11673.8 12047.9 12217.1 12299.4 12347.2 12765.6 8470.4 11786.6 12021.9 12027.6 12149.8 11469.1 11591.0 11735.9 11778.4 11990.2 11424.8 11478.9 11642.7 11709.8 12157.2 8188.3 9702.8 10029.9 10036.2 10251.7 9617.3 9883.2 9891.0 9989.6 10035.7 09:36:04 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:36:04 INFO - PROCESS | 1661 | 09:36:05 INFO - TEST-INFO | 1661: exit 0 09:36:05 INFO - TEST-OK | dromaeo_css | took 1113243ms 09:36:05 INFO - TEST-START | kraken 09:36:05 INFO - operating with platform_type : mac_ 09:36:05 INFO - Initialising browser for kraken test... 09:36:05 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp1ApOFj/profile http://localhost:49279/getInfo.html) 09:36:07 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 09:36:07 INFO - PROCESS | 1671 | colorDepth:24 09:36:07 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 09:36:07 INFO - PROCESS | 1671 | __metrics 09:36:07 INFO - PROCESS | 1671 | 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:36:07 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:36:08 INFO - TEST-INFO | 1671: exit 0 09:36:08 INFO - Browser initialized. 09:36:08 INFO - Running cycle 1/1 for kraken test... 09:36:08 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp1ApOFj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:20 INFO - PROCESS | 1674 | RSS: Main: 161734656 09:36:20 INFO - PROCESS | 1674 | 09:36:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:23 INFO - PROCESS | 1674 | RSS: Main: 224227328 09:36:23 INFO - PROCESS | 1674 | 09:36:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 09:36:27 INFO - PROCESS | 1674 | RSS: Main: 239693824 09:36:27 INFO - PROCESS | 1674 | 09:36:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 09:36:31 INFO - PROCESS | 1674 | RSS: Main: 324239360 09:36:31 INFO - PROCESS | 1674 | 09:36:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 09:36:33 INFO - PROCESS | 1674 | RSS: Main: 230408192 09:36:33 INFO - PROCESS | 1674 | 09:36:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:36 INFO - PROCESS | 1674 | RSS: Main: 232239104 09:36:36 INFO - PROCESS | 1674 | 09:36:39 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:39 INFO - PROCESS | 1674 | RSS: Main: 270626816 09:36:39 INFO - PROCESS | 1674 | 09:36:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:43 INFO - PROCESS | 1674 | RSS: Main: 270872576 09:36:43 INFO - PROCESS | 1674 | 09:36:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 09:36:46 INFO - PROCESS | 1674 | RSS: Main: 268541952 09:36:46 INFO - PROCESS | 1674 | 09:36:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:48 INFO - PROCESS | 1674 | RSS: Main: 259596288 09:36:48 INFO - PROCESS | 1674 | 09:36:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:51 INFO - PROCESS | 1674 | RSS: Main: 266424320 09:36:51 INFO - PROCESS | 1674 | 09:36:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:53 INFO - PROCESS | 1674 | RSS: Main: 270909440 09:36:53 INFO - PROCESS | 1674 | 09:36:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:57 INFO - PROCESS | 1674 | RSS: Main: 277901312 09:36:57 INFO - PROCESS | 1674 | 09:36:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:00 INFO - PROCESS | 1674 | RSS: Main: 275116032 09:37:00 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 09:37:02 INFO - PROCESS | 1674 | RSS: Main: 336154624 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | __start_tp_report 09:37:02 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 09:37:02 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 09:37:02 INFO - PROCESS | 1674 | |i|pagename|runs| 09:37:02 INFO - PROCESS | 1674 | |0;ai-astar;116;157;173;111;110;110;112;110;112;131 09:37:02 INFO - PROCESS | 1674 | |1;audio-beat-detection;157;121;141;164;120;164;147;188;157;121 09:37:02 INFO - PROCESS | 1674 | |2;audio-dft;187;187;186;185;187;187;185;183;182;184 09:37:02 INFO - PROCESS | 1674 | |3;audio-fft;69;78;72;148;56;58;76;93;157;69 09:37:02 INFO - PROCESS | 1674 | |4;audio-oscillator;93;92;139;80;82;96;119;80;78;124 09:37:02 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;84;99;88;90;86;91;88;90;86 09:37:02 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;94;94;95;93;94;95;94;93;94 09:37:02 INFO - PROCESS | 1674 | |7;imaging-desaturate;83;83;85;85;83;83;84;83;84;83 09:37:02 INFO - PROCESS | 1674 | |8;json-parse-financial;60;64;67;64;62;64;64;80;58;57 09:37:02 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;64;64;63;71;70;71;71;66;67;67 09:37:02 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;80;81;79;78;80;78;81;79;81;83 09:37:02 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;127;124;125;124;123;124;118;126;145;141 09:37:02 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;160;165;162;162;162;162;163;161;162;159 09:37:02 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;68;68;66;68;66;67;67;67;68 09:37:02 INFO - PROCESS | 1674 | __end_tp_report 09:37:02 INFO - PROCESS | 1674 | __start_cc_report 09:37:02 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1907 09:37:02 INFO - PROCESS | 1674 | __end_cc_report 09:37:02 INFO - PROCESS | 1674 | __startTimestamp1467650222460__endTimestamp 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | ------- Summary: start ------- 09:37:02 INFO - PROCESS | 1674 | Number of tests: 14 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:124.20 Median:114.00 stddev:22.72 (19.9%) stddev-sans-first:23.91 09:37:02 INFO - PROCESS | 1674 | Values: 116.0 157.0 173.0 111.0 110.0 110.0 112.0 110.0 112.0 131.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:157.00 stddev:22.52 (14.3%) stddev-sans-first:23.65 09:37:02 INFO - PROCESS | 1674 | Values: 157.0 121.0 141.0 164.0 120.0 164.0 147.0 188.0 157.0 121.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.50 stddev:1.83 (1.0%) stddev-sans-first:1.83 09:37:02 INFO - PROCESS | 1674 | Values: 187.0 187.0 186.0 185.0 187.0 187.0 185.0 183.0 182.0 184.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:87.60 Median:70.50 stddev:35.79 (50.8%) stddev-sans-first:37.33 09:37:02 INFO - PROCESS | 1674 | Values: 69.0 78.0 72.0 148.0 56.0 58.0 76.0 93.0 157.0 69.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:81.00 stddev:21.52 (26.6%) stddev-sans-first:22.73 09:37:02 INFO - PROCESS | 1674 | Values: 93.0 92.0 139.0 80.0 82.0 96.0 119.0 80.0 78.0 124.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:90.00 stddev:4.10 (4.6%) stddev-sans-first:4.34 09:37:02 INFO - PROCESS | 1674 | Values: 90.0 84.0 99.0 88.0 90.0 86.0 91.0 88.0 90.0 86.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:37:02 INFO - PROCESS | 1674 | Values: 94.0 94.0 94.0 95.0 93.0 94.0 95.0 94.0 93.0 94.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:83.50 stddev:0.84 (1.0%) stddev-sans-first:0.87 09:37:02 INFO - PROCESS | 1674 | Values: 83.0 83.0 85.0 85.0 83.0 83.0 84.0 83.0 84.0 83.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:6.41 (10.0%) stddev-sans-first:6.64 09:37:02 INFO - PROCESS | 1674 | Values: 60.0 64.0 67.0 64.0 62.0 64.0 64.0 80.0 58.0 57.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:68.50 stddev:3.17 (4.6%) stddev-sans-first:3.11 09:37:02 INFO - PROCESS | 1674 | Values: 64.0 64.0 63.0 71.0 70.0 71.0 71.0 66.0 67.0 67.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.50 stddev:1.56 (1.9%) stddev-sans-first:1.66 09:37:02 INFO - PROCESS | 1674 | Values: 80.0 81.0 79.0 78.0 80.0 78.0 81.0 79.0 81.0 83.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:125.50 stddev:8.46 (6.7%) stddev-sans-first:8.97 09:37:02 INFO - PROCESS | 1674 | Values: 127.0 124.0 125.0 124.0 123.0 124.0 118.0 126.0 145.0 141.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.80 Median:162.00 stddev:1.62 (1.0%) stddev-sans-first:1.58 09:37:02 INFO - PROCESS | 1674 | Values: 160.0 165.0 162.0 162.0 162.0 162.0 163.0 161.0 162.0 159.0 09:37:02 INFO - PROCESS | 1674 | 09:37:02 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 09:37:02 INFO - PROCESS | 1674 | Values: 69.0 68.0 68.0 66.0 68.0 66.0 67.0 67.0 67.0 68.0 09:37:02 INFO - PROCESS | 1674 | -------- Summary: end -------- 09:37:02 INFO - PROCESS | 1674 | 09:37:03 INFO - TEST-INFO | 1674: exit 0 09:37:03 INFO - TEST-OK | kraken | took 57833ms 09:37:03 INFO - SUITE-END | took 1171s 09:37:03 INFO - Completed test suite (00:19:31) 09:37:03 INFO - javascript benchmark 09:37:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3542.4863394807753, 3613.7831730096214, 3617.801020943847, 3626.4560315952613, 3696.353138200816, 5634.3520495143075, 5695.658260504372, 5731.484166425021, 5749.770009199632, 5773.7113144342775, 4711.882202944928, 5174.793008279665, 5251.081060814352, 5720.427957204278, 5727.0, 5214.765335559909, 5234.790608375661, 5242.187460943541, 5247.186686063647, 5264.552513036378, 5326.946730532683, 5372.0, 5372.409035006146, 5379.300690910176, 5380.946190538103, 5263.499967503081, 5317.601179911503, 5328.5470734987575, 5345.7594408251725, 5356.9196462052905, 5326.973365133169, 5370.0, 5370.516653501204, 5372.140457526777, 5408.486193811582, 5167.199084141964, 5212.218167274939, 5216.4522725114075, 5307.893842123174, 5311.787528498855, 5604.775808967636, 5633.15502674598, 5672.262605861211, 5674.404187560288, 5686.5735069869515, 6313.589616674947, 6357.93642063578, 6357.936420635827, 6371.585846919936, 6376.649284289362, 6077.939220607782, 6152.0, 6237.313895471494, 6384.776532821375, 6421.165248517709, 5865.88268234633, 5882.441168089024, 5905.2323197984415, 5906.26171728534, 5942.930272550982, 6365.809025729235, 6367.681615919186, 6370.58591191571, 6372.267189273293, 6381.585196962275, 6072.969635151796, 6107.795737660903, 6190.3190649028575, 6193.38066193377, 6218.502519798405, 6368.713407896656, 6379.681015949184, 6387.872242555216, 6391.776287829904, 6396.776112836028, 6222.502199824004, 6292.779752708633, 6317.5893566918, 6317.873642527124, 6318.905216421757, 6152.384761523901, 6204.13142160098, 6269.435750782452, 6334.081558174131, 6338.30278669346, 4966.428860680996, 4995.675281106716, 4996.5003499650475, 5003.824866129668, 5024.773885175176, 4534.546545345439, 4544.659150563721, 4547.704399214041, 4552.635789136861, 4578.587927086578, 4620.237660785932, 5869.148973398833, 5942.10868369748, 6033.064874944391, 6040.637561746222, 6322.810315690536, 6380.170577824853, 6386.776462823779, 6390.041493775878, 6412.0, 5967.492763115184, 5983.102534619842, 5994.880102397928, 5995.160677505153, 6003.429674180946, 5360.061989151883, 5389.7035663038905, 5410.458954104637, 5436.21174929633, 5447.536959358499, 6461.030845373231, 6483.189601299838, 6489.123968264224, 6548.148740663683, 6551.4103730664465, 6239.688015599293, 6405.455536279468, 6412.711427985659, 6415.3263907289065, 6415.454686351713, 6299.653519056313, 6374.553781235269, 6374.553781235454, 6381.8085457436355, 6392.648404337806, 6269.686515674107, 6297.590656607306, 6301.40136687014, 6302.960011598035, 6318.8736225274315, 4556.840510582114, 6217.751289948352, 6370.55406121567, 6377.0, 6398.072279519351, 6135.601185922901, 6160.353162918008, 6180.134781130735, 6182.505399568114, 6203.906941395973, 6175.96912015437, 6180.598261113013, 6186.628802271878, 6208.503319734501, 6219.0, 5003.974980125076, 5872.530197584098, 5937.614055086406, 6055.424734650202, 6058.878822423615, 5091.516305950929, 6003.219581454383, 6114.847128821815, 6124.356942521149, 6189.6286222826775, 6045.486133678686, 6047.365026672136, 6054.394560544087, 6062.0, 6072.1498990140535], "value": 5765.354905467763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.968861868288, 532.0980937311257, 575.715021064573, 578.144346367376, 578.8003138917074, 24459.143929962454, 39080.41379379308, 39549.406758898636, 39691.0, 39700.80149599255, 37889.62110378895, 39977.0, 42733.145337093236, 43114.7844260779, 43246.13507729844, 8975.416597921147, 9141.908580914172, 9682.322237443399, 9860.556274967643, 9875.95062024689, 9182.0, 9346.0, 11743.41282935854, 12034.638960831146, 12048.337341446217, 11657.475413606408, 11683.474243659013, 11725.648230553097, 11734.0, 11743.119266055037, 11724.648260552154, 11741.060715142768, 11811.763764724657, 11816.822747658793, 11824.172307938448, 11519.0, 11564.653060408202, 11649.825252621218, 11651.126165537618, 11659.242149260272, 41393.96515087117, 41873.1625367494, 42014.57985420168, 42027.0, 42043.94890127771, 3391.2030672791875, 3489.476578513218, 3518.4018716817945, 3602.4596310553366, 3793.468914351993, 28781.712182878113, 29267.829286828714, 29655.70344296551, 29759.0, 30506.0, 2761.3234757484547, 2770.5567109262624, 2771.1547977866744, 2803.887844486218, 2804.8597570121615, 333615.0, 334804.0, 335099.0, 335203.0, 335399.99999999756, 38197.42703859444, 38845.0, 68047.65976170088, 68510.65744671244, 68554.65722671355, 37214.44178337327, 37224.25551488955, 37531.81234093812, 37534.436983445274, 37595.0, 16203.432879849384, 16373.42693005739, 16395.180240988142, 16397.508074757778, 16422.1788910554, 12886.742265154644, 13039.739205215843, 13612.795808062887, 13677.837383822487, 13720.313984300745, 8555.700550480704, 8671.479711217347, 8672.089430609822, 8695.304375649934, 8768.991565969995, 2996.3258266889775, 3456.6370531094376, 3475.5134281200653, 3481.2863363010524, 3482.89551313461, 1006.3006210683651, 1015.9224386130381, 1028.6502589119586, 1141.6004398460639, 1158.7276989907366, 26484.47031059368, 29813.552796708067, 30329.848350758104, 31057.68942310593, 31869.8406507966, 1077.472038701031, 1082.7726177502655, 1109.7891400634014, 1183.6863231243726, 1211.878812118781, 15888.0, 15995.600109997344, 16079.276432560562, 16234.594135146715, 16307.266173022243, 633318.0, 634986.0, 641096.0, 642155.0, 644346.0, 64039.0, 64520.0, 64532.0, 64562.70874582482, 64954.67522662356, 1330.381372661717, 1390.2978995607357, 1544.609851133966, 1591.7851090102688, 1622.5781296862665, 29008.12975610735, 29853.104406867827, 30011.399772004876, 30727.539086913268, 30907.536386954664, 50373.748131260574, 50786.0, 50824.74587627038, 50853.745731271105, 50886.491135088174, 459756.0, 464385.0, 464803.0, 464974.0, 465075.0, 26901.865490673204, 29384.0, 29479.0, 29499.0, 29526.40947181087, 47218.0, 49161.0, 49535.504644953086, 51097.48902510927, 51301.74349128231, 34382.31235375329, 39235.019124522114, 40160.59839401569, 41081.38377924393, 41283.7935810319, 38447.423288651255, 38491.807540962116, 38561.8431447057, 38789.6121038786, 39731.801340994265, 38351.42472862965, 39630.40554391629, 39684.2063158741, 40043.599564003984, 40097.799511003424, 31357.0, 32037.519437208928, 33417.66582334146, 34478.0, 34989.650103498636, 24312.392190194678, 25927.611085834105, 26051.465482357245, 26085.0, 26302.342441439116], "value": 19162.49194214135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.7248184993278, 404.72074268754557, 407.56594227148094, 410.1796407185629, 418.51243301553694, 20615.896920515414, 20954.057067431964, 21669.241576544824, 21686.566268674636, 21710.89144554277, 20728.8963555182, 21243.04406301716, 21282.57434851302, 21294.36116916491, 21316.680249796267, 5651.717414129298, 5801.970990145054, 5923.378045305246, 5950.196723442345, 5953.107033944922, 4922.80308787648, 5731.226284451587, 5856.590038697272, 5890.381509941459, 6135.938640613582, 6107.175531303284, 6115.633062016271, 6132.938670613304, 6133.570650054498, 6152.354002829706, 5929.199558059671, 6083.574149809515, 6086.208792856923, 6678.332166783332, 6693.431058360045, 5915.053591425351, 5925.525957923399, 5933.821985340447, 5934.317553481362, 5946.464818166342, 13078.476860925552, 13437.731245375038, 13673.521426750116, 13691.246981416018, 13704.3833027514, 1734.731116676917, 2004.8295894848955, 2074.5954538864926, 2126.851120421571, 2190.134896715804, 9792.55933482995, 9865.260105492045, 9876.40741555509, 10034.89965100347, 15296.235188240655, 1867.3370953311514, 1875.2592725873203, 1895.1471837673103, 1915.3870761356372, 1932.6424611446787, 124844.0, 128223.0, 128768.99999999907, 129286.0607090885, 142050.0, 18057.729134063, 20282.898585506977, 20754.377368678965, 21458.034388452557, 21552.0, 36157.0, 36248.09379765527, 36426.0, 37015.0, 37101.628983710354, 11546.711332216593, 11561.959423651911, 11848.88151118495, 11927.463264153133, 12014.939925300318, 9869.756004917954, 9923.751906202402, 9927.553260103312, 9944.403335799876, 9994.650187243557, 9920.751981200528, 9969.0, 9986.25515431713, 10028.398296102258, 10115.848262276071, 3106.8601912913973, 3124.500079987191, 3265.0, 3801.334766415866, 4054.9797251013556, 738.9543795529373, 985.6845809340942, 989.7228775942604, 1055.2613170780485, 1078.2075174746556, 19433.0, 20200.49498762543, 20293.0, 20469.795302047085, 20694.379168624964, 994.5474808961905, 1023.9744006399751, 1028.7325295423243, 1035.7203555040096, 1038.2576457832686, 9400.106989836091, 10897.83653245202, 10927.726806829893, 10947.288426252268, 10987.835182472269, 560742.0, 657092.0, 668704.0, 681722.0, 681924.0, 102230.0, 103746.0, 118777.0, 139714.0, 148930.25534872257, 920.1994264989502, 1018.6231094495084, 1046.1787496814964, 1054.926155169146, 1055.8785739640039, 18046.368377106737, 18124.912505249726, 18263.81736182621, 18769.530761731065, 19069.427917162506, 53157.734211328694, 53827.46172538224, 55650.16524752051, 56320.436795633155, 56542.7172864133, 76894.0, 81430.59284703538, 81588.59205703934, 82690.0, 86237.0, 8172.713955011546, 8175.468594541336, 8217.95891020565, 8325.458845175044, 8519.148085191679, 10539.42033188182, 12047.457864396298, 12602.621921342374, 12673.873261267638, 13077.476900923857, 10942.890571094187, 11330.376829274139, 11563.884361156617, 11581.59464418775, 11642.01042911362, 10999.01008909202, 11020.614278500214, 11170.162237832032, 11374.488148033193, 11496.597619083292, 5891.558133139946, 8096.959515202387, 10192.694219173436, 11438.771224575628, 11789.823152652545, 8454.66181352739, 8459.365547584079, 8543.700970466003, 8583.742487725378, 8809.691660791841, 10577.682669519927, 11261.380624065756, 11408.429578521207, 11468.082553395541, 11689.88310116888], "value": 10483.787366510876, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.7318482673115, 1606.5019843848404, 1627.1538799824095, 1743.4769569129257, 1923.1922592511144, 8120.756377308683, 8921.64313427463, 9065.456072635638, 9067.957184923736, 9082.54587270637, 8686.087960764124, 8925.598348074336, 8926.330525210602, 8927.06265842087, 8930.19628233459, 2494.9750502495017, 2524.772770450659, 2526.406294520786, 2528.582783840664, 2529.190658989124, 2460.434100156965, 2531.9240422787348, 2539.5555777738914, 2541.9110331138413, 2549.146036077916, 2519.1812660885284, 2543.529447052292, 2565.28172111809, 2567.1785028790796, 2567.2939941516047, 2511.0834545390985, 2547.4522977559823, 2559.667243258383, 2561.078011915709, 2566.6021766626204, 2497.687789026372, 2537.7969762419148, 2541.453587478701, 2544.185860524633, 2555.399481121935, 8821.073787252375, 9045.0, 9082.000979892207, 9095.454272743591, 9104.0, 5523.392426833045, 5698.40166782486, 5710.2862142232225, 5713.171590119409, 5732.5987180897355, 3587.443946188345, 3649.5802982656805, 3701.5373078365205, 3725.3853114236117, 3750.456183853326, 1039.682896716501, 1049.1187402581868, 1062.1608928946123, 1073.1682945717055, 1084.0, 12065.819012714815, 12128.636140915698, 12331.075169362333, 12386.009119270346, 12408.875911240862, 4620.029793743352, 4652.95347046532, 4770.904581908342, 4773.0, 4786.7845946932475, 9641.469719165372, 9646.035396460298, 9648.614055437703, 9660.613575457044, 9669.951650241845, 5481.095619222783, 5493.285872836505, 5495.450454954473, 5495.972520137454, 5498.1752737089755, 4140.627343539096, 4464.665150113755, 4659.6505262105475, 5061.544460998454, 5108.923366149511, 3913.8434462621744, 3914.549826769901, 3978.5026871641044, 4006.338954072604, 4026.859059932947, 1126.549380247891, 1128.7968165730247, 1198.0, 1204.9457774399998, 1221.3221661977689, 854.2610641794927, 940.9341346105707, 942.7360339105062, 955.3599088610508, 960.1406740966795, 6021.397860213943, 6036.396360363929, 6142.539309551801, 6221.09794079865, 6260.46786023184, 473.3562355196929, 474.14416977356206, 477.2292747213295, 478.5668969582533, 489.649900321272, 3935.58676338986, 4081.1225586499045, 4082.6121518455698, 4124.154548317588, 4137.234611596864, 10990.615328463466, 11418.714532136763, 11500.597479088192, 11525.423728813526, 11533.904279093473, 10758.0855627271, 11149.16381271408, 11259.437028148559, 11260.042896353738, 11315.094792416587, 8490.915090849136, 8568.014678312074, 8711.21599056088, 8754.34342424308, 8761.25529330014, 5978.461938425563, 6010.639361638316, 6077.331493535619, 6124.295705993868, 6148.692565371803, 9680.031996800264, 9794.608215671293, 10007.749806254902, 10028.448435336126, 10181.898181018096, 10151.54318055704, 10345.63790267337, 10351.378917264989, 10515.421651809223, 10542.367457952547, 6665.700043497957, 6835.7949261522235, 6843.076184715199, 7178.24628414009, 7249.456290778107, 7768.572728499987, 8033.357331413356, 8109.8378032440205, 8640.611172497378, 8662.133786621287, 6992.825179370557, 7152.785416437515, 7579.54522728638, 7711.652975616223, 7743.451309738188, 6172.259328880563, 7553.7356192533, 7679.500832445873, 7787.9096926431475, 7898.01274840645, 7095.787126386217, 7222.205557388578, 7288.3076107769675, 7289.817754556178, 7298.160711518243, 5283.418823929442, 5309.495597918191, 5345.812896548602, 5349.358077030781, 5369.3556773187465, 5293.037734719638, 5304.549113325411, 5308.124159513792, 5542.0301447247375, 5590.021746194481], "value": 4634.348087619883, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.22033340316665, 332.6490552467147, 340.70192566322305, 342.7241070937894, 343.48648770088704, 6397.968010159954, 6455.386738259869, 6655.835228834952, 6692.799216023521, 6697.63163026034, 6679.365460281278, 6703.128593282866, 6703.497237707179, 6711.194656641198, 6745.966270168642, 5051.797928082881, 5055.165897626887, 5056.129232184097, 5059.924101138486, 5067.847964561069, 4314.460692413449, 4788.832390866321, 4882.658213925027, 5062.848114556569, 5065.518775716301, 4303.24693178694, 4730.763461826912, 4918.942427378113, 4956.801727930879, 4968.950310496903, 4983.476734942815, 4990.850274491771, 4991.550760431561, 4998.550130488237, 5001.224810154414, 3735.34631439497, 4316.438862947828, 4324.870253892388, 4332.805023773938, 4333.046729719456, 6362.0, 6392.7123279452535, 6409.615423074631, 6411.455026322768, 6421.711023003976, 5043.672161309503, 5584.413636568183, 5869.530437565028, 5881.206037184968, 5885.411458854123, 5931.317898441691, 6920.930790692129, 7095.432365410755, 7113.928860711379, 7123.964380178066, 4782.282657601388, 4797.880052998668, 4799.18413869643, 4805.0, 4807.471178170398, 7131.643417829087, 7312.707491700326, 7477.140128885193, 7479.5886226257535, 7506.249375062504, 3873.7288389813007, 4679.766011699401, 4716.646251531149, 4819.445763737145, 4846.006568653417, 4914.0908931847725, 4941.48114447985, 4962.478939711351, 4972.378452693413, 4972.676776009548, 5830.067189249699, 5838.299404071454, 5838.357780644125, 5842.620229685057, 5872.324682661463, 7157.355837974503, 7167.749128780468, 7178.2103968563415, 7191.0, 7207.13514378278, 1581.612504936275, 1646.8353164683676, 1652.1491431912575, 1707.556035430797, 1736.6787144378332, 1028.9691309260584, 1031.4584842957506, 1046.670298855858, 1049.0506091986765, 1056.3556230699267, 5642.012647786621, 6709.127813384326, 6877.0, 6905.75829845953, 6910.136232970879, 7346.118465784033, 7360.895865620199, 7362.079740032496, 7364.3372096511575, 7364.926350736531, 6224.78616669745, 6320.968395157995, 6878.174619045646, 6906.171259448898, 6912.792616221522, 3868.4390763339156, 3900.7854567999034, 3906.257811015898, 3906.3554513504955, 3906.6093390661276, 7675.424343174284, 7971.481853679492, 7973.0033745781775, 8013.318867896294, 8066.717664881701, 7636.0, 7704.537727736354, 7705.653245603961, 7706.267904549059, 7707.075150981918, 8015.639296231683, 8020.077691065486, 8039.437239393186, 8047.396445266745, 8051.114377418597, 7150.427965762622, 7415.740449084256, 7482.70069197226, 7487.2138425464545, 7488.438367122597, 7291.125064992235, 7332.81667958284, 7346.449016323904, 7352.228016058237, 7353.411727061929, 8006.719764808437, 8018.919810802051, 8028.839423211386, 8030.799230019296, 8036.839263214586, 3918.647321741057, 4612.930806037979, 4674.11191873543, 4739.620830333496, 4740.431148262231, 7243.710251589878, 7360.484766066324, 7409.518381305198, 7447.739329123455, 7471.775846724607, 7170.820729481805, 7210.0, 7219.169795473587, 7240.927590724025, 7242.529235599669, 7196.280371962762, 7207.063081799332, 7215.386692131227, 7220.3140701633265, 7225.096862892138, 6817.386435220854, 6976.965115174392, 7040.119985001875, 7051.541649792747, 7053.647317634201, 7125.857482850418, 7174.928250717426, 7183.676734547063, 7194.820129496805, 7198.604076775828, 5660.745266463102, 6512.274247324368, 7020.22777494465, 7028.683709232994, 7036.964815175891], "value": 5247.952334513283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.6578013652764, 695.9421679047847, 698.1552321690754, 721.9855602887942, 731.8170457385651, 15281.923590382046, 15316.69366612667, 15335.233238338067, 15359.84640153598, 15445.6138596535, 14098.71802563948, 14129.858701412983, 14143.009989300754, 14145.241677872818, 14159.29203539824, 756.5385115079811, 920.8388532006906, 946.668665966913, 948.2650945517211, 949.5252373813094, 928.1692884868039, 938.723076692374, 943.005129588284, 945.1729736480579, 945.9432434053945, 803.7106641609016, 999.7450650084219, 1001.3191030099566, 1002.5638847101498, 1005.1757558801786, 970.5244430229208, 1006.8741407324085, 1007.4056306778963, 1007.9869730920415, 1010.0202803280879, 751.3463286940342, 751.4964973467735, 751.999840212516, 755.0938873351945, 756.092688773474, 15130.621734456616, 15189.088654680754, 15231.086134831945, 15231.923840380727, 15268.31292591825, 12920.741585168338, 12994.610161695164, 13218.867811321956, 13442.462301507929, 13471.393787279596, 10119.696409107739, 10243.641472548425, 10260.435676037889, 10544.261901666887, 10684.465776711211, 3540.150363912642, 3545.2377738786026, 3591.6587924147166, 3651.2879988402274, 3818.6754125899333, 78878.42243155105, 79262.8110578336, 80325.59837200835, 83029.0, 83504.58247708723, 9603.711888643213, 9804.411735295906, 9902.356346837432, 10074.496275186211, 10128.848067278997, 22860.4284892879, 22893.656595151086, 22910.7708922912, 22938.0, 22986.425339366317, 7651.69393224276, 7676.347510461674, 7781.844363112706, 7875.409344299201, 7875.488093273919, 4113.835446582103, 4122.196171746541, 4196.727212731163, 4213.136307057046, 4256.787160641956, 3768.981155094262, 3836.8273427695262, 3837.712171587142, 3837.788921609338, 3840.9615903840604, 597.375742349246, 602.1569802276847, 604.7460066771966, 608.4919092557675, 688.3563867783638, 581.5318668471837, 596.4393470138143, 617.4350469320522, 627.3788948940517, 651.7230177174682, 8086.393520485987, 8249.29880960125, 8379.503459015936, 8388.035375931846, 8546.838366692748, 595.7319206357157, 598.7006496751624, 612.9671503516499, 623.0313595752679, 624.3226099681805, 3695.2609478104487, 3941.881743547698, 3952.822123004472, 4062.7765472899273, 4191.036061705825, 92625.073749263, 93397.0, 93713.59429608562, 97974.0, 98726.50636746913, 80548.98627534359, 84839.72740408899, 85199.0, 86769.13230867736, 87064.0, 73476.63261683764, 74758.87861682079, 74886.0, 76825.0, 78665.0, 7596.050493688289, 7749.845003100019, 8536.74389768308, 8719.738407847775, 8721.040685524715, 49632.0, 50052.749736251084, 52690.73654631702, 52772.20841687454, 52943.47056529386, 63318.733625328154, 70414.29585704078, 72967.63516182386, 73491.0, 75395.62302188454, 9136.314776391664, 11118.722031949266, 11233.32600044, 11310.208285419942, 11673.824892626788, 12047.939760301493, 12217.144799863925, 12299.385030748248, 12347.19743216688, 12765.61703148907, 8470.435564818612, 11786.646400607995, 12021.879781202077, 12027.579034733742, 12149.75700486003, 11469.082473402275, 11591.0, 11735.941320293345, 11778.352190629597, 11990.220635658654, 11424.771504569695, 11478.88521114778, 11642.6507204784, 11709.824352634547, 12157.209781364183, 8188.344932405274, 9702.805943881223, 10029.949850250701, 10036.19710423179, 10251.7437064074, 9617.278704097082, 9883.159931405911, 9890.950545247228, 9989.550470228713, 10035.749106272402], "value": 7108.680514583544, "unit": "score"}], "name": "dromaeo_css", "value": 7648.918093425823, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 157.0, 173.0, 111.0, 110.0, 110.0, 112.0, 110.0, 112.0, 131.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 121.0, 141.0, 164.0, 120.0, 164.0, 147.0, 188.0, 157.0, 121.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 185.0, 187.0, 187.0, 185.0, 183.0, 182.0, 184.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 72.0, 148.0, 56.0, 58.0, 76.0, 93.0, 157.0, 69.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 139.0, 80.0, 82.0, 96.0, 119.0, 80.0, 78.0, 124.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 99.0, 88.0, 90.0, 86.0, 91.0, 88.0, 90.0, 86.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 95.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 85.0, 83.0, 83.0, 84.0, 83.0, 84.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 67.0, 64.0, 62.0, 64.0, 64.0, 80.0, 58.0, 57.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 71.0, 70.0, 71.0, 71.0, 66.0, 67.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 78.0, 80.0, 78.0, 81.0, 79.0, 81.0, 83.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 124.0, 125.0, 124.0, 123.0, 124.0, 118.0, 126.0, 145.0, 141.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 162.0, 162.0, 162.0, 163.0, 161.0, 162.0, 159.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 66.0, 68.0, 66.0, 67.0, 67.0, 67.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1478.5000000000002, "alertThreshold": 2.0}]} 09:37:03 INFO - Return code: 0 09:37:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:37:03 ERROR - # TBPL SUCCESS # 09:37:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:03 INFO - 2016-07-04 09:37:03.762 screenresolution[1678:9752] starting screenresolution argv=screenresolution get 09:37:03 INFO - 2016-07-04 09:37:03.765 screenresolution[1678:9752] Display 0: 1600x1200x32@60 09:37:03 INFO - 2016-07-04 09:37:03.771 screenresolution[1679:9755] starting screenresolution argv=screenresolution list 09:37:03 INFO - Available Modes on Display 0 09:37:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:03 INFO - Intel Iris: 09:37:03 INFO - Chipset Model: Intel Iris 09:37:03 INFO - Type: GPU 09:37:03 INFO - Bus: Built-In 09:37:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:03 INFO - Vendor: Intel (0x8086) 09:37:03 INFO - Device ID: 0x0a2e 09:37:03 INFO - Revision ID: 0x0009 09:37:03 INFO - Displays: 09:37:03 INFO - Display: 09:37:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:04 INFO - Display Serial Number: 1600x1200 60 09:37:04 INFO - Main Display: Yes 09:37:04 INFO - Mirror: Off 09:37:04 INFO - Online: Yes 09:37:04 INFO - Rotation: Supported 09:37:04 INFO - Return code: 0 09:37:04 INFO - Running post-action listener: _resource_record_post_action 09:37:04 INFO - Running post-run listener: _resource_record_post_run 09:37:05 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 38270464; Write bytes: 491779072; Read time: 376; Write time: 1937 09:37:05 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 189235712; Write bytes: 184741888; Read time: 17407; Write time: 408 09:37:05 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 36488704; Write bytes: 296248320; Read time: 358; Write time: 1465 09:37:05 INFO - Running post-run listener: _upload_blobber_files 09:37:05 INFO - Blob upload gear active. 09:37:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:05 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:37:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:06 INFO - (blobuploader) - INFO - Done attempting. 09:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:07 INFO - (blobuploader) - INFO - Done attempting. 09:37:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:07 INFO - Return code: 0 09:37:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6e5a575f7d7c10e322a7e37acf1f1032389f79637f68e633adae4e3d7878eafe3f1ad12e0e02b0b8eac575a8f81f52380fd26866b626e67bc6091d12d78fc8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5d9549de821fb028c6c6c1eb598230f696abceb48c5759530b4b0a3feebf5a1ec3c897db3e1e0099b8f2a0f5df5e2ba4d74ddf0f31facfd76796b60419cca8d"} 09:37:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:07 INFO - Contents: 09:37:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6e5a575f7d7c10e322a7e37acf1f1032389f79637f68e633adae4e3d7878eafe3f1ad12e0e02b0b8eac575a8f81f52380fd26866b626e67bc6091d12d78fc8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5d9549de821fb028c6c6c1eb598230f696abceb48c5759530b4b0a3feebf5a1ec3c897db3e1e0099b8f2a0f5df5e2ba4d74ddf0f31facfd76796b60419cca8d"} 09:37:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:07 INFO - Copying logs to upload dir... 09:37:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.288906 ========= master_lag: 3.59 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-04 09:37:11.358774) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:11.362193) ========= 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.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6e5a575f7d7c10e322a7e37acf1f1032389f79637f68e633adae4e3d7878eafe3f1ad12e0e02b0b8eac575a8f81f52380fd26866b626e67bc6091d12d78fc8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5d9549de821fb028c6c6c1eb598230f696abceb48c5759530b4b0a3feebf5a1ec3c897db3e1e0099b8f2a0f5df5e2ba4d74ddf0f31facfd76796b60419cca8d"} build_url:https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/WO_8MOtZToGiUbFts4BvZQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6e5a575f7d7c10e322a7e37acf1f1032389f79637f68e633adae4e3d7878eafe3f1ad12e0e02b0b8eac575a8f81f52380fd26866b626e67bc6091d12d78fc8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5d9549de821fb028c6c6c1eb598230f696abceb48c5759530b4b0a3feebf5a1ec3c897db3e1e0099b8f2a0f5df5e2ba4d74ddf0f31facfd76796b60419cca8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:11.397017) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:11.397423) ========= 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.tD0mSwNNuA/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.44jCZGbJFS/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.004972 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:11.445561) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:37:11.445879) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:37:11.446186) ========= ========= Total master_lag: 3.72 =========