builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1469276090.99 results: success (0) buildid: 20160723031426 builduid: bd3a73148854423f93709c527de18191 revision: c63ed16dae66fff87a380cbacf40aa32bd58e714 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:50.988465) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:50.989321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:50.989623) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:51.016298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:51.016600) ========= 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.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-23 05:14:51-- 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% 39.5M=0s 2016-07-23 05:14:51 (39.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.647493 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:51.685234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:51.685524) ========= 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.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.021820 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:51.735084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-23 05:14:51.735701) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c63ed16dae66fff87a380cbacf40aa32bd58e714 --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 c63ed16dae66fff87a380cbacf40aa32bd58e714 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-23 05:14:51,808 truncating revision to first 12 chars 2016-07-23 05:14:51,808 Setting DEBUG logging. 2016-07-23 05:14:51,808 attempt 1/10 2016-07-23 05:14:51,808 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c63ed16dae66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-23 05:14:53,799 unpacking tar archive at: mozilla-beta-c63ed16dae66/testing/mozharness/ program finished with exit code 0 elapsedTime=2.248040 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-23 05:14:54.006153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:54.006471) ========= script_repo_revision: c63ed16dae66fff87a380cbacf40aa32bd58e714 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:54.006863) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:54.007134) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 05:14:54.025660) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-07-23 05:14:54.026088) ========= /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.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:14:54 INFO - MultiFileLogger online at 20160723 05:14:54 in /builds/slave/test 05:14:54 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 05:14:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:54 INFO - {'append_to_log': False, 05:14:54 INFO - 'base_work_dir': '/builds/slave/test', 05:14:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:14:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:54 INFO - 'branch': 'Mozilla-Beta', 05:14:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:54 INFO - 'config_files': ('talos/mac_config.py',), 05:14:54 INFO - 'default_actions': ('clobber', 05:14:54 INFO - 'read-buildbot-config', 05:14:54 INFO - 'download-and-extract', 05:14:54 INFO - 'populate-webroot', 05:14:54 INFO - 'create-virtualenv', 05:14:54 INFO - 'install', 05:14:54 INFO - 'run-tests'), 05:14:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:54 INFO - 'download_minidump_stackwalk': True, 05:14:54 INFO - 'download_symbols': 'ondemand', 05:14:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:54 INFO - 'installer_path': 'installer.exe', 05:14:54 INFO - 'log_level': 'info', 05:14:54 INFO - 'log_name': 'talos', 05:14:54 INFO - 'log_to_console': True, 05:14:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:54 INFO - 'opt_config_files': (), 05:14:54 INFO - 'pip_index': False, 05:14:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:54 INFO - 'cmd': ('bash', 05:14:54 INFO - '-c', 05:14:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:54 INFO - 'enabled': True, 05:14:54 INFO - 'halt_on_failure': False, 05:14:54 INFO - 'name': 'check_screen_resolution'},), 05:14:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:54 INFO - 'cmd': ('bash', 05:14:54 INFO - '-c', 05:14:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:54 INFO - 'enabled': True, 05:14:54 INFO - 'halt_on_failure': False, 05:14:54 INFO - 'name': 'check_screen_resolution'},), 05:14:54 INFO - 'run_cmd_checks_enabled': True, 05:14:54 INFO - 'sps_profile': False, 05:14:54 INFO - 'sps_profile_interval': 0, 05:14:54 INFO - 'suite': 'dromaeojs', 05:14:54 INFO - 'system_bits': '32', 05:14:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:54 INFO - 'title': 't-yosemite-r7-0159', 05:14:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:54 INFO - 'use_talos_json': True, 05:14:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:54 INFO - 'work_dir': 'build'} 05:14:54 INFO - ##### 05:14:54 INFO - ##### Running clobber step. 05:14:54 INFO - ##### 05:14:54 INFO - Running pre-action listener: _resource_record_pre_action 05:14:54 INFO - Running main action method: clobber 05:14:54 INFO - rmtree: /builds/slave/test/build 05:14:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:54 INFO - Running post-action listener: _resource_record_post_action 05:14:54 INFO - ##### 05:14:54 INFO - ##### Running read-buildbot-config step. 05:14:54 INFO - ##### 05:14:54 INFO - Running pre-action listener: _resource_record_pre_action 05:14:54 INFO - Running main action method: read_buildbot_config 05:14:54 INFO - Using buildbot properties: 05:14:54 INFO - { 05:14:54 INFO - "project": "", 05:14:54 INFO - "product": "firefox", 05:14:54 INFO - "script_repo_revision": "production", 05:14:54 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:14:54 INFO - "repository": "", 05:14:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:14:54 INFO - "buildid": "20160723031426", 05:14:54 INFO - "pgo_build": "False", 05:14:54 INFO - "basedir": "/builds/slave/test", 05:14:54 INFO - "buildnumber": 71, 05:14:54 INFO - "slavename": "t-yosemite-r7-0159", 05:14:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:14:54 INFO - "platform": "yosemite_r7", 05:14:54 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:14:54 INFO - "branch": "mozilla-beta", 05:14:54 INFO - "repo_path": "releases/mozilla-beta", 05:14:54 INFO - "revision": "c63ed16dae66fff87a380cbacf40aa32bd58e714", 05:14:54 INFO - "stage_platform": "macosx64", 05:14:54 INFO - "builduid": "bd3a73148854423f93709c527de18191", 05:14:54 INFO - "slavebuilddir": "test" 05:14:54 INFO - } 05:14:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 05:14:54 INFO - Running post-action listener: _resource_record_post_action 05:14:54 INFO - ##### 05:14:54 INFO - ##### Running download-and-extract step. 05:14:54 INFO - ##### 05:14:54 INFO - Running pre-action listener: _resource_record_pre_action 05:14:54 INFO - Running main action method: download_and_extract 05:14:54 INFO - mkdir: /builds/slave/test/build/tests 05:14: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')]} 05:14:54 INFO - https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:14:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:14:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:14:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:14:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:19 INFO - Downloaded 1165 bytes. 05:15:19 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:15:19 INFO - Using the following test package requirements: 05:15:19 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:15:19 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 05:15:19 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 05:15:19 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:15:19 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 05:15:19 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:15:19 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 05:15:19 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 05:15:19 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 05:15:19 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:15:19 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 05:15:19 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 05:15: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')]} 05:15:19 INFO - https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:15:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:15:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:31 INFO - Downloaded 18314924 bytes. 05:15:31 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'] 05:15:31 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 05:15:34 INFO - Return code: 0 05:15:34 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 05:15:34 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')]} 05:15:34 INFO - https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:15:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:15:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:39 INFO - Downloaded 18314924 bytes. 05:15:39 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'] 05:15:39 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 05:15:42 INFO - Return code: 0 05:15: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')]} 05:15:42 INFO - https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:15:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:15:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:15:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:43 INFO - Downloaded 10840145 bytes. 05:15:43 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'] 05:15:43 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 05:15:44 INFO - Return code: 0 05:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:44 INFO - https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 05:15:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:15:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:15:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 05:15:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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 05:15:47 INFO - Downloaded 86192726 bytes. 05:15:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:15:47 INFO - mkdir: /builds/slave/test/properties 05:15:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:15:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:15:47 INFO - Contents: 05:15:47 INFO - build_url:https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - Running post-action listener: set_extra_try_arguments 05:15:48 INFO - ##### 05:15:48 INFO - ##### Running populate-webroot step. 05:15:48 INFO - ##### 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: populate_webroot 05:15:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:15:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:15:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:15:48 INFO - '--tppagecycles', 05:15:48 INFO - '7'], 05:15:48 INFO - 'tests': ['tsvgm']}}, 05:15:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:15:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:15:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:15:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:15:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['damp', 'tps']}, 05:15:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'tests': ['damp', 'tps']}, 05:15:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['dromaeo_dom']}, 05:15:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:15:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['tsvgx', 05:15:48 INFO - 'tsvgr_opacity', 05:15:48 INFO - 'tart', 05:15:48 INFO - 'tscrollx', 05:15:48 INFO - 'cart']}, 05:15:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:15:48 INFO - 'tsvgr_opacity', 05:15:48 INFO - 'tart', 05:15:48 INFO - 'tscrollx', 05:15:48 INFO - 'cart']}, 05:15:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'talos_options': ['--disable-e10s'], 05:15:48 INFO - 'tests': ['tp5o']}, 05:15:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'tests': ['tp5o']}, 05:15:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'talos_options': ['--disable-e10s', 05:15:48 INFO - '--xperf_path', 05:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:48 INFO - 'tests': ['tp5n']}, 05:15:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:15:48 INFO - 'talos_options': ['--xperf_path', 05:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:48 INFO - 'tests': ['tp5n']}}, 05:15:48 INFO - 'talos.zip': {'path': '', 05:15:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - ##### 05:15:48 INFO - ##### Running create-virtualenv step. 05:15:48 INFO - ##### 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: create_virtualenv 05:15:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:15:48 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 05:15:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:15:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:15:48 INFO - Using real prefix '/tools/python27' 05:15:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:15:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:15:52 INFO - Installing pip.................done. 05:15:52 INFO - Return code: 0 05:15:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:52 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:15:52 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 05:15:52 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 05:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:52 INFO - 'HOME': '/Users/cltbld', 05:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:52 INFO - 'LOGNAME': 'cltbld', 05:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:52 INFO - 'MOZ_NO_REMOTE': '1', 05:15:52 INFO - 'NO_EM_RESTART': '1', 05:15:52 INFO - 'PAGER': '/bin/cat', 05:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:52 INFO - 'PWD': '/builds/slave/test', 05:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:52 INFO - 'SHELL': '/bin/bash', 05:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:15:52 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:52 INFO - 'USER': 'cltbld', 05:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:52 INFO - 'XPC_FLAGS': '0x0', 05:15:52 INFO - 'XPC_SERVICE_NAME': '0', 05:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:52 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 05:15:52 INFO - Cleaning up... 05:15:52 INFO - Return code: 0 05:15:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:52 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:15:52 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 05:15:52 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 05:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:52 INFO - 'HOME': '/Users/cltbld', 05:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:52 INFO - 'LOGNAME': 'cltbld', 05:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:52 INFO - 'MOZ_NO_REMOTE': '1', 05:15:52 INFO - 'NO_EM_RESTART': '1', 05:15:52 INFO - 'PAGER': '/bin/cat', 05:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:52 INFO - 'PWD': '/builds/slave/test', 05:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:52 INFO - 'SHELL': '/bin/bash', 05:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:15:52 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:52 INFO - 'USER': 'cltbld', 05:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:52 INFO - 'XPC_FLAGS': '0x0', 05:15:52 INFO - 'XPC_SERVICE_NAME': '0', 05:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:52 INFO - Downloading/unpacking psutil>=0.7.1 05:15:52 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 05:15:52 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 05:15:52 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 05:15:52 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 05:15:52 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 05:15:52 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 05:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:55 INFO - Installing collected packages: psutil 05:15:55 INFO - Running setup.py install for psutil 05:15:55 INFO - building 'psutil._psutil_osx' extension 05:15:55 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 05:15:56 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 05:15:56 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 05:15:56 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 05:15:56 INFO - building 'psutil._psutil_posix' extension 05:15:56 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 05:15:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:15:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:15:56 INFO - ^ 05:15:56 INFO - 1 warning generated. 05:15:56 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 05:15:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:56 INFO - Successfully installed psutil 05:15:56 INFO - Cleaning up... 05:15:56 INFO - Return code: 0 05:15:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:57 INFO - 'HOME': '/Users/cltbld', 05:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:57 INFO - 'LOGNAME': 'cltbld', 05:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:57 INFO - 'MOZ_NO_REMOTE': '1', 05:15:57 INFO - 'NO_EM_RESTART': '1', 05:15:57 INFO - 'PAGER': '/bin/cat', 05:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:57 INFO - 'PWD': '/builds/slave/test', 05:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:57 INFO - 'SHELL': '/bin/bash', 05:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:15:57 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:57 INFO - 'USER': 'cltbld', 05:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:57 INFO - 'XPC_FLAGS': '0x0', 05:15:57 INFO - 'XPC_SERVICE_NAME': '0', 05:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:15:57 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 05:15:57 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 05:15:57 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 05:15:57 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 05:15:57 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 05:15:57 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 05:16:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:16:00 INFO - Installing collected packages: mozsystemmonitor 05:16:00 INFO - Running setup.py install for mozsystemmonitor 05:16:00 INFO - Successfully installed mozsystemmonitor 05:16:00 INFO - Cleaning up... 05:16:00 INFO - Return code: 0 05:16:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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', '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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:16: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 blobuploader==1.2.4 05:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:00 INFO - 'HOME': '/Users/cltbld', 05:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:00 INFO - 'LOGNAME': 'cltbld', 05:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:00 INFO - 'MOZ_NO_REMOTE': '1', 05:16:00 INFO - 'NO_EM_RESTART': '1', 05:16:00 INFO - 'PAGER': '/bin/cat', 05:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:00 INFO - 'PWD': '/builds/slave/test', 05:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:00 INFO - 'SHELL': '/bin/bash', 05:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:00 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:00 INFO - 'USER': 'cltbld', 05:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:00 INFO - 'XPC_FLAGS': '0x0', 05:16:00 INFO - 'XPC_SERVICE_NAME': '0', 05:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:03 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:04 INFO - Downloading docopt-0.6.1.tar.gz 05:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:04 INFO - Running setup.py install for blobuploader 05:16:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:04 INFO - Running setup.py install for requests 05:16:05 INFO - Running setup.py install for docopt 05:16:05 INFO - Successfully installed blobuploader requests docopt 05:16:05 INFO - Cleaning up... 05:16:05 INFO - Return code: 0 05:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:05 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')]} 05:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:05 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')]} 05:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:05 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16:05 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 05:16:05 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 05:16:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:05 INFO - 'HOME': '/Users/cltbld', 05:16:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:05 INFO - 'LOGNAME': 'cltbld', 05:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:05 INFO - 'MOZ_NO_REMOTE': '1', 05:16:05 INFO - 'NO_EM_RESTART': '1', 05:16:05 INFO - 'PAGER': '/bin/cat', 05:16:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:05 INFO - 'PWD': '/builds/slave/test', 05:16:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:05 INFO - 'SHELL': '/bin/bash', 05:16:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:05 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:05 INFO - 'USER': 'cltbld', 05:16:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:05 INFO - 'XPC_FLAGS': '0x0', 05:16:05 INFO - 'XPC_SERVICE_NAME': '0', 05:16:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:05 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9N_R4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:05 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eu2X4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:05 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qyzd1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:05 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qLuwsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:05 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-blwy1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-DFXpIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qcOpN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fW8xEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gtO7xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-G2aJ10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-6qSSBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Wh8Fa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-rLoWi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-u9Z5cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:06 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-AnaPbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:07 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kb26ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:07 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-QaHFTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:07 INFO - Running setup.py install for manifestparser 05:16:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:07 INFO - Running setup.py install for mozcrash 05:16:07 INFO - Running setup.py install for mozdebug 05:16:07 INFO - Running setup.py install for mozdevice 05:16:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:16:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:07 INFO - Running setup.py install for mozfile 05:16:08 INFO - Running setup.py install for mozhttpd 05:16:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Running setup.py install for mozinfo 05:16:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Running setup.py install for mozInstall 05:16:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Running setup.py install for mozleak 05:16:08 INFO - Running setup.py install for mozlog 05:16:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Running setup.py install for moznetwork 05:16:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:08 INFO - Running setup.py install for mozprocess 05:16:09 INFO - Running setup.py install for mozprofile 05:16:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:09 INFO - Running setup.py install for mozrunner 05:16:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:09 INFO - Running setup.py install for mozscreenshot 05:16:09 INFO - Running setup.py install for moztest 05:16:09 INFO - Running setup.py install for mozversion 05:16:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:09 INFO - Cleaning up... 05:16:09 INFO - Return code: 0 05:16:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:09 INFO - 'HOME': '/Users/cltbld', 05:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:09 INFO - 'LOGNAME': 'cltbld', 05:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:09 INFO - 'MOZ_NO_REMOTE': '1', 05:16:09 INFO - 'NO_EM_RESTART': '1', 05:16:09 INFO - 'PAGER': '/bin/cat', 05:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:09 INFO - 'PWD': '/builds/slave/test', 05:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:09 INFO - 'SHELL': '/bin/bash', 05:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:09 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:09 INFO - 'USER': 'cltbld', 05:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:09 INFO - 'XPC_FLAGS': '0x0', 05:16:09 INFO - 'XPC_SERVICE_NAME': '0', 05:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8zsHz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:10 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)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-itrYIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:10 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)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-pQ3AJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:10 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)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Bw4AUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:10 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)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3vsjMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:10 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)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-09hp9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-odrS_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:10 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7ulxaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gCChC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-WWs3_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YQCgDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Z9HHgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-x22WbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jMSelt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:11 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)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jtaxH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oZKmVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:11 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9C3vlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:12 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)) 05:16:12 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)) 05:16:12 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)) 05:16:12 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)) 05:16:12 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)) 05:16:12 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)) 05:16:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:12 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 05:16:12 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 05:16:12 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 05:16:12 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 05:16:12 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 05:16:12 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 05:16:14 INFO - Downloading blessings-1.6.tar.gz 05:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:14 INFO - Installing collected packages: blessings 05:16:14 INFO - Running setup.py install for blessings 05:16:14 INFO - Successfully installed blessings 05:16:14 INFO - Cleaning up... 05:16:14 INFO - Return code: 0 05:16:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:15 INFO - Reading from file tmpfile_stdout 05:16:15 INFO - Current package versions: 05:16:15 INFO - blessings == 1.6 05:16:15 INFO - blobuploader == 1.2.4 05:16:15 INFO - docopt == 0.6.1 05:16:15 INFO - manifestparser == 1.1 05:16:15 INFO - mozInstall == 1.12 05:16:15 INFO - mozcrash == 0.17 05:16:15 INFO - mozdebug == 0.1 05:16:15 INFO - mozdevice == 0.48 05:16:15 INFO - mozfile == 1.2 05:16:15 INFO - mozhttpd == 0.7 05:16:15 INFO - mozinfo == 0.9 05:16:15 INFO - mozleak == 0.1 05:16:15 INFO - mozlog == 3.1 05:16:15 INFO - moznetwork == 0.27 05:16:15 INFO - mozprocess == 0.22 05:16:15 INFO - mozprofile == 0.28 05:16:15 INFO - mozrunner == 6.11 05:16:15 INFO - mozscreenshot == 0.1 05:16:15 INFO - mozsystemmonitor == 0.0 05:16:15 INFO - moztest == 0.7 05:16:15 INFO - mozversion == 1.4 05:16:15 INFO - psutil == 3.1.1 05:16:15 INFO - requests == 1.2.3 05:16:15 INFO - wsgiref == 0.1.2 05:16:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:15 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16:15 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 05:16:15 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 05:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:15 INFO - 'HOME': '/Users/cltbld', 05:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:15 INFO - 'LOGNAME': 'cltbld', 05:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:15 INFO - 'MOZ_NO_REMOTE': '1', 05:16:15 INFO - 'NO_EM_RESTART': '1', 05:16:15 INFO - 'PAGER': '/bin/cat', 05:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:15 INFO - 'PWD': '/builds/slave/test', 05:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:15 INFO - 'SHELL': '/bin/bash', 05:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:15 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:15 INFO - 'USER': 'cltbld', 05:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:15 INFO - 'XPC_FLAGS': '0x0', 05:16:15 INFO - 'XPC_SERVICE_NAME': '0', 05:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 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)) 05:16:15 INFO - Cleaning up... 05:16:15 INFO - Return code: 0 05:16:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:15 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16:15 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 05:16:15 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 05:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:15 INFO - 'HOME': '/Users/cltbld', 05:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:15 INFO - 'LOGNAME': 'cltbld', 05:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:15 INFO - 'MOZ_NO_REMOTE': '1', 05:16:15 INFO - 'NO_EM_RESTART': '1', 05:16:15 INFO - 'PAGER': '/bin/cat', 05:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:15 INFO - 'PWD': '/builds/slave/test', 05:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:15 INFO - 'SHELL': '/bin/bash', 05:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:15 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:15 INFO - 'USER': 'cltbld', 05:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:15 INFO - 'XPC_FLAGS': '0x0', 05:16:15 INFO - 'XPC_SERVICE_NAME': '0', 05:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:15 INFO - Downloading/unpacking jsonschema 05:16:15 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 05:16:15 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 05:16:15 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 05:16:15 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 05:16:15 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 05:16:15 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 05:16:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:18 INFO - Downloading/unpacking functools32 (from jsonschema) 05:16:18 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 05:16:18 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 05:16:18 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 05:16:18 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 05:16:18 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 05:16:18 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 05:16:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:18 INFO - warning: no files found matching '*.txt' 05:16:18 INFO - no previously-included directories found matching 'build' 05:16:18 INFO - no previously-included directories found matching 'dist' 05:16:18 INFO - no previously-included directories found matching '.git*' 05:16:18 INFO - Installing collected packages: jsonschema, functools32 05:16:18 INFO - Running setup.py install for functools32 05:16:18 INFO - warning: no files found matching '*.txt' 05:16:18 INFO - no previously-included directories found matching 'build' 05:16:18 INFO - no previously-included directories found matching 'dist' 05:16:18 INFO - no previously-included directories found matching '.git*' 05:16:18 INFO - Successfully installed jsonschema functools32 05:16:18 INFO - Cleaning up... 05:16:18 INFO - Return code: 0 05:16:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:16:18 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')]} 05:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:18 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')]} 05:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:18 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 0x11029ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102ac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd829d7a150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, '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.tIERRn86ep/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.kfOVOKscsJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:16:18 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 05:16:18 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 05:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:18 INFO - 'HOME': '/Users/cltbld', 05:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:18 INFO - 'LOGNAME': 'cltbld', 05:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:18 INFO - 'MOZ_NO_REMOTE': '1', 05:16:18 INFO - 'NO_EM_RESTART': '1', 05:16:18 INFO - 'PAGER': '/bin/cat', 05:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:18 INFO - 'PWD': '/builds/slave/test', 05:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:18 INFO - 'SHELL': '/bin/bash', 05:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:18 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:18 INFO - 'USER': 'cltbld', 05:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:18 INFO - 'XPC_FLAGS': '0x0', 05:16:18 INFO - 'XPC_SERVICE_NAME': '0', 05:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:19 INFO - Downloading/unpacking flake8 05:16:19 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 05:16:19 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 05:16:19 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 05:16:19 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 05:16:19 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 05:16:19 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 05:16:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:16:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:16:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 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 05:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:16:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:22 INFO - no previously-included directories found matching 'docs/_build' 05:16:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:16:23 INFO - Running setup.py install for pep8 05:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:23 INFO - no previously-included directories found matching 'docs/_build' 05:16:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:16:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:16:23 INFO - Cleaning up... 05:16:23 INFO - Return code: 0 05:16:23 INFO - Running post-action listener: _resource_record_post_action 05:16:23 INFO - Running post-action listener: _start_resource_monitoring 05:16:23 INFO - Starting resource monitoring. 05:16:23 INFO - ##### 05:16:23 INFO - ##### Running install step. 05:16:23 INFO - ##### 05:16:23 INFO - Running pre-action listener: _resource_record_pre_action 05:16:23 INFO - Running main action method: install 05:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:23 INFO - Reading from file tmpfile_stdout 05:16:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:23 INFO - Reading from file tmpfile_stdout 05:16:23 INFO - Output received: 05:16:23 INFO - Usage: mozinstall [options] installer 05:16:23 INFO - Options: 05:16:23 INFO - -h, --help show this help message and exit 05:16:23 INFO - -d DEST, --destination=DEST 05:16:23 INFO - Directory to install application into. [default: 05:16:23 INFO - "/builds/slave/test"] 05:16:23 INFO - --app=APP Application being installed. [default: firefox] 05:16:23 INFO - mkdir: /builds/slave/test/build/application 05:16:23 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'] 05:16:23 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 05:16:45 INFO - Reading from file tmpfile_stdout 05:16:45 INFO - Output received: 05:16:45 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:16:45 INFO - Running post-action listener: _resource_record_post_action 05:16:45 INFO - ##### 05:16:45 INFO - ##### Running run-tests step. 05:16:45 INFO - ##### 05:16:45 INFO - Running pre-action listener: _resource_record_pre_action 05:16:45 INFO - Running main action method: run_tests 05:16:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:45 INFO - 2016-07-23 05:16:45.344 screenresolution[7714:143526] starting screenresolution argv=screenresolution get 05:16:45 INFO - 2016-07-23 05:16:45.347 screenresolution[7714:143526] Display 0: 1600x1200x32@60 05:16:45 INFO - 2016-07-23 05:16:45.353 screenresolution[7715:143528] starting screenresolution argv=screenresolution list 05:16:45 INFO - Available Modes on Display 0 05:16:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:45 INFO - Intel Iris: 05:16:45 INFO - Chipset Model: Intel Iris 05:16:45 INFO - Type: GPU 05:16:45 INFO - Bus: Built-In 05:16:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:45 INFO - Vendor: Intel (0x8086) 05:16:45 INFO - Device ID: 0x0a2e 05:16:45 INFO - Revision ID: 0x0009 05:16:45 INFO - Displays: 05:16:45 INFO - Display: 05:16:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:45 INFO - Display Serial Number: 1600x1200 60 05:16:45 INFO - Main Display: Yes 05:16:45 INFO - Mirror: Off 05:16:45 INFO - Online: Yes 05:16:45 INFO - Rotation: Supported 05:16:45 INFO - Return code: 0 05:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:16:45 INFO - Python 2.7.3 05:16:45 INFO - Return code: 0 05:16:45 INFO - grabbing minidump binary from tooltool 05: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')]} 05:16:45 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 0x11013be00>, '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 0x10ffa4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110136b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:16:45 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 05:16:45 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 05:16:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:16:45 INFO - Return code: 0 05:16:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:16:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:16:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:16:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:16:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:16:46 INFO - Return code: 0 05:16:46 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/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 05:16:46 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/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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 05:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIERRn86ep/Render', 05:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:46 INFO - 'HOME': '/Users/cltbld', 05:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:46 INFO - 'LOGNAME': 'cltbld', 05:16:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:16:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:46 INFO - 'MOZ_NO_REMOTE': '1', 05:16:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:16:46 INFO - 'NO_EM_RESTART': '1', 05:16:46 INFO - 'PAGER': '/bin/cat', 05:16:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:46 INFO - 'PWD': '/builds/slave/test', 05:16:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:46 INFO - 'SHELL': '/bin/bash', 05:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kfOVOKscsJ/Listeners', 05:16:46 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:46 INFO - 'USER': 'cltbld', 05:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:46 INFO - 'XPC_FLAGS': '0x0', 05:16:46 INFO - 'XPC_SERVICE_NAME': '0', 05:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:46 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/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 05:16:46 INFO - mozversion application_buildid: 20160723031426 05:16:46 INFO - mozversion application_changeset: c63ed16dae66fff87a380cbacf40aa32bd58e714 05:16:46 INFO - mozversion application_display_name: Firefox 05:16:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:16:46 INFO - mozversion application_name: Firefox 05:16:46 INFO - mozversion application_remotingname: firefox 05:16:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:16:46 INFO - mozversion application_vendor: Mozilla 05:16:46 INFO - mozversion application_version: 48.0 05:16:46 INFO - mozversion platform_buildid: 20160723031426 05:16:46 INFO - mozversion platform_changeset: c63ed16dae66fff87a380cbacf40aa32bd58e714 05:16:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:16:46 INFO - mozversion platform_version: 48.0 05:16:46 INFO - using testdate: 1469276206 05:16:46 INFO - actual date: 1469276206 05:16:46 INFO - starting webserver on 'localhost:62495' 05:16:46 INFO - SUITE-START | Running 2 tests 05:16:46 INFO - TEST-START | dromaeo_css 05:16:46 INFO - operating with platform_type : mac_ 05:16:46 INFO - Initialising browser for dromaeo_css test... 05:16:47 INFO - TEST-INFO | started process 7733 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpZF4_EB/profile http://localhost:62495/getInfo.html) 05:16:49 INFO - PROCESS | 7733 | __metrics Screen width/height:1600/1200 05:16:49 INFO - PROCESS | 7733 | colorDepth:24 05:16:49 INFO - PROCESS | 7733 | Browser inner width/height: 1024/689 05:16:49 INFO - PROCESS | 7733 | __metrics 05:16:49 INFO - PROCESS | 7733 | 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" 05:16:49 ERROR - PROCESS | 7733 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:16:50 INFO - TEST-INFO | 7733: exit 0 05:16:50 INFO - Browser initialized. 05:16:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:16:50 INFO - TEST-INFO | started process 7736 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpZF4_EB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:02 INFO - PROCESS | 7736 | RSS: Main: 160624640 05:17:02 INFO - PROCESS | 7736 | 05:17:53 INFO - PROCESS | 7736 | 1469276273261 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] 05:20:03 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62495/tests/dromaeo/cssquery-ext.html) 05:20:04 INFO - PROCESS | 7736 | RSS: Main: 295870464 05:20:04 INFO - PROCESS | 7736 | 05:23:07 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-ext.html (next: http://localhost:62495/tests/dromaeo/cssquery-jquery.html) 05:23:07 INFO - PROCESS | 7736 | RSS: Main: 307601408 05:23:07 INFO - PROCESS | 7736 | 05:26:09 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62495/tests/dromaeo/cssquery-mootools.html) 05:26:10 INFO - PROCESS | 7736 | RSS: Main: 312864768 05:26:10 INFO - PROCESS | 7736 | 05:29:12 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62495/tests/dromaeo/cssquery-prototype.html) 05:29:13 INFO - PROCESS | 7736 | RSS: Main: 306413568 05:29:13 INFO - PROCESS | 7736 | 05:32:16 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62495/tests/dromaeo/cssquery-yui.html) 05:32:17 INFO - PROCESS | 7736 | RSS: Main: 269742080 05:32:17 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | Cycle 1(1): loaded http://localhost:62495/tests/dromaeo/cssquery-yui.html (next: http://localhost:62495/tests/dromaeo/cssquery-dojo.html) 05:35:19 INFO - PROCESS | 7736 | RSS: Main: 316293120 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | __start_tp_report 05:35:19 INFO - PROCESS | 7736 | _x_x_mozilla_page_load 05:35:19 INFO - PROCESS | 7736 | _x_x_mozilla_page_load_details 05:35:19 INFO - PROCESS | 7736 | |i|pagename|runs| 05:35:19 INFO - PROCESS | 7736 | |0;dojo.html;3416.64125266847;3483.2511010132816;3505.684488396044;3522.8766993155245;3567.108222944264;5760.913586296209;5785.595008349417;5828.591998560102;5829.7376618052185;5856.326522449919;5798.797042103517;5841.474267315941;5852.882942341151;5868.823935281948;5872.972229859768;5198.662086964345;5201.427842937273;5234.973825130888;5283.735813209343;5336.91994620081;5305.4164041955355;5337.946620533803;5345.251664766935;5348.572114230852;5348.866278343034;5242.973785131069;5268;5308.522232999029;5310.0441920454305;5314.76083576238;5346.518813306801;5375.596830237748;5376.327959005124;5390.353157621072;5426.2674538937135;4411.977940110311;5065.670731402447;5171.120909445403;5256.316678831766;5297.099493086184;4685.4377474702915;5648.4633959773755;5679.432056794329;5761.3950535194035;5774.740136693859;6203.2245969253845;6214.5649804513705;6218.720157592919;6227.937720622781;6232.34560371159;6150.753969841201;6184.041473571604;6208.317085120633;6261.060840873905;6281.685915704196;6058.212432383762;6060.484858786965;6087.421694938974;6087.786927457518;6103.877922441527;6073.574849760563;6369.490440764727;6372.362763723683;6378.45783108441;6406.23125224976;6092.9967963112595;6123.724432400552;6171.506279497629;6180.289266734293;6188.659623720739;6236;6239.75040998355;6258.530610204253;6262.812115636538;6283.5287353448675;6099.054646529707;6139.785107521305;6154.476869466056;6155.045967874987;6176.166217560572;6127.234095738033;6141.148492690724;6150.923588372017;6173.629582225081;6176.4441200292185;5001.274815151783;5003.799848006039;5026.84919452417;5045.142325804622;5046.495350464924;3664.5785734640326;4535.954640453553;4544.772761361919;4550.385697930803;4613.538646135359;5956.702164891738;5992.34084250732;6000.369961154016;6004.369541198199;6014.0377539593455;6121.908171377433;6192.907106393408;6196.349383314777;6223.96888015557;6226.501879849601;5972.641641501524;5981.461668449861;5994.460498555151;5998.400159984054;6018.548608854353;4640;5510.586705997066;5552.11166213412;5552.861178470571;5559.583031272671;5186.06650802844;6173.567850250529;6338.112664227104;6381.361863813674;6395.488360931115;6249.375062493714;6267.467265282502;6268.122462855295;6278.058291256348;6285.371462853861;6328.651924144032;6380.872382552416;6383.585066970632;6388.39310265524;6397.96801015992;6160.13758073861;6202.906956395567;6253.187085678833;6302.873942521215;6311.27420346666;6238.937610623836;6243.250809902841;6255.843603909757;6257.749690012531;6265.091561723433;6212.254529456494;6273.874522509615;6279.215098112736;6283.905741413974;6290.339514351111;6130.816075517741;6153.769246150878;6211.09939058843;6271.749130034747;6292.339304372975;6131.693415329305;6149.877002460015;6152.98475751496;6163.044728067157;6166.691665416801;6116;6264.49884009269;6278.434940855345;6284.5600807943;6312.305646378986;4753.857384278477;4903.166461701448;5658.151277308437;6032.487238584594;6050.273967123973 05:35:19 INFO - PROCESS | 7736 | |1;ext.html;509.452338735859;517.3274742834317;522.2766468441208;563.2311894264328;594.2898236607251;25633.871830640845;38282;39024.609753902456;39084.41373379395;39208.60791392085;39460;43246.56753432465;43338.783306083424;43370;43451;9010.954945225298;9531.904680953188;9890.357126786772;9895.604175832957;9915.652952146676;9052.818943621123;9168.954155229214;9187.862182067274;11770.705732356673;12039.398030098504;11837.053035757162;11925.70185745359;11939.462724177389;11941.880581194207;11948.044156467462;11808.055355571534;11845.348505832177;11869.5845645402;11923.880761192408;11928.46321915516;11674.591389301419;11742;11780.410979450993;11813.822792658117;11817.586384476588;40141.79929100365;40766.388504172166;40800;40814.38778418326;40834.387484187464;3257.576515053028;3379.6958273755235;3390.135515443546;3511.841967111486;3559.519464872235;27796.30509237285;28446.14661560136;29647;30045;30339.544906826417;2771.8198317109527;2782.471330447229;2810.1569529141175;2844.687084420712;2867.7562407195414;488541;489712;490697;491772.5411372956;492681;36087.63912360843;36895.4465683015;61215.38784612097;61913;61986.76026479445;35642;35809;35851.64148358483;35867;35917.820410898305;15780.289886955115;16014.759778603331;16096.436624718079;16114.677706445806;16187.352505899868;12808.039397045259;13680.65798355049;13785.241811700453;13789.586312410644;13799.51701690436;7688.538687678757;8188.959055204686;8263.297619702393;8323.500589964622;8334.874976875351;3267.4118658641673;3406.9148271293416;3468.9653103469145;3478.3043391321835;3487.6512348765427;1028.2134167361974;1039.667306461925;1041.578160844859;1079.978400431987;1140.5836869542723;29547.556786648216;29569.55645665317;29694;29981.70018299789;30549.38901221963;1152.5620264299512;1176.4117941029485;1202.2546021466746;1249.7937840256438;1260.9660078735533;12495.93752031234;14209.076410033314;15577.452977117537;15586.922065389826;15867.920660396625;653552;656212;659593;660063;661173;62687;63588;63682;63729.681351592946;63802;1419.254891182117;1559.6178936160713;1561.851624095709;1562.3750499800171;1567.9216039198222;28233;28478.857605711837;28793.56809647899;29238.26904327409;29365.11904642864;47714.5228547724;48456.03087938151;48586.75706621444;48630.27054594113;48648.5135148644;423809;427323.5901461543;427874;427980;664550;26127;26603.866980664974;26931.461370772868;26997.595036074083;27102.186934392;40432.3935140979;41161.58838411578;42016.86507936508;43600.34599480947;44118.33822492729;34960.30079398449;35808.28383432369;36112.63887361093;36414.90755277346;37108.62891371052;35709;35829.82085089558;36297;36501.45247821232;36848.63151368452;35261.64738352584;35480.93557193288;35788.46317305294;36285.818570908035;36679.81660091789;31005.914792982137;31203.531947020358;31546.84226578944;31631.52552711665;31738.04785856428;22785.746783927043;24040.51918961576;24446.14438494644;24535.38661533476;24578.893949771944 05:35:19 INFO - PROCESS | 7736 | |2;jquery.html;418.2221068812007;420.0884081543051;421.06523517790504;423.75845767912284;431.1958197960804;12117.182422636586;12628;20134.09396577154;20830.166793328248;20960.790392096074;18615.720764188514;19094.427167185007;23685;23748.881255593697;23990.760092399072;6937.87196618014;7047.89428158578;7104.715811367539;7106.751263705772;7117.145942486909;4995.375578052744;5272.420033796279;6903.1371078615175;7043.683034263445;7708.30625243728;8000.239977202185;8068.072171700271;8096.392770542233;8105.878411823798;8113.675452981874;7771.689132434696;7976.162502937224;7985.321247693953;7992.800179995488;8038.356931445471;7590.316871481593;7597.658105385271;7722.845543089107;7781;7871.960640196762;28887.42225155506;29148.8542557288;30179.396412071637;30315.93894213714;30370.696293037006;2263.3549438409973;2390.521895620883;2390.9282721518384;2405.8436201647014;2572.996531352774;15538.300776465087;15964.042157470472;16010.839891601168;16466.588335291595;17165.31338746449;1890.8203720646516;1900.0879577802732;1920.654282229198;1992.481954691776;2030.147338117994;117854.41072794753;129066.35466822606;138951;139180;142608;17873.7318940216;21547.245846395614;22428.215012474484;22433.775662243494;22520.211792587183;36265.45601815975;36286.63713362833;36886.631133688854;37042.44436333457;37050;9962.302638815358;11625.53497860093;11995.700107497209;12029.939850300692;12031.518739250332;10058.899411005796;10069.848952265722;10080.74798130038;10109.44398058099;10162.339447935861;10097.596096156218;10248;10342.32774869631;10357.84463233037;10416;2783.8468884211156;3082.7687923405833;3810.8750824324343;3819.350710379242;3841.8847434577006;884.7832281091045;905.5970093308546;949.9620015199453;1030.5105075089273;1036.5024788101753;20106.497337566678;20881;21664.566708665738;21808.170250762643;21970.90145492719;949.7293271417612;1004.1815920025302;1033.7415646088477;1039.5581877702098;1039.9740006499899;11325.433728313552;11427.948312523966;11472.849651483673;11572.132090093277;11575.942120289514;629449.9385043003;635766;635990;648053;658169;134650;136560;138808;142460.01389951303;143131.2843435776;997.7106639074732;1057.9100776433936;1070.4166229404993;1072.0940805019745;1073.377441084185;17541.035243061244;18254.26982920668;18915.527111822314;19268.807311926703;19456.61086778228;51496;52758.736206318725;54910.72544637411;57159;57350;81693;82862;83624.58187709023;83652.74520882069;85018;8235.670573177005;8360.372972027244;8440.324774018187;8506.574671266535;8810.779730506789;12652.367381631066;12815.871841281469;13035.869641303845;13257.734845302846;13407.396667149807;8307.293880020266;11005.284656497623;11142.721431964266;11306.378149201873;11506.252093613888;11330.206885517935;11375.943120284346;11409.315441073562;11564.537418503502;11693.181477296508;11407.37259450705;11904.464299106388;11992;12018.879811202125;12053.035757139583;8435.578221088797;8500.234978851933;8566.443181193203;8767.912320876709;8883.955580222058;11436.256643318158;11537.365444900612;11756.118291128028;11806.822897656713;11840.348780816794 05:35:19 INFO - PROCESS | 7736 | |3;mootools.html;1450.1878947789232;1622.618684609117;1661.7341225403934;1943.650142974265;2020.6146534416712;8329.333653307736;8995.685151019716;9181.357304988653;9188.586513606888;9206.4476131432;8900.866487002684;8919.464832110078;9021.458712477255;9050.140236677522;9050.818983620324;2554.7445255474445;2580;2585;2585.560454722692;2586.8059895507913;1895.4503194073702;2200.7689192634784;2264.3772962435296;2272.3864556569715;2274.124462082102;2545.707243666974;2572.8327658702124;2581.8967241310324;2587.676540432446;2593.1313010141625;2528.51958127956;2562.859042752648;2570.074773081708;2571.7299683533242;2576.536223479773;2515.371157210697;2557.3734435063343;2563.538563058649;2567.0501914291644;2567.7432256774173;8845.557722113934;9078.319126065571;9133.77165570866;9148.908510914938;9149.862752058725;3762.830672619739;3930.8231129599235;4346.456692913386;5295.841124766263;5646.943530564683;2479.524682813723;3122.6877312268593;3631.364511210554;3715.869944551928;3772.170122573057;903.290916630442;1054.4674939155755;1081.7019576508221;1084.1543595995136;1094.195766111907;11977.461014254379;12133.332666703327;12178.695532611668;12255.509779608805;12260.325682087481;4766.118268120409;4807.572176051071;4867.3915760529935;4902.509749025069;4912.336834527293;9578.56896439662;9680.661176858775;9692.079252471003;9720;9724.805503889882;5570.359408668055;5625.015622266087;5627.268455100811;5632.746526406321;5637.379888212294;4390.253656878338;4659.4175728034;4817.229243321051;5032.169692000852;5211.166213405837;3575.5173051637694;3932.5870783567884;4012.9197416051516;4086.959130408658;4096.5903409659395;1063.9468026598638;1258.704204511939;1271.510468469646;1286.414681319997;1291.509226493945;946.068122898944;976.5605477535138;978.814025335184;982.1995074014798;996.7308826616772;5984.431479009487;6059.515238780887;6062.727177277033;6223.751049957951;6232.495444447163;413.574018760671;487.43290321615467;497.9651424400257;500.4220125754759;504.4224363104225;3902.2585708715255;3953.486046813953;4008.959910400917;4082.081531655413;4082.632563069338;10831.83752243717;11260;11307.038901693448;11321.377324247082;11326.546938122548;11020;11042;11064.723381915355;11071.501782419811;11192.272502287326;8157.306628936607;8469.40714150016;8510.191531804468;8573.22840944318;8693.565321733888;5949.91075133882;6010.098485227251;6057.515398768;6115.082737589397;6177.413145751147;9520.571574279036;9873.950630246803;10036.799264014533;10040.748981275234;10046.799064018825;9717.319787614799;10063.49682515886;10178.694639160836;10308.433036183082;10453;7044.36600705939;7222.3860971817985;7239.927600724136;7765.650545725343;7921.722739704082;7564.962175189089;7912.762617121495;7951.403644726553;8303.128171542143;8534.615942282488;6528.543001989815;7545.547267163988;7575.431842611716;7674.117476490275;7676.808079797826;7466.850662986819;7497.100347958061;7538.509996850406;7595.430342724429;7616.9238307616215;7160.391366733886;7193.3166349196745;7194.136703595602;7216.819579510554;7231.963840180976;4895.6083513318135;4968.105740966661;5007;5062.443131255633;5064.139096353702;4991.5258050485145;4997.550220480174;5026.798928042837;5098.566621837185;5248.343957005374 05:35:19 INFO - PROCESS | 7736 | |4;prototype.html;334.6001528173832;340.81255309579734;342.0815111425822;349.84606773019885;352.9452934795108;6544.934550654492;6551.705173267208;6578.144841170653;6583.946568549033;6589.967050164754;5230.6600070995355;6492.902606460907;6512.218533775942;6523.543351965364;6535.248446428662;4618.976905115478;4742.217534106868;4760.595899070964;4837.7339246341435;4883.780229889663;4750.144973904696;4835.274708793674;4840.491748366424;4844.224924012159;4860.368152140234;4783.28250762385;4792.856214313576;4796.345260884989;4800.735959522225;4823.614110871122;4611.30830375443;4760.452547956995;4778.04439112179;4790.736509491977;4803.951960480385;4044.858429954967;4137.97931010346;4158.459400277975;4177.49870015597;4186.979065104685;6186.474149697281;6192.87614247717;6194.287656919467;6208.50331973441;6215.56491045627;4616.515265897099;5740.942590574083;5744.080947048494;5747.425257474261;5751.856203594902;6793.149582586655;6969.756058537927;6981.755638552677;6985.301469853025;6993.125859267591;4667.509911459316;4704;4718.646101542364;4725.10223057618;4733.786979585892;5897.8820423591715;7261.564306141649;7337.633118344062;7349.889751653676;7351.595662238575;4803.447603525569;4804.06320767447;4808.591269742041;4811.182099043171;4813.8469302606845;4881.511848815161;4883.365162528919;4883.58489528387;4896.681715688469;4899.877503062452;4654.767261636905;5709.34342550612;5718.7426565804635;5722.513586345207;5736.282964629421;6718.7312507499155;6991.755288564876;6995.090638217101;7081;7084.964575177091;1622.6592415592622;1647.472808701204;1649.2001379331016;1655.3213182595077;1655.7185278502443;1043.9269251152346;1043.968680939573;1062.914966802654;1063.5107850388908;1069.5721711315537;6062.575619706578;6188.44304012641;6405.807825765234;6574.3754343337305;6651.935690289531;7354.271746139653;7355.889661655079;7375.88936165958;7377.704891804375;7405;6757.459403247728;6888.242293347727;6905.861882762417;6911.723531058701;6916.861662766716;3834.5015148031134;3897.0647044709067;3897.2205558888336;3897.2595206910596;3899;7350.669219885011;7593.544387336778;7660.310572048543;7688.500247483896;7692.307692307719;7432.293932076444;7487.962560187164;7520.6239688015385;7535.246475352531;7546.056742907137;7677.808054798675;7707.267809558083;7707.537547747153;7707.614619269014;7711.537307761552;7357.374623157091;7358.889616655862;7366.226546212571;7372.557646541225;7383.778486645195;6534.379233972765;6915.827104322432;7194.748183813542;7200.8919866201;7267.890981635385;7648.273414025659;7650.579218142833;7671.07947046358;7683.846323073619;7708.807279818049;4624.289013065188;4678.929816052694;4689.507601701772;4692.249240121564;4695.765211739331;7273.527220730635;7279.818004549928;7291.08861392326;7292.088488938883;7303.525270857377;5376.623636345418;7016;7016.26329235423;7017.4386049116965;7047.647617619201;6903.551269167488;6935;6992.475564332593;7020.859582808417;7048.964755176191;6893.276205998298;6897.206821215625;6907.171139463297;6917.89623155643;6918.965405173143;7057.082579264663;7065.752698655522;7074.186468556182;7081.858362832818;7087.291270873077;6607.372299631527;6623.271440141484;6838.965805170942;6853.931460685529;6964.895526567004 05:35:19 INFO - PROCESS | 7736 | |5;yui.html;686.426833593949;726.716580533592;771.3289438188773;782.5891407011318;820.343725019984;15131.31909064092;15435.69128617427;15437.150956697364;15446.613834654125;17258.568535786613;16447.177641117956;16515;16529;16537.421190258345;16571;780.0483410239499;791.4855344026371;976.2141476111744;1005.4570531912794;1010.5149528226419;955.3885513271478;989.0505115089522;990.7126933189367;991.2417000994209;995.5768798260747;1028.3932479836894;1032.7366521536997;1037.9584816607326;1040.0275742181047;1042.0361165921531;813.5525460996488;830.5556527257888;834.8497270491309;1028.0131074168808;1029.0275689473451;626;734.2033893225856;772.7063715788021;776.0144616337277;778.7352300217897;15603.92198039014;15672.451464198812;15677.451289204937;15690.058596484247;15698.76451853223;12767.74464510714;12777;12893.161944473579;13146.276954767483;13156.473741050346;10100.343477673927;10320;10327.380357178594;10399;10521.053105220566;3470.132466883279;3561.394562924309;3563.4120370138894;3568.232829941549;3631.7276204284785;65301.34698653048;84916.72624910632;85016.57491712563;85079;85092;7312.976183334337;9341.065893410605;9551.0926461986;9697.272704547046;9921.355111917703;21981.23065692693;22050.33848984533;22053.669194962087;22068.88965555162;22072;7418.591977441185;7638.159802421729;7734.265244801735;7769.53382797034;7848.607569621496;3722;4202.096549241927;4247.511536173318;4256.425382573375;4390.495093064274;3674.7243956703355;3720.2373513429684;3735.8505659773305;3742.36379815432;3747.756395834262;669.9363560461749;692.9307069293131;697.7522979342361;698.9895151572731;712.7647876200841;578.288704892984;605.3280858247342;618.3723520626568;630.6531407725824;634.3497914637442;8142.796430089176;8300.169983001651;8302.8339433211;8372.03721572015;8487.493971950333;552.0642510943992;573.1689050876246;573.6787399056542;614.2291424262611;637.5823835387829;3696.630336966282;3905.4337121117846;3954.5056867891512;3987.2424239394422;3995.10110225197;80914.59542702249;86426.56786716027;88349;89096;89137.66293505603;68969.39705172167;69059;70611.29388706047;71155;73397.6330118346;67496;67827;68249.63500729957;68883.65558172278;69275;8236.217559331853;8306.04480484752;8458.365622578209;8480.830383392175;8818.29453643694;33184.668153318155;45045.32432013457;45887.082258355316;46335.768321158175;49572.00855982932;60456.39543604507;63662;65531.67234163799;69518.65240673967;71246;10896.49181490059;11066.28069175501;11365.147613928822;11370.886291136983;11887.76224475523;11913.52345906154;12128.09039322072;12195.329256890956;12273.754524909631;12410.317432541297;9104.408213466104;11055.170862185207;11524.308541487537;11860.288382697066;11970.46132924038;11420.828687569527;11501.309921404743;11682.94158529202;11919.3444360561;12044.939775301418;11371.31772093677;11540.653780386267;11584.07327413788;11625.6512304631;11648.825267621161;9782.75543111428;9893.109620134222;9955.303128780915;10110.49447527606;10256.692299231036;8813.867791983253;9126.771830704285;9752.609895604097;9771.022897710456;9825.164860986895 05:35:19 INFO - PROCESS | 7736 | __end_tp_report 05:35:19 INFO - PROCESS | 7736 | __start_cc_report 05:35:19 INFO - PROCESS | 7736 | _x_x_mozilla_cycle_collect,1167 05:35:19 INFO - PROCESS | 7736 | __end_cc_report 05:35:19 INFO - PROCESS | 7736 | __startTimestamp1469277319964__endTimestamp 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | ------- Summary: start ------- 05:35:19 INFO - PROCESS | 7736 | Number of tests: 6 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#0] dojo.html Cycles:165 Average:5789.76 Median:6099.05 stddev:657.36 (10.8%) stddev-sans-first:632.46 05:35:19 INFO - PROCESS | 7736 | Values: 3416.6 3483.3 3505.7 3522.9 3567.1 5760.9 5785.6 5828.6 5829.7 5856.3 5798.8 5841.5 5852.9 5868.8 5873.0 5198.7 5201.4 5235.0 5283.7 5336.9 5305.4 5337.9 5345.3 5348.6 5348.9 5243.0 5268.0 5308.5 5310.0 5314.8 5346.5 5375.6 5376.3 5390.4 5426.3 4412.0 5065.7 5171.1 5256.3 5297.1 4685.4 5648.5 5679.4 5761.4 5774.7 6203.2 6214.6 6218.7 6227.9 6232.3 6150.8 6184.0 6208.3 6261.1 6281.7 6058.2 6060.5 6087.4 6087.8 6103.9 6073.6 6369.5 6372.4 6378.5 6406.2 6093.0 6123.7 6171.5 6180.3 6188.7 6236.0 6239.8 6258.5 6262.8 6283.5 6099.1 6139.8 6154.5 6155.0 6176.2 6127.2 6141.1 6150.9 6173.6 6176.4 5001.3 5003.8 5026.8 5045.1 5046.5 3664.6 4536.0 4544.8 4550.4 4613.5 5956.7 5992.3 6000.4 6004.4 6014.0 6121.9 6192.9 6196.3 6224.0 6226.5 5972.6 5981.5 5994.5 5998.4 6018.5 4640.0 5510.6 5552.1 5552.9 5559.6 5186.1 6173.6 6338.1 6381.4 6395.5 6249.4 6267.5 6268.1 6278.1 6285.4 6328.7 6380.9 6383.6 6388.4 6398.0 6160.1 6202.9 6253.2 6302.9 6311.3 6238.9 6243.3 6255.8 6257.7 6265.1 6212.3 6273.9 6279.2 6283.9 6290.3 6130.8 6153.8 6211.1 6271.7 6292.3 6131.7 6149.9 6153.0 6163.0 6166.7 6116.0 6264.5 6278.4 6284.6 6312.3 4753.9 4903.2 5658.2 6032.5 6050.3 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#1] ext.html Cycles:180 Average:66238.91 Median:19223.98 stddev:147340.60 (766.4%) stddev-sans-first:147671.28 05:35:19 INFO - PROCESS | 7736 | Values: 509.5 517.3 522.3 563.2 594.3 25633.9 38282.0 39024.6 39084.4 39208.6 39460.0 43246.6 43338.8 43370.0 43451.0 9011.0 9531.9 9890.4 9895.6 9915.7 9052.8 9169.0 9187.9 11770.7 12039.4 11837.1 11925.7 11939.5 11941.9 11948.0 11808.1 11845.3 11869.6 11923.9 11928.5 11674.6 11742.0 11780.4 11813.8 11817.6 40141.8 40766.4 40800.0 40814.4 40834.4 3257.6 3379.7 3390.1 3511.8 3559.5 27796.3 28446.1 29647.0 30045.0 30339.5 2771.8 2782.5 2810.2 2844.7 2867.8 488541.0 489712.0 490697.0 491772.5 492681.0 36087.6 36895.4 61215.4 61913.0 61986.8 35642.0 35809.0 35851.6 35867.0 35917.8 15780.3 16014.8 16096.4 16114.7 16187.4 12808.0 13680.7 13785.2 13789.6 13799.5 7688.5 8189.0 8263.3 8323.5 8334.9 3267.4 3406.9 3469.0 3478.3 3487.7 1028.2 1039.7 1041.6 1080.0 1140.6 29547.6 29569.6 29694.0 29981.7 30549.4 1152.6 1176.4 1202.3 1249.8 1261.0 12495.9 14209.1 15577.5 15586.9 15867.9 653552.0 656212.0 659593.0 660063.0 661173.0 62687.0 63588.0 63682.0 63729.7 63802.0 1419.3 1559.6 1561.9 1562.4 1567.9 28233.0 28478.9 28793.6 29238.3 29365.1 47714.5 48456.0 48586.8 48630.3 48648.5 423809.0 427323.6 427874.0 427980.0 664550.0 26127.0 26603.9 26931.5 26997.6 27102.2 40432.4 41161.6 42016.9 43600.3 44118.3 34960.3 35808.3 36112.6 36414.9 37108.6 35709.0 35829.8 36297.0 36501.5 36848.6 35261.6 35480.9 35788.5 36285.8 36679.8 31005.9 31203.5 31546.8 31631.5 31738.0 22785.7 24040.5 24446.1 24535.4 24578.9 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#2] jquery.html Cycles:180 Average:38975.64 Median:21889.54 stddev:107059.75 (489.1%) stddev-sans-first:107320.94 05:35:19 INFO - PROCESS | 7736 | Values: 418.2 420.1 421.1 423.8 431.2 12117.2 12628.0 20134.1 20830.2 20960.8 18615.7 19094.4 23685.0 23748.9 23990.8 6937.9 7047.9 7104.7 7106.8 7117.1 4995.4 5272.4 6903.1 7043.7 7708.3 8000.2 8068.1 8096.4 8105.9 8113.7 7771.7 7976.2 7985.3 7992.8 8038.4 7590.3 7597.7 7722.8 7781.0 7872.0 28887.4 29148.9 30179.4 30315.9 30370.7 2263.4 2390.5 2390.9 2405.8 2573.0 15538.3 15964.0 16010.8 16466.6 17165.3 1890.8 1900.1 1920.7 1992.5 2030.1 117854.4 129066.4 138951.0 139180.0 142608.0 17873.7 21547.2 22428.2 22433.8 22520.2 36265.5 36286.6 36886.6 37042.4 37050.0 9962.3 11625.5 11995.7 12029.9 12031.5 10058.9 10069.8 10080.7 10109.4 10162.3 10097.6 10248.0 10342.3 10357.8 10416.0 2783.8 3082.8 3810.9 3819.4 3841.9 884.8 905.6 950.0 1030.5 1036.5 20106.5 20881.0 21664.6 21808.2 21970.9 949.7 1004.2 1033.7 1039.6 1040.0 11325.4 11427.9 11472.8 11572.1 11575.9 629449.9 635766.0 635990.0 648053.0 658169.0 134650.0 136560.0 138808.0 142460.0 143131.3 997.7 1057.9 1070.4 1072.1 1073.4 17541.0 18254.3 18915.5 19268.8 19456.6 51496.0 52758.7 54910.7 57159.0 57350.0 81693.0 82862.0 83624.6 83652.7 85018.0 8235.7 8360.4 8440.3 8506.6 8810.8 12652.4 12815.9 13035.9 13257.7 13407.4 8307.3 11005.3 11142.7 11306.4 11506.3 11330.2 11375.9 11409.3 11564.5 11693.2 11407.4 11904.5 11992.0 12018.9 12053.0 8435.6 8500.2 8566.4 8767.9 8884.0 11436.3 11537.4 11756.1 11806.8 11840.3 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#3] mootools.html Cycles:180 Average:5796.78 Median:2733.04 stddev:3289.54 (120.4%) stddev-sans-first:3282.55 05:35:19 INFO - PROCESS | 7736 | Values: 1450.2 1622.6 1661.7 1943.7 2020.6 8329.3 8995.7 9181.4 9188.6 9206.4 8900.9 8919.5 9021.5 9050.1 9050.8 2554.7 2580.0 2585.0 2585.6 2586.8 1895.5 2200.8 2264.4 2272.4 2274.1 2545.7 2572.8 2581.9 2587.7 2593.1 2528.5 2562.9 2570.1 2571.7 2576.5 2515.4 2557.4 2563.5 2567.1 2567.7 8845.6 9078.3 9133.8 9148.9 9149.9 3762.8 3930.8 4346.5 5295.8 5646.9 2479.5 3122.7 3631.4 3715.9 3772.2 903.3 1054.5 1081.7 1084.2 1094.2 11977.5 12133.3 12178.7 12255.5 12260.3 4766.1 4807.6 4867.4 4902.5 4912.3 9578.6 9680.7 9692.1 9720.0 9724.8 5570.4 5625.0 5627.3 5632.7 5637.4 4390.3 4659.4 4817.2 5032.2 5211.2 3575.5 3932.6 4012.9 4087.0 4096.6 1063.9 1258.7 1271.5 1286.4 1291.5 946.1 976.6 978.8 982.2 996.7 5984.4 6059.5 6062.7 6223.8 6232.5 413.6 487.4 498.0 500.4 504.4 3902.3 3953.5 4009.0 4082.1 4082.6 10831.8 11260.0 11307.0 11321.4 11326.5 11020.0 11042.0 11064.7 11071.5 11192.3 8157.3 8469.4 8510.2 8573.2 8693.6 5949.9 6010.1 6057.5 6115.1 6177.4 9520.6 9874.0 10036.8 10040.7 10046.8 9717.3 10063.5 10178.7 10308.4 10453.0 7044.4 7222.4 7239.9 7765.7 7921.7 7565.0 7912.8 7951.4 8303.1 8534.6 6528.5 7545.5 7575.4 7674.1 7676.8 7466.9 7497.1 7538.5 7595.4 7616.9 7160.4 7193.3 7194.1 7216.8 7232.0 4895.6 4968.1 5007.0 5062.4 5064.1 4991.5 4997.6 5026.8 5098.6 5248.3 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#4] prototype.html Cycles:180 Average:5764.69 Median:6540.09 stddev:1835.76 (28.1%) stddev-sans-first:1795.09 05:35:19 INFO - PROCESS | 7736 | Values: 334.6 340.8 342.1 349.8 352.9 6544.9 6551.7 6578.1 6583.9 6590.0 5230.7 6492.9 6512.2 6523.5 6535.2 4619.0 4742.2 4760.6 4837.7 4883.8 4750.1 4835.3 4840.5 4844.2 4860.4 4783.3 4792.9 4796.3 4800.7 4823.6 4611.3 4760.5 4778.0 4790.7 4804.0 4044.9 4138.0 4158.5 4177.5 4187.0 6186.5 6192.9 6194.3 6208.5 6215.6 4616.5 5740.9 5744.1 5747.4 5751.9 6793.1 6969.8 6981.8 6985.3 6993.1 4667.5 4704.0 4718.6 4725.1 4733.8 5897.9 7261.6 7337.6 7349.9 7351.6 4803.4 4804.1 4808.6 4811.2 4813.8 4881.5 4883.4 4883.6 4896.7 4899.9 4654.8 5709.3 5718.7 5722.5 5736.3 6718.7 6991.8 6995.1 7081.0 7085.0 1622.7 1647.5 1649.2 1655.3 1655.7 1043.9 1044.0 1062.9 1063.5 1069.6 6062.6 6188.4 6405.8 6574.4 6651.9 7354.3 7355.9 7375.9 7377.7 7405.0 6757.5 6888.2 6905.9 6911.7 6916.9 3834.5 3897.1 3897.2 3897.3 3899.0 7350.7 7593.5 7660.3 7688.5 7692.3 7432.3 7488.0 7520.6 7535.2 7546.1 7677.8 7707.3 7707.5 7707.6 7711.5 7357.4 7358.9 7366.2 7372.6 7383.8 6534.4 6915.8 7194.7 7200.9 7267.9 7648.3 7650.6 7671.1 7683.8 7708.8 4624.3 4678.9 4689.5 4692.2 4695.8 7273.5 7279.8 7291.1 7292.1 7303.5 5376.6 7016.0 7016.3 7017.4 7047.6 6903.6 6935.0 6992.5 7020.9 7049.0 6893.3 6897.2 6907.2 6917.9 6919.0 7057.1 7065.8 7074.2 7081.9 7087.3 6607.4 6623.3 6839.0 6853.9 6964.9 05:35:19 INFO - PROCESS | 7736 | 05:35:19 INFO - PROCESS | 7736 | [#5] yui.html Cycles:180 Average:17881.71 Median:562.62 stddev:24460.39 (4347.6%) stddev-sans-first:24494.93 05:35:19 INFO - PROCESS | 7736 | Values: 686.4 726.7 771.3 782.6 820.3 15131.3 15435.7 15437.2 15446.6 17258.6 16447.2 16515.0 16529.0 16537.4 16571.0 780.0 791.5 976.2 1005.5 1010.5 955.4 989.1 990.7 991.2 995.6 1028.4 1032.7 1038.0 1040.0 1042.0 813.6 830.6 834.8 1028.0 1029.0 626.0 734.2 772.7 776.0 778.7 15603.9 15672.5 15677.5 15690.1 15698.8 12767.7 12777.0 12893.2 13146.3 13156.5 10100.3 10320.0 10327.4 10399.0 10521.1 3470.1 3561.4 3563.4 3568.2 3631.7 65301.3 84916.7 85016.6 85079.0 85092.0 7313.0 9341.1 9551.1 9697.3 9921.4 21981.2 22050.3 22053.7 22068.9 22072.0 7418.6 7638.2 7734.3 7769.5 7848.6 3722.0 4202.1 4247.5 4256.4 4390.5 3674.7 3720.2 3735.9 3742.4 3747.8 669.9 692.9 697.8 699.0 712.8 578.3 605.3 618.4 630.7 634.3 8142.8 8300.2 8302.8 8372.0 8487.5 552.1 573.2 573.7 614.2 637.6 3696.6 3905.4 3954.5 3987.2 3995.1 80914.6 86426.6 88349.0 89096.0 89137.7 68969.4 69059.0 70611.3 71155.0 73397.6 67496.0 67827.0 68249.6 68883.7 69275.0 8236.2 8306.0 8458.4 8480.8 8818.3 33184.7 45045.3 45887.1 46335.8 49572.0 60456.4 63662.0 65531.7 69518.7 71246.0 10896.5 11066.3 11365.1 11370.9 11887.8 11913.5 12128.1 12195.3 12273.8 12410.3 9104.4 11055.2 11524.3 11860.3 11970.5 11420.8 11501.3 11682.9 11919.3 12044.9 11371.3 11540.7 11584.1 11625.7 11648.8 9782.8 9893.1 9955.3 10110.5 10256.7 8813.9 9126.8 9752.6 9771.0 9825.2 05:35:19 INFO - PROCESS | 7736 | -------- Summary: end -------- 05:35:19 INFO - PROCESS | 7736 | 05:35:20 INFO - TEST-INFO | 7736: exit 0 05:35:20 INFO - TEST-OK | dromaeo_css | took 1113541ms 05:35:20 INFO - TEST-START | kraken 05:35:20 INFO - operating with platform_type : mac_ 05:35:20 INFO - Initialising browser for kraken test... 05:35:20 INFO - TEST-INFO | started process 7829 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpOBO5Yp/profile http://localhost:62495/getInfo.html) 05:35:22 INFO - PROCESS | 7829 | __metrics Screen width/height:1600/1200 05:35:22 INFO - PROCESS | 7829 | colorDepth:24 05:35:22 INFO - PROCESS | 7829 | Browser inner width/height: 1024/689 05:35:22 INFO - PROCESS | 7829 | __metrics 05:35:22 INFO - PROCESS | 7829 | 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" 05:35:22 ERROR - PROCESS | 7829 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:35:23 INFO - TEST-INFO | 7829: exit 0 05:35:23 INFO - Browser initialized. 05:35:23 INFO - Running cycle 1/1 for kraken test... 05:35:23 INFO - TEST-INFO | started process 7832 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpOBO5Yp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:35:35 INFO - PROCESS | 7832 | RSS: Main: 160468992 05:35:35 INFO - PROCESS | 7832 | 05:35:37 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62495/tests/kraken/driver.html?testName=audio-beat-detection) 05:35:38 INFO - PROCESS | 7832 | RSS: Main: 224198656 05:35:38 INFO - PROCESS | 7832 | 05:35:41 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62495/tests/kraken/driver.html?testName=audio-dft) 05:35:41 INFO - PROCESS | 7832 | RSS: Main: 239804416 05:35:41 INFO - PROCESS | 7832 | 05:35:45 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62495/tests/kraken/driver.html?testName=audio-fft) 05:35:45 INFO - PROCESS | 7832 | RSS: Main: 326963200 05:35:45 INFO - PROCESS | 7832 | 05:35:47 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62495/tests/kraken/driver.html?testName=audio-oscillator) 05:35:48 INFO - PROCESS | 7832 | RSS: Main: 230277120 05:35:48 INFO - PROCESS | 7832 | 05:35:50 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62495/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:35:50 INFO - PROCESS | 7832 | RSS: Main: 231866368 05:35:50 INFO - PROCESS | 7832 | 05:35:53 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62495/tests/kraken/driver.html?testName=imaging-darkroom) 05:35:54 INFO - PROCESS | 7832 | RSS: Main: 270499840 05:35:54 INFO - PROCESS | 7832 | 05:35:57 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62495/tests/kraken/driver.html?testName=imaging-desaturate) 05:35:57 INFO - PROCESS | 7832 | RSS: Main: 270254080 05:35:57 INFO - PROCESS | 7832 | 05:36:00 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62495/tests/kraken/driver.html?testName=json-parse-financial) 05:36:01 INFO - PROCESS | 7832 | RSS: Main: 269512704 05:36:01 INFO - PROCESS | 7832 | 05:36:02 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62495/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:03 INFO - PROCESS | 7832 | RSS: Main: 259469312 05:36:03 INFO - PROCESS | 7832 | 05:36:05 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:05 INFO - PROCESS | 7832 | RSS: Main: 265752576 05:36:05 INFO - PROCESS | 7832 | 05:36:07 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:08 INFO - PROCESS | 7832 | RSS: Main: 270913536 05:36:08 INFO - PROCESS | 7832 | 05:36:11 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:11 INFO - PROCESS | 7832 | RSS: Main: 278147072 05:36:11 INFO - PROCESS | 7832 | 05:36:14 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:15 INFO - PROCESS | 7832 | RSS: Main: 275251200 05:36:15 INFO - PROCESS | 7832 | 05:36:16 INFO - PROCESS | 7832 | Cycle 1(1): loaded http://localhost:62495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62495/tests/kraken/driver.html?testName=ai-astar) 05:36:17 INFO - PROCESS | 7832 | RSS: Main: 336699392 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | __start_tp_report 05:36:17 INFO - PROCESS | 7832 | _x_x_mozilla_page_load 05:36:17 INFO - PROCESS | 7832 | _x_x_mozilla_page_load_details 05:36:17 INFO - PROCESS | 7832 | |i|pagename|runs| 05:36:17 INFO - PROCESS | 7832 | |0;ai-astar;114;114;118;111;113;113;113;114;113;133 05:36:17 INFO - PROCESS | 7832 | |1;audio-beat-detection;159;121;146;165;118;138;121;162;162;155 05:36:17 INFO - PROCESS | 7832 | |2;audio-dft;188;188;186;184;188;186;186;187;187;202 05:36:17 INFO - PROCESS | 7832 | |3;audio-fft;71;79;75;161;56;55;56;64;131;56 05:36:17 INFO - PROCESS | 7832 | |4;audio-oscillator;92;92;146;79;77;80;130;79;77;129 05:36:17 INFO - PROCESS | 7832 | |5;imaging-gaussian-blur;86;84;86;84;85;83;87;84;88;84 05:36:17 INFO - PROCESS | 7832 | |6;imaging-darkroom;91;92;92;93;93;93;96;112;92;92 05:36:17 INFO - PROCESS | 7832 | |7;imaging-desaturate;90;88;92;87;88;89;92;91;91;83 05:36:17 INFO - PROCESS | 7832 | |8;json-parse-financial;61;63;64;64;65;64;62;79;59;56 05:36:17 INFO - PROCESS | 7832 | |9;json-stringify-tinderbox;62;61;61;79;71;68;66;66;66;66 05:36:17 INFO - PROCESS | 7832 | |10;stanford-crypto-aes;81;78;80;78;81;78;78;75;82;83 05:36:17 INFO - PROCESS | 7832 | |11;stanford-crypto-ccm;125;124;128;143;138;136;115;132;171;141 05:36:17 INFO - PROCESS | 7832 | |12;stanford-crypto-pbkdf2;164;165;163;163;166;165;162;161;159;163 05:36:17 INFO - PROCESS | 7832 | |13;stanford-crypto-sha256-iterative;66;65;69;66;67;66;66;71;69;67 05:36:17 INFO - PROCESS | 7832 | __end_tp_report 05:36:17 INFO - PROCESS | 7832 | __start_cc_report 05:36:17 INFO - PROCESS | 7832 | _x_x_mozilla_cycle_collect,1904 05:36:17 INFO - PROCESS | 7832 | __end_cc_report 05:36:17 INFO - PROCESS | 7832 | __startTimestamp1469277377102__endTimestamp 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | ------- Summary: start ------- 05:36:17 INFO - PROCESS | 7832 | Number of tests: 14 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:6.36 (5.6%) stddev-sans-first:6.72 05:36:17 INFO - PROCESS | 7832 | Values: 114.0 114.0 118.0 111.0 113.0 113.0 113.0 114.0 113.0 133.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:157.00 stddev:18.87 (12.0%) stddev-sans-first:19.29 05:36:17 INFO - PROCESS | 7832 | Values: 159.0 121.0 146.0 165.0 118.0 138.0 121.0 162.0 162.0 155.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#2] audio-dft Cycles:10 Average:188.20 Median:187.50 stddev:5.01 (2.7%) stddev-sans-first:5.31 05:36:17 INFO - PROCESS | 7832 | Values: 188.0 188.0 186.0 184.0 188.0 186.0 186.0 187.0 187.0 202.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#3] audio-fft Cycles:10 Average:80.40 Median:60.00 stddev:36.33 (60.5%) stddev-sans-first:38.37 05:36:17 INFO - PROCESS | 7832 | Values: 71.0 79.0 75.0 161.0 56.0 55.0 56.0 64.0 131.0 56.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:79.00 stddev:26.43 (33.5%) stddev-sans-first:27.95 05:36:17 INFO - PROCESS | 7832 | Values: 92.0 92.0 146.0 79.0 77.0 80.0 130.0 79.0 77.0 129.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:85.50 stddev:1.60 (1.9%) stddev-sans-first:1.66 05:36:17 INFO - PROCESS | 7832 | Values: 86.0 84.0 86.0 84.0 85.0 83.0 87.0 84.0 88.0 84.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:92.50 stddev:6.26 (6.8%) stddev-sans-first:6.50 05:36:17 INFO - PROCESS | 7832 | Values: 91.0 92.0 92.0 93.0 93.0 93.0 96.0 112.0 92.0 92.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:90.50 stddev:2.77 (3.1%) stddev-sans-first:2.92 05:36:17 INFO - PROCESS | 7832 | Values: 90.0 88.0 92.0 87.0 88.0 89.0 92.0 91.0 91.0 83.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:6.04 (9.4%) stddev-sans-first:6.32 05:36:17 INFO - PROCESS | 7832 | Values: 61.0 63.0 64.0 64.0 65.0 64.0 62.0 79.0 59.0 56.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.00 stddev:5.38 (8.1%) stddev-sans-first:5.44 05:36:17 INFO - PROCESS | 7832 | Values: 62.0 61.0 61.0 79.0 71.0 68.0 66.0 66.0 66.0 66.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:80.50 stddev:2.41 (3.0%) stddev-sans-first:2.49 05:36:17 INFO - PROCESS | 7832 | Values: 81.0 78.0 80.0 78.0 81.0 78.0 78.0 75.0 82.0 83.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:137.00 stddev:15.22 (11.1%) stddev-sans-first:15.68 05:36:17 INFO - PROCESS | 7832 | Values: 125.0 124.0 128.0 143.0 138.0 136.0 115.0 132.0 171.0 141.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.50 stddev:2.08 (1.3%) stddev-sans-first:2.18 05:36:17 INFO - PROCESS | 7832 | Values: 164.0 165.0 163.0 163.0 166.0 165.0 162.0 161.0 159.0 163.0 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - PROCESS | 7832 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.87 (2.8%) stddev-sans-first:1.94 05:36:17 INFO - PROCESS | 7832 | Values: 66.0 65.0 69.0 66.0 67.0 66.0 66.0 71.0 69.0 67.0 05:36:17 INFO - PROCESS | 7832 | -------- Summary: end -------- 05:36:17 INFO - PROCESS | 7832 | 05:36:17 INFO - TEST-INFO | 7832: exit 0 05:36:17 INFO - TEST-OK | kraken | took 57361ms 05:36:17 INFO - SUITE-END | took 1170s 05:36:18 INFO - Completed test suite (00:19:31) 05:36:18 INFO - javascript benchmark 05:36:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3416.64125266847, 3483.2511010132816, 3505.684488396044, 3522.8766993155245, 3567.108222944264, 5760.913586296209, 5785.595008349417, 5828.591998560102, 5829.7376618052185, 5856.326522449919, 5798.797042103517, 5841.474267315941, 5852.882942341151, 5868.823935281948, 5872.972229859768, 5198.662086964345, 5201.427842937273, 5234.973825130888, 5283.735813209343, 5336.91994620081, 5305.4164041955355, 5337.946620533803, 5345.251664766935, 5348.572114230852, 5348.866278343034, 5242.973785131069, 5268.0, 5308.522232999029, 5310.0441920454305, 5314.76083576238, 5346.518813306801, 5375.596830237748, 5376.327959005124, 5390.353157621072, 5426.2674538937135, 4411.977940110311, 5065.670731402447, 5171.120909445403, 5256.316678831766, 5297.099493086184, 4685.4377474702915, 5648.4633959773755, 5679.432056794329, 5761.3950535194035, 5774.740136693859, 6203.2245969253845, 6214.5649804513705, 6218.720157592919, 6227.937720622781, 6232.34560371159, 6150.753969841201, 6184.041473571604, 6208.317085120633, 6261.060840873905, 6281.685915704196, 6058.212432383762, 6060.484858786965, 6087.421694938974, 6087.786927457518, 6103.877922441527, 6073.574849760563, 6369.490440764727, 6372.362763723683, 6378.45783108441, 6406.23125224976, 6092.9967963112595, 6123.724432400552, 6171.506279497629, 6180.289266734293, 6188.659623720739, 6236.0, 6239.75040998355, 6258.530610204253, 6262.812115636538, 6283.5287353448675, 6099.054646529707, 6139.785107521305, 6154.476869466056, 6155.045967874987, 6176.166217560572, 6127.234095738033, 6141.148492690724, 6150.923588372017, 6173.629582225081, 6176.4441200292185, 5001.274815151783, 5003.799848006039, 5026.84919452417, 5045.142325804622, 5046.495350464924, 3664.5785734640326, 4535.954640453553, 4544.772761361919, 4550.385697930803, 4613.538646135359, 5956.702164891738, 5992.34084250732, 6000.369961154016, 6004.369541198199, 6014.0377539593455, 6121.908171377433, 6192.907106393408, 6196.349383314777, 6223.96888015557, 6226.501879849601, 5972.641641501524, 5981.461668449861, 5994.460498555151, 5998.400159984054, 6018.548608854353, 4640.0, 5510.586705997066, 5552.11166213412, 5552.861178470571, 5559.583031272671, 5186.06650802844, 6173.567850250529, 6338.112664227104, 6381.361863813674, 6395.488360931115, 6249.375062493714, 6267.467265282502, 6268.122462855295, 6278.058291256348, 6285.371462853861, 6328.651924144032, 6380.872382552416, 6383.585066970632, 6388.39310265524, 6397.96801015992, 6160.13758073861, 6202.906956395567, 6253.187085678833, 6302.873942521215, 6311.27420346666, 6238.937610623836, 6243.250809902841, 6255.843603909757, 6257.749690012531, 6265.091561723433, 6212.254529456494, 6273.874522509615, 6279.215098112736, 6283.905741413974, 6290.339514351111, 6130.816075517741, 6153.769246150878, 6211.09939058843, 6271.749130034747, 6292.339304372975, 6131.693415329305, 6149.877002460015, 6152.98475751496, 6163.044728067157, 6166.691665416801, 6116.0, 6264.49884009269, 6278.434940855345, 6284.5600807943, 6312.305646378986, 4753.857384278477, 4903.166461701448, 5658.151277308437, 6032.487238584594, 6050.273967123973], "value": 5749.420828190922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.452338735859, 517.3274742834317, 522.2766468441208, 563.2311894264328, 594.2898236607251, 25633.871830640845, 38282.0, 39024.609753902456, 39084.41373379395, 39208.60791392085, 39460.0, 43246.56753432465, 43338.783306083424, 43370.0, 43451.0, 9010.954945225298, 9531.904680953188, 9890.357126786772, 9895.604175832957, 9915.652952146676, 9052.818943621123, 9168.954155229214, 9187.862182067274, 11770.705732356673, 12039.398030098504, 11837.053035757162, 11925.70185745359, 11939.462724177389, 11941.880581194207, 11948.044156467462, 11808.055355571534, 11845.348505832177, 11869.5845645402, 11923.880761192408, 11928.46321915516, 11674.591389301419, 11742.0, 11780.410979450993, 11813.822792658117, 11817.586384476588, 40141.79929100365, 40766.388504172166, 40800.0, 40814.38778418326, 40834.387484187464, 3257.576515053028, 3379.6958273755235, 3390.135515443546, 3511.841967111486, 3559.519464872235, 27796.30509237285, 28446.14661560136, 29647.0, 30045.0, 30339.544906826417, 2771.8198317109527, 2782.471330447229, 2810.1569529141175, 2844.687084420712, 2867.7562407195414, 488541.0, 489712.0, 490697.0, 491772.5411372956, 492681.0, 36087.63912360843, 36895.4465683015, 61215.38784612097, 61913.0, 61986.76026479445, 35642.0, 35809.0, 35851.64148358483, 35867.0, 35917.820410898305, 15780.289886955115, 16014.759778603331, 16096.436624718079, 16114.677706445806, 16187.352505899868, 12808.039397045259, 13680.65798355049, 13785.241811700453, 13789.586312410644, 13799.51701690436, 7688.538687678757, 8188.959055204686, 8263.297619702393, 8323.500589964622, 8334.874976875351, 3267.4118658641673, 3406.9148271293416, 3468.9653103469145, 3478.3043391321835, 3487.6512348765427, 1028.2134167361974, 1039.667306461925, 1041.578160844859, 1079.978400431987, 1140.5836869542723, 29547.556786648216, 29569.55645665317, 29694.0, 29981.70018299789, 30549.38901221963, 1152.5620264299512, 1176.4117941029485, 1202.2546021466746, 1249.7937840256438, 1260.9660078735533, 12495.93752031234, 14209.076410033314, 15577.452977117537, 15586.922065389826, 15867.920660396625, 653552.0, 656212.0, 659593.0, 660063.0, 661173.0, 62687.0, 63588.0, 63682.0, 63729.681351592946, 63802.0, 1419.254891182117, 1559.6178936160713, 1561.851624095709, 1562.3750499800171, 1567.9216039198222, 28233.0, 28478.857605711837, 28793.56809647899, 29238.26904327409, 29365.11904642864, 47714.5228547724, 48456.03087938151, 48586.75706621444, 48630.27054594113, 48648.5135148644, 423809.0, 427323.5901461543, 427874.0, 427980.0, 664550.0, 26127.0, 26603.866980664974, 26931.461370772868, 26997.595036074083, 27102.186934392, 40432.3935140979, 41161.58838411578, 42016.86507936508, 43600.34599480947, 44118.33822492729, 34960.30079398449, 35808.28383432369, 36112.63887361093, 36414.90755277346, 37108.62891371052, 35709.0, 35829.82085089558, 36297.0, 36501.45247821232, 36848.63151368452, 35261.64738352584, 35480.93557193288, 35788.46317305294, 36285.818570908035, 36679.81660091789, 31005.914792982137, 31203.531947020358, 31546.84226578944, 31631.52552711665, 31738.04785856428, 22785.746783927043, 24040.51918961576, 24446.14438494644, 24535.38661533476, 24578.893949771944], "value": 18867.89945061684, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.2221068812007, 420.0884081543051, 421.06523517790504, 423.75845767912284, 431.1958197960804, 12117.182422636586, 12628.0, 20134.09396577154, 20830.166793328248, 20960.790392096074, 18615.720764188514, 19094.427167185007, 23685.0, 23748.881255593697, 23990.760092399072, 6937.87196618014, 7047.89428158578, 7104.715811367539, 7106.751263705772, 7117.145942486909, 4995.375578052744, 5272.420033796279, 6903.1371078615175, 7043.683034263445, 7708.30625243728, 8000.239977202185, 8068.072171700271, 8096.392770542233, 8105.878411823798, 8113.675452981874, 7771.689132434696, 7976.162502937224, 7985.321247693953, 7992.800179995488, 8038.356931445471, 7590.316871481593, 7597.658105385271, 7722.845543089107, 7781.0, 7871.960640196762, 28887.42225155506, 29148.8542557288, 30179.396412071637, 30315.93894213714, 30370.696293037006, 2263.3549438409973, 2390.521895620883, 2390.9282721518384, 2405.8436201647014, 2572.996531352774, 15538.300776465087, 15964.042157470472, 16010.839891601168, 16466.588335291595, 17165.31338746449, 1890.8203720646516, 1900.0879577802732, 1920.654282229198, 1992.481954691776, 2030.147338117994, 117854.41072794753, 129066.35466822606, 138951.0, 139180.0, 142608.0, 17873.7318940216, 21547.245846395614, 22428.215012474484, 22433.775662243494, 22520.211792587183, 36265.45601815975, 36286.63713362833, 36886.631133688854, 37042.44436333457, 37050.0, 9962.302638815358, 11625.53497860093, 11995.700107497209, 12029.939850300692, 12031.518739250332, 10058.899411005796, 10069.848952265722, 10080.74798130038, 10109.44398058099, 10162.339447935861, 10097.596096156218, 10248.0, 10342.32774869631, 10357.84463233037, 10416.0, 2783.8468884211156, 3082.7687923405833, 3810.8750824324343, 3819.350710379242, 3841.8847434577006, 884.7832281091045, 905.5970093308546, 949.9620015199453, 1030.5105075089273, 1036.5024788101753, 20106.497337566678, 20881.0, 21664.566708665738, 21808.170250762643, 21970.90145492719, 949.7293271417612, 1004.1815920025302, 1033.7415646088477, 1039.5581877702098, 1039.9740006499899, 11325.433728313552, 11427.948312523966, 11472.849651483673, 11572.132090093277, 11575.942120289514, 629449.9385043003, 635766.0, 635990.0, 648053.0, 658169.0, 134650.0, 136560.0, 138808.0, 142460.01389951303, 143131.2843435776, 997.7106639074732, 1057.9100776433936, 1070.4166229404993, 1072.0940805019745, 1073.377441084185, 17541.035243061244, 18254.26982920668, 18915.527111822314, 19268.807311926703, 19456.61086778228, 51496.0, 52758.736206318725, 54910.72544637411, 57159.0, 57350.0, 81693.0, 82862.0, 83624.58187709023, 83652.74520882069, 85018.0, 8235.670573177005, 8360.372972027244, 8440.324774018187, 8506.574671266535, 8810.779730506789, 12652.367381631066, 12815.871841281469, 13035.869641303845, 13257.734845302846, 13407.396667149807, 8307.293880020266, 11005.284656497623, 11142.721431964266, 11306.378149201873, 11506.252093613888, 11330.206885517935, 11375.943120284346, 11409.315441073562, 11564.537418503502, 11693.181477296508, 11407.37259450705, 11904.464299106388, 11992.0, 12018.879811202125, 12053.035757139583, 8435.578221088797, 8500.234978851933, 8566.443181193203, 8767.912320876709, 8883.955580222058, 11436.256643318158, 11537.365444900612, 11756.118291128028, 11806.822897656713, 11840.348780816794], "value": 11392.142113226311, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.1878947789232, 1622.618684609117, 1661.7341225403934, 1943.650142974265, 2020.6146534416712, 8329.333653307736, 8995.685151019716, 9181.357304988653, 9188.586513606888, 9206.4476131432, 8900.866487002684, 8919.464832110078, 9021.458712477255, 9050.140236677522, 9050.818983620324, 2554.7445255474445, 2580.0, 2585.0, 2585.560454722692, 2586.8059895507913, 1895.4503194073702, 2200.7689192634784, 2264.3772962435296, 2272.3864556569715, 2274.124462082102, 2545.707243666974, 2572.8327658702124, 2581.8967241310324, 2587.676540432446, 2593.1313010141625, 2528.51958127956, 2562.859042752648, 2570.074773081708, 2571.7299683533242, 2576.536223479773, 2515.371157210697, 2557.3734435063343, 2563.538563058649, 2567.0501914291644, 2567.7432256774173, 8845.557722113934, 9078.319126065571, 9133.77165570866, 9148.908510914938, 9149.862752058725, 3762.830672619739, 3930.8231129599235, 4346.456692913386, 5295.841124766263, 5646.943530564683, 2479.524682813723, 3122.6877312268593, 3631.364511210554, 3715.869944551928, 3772.170122573057, 903.290916630442, 1054.4674939155755, 1081.7019576508221, 1084.1543595995136, 1094.195766111907, 11977.461014254379, 12133.332666703327, 12178.695532611668, 12255.509779608805, 12260.325682087481, 4766.118268120409, 4807.572176051071, 4867.3915760529935, 4902.509749025069, 4912.336834527293, 9578.56896439662, 9680.661176858775, 9692.079252471003, 9720.0, 9724.805503889882, 5570.359408668055, 5625.015622266087, 5627.268455100811, 5632.746526406321, 5637.379888212294, 4390.253656878338, 4659.4175728034, 4817.229243321051, 5032.169692000852, 5211.166213405837, 3575.5173051637694, 3932.5870783567884, 4012.9197416051516, 4086.959130408658, 4096.5903409659395, 1063.9468026598638, 1258.704204511939, 1271.510468469646, 1286.414681319997, 1291.509226493945, 946.068122898944, 976.5605477535138, 978.814025335184, 982.1995074014798, 996.7308826616772, 5984.431479009487, 6059.515238780887, 6062.727177277033, 6223.751049957951, 6232.495444447163, 413.574018760671, 487.43290321615467, 497.9651424400257, 500.4220125754759, 504.4224363104225, 3902.2585708715255, 3953.486046813953, 4008.959910400917, 4082.081531655413, 4082.632563069338, 10831.83752243717, 11260.0, 11307.038901693448, 11321.377324247082, 11326.546938122548, 11020.0, 11042.0, 11064.723381915355, 11071.501782419811, 11192.272502287326, 8157.306628936607, 8469.40714150016, 8510.191531804468, 8573.22840944318, 8693.565321733888, 5949.91075133882, 6010.098485227251, 6057.515398768, 6115.082737589397, 6177.413145751147, 9520.571574279036, 9873.950630246803, 10036.799264014533, 10040.748981275234, 10046.799064018825, 9717.319787614799, 10063.49682515886, 10178.694639160836, 10308.433036183082, 10453.0, 7044.36600705939, 7222.3860971817985, 7239.927600724136, 7765.650545725343, 7921.722739704082, 7564.962175189089, 7912.762617121495, 7951.403644726553, 8303.128171542143, 8534.615942282488, 6528.543001989815, 7545.547267163988, 7575.431842611716, 7674.117476490275, 7676.808079797826, 7466.850662986819, 7497.100347958061, 7538.509996850406, 7595.430342724429, 7616.9238307616215, 7160.391366733886, 7193.3166349196745, 7194.136703595602, 7216.819579510554, 7231.963840180976, 4895.6083513318135, 4968.105740966661, 5007.0, 5062.443131255633, 5064.139096353702, 4991.5258050485145, 4997.550220480174, 5026.798928042837, 5098.566621837185, 5248.343957005374], "value": 4588.050194585319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.6001528173832, 340.81255309579734, 342.0815111425822, 349.84606773019885, 352.9452934795108, 6544.934550654492, 6551.705173267208, 6578.144841170653, 6583.946568549033, 6589.967050164754, 5230.6600070995355, 6492.902606460907, 6512.218533775942, 6523.543351965364, 6535.248446428662, 4618.976905115478, 4742.217534106868, 4760.595899070964, 4837.7339246341435, 4883.780229889663, 4750.144973904696, 4835.274708793674, 4840.491748366424, 4844.224924012159, 4860.368152140234, 4783.28250762385, 4792.856214313576, 4796.345260884989, 4800.735959522225, 4823.614110871122, 4611.30830375443, 4760.452547956995, 4778.04439112179, 4790.736509491977, 4803.951960480385, 4044.858429954967, 4137.97931010346, 4158.459400277975, 4177.49870015597, 4186.979065104685, 6186.474149697281, 6192.87614247717, 6194.287656919467, 6208.50331973441, 6215.56491045627, 4616.515265897099, 5740.942590574083, 5744.080947048494, 5747.425257474261, 5751.856203594902, 6793.149582586655, 6969.756058537927, 6981.755638552677, 6985.301469853025, 6993.125859267591, 4667.509911459316, 4704.0, 4718.646101542364, 4725.10223057618, 4733.786979585892, 5897.8820423591715, 7261.564306141649, 7337.633118344062, 7349.889751653676, 7351.595662238575, 4803.447603525569, 4804.06320767447, 4808.591269742041, 4811.182099043171, 4813.8469302606845, 4881.511848815161, 4883.365162528919, 4883.58489528387, 4896.681715688469, 4899.877503062452, 4654.767261636905, 5709.34342550612, 5718.7426565804635, 5722.513586345207, 5736.282964629421, 6718.7312507499155, 6991.755288564876, 6995.090638217101, 7081.0, 7084.964575177091, 1622.6592415592622, 1647.472808701204, 1649.2001379331016, 1655.3213182595077, 1655.7185278502443, 1043.9269251152346, 1043.968680939573, 1062.914966802654, 1063.5107850388908, 1069.5721711315537, 6062.575619706578, 6188.44304012641, 6405.807825765234, 6574.3754343337305, 6651.935690289531, 7354.271746139653, 7355.889661655079, 7375.88936165958, 7377.704891804375, 7405.0, 6757.459403247728, 6888.242293347727, 6905.861882762417, 6911.723531058701, 6916.861662766716, 3834.5015148031134, 3897.0647044709067, 3897.2205558888336, 3897.2595206910596, 3899.0, 7350.669219885011, 7593.544387336778, 7660.310572048543, 7688.500247483896, 7692.307692307719, 7432.293932076444, 7487.962560187164, 7520.6239688015385, 7535.246475352531, 7546.056742907137, 7677.808054798675, 7707.267809558083, 7707.537547747153, 7707.614619269014, 7711.537307761552, 7357.374623157091, 7358.889616655862, 7366.226546212571, 7372.557646541225, 7383.778486645195, 6534.379233972765, 6915.827104322432, 7194.748183813542, 7200.8919866201, 7267.890981635385, 7648.273414025659, 7650.579218142833, 7671.07947046358, 7683.846323073619, 7708.807279818049, 4624.289013065188, 4678.929816052694, 4689.507601701772, 4692.249240121564, 4695.765211739331, 7273.527220730635, 7279.818004549928, 7291.08861392326, 7292.088488938883, 7303.525270857377, 5376.623636345418, 7016.0, 7016.26329235423, 7017.4386049116965, 7047.647617619201, 6903.551269167488, 6935.0, 6992.475564332593, 7020.859582808417, 7048.964755176191, 6893.276205998298, 6897.206821215625, 6907.171139463297, 6917.89623155643, 6918.965405173143, 7057.082579264663, 7065.752698655522, 7074.186468556182, 7081.858362832818, 7087.291270873077, 6607.372299631527, 6623.271440141484, 6838.965805170942, 6853.931460685529, 6964.895526567004], "value": 5156.248494200127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.426833593949, 726.716580533592, 771.3289438188773, 782.5891407011318, 820.343725019984, 15131.31909064092, 15435.69128617427, 15437.150956697364, 15446.613834654125, 17258.568535786613, 16447.177641117956, 16515.0, 16529.0, 16537.421190258345, 16571.0, 780.0483410239499, 791.4855344026371, 976.2141476111744, 1005.4570531912794, 1010.5149528226419, 955.3885513271478, 989.0505115089522, 990.7126933189367, 991.2417000994209, 995.5768798260747, 1028.3932479836894, 1032.7366521536997, 1037.9584816607326, 1040.0275742181047, 1042.0361165921531, 813.5525460996488, 830.5556527257888, 834.8497270491309, 1028.0131074168808, 1029.0275689473451, 626.0, 734.2033893225856, 772.7063715788021, 776.0144616337277, 778.7352300217897, 15603.92198039014, 15672.451464198812, 15677.451289204937, 15690.058596484247, 15698.76451853223, 12767.74464510714, 12777.0, 12893.161944473579, 13146.276954767483, 13156.473741050346, 10100.343477673927, 10320.0, 10327.380357178594, 10399.0, 10521.053105220566, 3470.132466883279, 3561.394562924309, 3563.4120370138894, 3568.232829941549, 3631.7276204284785, 65301.34698653048, 84916.72624910632, 85016.57491712563, 85079.0, 85092.0, 7312.976183334337, 9341.065893410605, 9551.0926461986, 9697.272704547046, 9921.355111917703, 21981.23065692693, 22050.33848984533, 22053.669194962087, 22068.88965555162, 22072.0, 7418.591977441185, 7638.159802421729, 7734.265244801735, 7769.53382797034, 7848.607569621496, 3722.0, 4202.096549241927, 4247.511536173318, 4256.425382573375, 4390.495093064274, 3674.7243956703355, 3720.2373513429684, 3735.8505659773305, 3742.36379815432, 3747.756395834262, 669.9363560461749, 692.9307069293131, 697.7522979342361, 698.9895151572731, 712.7647876200841, 578.288704892984, 605.3280858247342, 618.3723520626568, 630.6531407725824, 634.3497914637442, 8142.796430089176, 8300.169983001651, 8302.8339433211, 8372.03721572015, 8487.493971950333, 552.0642510943992, 573.1689050876246, 573.6787399056542, 614.2291424262611, 637.5823835387829, 3696.630336966282, 3905.4337121117846, 3954.5056867891512, 3987.2424239394422, 3995.10110225197, 80914.59542702249, 86426.56786716027, 88349.0, 89096.0, 89137.66293505603, 68969.39705172167, 69059.0, 70611.29388706047, 71155.0, 73397.6330118346, 67496.0, 67827.0, 68249.63500729957, 68883.65558172278, 69275.0, 8236.217559331853, 8306.04480484752, 8458.365622578209, 8480.830383392175, 8818.29453643694, 33184.668153318155, 45045.32432013457, 45887.082258355316, 46335.768321158175, 49572.00855982932, 60456.39543604507, 63662.0, 65531.67234163799, 69518.65240673967, 71246.0, 10896.49181490059, 11066.28069175501, 11365.147613928822, 11370.886291136983, 11887.76224475523, 11913.52345906154, 12128.09039322072, 12195.329256890956, 12273.754524909631, 12410.317432541297, 9104.408213466104, 11055.170862185207, 11524.308541487537, 11860.288382697066, 11970.46132924038, 11420.828687569527, 11501.309921404743, 11682.94158529202, 11919.3444360561, 12044.939775301418, 11371.31772093677, 11540.653780386267, 11584.07327413788, 11625.6512304631, 11648.825267621161, 9782.75543111428, 9893.109620134222, 9955.303128780915, 10110.49447527606, 10256.692299231036, 8813.867791983253, 9126.771830704285, 9752.609895604097, 9771.022897710456, 9825.164860986895], "value": 7022.555957738837, "unit": "score"}], "name": "dromaeo_css", "value": 7680.828450064842, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 111.0, 113.0, 113.0, 113.0, 114.0, 113.0, 133.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 121.0, 146.0, 165.0, 118.0, 138.0, 121.0, 162.0, 162.0, 155.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 186.0, 184.0, 188.0, 186.0, 186.0, 187.0, 187.0, 202.0], "value": 188.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 75.0, 161.0, 56.0, 55.0, 56.0, 64.0, 131.0, 56.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 146.0, 79.0, 77.0, 80.0, 130.0, 79.0, 77.0, 129.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 85.0, 83.0, 87.0, 84.0, 88.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 93.0, 93.0, 93.0, 96.0, 112.0, 92.0, 92.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 92.0, 87.0, 88.0, 89.0, 92.0, 91.0, 91.0, 83.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 64.0, 64.0, 65.0, 64.0, 62.0, 79.0, 59.0, 56.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 79.0, 71.0, 68.0, 66.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 80.0, 78.0, 81.0, 78.0, 78.0, 75.0, 82.0, 83.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 128.0, 143.0, 138.0, 136.0, 115.0, 132.0, 171.0, 141.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 163.0, 166.0, 165.0, 162.0, 161.0, 159.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 69.0, 66.0, 67.0, 66.0, 66.0, 71.0, 69.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1471.1000000000001, "alertThreshold": 2.0}]} 05:36:18 INFO - Return code: 0 05:36:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:36:18 ERROR - # TBPL SUCCESS # 05:36:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:18 INFO - 2016-07-23 05:36:18.431 screenresolution[7836:145627] starting screenresolution argv=screenresolution get 05:36:18 INFO - 2016-07-23 05:36:18.433 screenresolution[7836:145627] Display 0: 1600x1200x32@60 05:36:18 INFO - 2016-07-23 05:36:18.440 screenresolution[7837:145630] starting screenresolution argv=screenresolution list 05:36:18 INFO - Available Modes on Display 0 05:36:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:18 INFO - Intel Iris: 05:36:18 INFO - Chipset Model: Intel Iris 05:36:18 INFO - Type: GPU 05:36:18 INFO - Bus: Built-In 05:36:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:18 INFO - Vendor: Intel (0x8086) 05:36:18 INFO - Device ID: 0x0a2e 05:36:18 INFO - Revision ID: 0x0009 05:36:18 INFO - Displays: 05:36:18 INFO - Display: 05:36:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:18 INFO - Display Serial Number: 1600x1200 60 05:36:18 INFO - Main Display: Yes 05:36:18 INFO - Mirror: Off 05:36:18 INFO - Online: Yes 05:36:18 INFO - Rotation: Supported 05:36:18 INFO - Return code: 0 05:36:18 INFO - Running post-action listener: _resource_record_post_action 05:36:18 INFO - Running post-run listener: _resource_record_post_run 05:36:19 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 89257984; Write bytes: 509090816; Read time: 1540; Write time: 1958 05:36:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 180691456; Write bytes: 174288896; Read time: 16370; Write time: 322 05:36:19 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 89257984; Write bytes: 312448000; Read time: 1540; Write time: 1541 05:36:19 INFO - Running post-run listener: _upload_blobber_files 05:36:19 INFO - Blob upload gear active. 05:36:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:36:19 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 05:36:19 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'] 05:36:19 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 05:36:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:36:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:20 INFO - (blobuploader) - INFO - Done attempting. 05:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:21 INFO - (blobuploader) - INFO - Done attempting. 05:36:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:36:21 INFO - Return code: 0 05:36:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:36:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:36:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6acd9051f0a94e33fc70643097e5f2bab695ef59800882401c7e89dc9939d92082179a2c74b20d3accc2d6991288afb9497406ef88c08b604c83eac13ca9f7ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769394c277c0aaa257352b41c02da08da17a7d1e6607081fa9edea323c41ab13bb5b54184a5ff525d149736ac3dccc3e6000ca43aa71e752eb8efdade2608658"} 05:36:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:36:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:36:21 INFO - Contents: 05:36:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6acd9051f0a94e33fc70643097e5f2bab695ef59800882401c7e89dc9939d92082179a2c74b20d3accc2d6991288afb9497406ef88c08b604c83eac13ca9f7ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769394c277c0aaa257352b41c02da08da17a7d1e6607081fa9edea323c41ab13bb5b54184a5ff525d149736ac3dccc3e6000ca43aa71e752eb8efdade2608658"} 05:36:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:36:21 INFO - Copying logs to upload dir... 05:36:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:36:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.997965 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-07-23 05:36:22.060252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 05:36:22.064293) ========= 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.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/6acd9051f0a94e33fc70643097e5f2bab695ef59800882401c7e89dc9939d92082179a2c74b20d3accc2d6991288afb9497406ef88c08b604c83eac13ca9f7ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769394c277c0aaa257352b41c02da08da17a7d1e6607081fa9edea323c41ab13bb5b54184a5ff525d149736ac3dccc3e6000ca43aa71e752eb8efdade2608658"} build_url:https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011622 build_url: 'https://queue.taskcluster.net/v1/task/OZYTQw0QST-8rpzeHu_o_Q/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/6acd9051f0a94e33fc70643097e5f2bab695ef59800882401c7e89dc9939d92082179a2c74b20d3accc2d6991288afb9497406ef88c08b604c83eac13ca9f7ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769394c277c0aaa257352b41c02da08da17a7d1e6607081fa9edea323c41ab13bb5b54184a5ff525d149736ac3dccc3e6000ca43aa71e752eb8efdade2608658"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 05:36:22.097775) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:36:22.098130) ========= 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.tIERRn86ep/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.kfOVOKscsJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005388 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 05:36:22.147978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 05:36:22.148290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 05:36:22.148656) ========= ========= Total master_lag: 0.17 =========