builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0071 starttime: 1461426956.37 results: success (0) buildid: 20160423070819 builduid: b60ff556624a422da8cc312f688fe162 revision: e976a219a56c556433590cb67c8b238015da610d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 08:55:56.373557) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 08:55:56.373992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 19 secs) (at 2016-04-23 08:55:56.374273) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 19 secs) (at 2016-04-23 08:56:15.588155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-23 08:56:15.588623) ========= 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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-04-23 08:56:16-- 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% 8.71M=0.001s 2016-04-23 08:56:16 (8.71 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.511702 ========= master_lag: 0.98 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-23 08:56:17.075486) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-04-23 08:56:17.075779) ========= 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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.020068 ========= master_lag: 5.98 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-04-23 08:56:23.076093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-23 08:56:23.076534) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e976a219a56c556433590cb67c8b238015da610d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e976a219a56c556433590cb67c8b238015da610d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-04-23 08:56:24,969 truncating revision to first 12 chars 2016-04-23 08:56:24,969 Setting DEBUG logging. 2016-04-23 08:56:24,969 attempt 1/10 2016-04-23 08:56:24,969 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e976a219a56c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 08:56:26,457 unpacking tar archive at: fx-team-e976a219a56c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.727532 ========= master_lag: 5.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-23 08:56:30.252978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 08:56:30.253364) ========= script_repo_revision: e976a219a56c556433590cb67c8b238015da610d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 08:56:30.253819) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 46 secs) (at 2016-04-23 08:56:30.254128) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 46 secs) (at 2016-04-23 08:57:16.485247) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-23 08:57:16.485567) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:16 INFO - MultiFileLogger online at 20160423 08:57:16 in /builds/slave/test 08:57:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:57:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:57:16 INFO - {'append_to_log': False, 08:57:16 INFO - 'base_work_dir': '/builds/slave/test', 08:57:16 INFO - 'blob_upload_branch': 'Fx-Team', 08:57:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:57:16 INFO - 'branch': 'Fx-Team', 08:57:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:57:16 INFO - 'config_files': ('talos/mac_config.py',), 08:57:16 INFO - 'default_actions': ('clobber', 08:57:16 INFO - 'read-buildbot-config', 08:57:16 INFO - 'download-and-extract', 08:57:16 INFO - 'populate-webroot', 08:57:16 INFO - 'create-virtualenv', 08:57:16 INFO - 'install', 08:57:16 INFO - 'run-tests'), 08:57:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:57:16 INFO - 'download_minidump_stackwalk': True, 08:57:16 INFO - 'download_symbols': 'ondemand', 08:57:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:57:16 INFO - 'tooltool.py': '/tools/tooltool.py', 08:57:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:57:16 INFO - '/tools/misc-python/virtualenv.py')}, 08:57:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:57:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:57:16 INFO - 'installer_path': 'installer.exe', 08:57:16 INFO - 'log_level': 'info', 08:57:16 INFO - 'log_name': 'talos', 08:57:16 INFO - 'log_to_console': True, 08:57:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:57:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:57:16 INFO - 'opt_config_files': (), 08:57:16 INFO - 'pip_index': False, 08:57:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:16 INFO - 'cmd': ('bash', 08:57:16 INFO - '-c', 08:57:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:16 INFO - 'enabled': True, 08:57:16 INFO - 'halt_on_failure': False, 08:57:16 INFO - 'name': 'check_screen_resolution'},), 08:57:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:16 INFO - 'cmd': ('bash', 08:57:16 INFO - '-c', 08:57:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:16 INFO - 'enabled': True, 08:57:16 INFO - 'halt_on_failure': False, 08:57:16 INFO - 'name': 'check_screen_resolution'},), 08:57:16 INFO - 'run_cmd_checks_enabled': True, 08:57:16 INFO - 'sps_profile': False, 08:57:16 INFO - 'sps_profile_interval': 0, 08:57:16 INFO - 'suite': 'dromaeojs', 08:57:16 INFO - 'system_bits': '32', 08:57:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:57:16 INFO - 'title': 't-yosemite-r7-0071', 08:57:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:57:16 INFO - 'use_talos_json': True, 08:57:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:57:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:57:16 INFO - 'work_dir': 'build'} 08:57:16 INFO - ##### 08:57:16 INFO - ##### Running clobber step. 08:57:16 INFO - ##### 08:57:16 INFO - Running pre-action listener: _resource_record_pre_action 08:57:16 INFO - Running main action method: clobber 08:57:16 INFO - rmtree: /builds/slave/test/build 08:57:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:57:18 INFO - Running post-action listener: _resource_record_post_action 08:57:18 INFO - ##### 08:57:18 INFO - ##### Running read-buildbot-config step. 08:57:18 INFO - ##### 08:57:18 INFO - Running pre-action listener: _resource_record_pre_action 08:57:18 INFO - Running main action method: read_buildbot_config 08:57:18 INFO - Using buildbot properties: 08:57:18 INFO - { 08:57:18 INFO - "project": "", 08:57:18 INFO - "product": "firefox", 08:57:18 INFO - "script_repo_revision": "production", 08:57:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:57:18 INFO - "repository": "", 08:57:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:57:18 INFO - "buildid": "20160423070819", 08:57:18 INFO - "pgo_build": "False", 08:57:18 INFO - "basedir": "/builds/slave/test", 08:57:18 INFO - "buildnumber": 762, 08:57:18 INFO - "slavename": "t-yosemite-r7-0071", 08:57:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:57:18 INFO - "platform": "yosemite_r7", 08:57:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:57:18 INFO - "branch": "fx-team", 08:57:18 INFO - "repo_path": "integration/fx-team", 08:57:18 INFO - "revision": "e976a219a56c556433590cb67c8b238015da610d", 08:57:18 INFO - "stage_platform": "macosx64", 08:57:18 INFO - "builduid": "b60ff556624a422da8cc312f688fe162", 08:57:18 INFO - "slavebuilddir": "test" 08:57:18 INFO - } 08:57:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 08:57:18 INFO - Running post-action listener: _resource_record_post_action 08:57:18 INFO - ##### 08:57:18 INFO - ##### Running download-and-extract step. 08:57:18 INFO - ##### 08:57:18 INFO - Running pre-action listener: _resource_record_pre_action 08:57:18 INFO - Running main action method: download_and_extract 08:57:18 INFO - mkdir: /builds/slave/test/build/tests 08:57: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')]} 08:57:18 INFO - https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:57:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:57:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:57:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 08:57:19 INFO - Downloaded 1199 bytes. 08:57:19 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:57:19 INFO - Using the following test package requirements: 08:57:19 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:57:19 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 08:57:19 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 08:57:19 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'jsshell-mac.zip'], 08:57:19 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 08:57:19 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:57:19 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 08:57:19 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 08:57:19 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 08:57:19 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:57:19 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 08:57:19 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:57: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')]} 08:57:19 INFO - https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:57:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:57:20 INFO - Downloaded 18109590 bytes. 08:57:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:57:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:57:23 INFO - Return code: 0 08:57:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:57:23 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')]} 08:57:23 INFO - https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:57:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:57:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:57:25 INFO - Downloaded 18109590 bytes. 08:57:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:57:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:57:28 INFO - Return code: 0 08:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:57:28 INFO - https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:57:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:57:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:57:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:57:30 INFO - Downloaded 10840145 bytes. 08:57:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:57:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:57:31 INFO - Return code: 0 08:57:31 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')]} 08:57:31 INFO - https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:57:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:57:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:57:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 08:57:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 08:57:35 INFO - Downloaded 110124090 bytes. 08:57:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:57:35 INFO - mkdir: /builds/slave/test/properties 08:57:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:57:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:57:35 INFO - Contents: 08:57:35 INFO - build_url:https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:57:36 INFO - Running post-action listener: _resource_record_post_action 08:57:36 INFO - Running post-action listener: set_extra_try_arguments 08:57:36 INFO - ##### 08:57:36 INFO - ##### Running populate-webroot step. 08:57:36 INFO - ##### 08:57:36 INFO - Running pre-action listener: _resource_record_pre_action 08:57:36 INFO - Running main action method: populate_webroot 08:57:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:57:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:57:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:57:36 INFO - '--tppagecycles', 08:57:36 INFO - '7'], 08:57:36 INFO - 'tests': ['tsvgm']}}, 08:57:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:57:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:57:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:57:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:57:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['damp', 'tps']}, 08:57:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'tests': ['damp', 'tps']}, 08:57:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['dromaeo_dom']}, 08:57:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:57:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['a11yr', 08:57:36 INFO - 'ts_paint', 08:57:36 INFO - 'tpaint', 08:57:36 INFO - 'sessionrestore', 08:57:36 INFO - 'sessionrestore_no_auto_restore', 08:57:36 INFO - 'tabpaint']}, 08:57:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['tsvgx', 08:57:36 INFO - 'tsvgr_opacity', 08:57:36 INFO - 'tart', 08:57:36 INFO - 'tscrollx', 08:57:36 INFO - 'cart']}, 08:57:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:57:36 INFO - 'tsvgr_opacity', 08:57:36 INFO - 'tart', 08:57:36 INFO - 'tscrollx', 08:57:36 INFO - 'cart']}, 08:57:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'talos_options': ['--disable-e10s'], 08:57:36 INFO - 'tests': ['tp5o']}, 08:57:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'tests': ['tp5o']}, 08:57:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'talos_options': ['--disable-e10s', 08:57:36 INFO - '--xperf_path', 08:57:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:36 INFO - 'tests': ['tp5n']}, 08:57:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:36 INFO - 'talos_options': ['--xperf_path', 08:57:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:36 INFO - 'tests': ['tp5n']}}, 08:57:36 INFO - 'talos.zip': {'path': '', 08:57:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:57:36 INFO - Running post-action listener: _resource_record_post_action 08:57:36 INFO - ##### 08:57:36 INFO - ##### Running create-virtualenv step. 08:57:36 INFO - ##### 08:57:36 INFO - Running pre-action listener: _resource_record_pre_action 08:57:36 INFO - Running main action method: create_virtualenv 08:57:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:57:36 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 08:57:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:57:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:57:36 INFO - Using real prefix '/tools/python27' 08:57:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:57:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:57:40 INFO - Installing pip.................done. 08:57:40 INFO - Return code: 0 08:57:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:57:40 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')]} 08:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:40 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')]} 08:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:40 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:40 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 08:57:40 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 08:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:40 INFO - 'HOME': '/Users/cltbld', 08:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:40 INFO - 'LOGNAME': 'cltbld', 08:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:40 INFO - 'MOZ_NO_REMOTE': '1', 08:57:40 INFO - 'NO_EM_RESTART': '1', 08:57:40 INFO - 'PAGER': '/bin/cat', 08:57:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:40 INFO - 'PWD': '/builds/slave/test', 08:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:40 INFO - 'SHELL': '/bin/bash', 08:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:40 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:40 INFO - 'USER': 'cltbld', 08:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:40 INFO - 'XPC_FLAGS': '0x0', 08:57:40 INFO - 'XPC_SERVICE_NAME': '0', 08:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:40 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 08:57:40 INFO - Cleaning up... 08:57:40 INFO - Return code: 0 08:57:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:57:40 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')]} 08:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:40 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')]} 08:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:40 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:40 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 08:57:40 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 08:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:40 INFO - 'HOME': '/Users/cltbld', 08:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:40 INFO - 'LOGNAME': 'cltbld', 08:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:40 INFO - 'MOZ_NO_REMOTE': '1', 08:57:40 INFO - 'NO_EM_RESTART': '1', 08:57:40 INFO - 'PAGER': '/bin/cat', 08:57:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:40 INFO - 'PWD': '/builds/slave/test', 08:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:40 INFO - 'SHELL': '/bin/bash', 08:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:40 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:40 INFO - 'USER': 'cltbld', 08:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:40 INFO - 'XPC_FLAGS': '0x0', 08:57:40 INFO - 'XPC_SERVICE_NAME': '0', 08:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:40 INFO - Downloading/unpacking psutil>=0.7.1 08:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:57:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:43 INFO - Installing collected packages: psutil 08:57:43 INFO - Running setup.py install for psutil 08:57:43 INFO - building 'psutil._psutil_osx' extension 08:57:43 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 08:57:44 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 08:57:44 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 08:57:44 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 08:57:44 INFO - building 'psutil._psutil_posix' extension 08:57:44 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 08:57:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:57:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:57:44 INFO - ^ 08:57:44 INFO - 1 warning generated. 08:57:44 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 08:57:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:44 INFO - Successfully installed psutil 08:57:44 INFO - Cleaning up... 08:57:44 INFO - Return code: 0 08:57:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:44 INFO - 'HOME': '/Users/cltbld', 08:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:44 INFO - 'LOGNAME': 'cltbld', 08:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:44 INFO - 'MOZ_NO_REMOTE': '1', 08:57:44 INFO - 'NO_EM_RESTART': '1', 08:57:44 INFO - 'PAGER': '/bin/cat', 08:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:44 INFO - 'PWD': '/builds/slave/test', 08:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:44 INFO - 'SHELL': '/bin/bash', 08:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:44 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:44 INFO - 'USER': 'cltbld', 08:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:44 INFO - 'XPC_FLAGS': '0x0', 08:57:44 INFO - 'XPC_SERVICE_NAME': '0', 08:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:57:47 INFO - Installing collected packages: mozsystemmonitor 08:57:47 INFO - Running setup.py install for mozsystemmonitor 08:57:47 INFO - Successfully installed mozsystemmonitor 08:57:47 INFO - Cleaning up... 08:57:47 INFO - Return code: 0 08:57:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:47 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:47 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 08:57:47 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 08:57:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:47 INFO - 'HOME': '/Users/cltbld', 08:57:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:47 INFO - 'LOGNAME': 'cltbld', 08:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:47 INFO - 'MOZ_NO_REMOTE': '1', 08:57:47 INFO - 'NO_EM_RESTART': '1', 08:57:47 INFO - 'PAGER': '/bin/cat', 08:57:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:47 INFO - 'PWD': '/builds/slave/test', 08:57:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:47 INFO - 'SHELL': '/bin/bash', 08:57:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:47 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:47 INFO - 'USER': 'cltbld', 08:57:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:47 INFO - 'XPC_FLAGS': '0x0', 08:57:47 INFO - 'XPC_SERVICE_NAME': '0', 08:57:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:47 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:50 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57:50 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 08:57:50 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 08:57:50 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 08:57:50 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 08:57:50 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 08:57:50 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 08:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 INFO - Downloading docopt-0.6.1.tar.gz 08:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:51 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:51 INFO - Running setup.py install for blobuploader 08:57:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:51 INFO - Running setup.py install for requests 08:57:52 INFO - Running setup.py install for docopt 08:57:52 INFO - Successfully installed blobuploader requests docopt 08:57:52 INFO - Cleaning up... 08:57:52 INFO - Return code: 0 08:57:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:52 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:52 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 08:57:52 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 08:57:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:52 INFO - 'HOME': '/Users/cltbld', 08:57:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:52 INFO - 'LOGNAME': 'cltbld', 08:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:52 INFO - 'MOZ_NO_REMOTE': '1', 08:57:52 INFO - 'NO_EM_RESTART': '1', 08:57:52 INFO - 'PAGER': '/bin/cat', 08:57:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:52 INFO - 'PWD': '/builds/slave/test', 08:57:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:52 INFO - 'SHELL': '/bin/bash', 08:57:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:52 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:52 INFO - 'USER': 'cltbld', 08:57:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:52 INFO - 'XPC_FLAGS': '0x0', 08:57:52 INFO - 'XPC_SERVICE_NAME': '0', 08:57:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-kvX5LH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-BoL1SX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-CEIH_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-48tq6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ipkhAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-AV2niY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Fmrphu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-f5DRmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-YiewGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-SlICGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Fe4DPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-WhIDcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xZZsj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-vxQ048-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-T0tge_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0G1Yw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-JWNFlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:54 INFO - Running setup.py install for manifestparser 08:57:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:54 INFO - Running setup.py install for mozcrash 08:57:54 INFO - Running setup.py install for mozdebug 08:57:54 INFO - Running setup.py install for mozdevice 08:57:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Running setup.py install for mozfile 08:57:55 INFO - Running setup.py install for mozhttpd 08:57:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Running setup.py install for mozinfo 08:57:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Running setup.py install for mozInstall 08:57:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:55 INFO - Running setup.py install for mozleak 08:57:55 INFO - Running setup.py install for mozlog 08:57:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Running setup.py install for moznetwork 08:57:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Running setup.py install for mozprocess 08:57:56 INFO - Running setup.py install for mozprofile 08:57:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Running setup.py install for mozrunner 08:57:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:56 INFO - Running setup.py install for mozscreenshot 08:57:56 INFO - Running setup.py install for moztest 08:57:56 INFO - Running setup.py install for mozversion 08:57:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:57 INFO - Cleaning up... 08:57:57 INFO - Return code: 0 08:57:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:57 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:57:57 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 08:57:57 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 08:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:57 INFO - 'HOME': '/Users/cltbld', 08:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:57 INFO - 'LOGNAME': 'cltbld', 08:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:57 INFO - 'MOZ_NO_REMOTE': '1', 08:57:57 INFO - 'NO_EM_RESTART': '1', 08:57:57 INFO - 'PAGER': '/bin/cat', 08:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:57 INFO - 'PWD': '/builds/slave/test', 08:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:57 INFO - 'SHELL': '/bin/bash', 08:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:57:57 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:57 INFO - 'USER': 'cltbld', 08:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:57 INFO - 'XPC_FLAGS': '0x0', 08:57:57 INFO - 'XPC_SERVICE_NAME': '0', 08:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Y7gZVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:57 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)) 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-c59KeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:57 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)) 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-k0rUcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:57 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)) 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xid0Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:57 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)) 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QYxnAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:57 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)) 08:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-MJ441R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0XO9N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-6_Aph7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-cGxK4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-JebpPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-WgSlyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-U7cRET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-KgTVbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-g2UwDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-rz6WcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:58 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)) 08:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-NpX7hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:59 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)) 08:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Zcmux7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:59 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)) 08:57:59 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)) 08:57:59 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)) 08:57:59 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)) 08:57:59 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)) 08:57:59 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)) 08:57:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:01 INFO - Downloading blessings-1.6.tar.gz 08:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:58:01 INFO - Installing collected packages: blessings 08:58:01 INFO - Running setup.py install for blessings 08:58:01 INFO - Successfully installed blessings 08:58:01 INFO - Cleaning up... 08:58:01 INFO - Return code: 0 08:58:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:02 INFO - Reading from file tmpfile_stdout 08:58:02 INFO - Current package versions: 08:58:02 INFO - blessings == 1.6 08:58:02 INFO - blobuploader == 1.2.4 08:58:02 INFO - docopt == 0.6.1 08:58:02 INFO - manifestparser == 1.1 08:58:02 INFO - mozInstall == 1.12 08:58:02 INFO - mozcrash == 0.17 08:58:02 INFO - mozdebug == 0.1 08:58:02 INFO - mozdevice == 0.48 08:58:02 INFO - mozfile == 1.2 08:58:02 INFO - mozhttpd == 0.7 08:58:02 INFO - mozinfo == 0.9 08:58:02 INFO - mozleak == 0.1 08:58:02 INFO - mozlog == 3.1 08:58:02 INFO - moznetwork == 0.27 08:58:02 INFO - mozprocess == 0.22 08:58:02 INFO - mozprofile == 0.28 08:58:02 INFO - mozrunner == 6.11 08:58:02 INFO - mozscreenshot == 0.1 08:58:02 INFO - mozsystemmonitor == 0.0 08:58:02 INFO - moztest == 0.7 08:58:02 INFO - mozversion == 1.4 08:58:02 INFO - psutil == 3.1.1 08:58:02 INFO - requests == 1.2.3 08:58:02 INFO - wsgiref == 0.1.2 08:58:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:02 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:58:02 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 08:58:02 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 08:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:02 INFO - 'HOME': '/Users/cltbld', 08:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:02 INFO - 'LOGNAME': 'cltbld', 08:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:02 INFO - 'MOZ_NO_REMOTE': '1', 08:58:02 INFO - 'NO_EM_RESTART': '1', 08:58:02 INFO - 'PAGER': '/bin/cat', 08:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:02 INFO - 'PWD': '/builds/slave/test', 08:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:02 INFO - 'SHELL': '/bin/bash', 08:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:58:02 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:02 INFO - 'USER': 'cltbld', 08:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:02 INFO - 'XPC_FLAGS': '0x0', 08:58:02 INFO - 'XPC_SERVICE_NAME': '0', 08:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 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)) 08:58:02 INFO - Cleaning up... 08:58:02 INFO - Return code: 0 08:58:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:02 INFO - 'HOME': '/Users/cltbld', 08:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:02 INFO - 'LOGNAME': 'cltbld', 08:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:02 INFO - 'MOZ_NO_REMOTE': '1', 08:58:02 INFO - 'NO_EM_RESTART': '1', 08:58:02 INFO - 'PAGER': '/bin/cat', 08:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:02 INFO - 'PWD': '/builds/slave/test', 08:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:02 INFO - 'SHELL': '/bin/bash', 08:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:58:02 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:02 INFO - 'USER': 'cltbld', 08:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:02 INFO - 'XPC_FLAGS': '0x0', 08:58:02 INFO - 'XPC_SERVICE_NAME': '0', 08:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:02 INFO - Downloading/unpacking jsonschema 08:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:58:05 INFO - Downloading/unpacking functools32 (from jsonschema) 08:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:05 INFO - Downloading functools32-3.2.3-2.tar.gz 08:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:58:05 INFO - warning: no files found matching '*.txt' 08:58:05 INFO - no previously-included directories found matching 'build' 08:58:05 INFO - no previously-included directories found matching 'dist' 08:58:05 INFO - no previously-included directories found matching '.git*' 08:58:05 INFO - Installing collected packages: jsonschema, functools32 08:58:05 INFO - Running setup.py install for functools32 08:58:05 INFO - warning: no files found matching '*.txt' 08:58:05 INFO - no previously-included directories found matching 'build' 08:58:05 INFO - no previously-included directories found matching 'dist' 08:58:05 INFO - no previously-included directories found matching '.git*' 08:58:05 INFO - Successfully installed jsonschema functools32 08:58:05 INFO - Cleaning up... 08:58:05 INFO - Return code: 0 08:58:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:58: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')]} 08:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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')]} 08:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:05 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 0x10c6a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8853cc5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, '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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 08:58:05 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 08:58:05 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 08:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:05 INFO - 'HOME': '/Users/cltbld', 08:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:05 INFO - 'LOGNAME': 'cltbld', 08:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:05 INFO - 'MOZ_NO_REMOTE': '1', 08:58:05 INFO - 'NO_EM_RESTART': '1', 08:58:05 INFO - 'PAGER': '/bin/cat', 08:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:05 INFO - 'PWD': '/builds/slave/test', 08:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:05 INFO - 'SHELL': '/bin/bash', 08:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:58:05 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:05 INFO - 'USER': 'cltbld', 08:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:05 INFO - 'XPC_FLAGS': '0x0', 08:58:05 INFO - 'XPC_SERVICE_NAME': '0', 08:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:06 INFO - Downloading/unpacking flake8 08:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:58:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:58:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:58:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:58:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:58:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:58:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:58:09 INFO - no previously-included directories found matching 'docs/_build' 08:58:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:58:09 INFO - Running setup.py install for pep8 08:58:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:58:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:58:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:58:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:58:09 INFO - no previously-included directories found matching 'docs/_build' 08:58:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:58:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:58:10 INFO - Cleaning up... 08:58:10 INFO - Return code: 0 08:58:10 INFO - Running post-action listener: _resource_record_post_action 08:58:10 INFO - Running post-action listener: _start_resource_monitoring 08:58:10 INFO - Starting resource monitoring. 08:58:10 INFO - ##### 08:58:10 INFO - ##### Running install step. 08:58:10 INFO - ##### 08:58:10 INFO - Running pre-action listener: _resource_record_pre_action 08:58:10 INFO - Running main action method: install 08:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:10 INFO - Reading from file tmpfile_stdout 08:58:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:58:10 INFO - Reading from file tmpfile_stdout 08:58:10 INFO - Output received: 08:58:10 INFO - Usage: mozinstall [options] installer 08:58:10 INFO - Options: 08:58:10 INFO - -h, --help show this help message and exit 08:58:10 INFO - -d DEST, --destination=DEST 08:58:10 INFO - Directory to install application into. [default: 08:58:10 INFO - "/builds/slave/test"] 08:58:10 INFO - --app=APP Application being installed. [default: firefox] 08:58:10 INFO - mkdir: /builds/slave/test/build/application 08:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:58:43 INFO - Reading from file tmpfile_stdout 08:58:43 INFO - Output received: 08:58:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:58:43 INFO - Running post-action listener: _resource_record_post_action 08:58:43 INFO - ##### 08:58:43 INFO - ##### Running run-tests step. 08:58:43 INFO - ##### 08:58:43 INFO - Running pre-action listener: _resource_record_pre_action 08:58:43 INFO - Running main action method: run_tests 08:58:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:58:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:58:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:58:43 INFO - 2016-04-23 08:58:43.547 screenresolution[1917:20215] starting screenresolution argv=screenresolution get 08:58:43 INFO - 2016-04-23 08:58:43.550 screenresolution[1917:20215] Display 0: 1600x1200x32@60 08:58:43 INFO - 2016-04-23 08:58:43.556 screenresolution[1918:20217] starting screenresolution argv=screenresolution list 08:58:43 INFO - Available Modes on Display 0 08:58:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:58:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:58:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:58:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:58:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:58:43 INFO - Intel Iris: 08:58:43 INFO - Chipset Model: Intel Iris 08:58:43 INFO - Type: GPU 08:58:43 INFO - Bus: Built-In 08:58:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:58:43 INFO - Vendor: Intel (0x8086) 08:58:43 INFO - Device ID: 0x0a2e 08:58:43 INFO - Revision ID: 0x0009 08:58:43 INFO - Displays: 08:58:43 INFO - Display: 08:58:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:58:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:58:43 INFO - Display Serial Number: 1600x1200 60 08:58:43 INFO - Main Display: Yes 08:58:43 INFO - Mirror: Off 08:58:43 INFO - Online: Yes 08:58:43 INFO - Rotation: Supported 08:58:43 INFO - Return code: 0 08:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:58:43 INFO - Python 2.7.3 08:58:43 INFO - Return code: 0 08:58:43 INFO - grabbing minidump binary from tooltool 08:58:43 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')]} 08:58:43 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 0x10c544e00>, '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 0x10c36ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c53fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:58:43 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 08:58:43 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 08:58:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:58:43 INFO - Return code: 0 08:58:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:58:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:58:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:58:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:58:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:58:44 INFO - Return code: 0 08:58:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 08:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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 08:58:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ev1T6EBLxl/Render', 08:58:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:44 INFO - 'HOME': '/Users/cltbld', 08:58:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:44 INFO - 'LOGNAME': 'cltbld', 08:58:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:58:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:44 INFO - 'MOZ_NO_REMOTE': '1', 08:58:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:58:44 INFO - 'NO_EM_RESTART': '1', 08:58:44 INFO - 'PAGER': '/bin/cat', 08:58:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:44 INFO - 'PWD': '/builds/slave/test', 08:58:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:58:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:44 INFO - 'SHELL': '/bin/bash', 08:58:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kk8nIA86N8/Listeners', 08:58:44 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 08:58:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:44 INFO - 'USER': 'cltbld', 08:58:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:44 INFO - 'XPC_FLAGS': '0x0', 08:58:44 INFO - 'XPC_SERVICE_NAME': '0', 08:58:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 08:58:45 INFO - mozversion application_buildid: 20160423070819 08:58:45 INFO - mozversion application_changeset: e976a219a56c556433590cb67c8b238015da610d 08:58:45 INFO - mozversion application_display_name: Nightly 08:58:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:58:45 INFO - mozversion application_name: Firefox 08:58:45 INFO - mozversion application_remotingname: firefox 08:58:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:58:45 INFO - mozversion application_vendor: Mozilla 08:58:45 INFO - mozversion application_version: 48.0a1 08:58:45 INFO - mozversion platform_buildid: 20160423070819 08:58:45 INFO - mozversion platform_changeset: e976a219a56c556433590cb67c8b238015da610d 08:58:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:58:45 INFO - mozversion platform_version: 48.0a1 08:58:45 INFO - using testdate: 1461427125 08:58:45 INFO - actual date: 1461427125 08:58:45 INFO - starting webserver on 'localhost:55529' 08:58:45 INFO - SUITE-START | Running 2 tests 08:58:45 INFO - TEST-START | dromaeo_css 08:58:45 INFO - operating with platform_type : mac_ 08:58:45 INFO - Initialising browser for dromaeo_css test... 08:58:45 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpFWE7Px/profile http://localhost:55529/getInfo.html) 08:58:47 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 08:58:47 INFO - PROCESS | 1936 | colorDepth:24 08:58:47 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 08:58:47 INFO - PROCESS | 1936 | __metrics 08:58:47 INFO - PROCESS | 1936 | 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" 08:58:47 ERROR - PROCESS | 1936 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:58:48 INFO - TEST-INFO | 1936: exit 0 08:58:48 INFO - Browser initialized. 08:58:48 INFO - Running cycle 1/1 for dromaeo_css test... 08:58:48 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpFWE7Px/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:59 INFO - PROCESS | 1938 | RSS: Main: 239128576 08:58:59 INFO - PROCESS | 1938 | 08:59:51 INFO - PROCESS | 1938 | 1461427191541 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:02:01 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55529/tests/dromaeo/cssquery-ext.html) 09:02:03 INFO - PROCESS | 1938 | RSS: Main: 326512640 09:02:03 INFO - PROCESS | 1938 | 09:05:06 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-ext.html (next: http://localhost:55529/tests/dromaeo/cssquery-jquery.html) 09:05:06 INFO - PROCESS | 1938 | RSS: Main: 375078912 09:05:06 INFO - PROCESS | 1938 | 09:08:08 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55529/tests/dromaeo/cssquery-mootools.html) 09:08:09 INFO - PROCESS | 1938 | RSS: Main: 370876416 09:08:09 INFO - PROCESS | 1938 | 09:11:11 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55529/tests/dromaeo/cssquery-prototype.html) 09:11:12 INFO - PROCESS | 1938 | RSS: Main: 376889344 09:11:12 INFO - PROCESS | 1938 | 09:14:15 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55529/tests/dromaeo/cssquery-yui.html) 09:14:15 INFO - PROCESS | 1938 | RSS: Main: 337903616 09:14:15 INFO - PROCESS | 1938 | 09:17:18 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55529/tests/dromaeo/cssquery-yui.html (next: http://localhost:55529/tests/dromaeo/cssquery-dojo.html) 09:17:19 INFO - PROCESS | 1938 | RSS: Main: 385798144 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | __start_tp_report 09:17:19 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 09:17:19 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 09:17:19 INFO - PROCESS | 1938 | |i|pagename|runs| 09:17:19 INFO - PROCESS | 1938 | |0;dojo.html;3141.795783274087;3454.635419009493;3485.494603282524;3488.3023395320943;3522.9823850880744;5634.183043458696;5693.572982026344;5710.600257981938;5710.857228569288;5754.769809207637;5617.887642247153;5668.603197776158;5685.602007859451;5694.2312787773635;5700.201971723962;5178.637495375326;5191.351081114861;5199.09015922214;5206.973965130169;5293.15309550472;5274.182501712241;5286.4449232830575;5289.576833853283;5290.259363689087;5302.098643230641;5211.739413029352;5280.366356037281;5287.576993840503;5303.23103150043;5337.5196232339085;5320.175372817239;5378.569714422876;5386.622936394454;5393.325834270716;5420.457954204588;4814.590759785422;4963.759060234941;5109.693418394908;5142.434332223453;5190.974045129788;5614.382417934024;5639.238702775114;5650.067738823089;5659.151127330892;5679.290088738908;6271.122042913996;6272.1219029335925;6290.339514351019;6301.432871041582;6310.337414571496;6102.51954821894;6177.4749146322165;6246.281677607088;6262.812115636538;6296.559240853142;5836.037053886062;5837.7956771513645;5860.882782344329;5866.413358664099;5894.233749612523;6321.494280457552;6327.905081423783;6339.429451349309;6339.714712837934;6345.365463453618;6126.295476020315;6132.600268413531;6226.813195604139;6231.3145553989025;6231.594946328474;6223.502119830403;6233.812985610439;6246;6250.468710159687;6253.937460625427;6198.318184999646;6258.217722784652;6264.749410023639;6271.905921411182;6279.623222606659;6127.494965205055;6192.535559833031;6244.313125556185;6350.364963503613;6356.396142366468;4957.82647607332;5005.5745261653165;5006.949930500721;5016.996600679879;5029.748512574357;4470.307102399133;4475.552444755564;4485.865424037284;4493.865184044484;4511.729296242236;5703.828885133453;5767.625104368202;5804.245448091806;5861.677607731615;5868.647881127146;6305.873882522324;6362.363763623601;6364.809055728336;6385.808425747235;6390.7763228286785;5703.116017017369;5723.59934804568;5727.484526392562;5747.453991870766;5772.6536407815665;5346.679199248058;5392;5435.701036443033;5451.7733509960735;5467.425920278313;6301.275353334425;6400.775972840928;6418.614883106935;6429.54993150475;6432.195975503062;6209.161763161916;6320.652364120017;6352.333005034589;6372.299047104722;6379.266384365857;6382.968085159545;6397.424231819158;6452.064450654535;6465.353464653497;6475.579087359495;5157.252198431282;6207.255129384503;6260.40526150015;6263.592866463665;6264;5795.246617939641;6145.8156255312415;6262.46769024638;6272.18461599989;6353.809385718436;6124.411449640054;6128.3503509891225;6131.713108457195;6132.080177995836;6133.8466538335115;5978.432048955342;6119.654885716854;6144.969275153596;6151.753929842936;6166.260048794174;5879.235699359056;5985.700714964148;6012.849678758066;6033;6115.143879856734;5987.13186587951;6098.26820781509;6117.602355846856;6158.815235542941;6201.441870231701;5942.108683697307;6021.676049701183;6024.975754121722;6025.969870150797;6087.086936959491 09:17:19 INFO - PROCESS | 1938 | |1;ext.html;512.8025710101609;528.7171363320624;566.7392999220358;591.4529060618927;591.8638713095986;26592.06927757529;38784.806075969616;39580;39631.80184099075;39678.99999999996;42617.573824261744;43260;43275.56724432755;43341.91645208871;43458.044388002534;8619.224269815715;8832.425892316978;9649.758756031102;9908.405495670248;9909.554070066864;8773.95613021934;8918.420302680304;8925.687600933968;9467.857982130274;11853.70365740859;11622.360770157638;11713.1215158863;11725.706857328592;11729;11729.413529323543;11532.01977831881;11658.708532286675;11666.766664666744;11674.883251167465;11682.24065435744;8669.436486628349;10204.897951020543;11519.48162332697;11862.822057669056;11862.940685296604;29895.551566726517;39446.211075778614;39593.40609890825;39627.60372396268;39694.80152599219;3223.935521289561;3334.6832050955363;3371.7134043606325;3484.007057988461;3788.488554045196;28009.01968431095;28557;28755.424891502054;29049;29652;2510.497900419923;2712.2134580971497;2721.8230814996964;2726.8227565208394;2802.649668791401;340619;341026.2948685266;341346;342013;343503.2824835885;35695;62817.057744133876;63191.6840415795;63289.73420531564;63410;35740.28519429649;35867;35909.28181436357;35977.46033809495;35982.46026309607;12605.30670813114;14865.405383784528;14889.47886823956;15107.54677359681;15129.319180636898;9694.709158725249;12733.108682392329;12795.808062879065;12796.680082998;12811.231326120462;7649.502782319131;8110.432269741062;8299.29455996247;8357.916420835714;8380.20388063133;3410.3520331137006;3460.584729832436;3463.1861512544533;3470.548828652259;3494.7553671242767;1013.975884356805;1036.4403222259896;1050.8965586134527;1060.6764936694228;1080.152080616712;25985.090521832022;28914.843406263513;29467.263318417212;29597.852010739807;29825.850870745508;912.8265629530368;1130.7455822439886;1139.8062329404022;1157.9652610421701;1180.8228765685217;15264;15290;15365.154916479702;15391.615209619626;15611.985220960602;632716;637318;646556;647457;647662;60954.78090438167;62107;62112.06831897525;62132.06801897975;62201.755964881355;1404.39610967283;1451.136573738645;1497.213962669607;1502.7971223884635;1504.3230546254229;25751.484970300113;29448.26379340448;29553.408931821672;29870;30490.69509304967;50703.74648126736;51570;51633.7418312906;51639.225411619605;51646.74176629093;422432;443387;443481;444137;444590;23940.162094326613;26464;26712.46575068449;27365.58951615767;27519.17442476729;43783.78108109439;43879;43962.56037439585;44171;44560.55439445564;36648.63351366559;37346.813265933495;37477.43783843299;38103.618963810004;38243.08784560816;37206.81396593;37661.623383765815;38197.236055279296;38537.614623853406;39446.40830387604;36495.27009459849;37093.81453092717;37226.8138659305;37271.88184354474;38268;32774.34451310913;33439;33508.49737253894;33576.32847342991;33611;20182.38540916694;24538.754612453646;24631.014759409423;25104.62343064892;25112 09:17:19 INFO - PROCESS | 1938 | |2;jquery.html;397.29480172693485;397.3423983307627;399.69024006395045;399.9860004899828;412.1468560080634;14379.424823007093;19685.311014114508;20753.584928301443;20766;21166.788332116674;16030.5992350191;18051.909740451312;20092.397228083144;20725.896370518127;20917.895410522928;5717.056685646874;5958.6424814511265;5970;5975.880482390349;5988.790392336269;5772.451617096369;5810.360860305363;5819.7963071292515;5831.096180092092;5902.645841249518;5643.8024669136585;5834;5861.3552509223955;5870.295564532241;5870.559708021894;5580.581456390787;5806.606414460542;5824.854378640568;5834;5840.912386314188;5099.770510327007;5108.846734597968;5134.794608215667;5139.049275883936;5139.871503212412;24615.892284847225;25727;26120;26248.475030499474;26268;2530.9746902530924;2550.005497855838;2556.8593727344987;2850.9144725658057;2926.1221633509863;12721.55474558386;13129.474821007148;14586.562403127817;15075.39698412073;15508.45720399792;1676.51381099481;1677.4328503907311;1696.2621259751925;1763.4885883093887;1790.97313540297;90353;106925;134472.999999999;137211;140118;10932.890671093188;11563.710907227387;11597.18819682614;11623;11664.066874650007;14934.029288096239;14998.850011499964;15051.322690478955;15070.397184112731;15078.623034424227;6345.809625711236;7163.638908607348;7377.889331659922;8597.871031934526;8742.431741936754;8571.571421428904;8575.442596231225;8576.399652024298;8671.609777560025;8710.912890871137;3749.5875453700073;4565.497795242521;4576;4583.908321833612;4634.21218392874;1923.557581756204;1967.2131147541097;1976.2984140630156;1991.442396129086;2003.7795842457317;884.3014018924977;903.516618609041;930.4928813796497;1033.7312298802365;1046.1682962045193;19741.012949352476;20229;20332.796672033386;20485.48786280325;20523.897380513;925.319889880937;968.6900191938512;981.3964411886781;999.0808456220295;1031.5873650539845;10757.085647719874;11004;11036.613718519813;11060.612878549213;11082.722931926604;517339;523984;524171.72025216825;585306;598033.009834948;129494;138416.30791846177;149507.25246373916;153795;154741.2262938678;449.7301619028622;469.1508369850649;472.6242637103511;473.81521206729406;474.122872685529;10815.945920270347;11149.163812714241;11172.273802202832;11245.100391968459;11367;53667.46332536625;53875.46124538704;54854;55871;56128.43871561232;70478.64760676163;71646.64176679084;73351.63324183345;78039;82323;7896.7236146734585;7961.482503637245;8051.597420129087;8063.959680201796;8497.405181637227;9029;11822.586209482972;12272.938635306766;12486.313252771184;12754.872451275369;10767.030967212988;10869.782604348027;11130.833037504606;11130.888691113309;11250.268732532686;10650.946745266225;10707.732306692395;10784.406857622593;10868.510917008596;11015.779684406427;11081.667549973514;11111.222214444912;11112.555497779998;11332.036776874058;11508.251963622337;7720.459567830198;8056.718014869686;8148.470349427268;8477.74566762998;8552.529610871461;10574;10855.348679079281;11022.779544409228;11033.282836615594;11184.720381990515 09:17:19 INFO - PROCESS | 1938 | |3;mootools.html;1414.660481484444;1596.5529651697525;1602.0868105180045;1889.3009586453015;2027.300581299451;7865.213478652139;8560.229579337858;8785.692500762458;8787.868181977276;8796.340274479415;8074.555899425529;8273.793155171124;8281.792955176124;8282.37882158839;8286.337093032558;2430.5989511730586;2459.077845807822;2462.101333013446;2463.7659422354845;2466.3587467258553;2387.6537902004197;2454.104251948044;2456.496418234258;2461.778439940405;2466.3094333586537;2393.365758074112;2434.9756502435016;2439.9146029888957;2463.4334103156284;2473.0355161487037;2397.11306816479;2410.758924107584;2419.7822196002357;2419.9957017837664;2424.248482970285;2380.833341666084;2400.5198960208027;2407.8194135439912;2408.229366602688;2420.6006009008493;8618.827623447496;8652.8702069469;8695.08701586337;8723.214910658007;8741.038485766561;5191.273221748958;5408.486193811582;5432.945670543323;5451.182322651594;5455.863603409946;3422.435298175798;3485.466394786286;3542.769719968194;3549.5208146900354;3560.305740380627;977.9608815647366;995.144176008632;997.2321312589338;1003.8845532763679;1006.5269323418055;11353.921377469127;11727.765444691144;11733.589324373692;11749.412529373498;11767.293962362286;4502.864914052584;4505.7521836298665;4686.109639168547;4717.103750287434;4729.006908549243;9186.81626367469;9197.586108625128;9205.401648892801;9208.26333893287;9215.631374744935;5278.155495120763;5281.39263984639;5281.551068159172;5289.973550132225;5293.391260005072;4281.957180428218;4368.803403846835;4387.508247195969;4820.758962051954;4936.531029552187;3820.503334566489;3851.7111216658864;3914.2954268231424;3923.6664883485223;3966.087799806061;1207.3359652191434;1207.5169932027259;1208.4259976511262;1219.3415555599877;1225.1240363140398;920.6593560382702;935.7613808478794;940.6801687426308;950.3680052764972;978.882534095913;5841.240638716939;5874.181781841837;5940.46535811779;6132.110843927606;6202.193714817045;893.8748575199477;931.6739141300626;952.2619969523536;985.84719368498;1019.0828254570797;3836.0793409582056;4052.3313653247474;4056.2571355924433;4091.57038510958;4107.938380924288;10405.115565176893;10806.189535784848;10916.345019298868;10934.617288395028;10952.507137178845;10759.623413180658;10811.081058110149;10864.891351086546;10880.455977201267;10896.78206435867;8191.549464779494;8266.421350505527;8348.582570871433;8350.248477637164;8365.99608047026;5678.4605462480995;5890.970545147246;5987.7604895805425;5998.25021872266;6058.636481811105;9472.715818525456;9539.618415263589;9846.064623860722;9849.064338887794;9854.359466634645;9796.65711700087;9930.602775888883;9949.850752238866;9953.253505987224;10067.949660251652;5807.361190269152;7141.64291785419;7154.821129471805;7162.7493037743425;7162.89255661176;8137.715179968673;8426.325893928593;8507.829843402973;8524.849145365297;8544.2310192083;7129.82175445618;7386.965824784642;7425.554466732014;7764.534127952341;7882.09355924076;7473;7546.471746977879;7558.811029724301;7640.388768898586;7741.690332386642;6987.02181694553;7026;7079.716811327489;7088;7093.964530177316;4924.409070911371;4935.383077115361;5102.617303702163;5228.790848366023;5232.816851410183;5239.921401178909;5273.367195936512;5349.786008559614;5386.676799392049;5445.2104444854485 09:17:19 INFO - PROCESS | 1938 | |4;prototype.html;311.86745633105926;315.3582459694521;325.94621887388575;331.8025774664074;332.2507745034946;5125.564327032207;5598;5651.1523271509295;5945.197398351233;5980.192673989016;5796.739146738396;5868.3838196989345;5874.236349274599;5881.470667639912;5911.497522710564;3950.42718805773;4336.6989903029125;4490.910181796354;4528.705634133771;4558.922727159171;4408.448943882015;4463.24373033419;4516.887077823048;4526.162659907911;4545.909081818361;3737.9346886137455;4409.29451287794;4441.156180325744;4458;4473.552644735517;4448.822047118111;4464.977675111636;4470.955290447087;4471.262241730111;4483.39474170986;3117.953230701541;3541.6458354164633;3886.3587508061137;3894.383830710252;3902.414637804352;4948.505149485059;5655.66066036035;5664.320281566197;5673.432656734335;5686.54507639392;5103;5156.716380599066;5159.561437277798;5166.535011848951;5173.224016397533;5231.9476805232225;5990.880182396328;6295.811125666238;6327.303996560375;6342;3840.9629400061826;3947.7631342119566;4266.786660666954;4273.67947403946;4293.742375457451;5759.625624334405;5980.28236611605;6232.906506402408;6615.272320044791;6674.96289188423;3940.566537680853;4256.489221293403;4280.743155410685;4336.241157797436;4341;4435.423394958635;4444.933326000112;4455.376247325377;4462.620677242406;4463.776811159429;5139.177731562932;5209.140491818884;5223.764930578133;5225.8905950959115;5228.55557277628;6051.757929682852;6102.237220347457;6367.554271200971;6378.585391949508;6420.133282006869;1439.4673970630724;1531.4869518711357;1535.431890200633;1541.4758981946193;1546.8917175797585;989.737719504332;1020.4438580973388;1024.2522958240527;1036.9066783989476;1040.6461802987021;4859.854204373874;5854.443827836349;5865.944130056546;5874.823755287348;5888.587798854039;5523.834284971457;6095.603785753912;6441.3880681335195;6494.610323380518;6568.310327415648;4852.902941941142;5403.540699040547;5844.6785426801935;5908.645481271137;5909.084091965752;3120.937581248362;3444.6727560881677;3449.413599688059;3468.843902024415;3503.2292895562937;6534.673266336664;6840.486963477759;6873.621950792654;6884.51808373409;6966.651667416609;6026.608270462406;6382.266039405435;6734.393904548614;6779.593224406552;6787.219469760942;5938.495227905676;6029.974904266285;6693.966530167416;6794.796156115324;6893.517453778288;6523.151990241238;6547.738090476328;6549;6558.1802274715665;6559.540832141895;6466.773662921775;6481.449076828522;6502.187226596676;6514.902276465951;6516.706748196438;6064.757409703562;6595.274519802914;6984.546004509691;7003.614801185984;7008.264132266038;3738.4018557030745;3778.6977041837135;4032.8790136295956;4326.891827704207;4354.301822289373;5048.864005598711;6420.101185834081;6449.064885591657;6654;6684.799456016327;5835.503982161564;6434.077930383167;6443;6443.96778016107;6453.9354606453335;5668.716564171693;6141.385861413823;6187.659678717714;6427.903581446189;6448.613083215022;5219.739013049409;6238.532110091852;6259.96870015647;6283.7800676976085;6284.214473190851;6394.744210231539;6408.231012278557;6411.319736592407;6420.6147631142285;6427.035944608346;5431;5899.430844339113;6269.435750782452;6271.498280137487;6296.464800492009 09:17:19 INFO - PROCESS | 1938 | |5;yui.html;599.7900734742841;655.6492276632002;661.5270081891447;746.8095635612918;749.1493722267546;14662.12027278364;14800.407983680654;15109.320080596372;15238.542843714693;15271.770923436157;13812.033157678967;13838.169709817417;13928.233947132929;13949.372278247478;13994.650133746662;738.6897503048732;760.1448370583093;916.8670542771295;920.2821798996794;923.3536524432924;906.365544119116;939.3940908114262;942.6041062753626;942.8585712143165;945.125758673228;984.620920945434;992.5483904823324;993.4635296939643;993.5181437003048;994.6916455898695;756.791882232385;940.8729821474082;981.381729510411;983.6901376066552;986.5708416838662;725.5247812682701;730.3135053050116;730.4193166432646;731.1007460823164;733.1495465260273;14802.481913132988;14879.33043013067;14882.851171488255;14887.851121488755;14890.553283401516;12379.504819807196;12385.814212786816;12425.44085516154;12707.809382859265;12743.681407964783;9864.704058878173;10025.949870250675;10042.598296068149;10124.34191777532;10554.997275258915;3402.2344972381266;3475.131217195701;3514.947275790865;3535.681788639009;3583.3549961007006;71978;74708.62645686792;75074.24925750727;75141.62429187873;76537.61731191364;9229.723108306762;9487.715368538955;9604.471754053595;9628.518574071268;9682;16941.83058169427;21550.461238469165;21613;21622.459438513848;21645.67531487029;7354.458584438785;7405.925940740632;7453.329200371993;7480.3641690456925;7549.584772837547;4078.6329230368815;4140.337545992687;4144.7513149211145;4147.502299724052;4149.107941792529;3590.3537363274863;3664.59689434162;3664.8350824212976;3671.0271777978855;3677.062349100962;642.2966851297822;659.4691273524762;660.2044536333707;663.4128796015561;664.4518272425269;490.9779059942311;545.9481349271813;578.3580225949192;605.2736715940889;614.6035806904522;7644.80887977805;7786.7663970080985;7811.414143939227;7866;7903.36773058154;565.191775760658;566.2723400430509;584.065073008129;586.305228304452;593.1991811055041;3751.174741556853;3769.849206031783;3790.677792387678;3841.8271177797064;3859.6333348331864;82182;83720;84315;85958.57020714856;86676.13323866681;60730;65106.348936510025;66339.66830165818;68189;68645.3135468649;60067;64082.67958660177;64410.67794660997;64417;64662.35337646658;7279.344858962507;7320.96339518299;7485.962570187115;7518.511296765692;7572.318491335806;40754.18491630092;41481.58518414898;42248.36627450652;42355.72932811897;43160.78419607882;46855.062898742515;55193;60548.39451605428;60670;61040.694796525735;10097.495125243562;10118.392896426238;10368.844467332845;10392.532336045047;10723.03492685662;10615.840762388725;10780.407077610493;10877.619283325046;10938.835917461085;11186.161037922026;8241.752747417588;10046.296759227076;10077.445740484343;10279.126274266771;10597.523111459848;10111.140553053074;10220.335678180894;10239.027292407209;10518.78962420732;10560.947195263974;9965.451900145272;10183.490825458846;10270.794584108426;10314.793704126025;10443.582256709646;8678.392512524062;8782.824343512966;8888.866666999871;9142.497162656118;9227.907720922705;8596.441231319945;8629.913700863162;8727.345449091217;8894.33292503052;8908.287337013153 09:17:19 INFO - PROCESS | 1938 | __end_tp_report 09:17:19 INFO - PROCESS | 1938 | __start_cc_report 09:17:19 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1217 09:17:19 INFO - PROCESS | 1938 | __end_cc_report 09:17:19 INFO - PROCESS | 1938 | __startTimestamp1461428239050__endTimestamp 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | ------- Summary: start ------- 09:17:19 INFO - PROCESS | 1938 | Number of tests: 6 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5782.86 Median:6012.85 stddev:642.93 (10.7%) stddev-sans-first:610.61 09:17:19 INFO - PROCESS | 1938 | Values: 3141.8 3454.6 3485.5 3488.3 3523.0 5634.2 5693.6 5710.6 5710.9 5754.8 5617.9 5668.6 5685.6 5694.2 5700.2 5178.6 5191.4 5199.1 5207.0 5293.2 5274.2 5286.4 5289.6 5290.3 5302.1 5211.7 5280.4 5287.6 5303.2 5337.5 5320.2 5378.6 5386.6 5393.3 5420.5 4814.6 4963.8 5109.7 5142.4 5191.0 5614.4 5639.2 5650.1 5659.2 5679.3 6271.1 6272.1 6290.3 6301.4 6310.3 6102.5 6177.5 6246.3 6262.8 6296.6 5836.0 5837.8 5860.9 5866.4 5894.2 6321.5 6327.9 6339.4 6339.7 6345.4 6126.3 6132.6 6226.8 6231.3 6231.6 6223.5 6233.8 6246.0 6250.5 6253.9 6198.3 6258.2 6264.7 6271.9 6279.6 6127.5 6192.5 6244.3 6350.4 6356.4 4957.8 5005.6 5006.9 5017.0 5029.7 4470.3 4475.6 4485.9 4493.9 4511.7 5703.8 5767.6 5804.2 5861.7 5868.6 6305.9 6362.4 6364.8 6385.8 6390.8 5703.1 5723.6 5727.5 5747.5 5772.7 5346.7 5392.0 5435.7 5451.8 5467.4 6301.3 6400.8 6418.6 6429.5 6432.2 6209.2 6320.7 6352.3 6372.3 6379.3 6383.0 6397.4 6452.1 6465.4 6475.6 5157.3 6207.3 6260.4 6263.6 6264.0 5795.2 6145.8 6262.5 6272.2 6353.8 6124.4 6128.4 6131.7 6132.1 6133.8 5978.4 6119.7 6145.0 6151.8 6166.3 5879.2 5985.7 6012.8 6033.0 6115.1 5987.1 6098.3 6117.6 6158.8 6201.4 5942.1 6021.7 6025.0 6026.0 6087.1 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:60911.36 Median:3466.87 stddev:131326.39 (3788.0%) stddev-sans-first:131616.50 09:17:19 INFO - PROCESS | 1938 | Values: 512.8 528.7 566.7 591.5 591.9 26592.1 38784.8 39580.0 39631.8 39679.0 42617.6 43260.0 43275.6 43341.9 43458.0 8619.2 8832.4 9649.8 9908.4 9909.6 8774.0 8918.4 8925.7 9467.9 11853.7 11622.4 11713.1 11725.7 11729.0 11729.4 11532.0 11658.7 11666.8 11674.9 11682.2 8669.4 10204.9 11519.5 11862.8 11862.9 29895.6 39446.2 39593.4 39627.6 39694.8 3223.9 3334.7 3371.7 3484.0 3788.5 28009.0 28557.0 28755.4 29049.0 29652.0 2510.5 2712.2 2721.8 2726.8 2802.6 340619.0 341026.3 341346.0 342013.0 343503.3 35695.0 62817.1 63191.7 63289.7 63410.0 35740.3 35867.0 35909.3 35977.5 35982.5 12605.3 14865.4 14889.5 15107.5 15129.3 9694.7 12733.1 12795.8 12796.7 12811.2 7649.5 8110.4 8299.3 8357.9 8380.2 3410.4 3460.6 3463.2 3470.5 3494.8 1014.0 1036.4 1050.9 1060.7 1080.2 25985.1 28914.8 29467.3 29597.9 29825.9 912.8 1130.7 1139.8 1158.0 1180.8 15264.0 15290.0 15365.2 15391.6 15612.0 632716.0 637318.0 646556.0 647457.0 647662.0 60954.8 62107.0 62112.1 62132.1 62201.8 1404.4 1451.1 1497.2 1502.8 1504.3 25751.5 29448.3 29553.4 29870.0 30490.7 50703.7 51570.0 51633.7 51639.2 51646.7 422432.0 443387.0 443481.0 444137.0 444590.0 23940.2 26464.0 26712.5 27365.6 27519.2 43783.8 43879.0 43962.6 44171.0 44560.6 36648.6 37346.8 37477.4 38103.6 38243.1 37206.8 37661.6 38197.2 38537.6 39446.4 36495.3 37093.8 37226.8 37271.9 38268.0 32774.3 33439.0 33508.5 33576.3 33611.0 20182.4 24538.8 24631.0 25104.6 25112.0 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:34014.92 Median:25923.50 stddev:93143.50 (359.3%) stddev-sans-first:93370.59 09:17:19 INFO - PROCESS | 1938 | Values: 397.3 397.3 399.7 400.0 412.1 14379.4 19685.3 20753.6 20766.0 21166.8 16030.6 18051.9 20092.4 20725.9 20917.9 5717.1 5958.6 5970.0 5975.9 5988.8 5772.5 5810.4 5819.8 5831.1 5902.6 5643.8 5834.0 5861.4 5870.3 5870.6 5580.6 5806.6 5824.9 5834.0 5840.9 5099.8 5108.8 5134.8 5139.0 5139.9 24615.9 25727.0 26120.0 26248.5 26268.0 2531.0 2550.0 2556.9 2850.9 2926.1 12721.6 13129.5 14586.6 15075.4 15508.5 1676.5 1677.4 1696.3 1763.5 1791.0 90353.0 106925.0 134473.0 137211.0 140118.0 10932.9 11563.7 11597.2 11623.0 11664.1 14934.0 14998.9 15051.3 15070.4 15078.6 6345.8 7163.6 7377.9 8597.9 8742.4 8571.6 8575.4 8576.4 8671.6 8710.9 3749.6 4565.5 4576.0 4583.9 4634.2 1923.6 1967.2 1976.3 1991.4 2003.8 884.3 903.5 930.5 1033.7 1046.2 19741.0 20229.0 20332.8 20485.5 20523.9 925.3 968.7 981.4 999.1 1031.6 10757.1 11004.0 11036.6 11060.6 11082.7 517339.0 523984.0 524171.7 585306.0 598033.0 129494.0 138416.3 149507.3 153795.0 154741.2 449.7 469.2 472.6 473.8 474.1 10815.9 11149.2 11172.3 11245.1 11367.0 53667.5 53875.5 54854.0 55871.0 56128.4 70478.6 71646.6 73351.6 78039.0 82323.0 7896.7 7961.5 8051.6 8064.0 8497.4 9029.0 11822.6 12272.9 12486.3 12754.9 10767.0 10869.8 11130.8 11130.9 11250.3 10650.9 10707.7 10784.4 10868.5 11015.8 11081.7 11111.2 11112.6 11332.0 11508.3 7720.5 8056.7 8148.5 8477.7 8552.5 10574.0 10855.3 11022.8 11033.3 11184.7 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5695.95 Median:5291.68 stddev:3145.03 (59.4%) stddev-sans-first:3137.39 09:17:19 INFO - PROCESS | 1938 | Values: 1414.7 1596.6 1602.1 1889.3 2027.3 7865.2 8560.2 8785.7 8787.9 8796.3 8074.6 8273.8 8281.8 8282.4 8286.3 2430.6 2459.1 2462.1 2463.8 2466.4 2387.7 2454.1 2456.5 2461.8 2466.3 2393.4 2435.0 2439.9 2463.4 2473.0 2397.1 2410.8 2419.8 2420.0 2424.2 2380.8 2400.5 2407.8 2408.2 2420.6 8618.8 8652.9 8695.1 8723.2 8741.0 5191.3 5408.5 5432.9 5451.2 5455.9 3422.4 3485.5 3542.8 3549.5 3560.3 978.0 995.1 997.2 1003.9 1006.5 11353.9 11727.8 11733.6 11749.4 11767.3 4502.9 4505.8 4686.1 4717.1 4729.0 9186.8 9197.6 9205.4 9208.3 9215.6 5278.2 5281.4 5281.6 5290.0 5293.4 4282.0 4368.8 4387.5 4820.8 4936.5 3820.5 3851.7 3914.3 3923.7 3966.1 1207.3 1207.5 1208.4 1219.3 1225.1 920.7 935.8 940.7 950.4 978.9 5841.2 5874.2 5940.5 6132.1 6202.2 893.9 931.7 952.3 985.8 1019.1 3836.1 4052.3 4056.3 4091.6 4107.9 10405.1 10806.2 10916.3 10934.6 10952.5 10759.6 10811.1 10864.9 10880.5 10896.8 8191.5 8266.4 8348.6 8350.2 8366.0 5678.5 5891.0 5987.8 5998.3 6058.6 9472.7 9539.6 9846.1 9849.1 9854.4 9796.7 9930.6 9949.9 9953.3 10067.9 5807.4 7141.6 7154.8 7162.7 7162.9 8137.7 8426.3 8507.8 8524.8 8544.2 7129.8 7387.0 7425.6 7764.5 7882.1 7473.0 7546.5 7558.8 7640.4 7741.7 6987.0 7026.0 7079.7 7088.0 7094.0 4924.4 4935.4 5102.6 5228.8 5232.8 5239.9 5273.4 5349.8 5386.7 5445.2 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:5131.16 Median:5723.09 stddev:1607.52 (28.1%) stddev-sans-first:1570.80 09:17:19 INFO - PROCESS | 1938 | Values: 311.9 315.4 325.9 331.8 332.3 5125.6 5598.0 5651.2 5945.2 5980.2 5796.7 5868.4 5874.2 5881.5 5911.5 3950.4 4336.7 4490.9 4528.7 4558.9 4408.4 4463.2 4516.9 4526.2 4545.9 3737.9 4409.3 4441.2 4458.0 4473.6 4448.8 4465.0 4471.0 4471.3 4483.4 3118.0 3541.6 3886.4 3894.4 3902.4 4948.5 5655.7 5664.3 5673.4 5686.5 5103.0 5156.7 5159.6 5166.5 5173.2 5231.9 5990.9 6295.8 6327.3 6342.0 3841.0 3947.8 4266.8 4273.7 4293.7 5759.6 5980.3 6232.9 6615.3 6675.0 3940.6 4256.5 4280.7 4336.2 4341.0 4435.4 4444.9 4455.4 4462.6 4463.8 5139.2 5209.1 5223.8 5225.9 5228.6 6051.8 6102.2 6367.6 6378.6 6420.1 1439.5 1531.5 1535.4 1541.5 1546.9 989.7 1020.4 1024.3 1036.9 1040.6 4859.9 5854.4 5865.9 5874.8 5888.6 5523.8 6095.6 6441.4 6494.6 6568.3 4852.9 5403.5 5844.7 5908.6 5909.1 3120.9 3444.7 3449.4 3468.8 3503.2 6534.7 6840.5 6873.6 6884.5 6966.7 6026.6 6382.3 6734.4 6779.6 6787.2 5938.5 6030.0 6694.0 6794.8 6893.5 6523.2 6547.7 6549.0 6558.2 6559.5 6466.8 6481.4 6502.2 6514.9 6516.7 6064.8 6595.3 6984.5 7003.6 7008.3 3738.4 3778.7 4032.9 4326.9 4354.3 5048.9 6420.1 6449.1 6654.0 6684.8 5835.5 6434.1 6443.0 6444.0 6453.9 5668.7 6141.4 6187.7 6427.9 6448.6 5219.7 6238.5 6260.0 6283.8 6284.2 6394.7 6408.2 6411.3 6420.6 6427.0 5431.0 5899.4 6269.4 6271.5 6296.5 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:16614.54 Median:60609.20 stddev:22762.36 (37.6%) stddev-sans-first:22794.45 09:17:19 INFO - PROCESS | 1938 | Values: 599.8 655.6 661.5 746.8 749.1 14662.1 14800.4 15109.3 15238.5 15271.8 13812.0 13838.2 13928.2 13949.4 13994.7 738.7 760.1 916.9 920.3 923.4 906.4 939.4 942.6 942.9 945.1 984.6 992.5 993.5 993.5 994.7 756.8 940.9 981.4 983.7 986.6 725.5 730.3 730.4 731.1 733.1 14802.5 14879.3 14882.9 14887.9 14890.6 12379.5 12385.8 12425.4 12707.8 12743.7 9864.7 10025.9 10042.6 10124.3 10555.0 3402.2 3475.1 3514.9 3535.7 3583.4 71978.0 74708.6 75074.2 75141.6 76537.6 9229.7 9487.7 9604.5 9628.5 9682.0 16941.8 21550.5 21613.0 21622.5 21645.7 7354.5 7405.9 7453.3 7480.4 7549.6 4078.6 4140.3 4144.8 4147.5 4149.1 3590.4 3664.6 3664.8 3671.0 3677.1 642.3 659.5 660.2 663.4 664.5 491.0 545.9 578.4 605.3 614.6 7644.8 7786.8 7811.4 7866.0 7903.4 565.2 566.3 584.1 586.3 593.2 3751.2 3769.8 3790.7 3841.8 3859.6 82182.0 83720.0 84315.0 85958.6 86676.1 60730.0 65106.3 66339.7 68189.0 68645.3 60067.0 64082.7 64410.7 64417.0 64662.4 7279.3 7321.0 7486.0 7518.5 7572.3 40754.2 41481.6 42248.4 42355.7 43160.8 46855.1 55193.0 60548.4 60670.0 61040.7 10097.5 10118.4 10368.8 10392.5 10723.0 10615.8 10780.4 10877.6 10938.8 11186.2 8241.8 10046.3 10077.4 10279.1 10597.5 10111.1 10220.3 10239.0 10518.8 10560.9 9965.5 10183.5 10270.8 10314.8 10443.6 8678.4 8782.8 8888.9 9142.5 9227.9 8596.4 8629.9 8727.3 8894.3 8908.3 09:17:19 INFO - PROCESS | 1938 | -------- Summary: end -------- 09:17:19 INFO - PROCESS | 1938 | 09:17:19 INFO - TEST-INFO | 1938: exit 0 09:17:19 INFO - TEST-OK | dromaeo_css | took 1114543ms 09:17:19 INFO - TEST-START | kraken 09:17:19 INFO - operating with platform_type : mac_ 09:17:19 INFO - Initialising browser for kraken test... 09:17:19 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpPIMn0y/profile http://localhost:55529/getInfo.html) 09:17:22 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 09:17:22 INFO - PROCESS | 1941 | colorDepth:24 09:17:22 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 09:17:22 INFO - PROCESS | 1941 | __metrics 09:17:22 INFO - PROCESS | 1941 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:22 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:17:23 INFO - TEST-INFO | 1941: exit 0 09:17:23 INFO - Browser initialized. 09:17:23 INFO - Running cycle 1/1 for kraken test... 09:17:23 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpPIMn0y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:34 INFO - PROCESS | 1943 | RSS: Main: 239144960 09:17:34 INFO - PROCESS | 1943 | 09:17:37 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55529/tests/kraken/driver.html?testName=audio-beat-detection) 09:17:38 INFO - PROCESS | 1943 | RSS: Main: 287199232 09:17:38 INFO - PROCESS | 1943 | 09:17:41 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55529/tests/kraken/driver.html?testName=audio-dft) 09:17:41 INFO - PROCESS | 1943 | RSS: Main: 300363776 09:17:41 INFO - PROCESS | 1943 | 09:17:45 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55529/tests/kraken/driver.html?testName=audio-fft) 09:17:46 INFO - PROCESS | 1943 | RSS: Main: 388182016 09:17:46 INFO - PROCESS | 1943 | 09:17:48 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55529/tests/kraken/driver.html?testName=audio-oscillator) 09:17:48 INFO - PROCESS | 1943 | RSS: Main: 288366592 09:17:48 INFO - PROCESS | 1943 | 09:17:50 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55529/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:17:51 INFO - PROCESS | 1943 | RSS: Main: 292937728 09:17:51 INFO - PROCESS | 1943 | 09:17:54 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55529/tests/kraken/driver.html?testName=imaging-darkroom) 09:17:54 INFO - PROCESS | 1943 | RSS: Main: 330981376 09:17:54 INFO - PROCESS | 1943 | 09:17:57 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55529/tests/kraken/driver.html?testName=imaging-desaturate) 09:17:58 INFO - PROCESS | 1943 | RSS: Main: 331280384 09:17:58 INFO - PROCESS | 1943 | 09:18:01 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55529/tests/kraken/driver.html?testName=json-parse-financial) 09:18:01 INFO - PROCESS | 1943 | RSS: Main: 328654848 09:18:01 INFO - PROCESS | 1943 | 09:18:03 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55529/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:18:03 INFO - PROCESS | 1943 | RSS: Main: 321937408 09:18:03 INFO - PROCESS | 1943 | 09:18:05 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:06 INFO - PROCESS | 1943 | RSS: Main: 328634368 09:18:06 INFO - PROCESS | 1943 | 09:18:08 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:09 INFO - PROCESS | 1943 | RSS: Main: 330424320 09:18:09 INFO - PROCESS | 1943 | 09:18:12 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:18:12 INFO - PROCESS | 1943 | RSS: Main: 335757312 09:18:12 INFO - PROCESS | 1943 | 09:18:15 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:18:16 INFO - PROCESS | 1943 | RSS: Main: 333225984 09:18:16 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55529/tests/kraken/driver.html?testName=ai-astar) 09:18:18 INFO - PROCESS | 1943 | RSS: Main: 398409728 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | __start_tp_report 09:18:18 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 09:18:18 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 09:18:18 INFO - PROCESS | 1943 | |i|pagename|runs| 09:18:18 INFO - PROCESS | 1943 | |0;ai-astar;112;113;108;111;114;114;113;114;104;114 09:18:18 INFO - PROCESS | 1943 | |1;audio-beat-detection;160;123;144;173;119;150;156;148;175;122 09:18:18 INFO - PROCESS | 1943 | |2;audio-dft;186;186;188;185;185;187;190;259;185;182 09:18:18 INFO - PROCESS | 1943 | |3;audio-fft;80;73;90;91;56;58;59;83;110;83 09:18:18 INFO - PROCESS | 1943 | |4;audio-oscillator;98;100;135;78;81;79;128;80;79;127 09:18:18 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;87;84;90;85;87;82;87;84;86;84 09:18:18 INFO - PROCESS | 1943 | |6;imaging-darkroom;93;92;92;92;92;92;92;92;92;91 09:18:18 INFO - PROCESS | 1943 | |7;imaging-desaturate;84;80;84;83;85;84;86;87;86;84 09:18:18 INFO - PROCESS | 1943 | |8;json-parse-financial;57;60;59;58;58;58;60;81;57;58 09:18:18 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;61;75;90;73;69;66;66;67;68;68 09:18:18 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;82;81;83;83;82;82;108;70;94;105 09:18:18 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;142;147;150;142;135;136;137;138;176;145 09:18:18 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;171;171;165;193;249;191;167;170;172;170 09:18:18 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;70;69;71;68;72;70;68;67;67;69 09:18:18 INFO - PROCESS | 1943 | __end_tp_report 09:18:18 INFO - PROCESS | 1943 | __start_cc_report 09:18:18 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2037 09:18:18 INFO - PROCESS | 1943 | __end_cc_report 09:18:18 INFO - PROCESS | 1943 | __startTimestamp1461428298439__endTimestamp 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | ------- Summary: start ------- 09:18:18 INFO - PROCESS | 1943 | Number of tests: 14 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.50 stddev:3.30 (2.9%) stddev-sans-first:3.50 09:18:18 INFO - PROCESS | 1943 | Values: 112.0 113.0 108.0 111.0 114.0 114.0 113.0 114.0 104.0 114.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:153.00 stddev:20.31 (13.3%) stddev-sans-first:20.99 09:18:18 INFO - PROCESS | 1943 | Values: 160.0 123.0 144.0 173.0 119.0 150.0 156.0 148.0 175.0 122.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:193.30 Median:186.50 stddev:23.18 (12.4%) stddev-sans-first:24.44 09:18:18 INFO - PROCESS | 1943 | Values: 186.0 186.0 188.0 185.0 185.0 187.0 190.0 259.0 185.0 182.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:78.30 Median:81.50 stddev:17.19 (21.1%) stddev-sans-first:18.22 09:18:18 INFO - PROCESS | 1943 | Values: 80.0 73.0 90.0 91.0 56.0 58.0 59.0 83.0 110.0 83.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:79.00 stddev:23.21 (29.4%) stddev-sans-first:24.61 09:18:18 INFO - PROCESS | 1943 | Values: 98.0 100.0 135.0 78.0 81.0 79.0 128.0 80.0 79.0 127.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:2.27 (2.6%) stddev-sans-first:2.35 09:18:18 INFO - PROCESS | 1943 | Values: 87.0 84.0 90.0 85.0 87.0 82.0 87.0 84.0 86.0 84.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:92.00 Median:92.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 09:18:18 INFO - PROCESS | 1943 | Values: 93.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 91.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:1.95 (2.3%) stddev-sans-first:2.06 09:18:18 INFO - PROCESS | 1943 | Values: 84.0 80.0 84.0 83.0 85.0 84.0 86.0 87.0 86.0 84.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:58.50 stddev:7.24 (12.4%) stddev-sans-first:7.57 09:18:18 INFO - PROCESS | 1943 | Values: 57.0 60.0 59.0 58.0 58.0 58.0 60.0 81.0 57.0 58.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:68.50 stddev:7.92 (11.6%) stddev-sans-first:7.65 09:18:18 INFO - PROCESS | 1943 | Values: 61.0 75.0 90.0 73.0 69.0 66.0 66.0 67.0 68.0 68.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:82.00 stddev:11.77 (14.3%) stddev-sans-first:12.34 09:18:18 INFO - PROCESS | 1943 | Values: 82.0 81.0 83.0 83.0 82.0 82.0 108.0 70.0 94.0 105.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:143.50 stddev:12.03 (8.4%) stddev-sans-first:12.71 09:18:18 INFO - PROCESS | 1943 | Values: 142.0 147.0 150.0 142.0 135.0 136.0 137.0 138.0 176.0 145.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:171.50 stddev:25.46 (14.8%) stddev-sans-first:26.70 09:18:18 INFO - PROCESS | 1943 | Values: 171.0 171.0 165.0 193.0 249.0 191.0 167.0 170.0 172.0 170.0 09:18:18 INFO - PROCESS | 1943 | 09:18:18 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:1.66 (2.4%) stddev-sans-first:1.73 09:18:18 INFO - PROCESS | 1943 | Values: 70.0 69.0 71.0 68.0 72.0 70.0 68.0 67.0 67.0 69.0 09:18:18 INFO - PROCESS | 1943 | -------- Summary: end -------- 09:18:18 INFO - PROCESS | 1943 | 09:18:19 INFO - TEST-INFO | 1943: exit 0 09:18:19 INFO - TEST-OK | kraken | took 59525ms 09:18:19 INFO - SUITE-END | took 1174s 09:18:19 INFO - Completed test suite (00:19:34) 09:18:19 INFO - javascript benchmark 09:18:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.795783274087, 3454.635419009493, 3485.494603282524, 3488.3023395320943, 3522.9823850880744, 5634.183043458696, 5693.572982026344, 5710.600257981938, 5710.857228569288, 5754.769809207637, 5617.887642247153, 5668.603197776158, 5685.602007859451, 5694.2312787773635, 5700.201971723962, 5178.637495375326, 5191.351081114861, 5199.09015922214, 5206.973965130169, 5293.15309550472, 5274.182501712241, 5286.4449232830575, 5289.576833853283, 5290.259363689087, 5302.098643230641, 5211.739413029352, 5280.366356037281, 5287.576993840503, 5303.23103150043, 5337.5196232339085, 5320.175372817239, 5378.569714422876, 5386.622936394454, 5393.325834270716, 5420.457954204588, 4814.590759785422, 4963.759060234941, 5109.693418394908, 5142.434332223453, 5190.974045129788, 5614.382417934024, 5639.238702775114, 5650.067738823089, 5659.151127330892, 5679.290088738908, 6271.122042913996, 6272.1219029335925, 6290.339514351019, 6301.432871041582, 6310.337414571496, 6102.51954821894, 6177.4749146322165, 6246.281677607088, 6262.812115636538, 6296.559240853142, 5836.037053886062, 5837.7956771513645, 5860.882782344329, 5866.413358664099, 5894.233749612523, 6321.494280457552, 6327.905081423783, 6339.429451349309, 6339.714712837934, 6345.365463453618, 6126.295476020315, 6132.600268413531, 6226.813195604139, 6231.3145553989025, 6231.594946328474, 6223.502119830403, 6233.812985610439, 6246.0, 6250.468710159687, 6253.937460625427, 6198.318184999646, 6258.217722784652, 6264.749410023639, 6271.905921411182, 6279.623222606659, 6127.494965205055, 6192.535559833031, 6244.313125556185, 6350.364963503613, 6356.396142366468, 4957.82647607332, 5005.5745261653165, 5006.949930500721, 5016.996600679879, 5029.748512574357, 4470.307102399133, 4475.552444755564, 4485.865424037284, 4493.865184044484, 4511.729296242236, 5703.828885133453, 5767.625104368202, 5804.245448091806, 5861.677607731615, 5868.647881127146, 6305.873882522324, 6362.363763623601, 6364.809055728336, 6385.808425747235, 6390.7763228286785, 5703.116017017369, 5723.59934804568, 5727.484526392562, 5747.453991870766, 5772.6536407815665, 5346.679199248058, 5392.0, 5435.701036443033, 5451.7733509960735, 5467.425920278313, 6301.275353334425, 6400.775972840928, 6418.614883106935, 6429.54993150475, 6432.195975503062, 6209.161763161916, 6320.652364120017, 6352.333005034589, 6372.299047104722, 6379.266384365857, 6382.968085159545, 6397.424231819158, 6452.064450654535, 6465.353464653497, 6475.579087359495, 5157.252198431282, 6207.255129384503, 6260.40526150015, 6263.592866463665, 6264.0, 5795.246617939641, 6145.8156255312415, 6262.46769024638, 6272.18461599989, 6353.809385718436, 6124.411449640054, 6128.3503509891225, 6131.713108457195, 6132.080177995836, 6133.8466538335115, 5978.432048955342, 6119.654885716854, 6144.969275153596, 6151.753929842936, 6166.260048794174, 5879.235699359056, 5985.700714964148, 6012.849678758066, 6033.0, 6115.143879856734, 5987.13186587951, 6098.26820781509, 6117.602355846856, 6158.815235542941, 6201.441870231701, 5942.108683697307, 6021.676049701183, 6024.975754121722, 6025.969870150797, 6087.086936959491], "value": 5741.769874697547, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.8025710101609, 528.7171363320624, 566.7392999220358, 591.4529060618927, 591.8638713095986, 26592.06927757529, 38784.806075969616, 39580.0, 39631.80184099075, 39678.99999999996, 42617.573824261744, 43260.0, 43275.56724432755, 43341.91645208871, 43458.044388002534, 8619.224269815715, 8832.425892316978, 9649.758756031102, 9908.405495670248, 9909.554070066864, 8773.95613021934, 8918.420302680304, 8925.687600933968, 9467.857982130274, 11853.70365740859, 11622.360770157638, 11713.1215158863, 11725.706857328592, 11729.0, 11729.413529323543, 11532.01977831881, 11658.708532286675, 11666.766664666744, 11674.883251167465, 11682.24065435744, 8669.436486628349, 10204.897951020543, 11519.48162332697, 11862.822057669056, 11862.940685296604, 29895.551566726517, 39446.211075778614, 39593.40609890825, 39627.60372396268, 39694.80152599219, 3223.935521289561, 3334.6832050955363, 3371.7134043606325, 3484.007057988461, 3788.488554045196, 28009.01968431095, 28557.0, 28755.424891502054, 29049.0, 29652.0, 2510.497900419923, 2712.2134580971497, 2721.8230814996964, 2726.8227565208394, 2802.649668791401, 340619.0, 341026.2948685266, 341346.0, 342013.0, 343503.2824835885, 35695.0, 62817.057744133876, 63191.6840415795, 63289.73420531564, 63410.0, 35740.28519429649, 35867.0, 35909.28181436357, 35977.46033809495, 35982.46026309607, 12605.30670813114, 14865.405383784528, 14889.47886823956, 15107.54677359681, 15129.319180636898, 9694.709158725249, 12733.108682392329, 12795.808062879065, 12796.680082998, 12811.231326120462, 7649.502782319131, 8110.432269741062, 8299.29455996247, 8357.916420835714, 8380.20388063133, 3410.3520331137006, 3460.584729832436, 3463.1861512544533, 3470.548828652259, 3494.7553671242767, 1013.975884356805, 1036.4403222259896, 1050.8965586134527, 1060.6764936694228, 1080.152080616712, 25985.090521832022, 28914.843406263513, 29467.263318417212, 29597.852010739807, 29825.850870745508, 912.8265629530368, 1130.7455822439886, 1139.8062329404022, 1157.9652610421701, 1180.8228765685217, 15264.0, 15290.0, 15365.154916479702, 15391.615209619626, 15611.985220960602, 632716.0, 637318.0, 646556.0, 647457.0, 647662.0, 60954.78090438167, 62107.0, 62112.06831897525, 62132.06801897975, 62201.755964881355, 1404.39610967283, 1451.136573738645, 1497.213962669607, 1502.7971223884635, 1504.3230546254229, 25751.484970300113, 29448.26379340448, 29553.408931821672, 29870.0, 30490.69509304967, 50703.74648126736, 51570.0, 51633.7418312906, 51639.225411619605, 51646.74176629093, 422432.0, 443387.0, 443481.0, 444137.0, 444590.0, 23940.162094326613, 26464.0, 26712.46575068449, 27365.58951615767, 27519.17442476729, 43783.78108109439, 43879.0, 43962.56037439585, 44171.0, 44560.55439445564, 36648.63351366559, 37346.813265933495, 37477.43783843299, 38103.618963810004, 38243.08784560816, 37206.81396593, 37661.623383765815, 38197.236055279296, 38537.614623853406, 39446.40830387604, 36495.27009459849, 37093.81453092717, 37226.8138659305, 37271.88184354474, 38268.0, 32774.34451310913, 33439.0, 33508.49737253894, 33576.32847342991, 33611.0, 20182.38540916694, 24538.754612453646, 24631.014759409423, 25104.62343064892, 25112.0], "value": 18494.831618833043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.29480172693485, 397.3423983307627, 399.69024006395045, 399.9860004899828, 412.1468560080634, 14379.424823007093, 19685.311014114508, 20753.584928301443, 20766.0, 21166.788332116674, 16030.5992350191, 18051.909740451312, 20092.397228083144, 20725.896370518127, 20917.895410522928, 5717.056685646874, 5958.6424814511265, 5970.0, 5975.880482390349, 5988.790392336269, 5772.451617096369, 5810.360860305363, 5819.7963071292515, 5831.096180092092, 5902.645841249518, 5643.8024669136585, 5834.0, 5861.3552509223955, 5870.295564532241, 5870.559708021894, 5580.581456390787, 5806.606414460542, 5824.854378640568, 5834.0, 5840.912386314188, 5099.770510327007, 5108.846734597968, 5134.794608215667, 5139.049275883936, 5139.871503212412, 24615.892284847225, 25727.0, 26120.0, 26248.475030499474, 26268.0, 2530.9746902530924, 2550.005497855838, 2556.8593727344987, 2850.9144725658057, 2926.1221633509863, 12721.55474558386, 13129.474821007148, 14586.562403127817, 15075.39698412073, 15508.45720399792, 1676.51381099481, 1677.4328503907311, 1696.2621259751925, 1763.4885883093887, 1790.97313540297, 90353.0, 106925.0, 134472.999999999, 137211.0, 140118.0, 10932.890671093188, 11563.710907227387, 11597.18819682614, 11623.0, 11664.066874650007, 14934.029288096239, 14998.850011499964, 15051.322690478955, 15070.397184112731, 15078.623034424227, 6345.809625711236, 7163.638908607348, 7377.889331659922, 8597.871031934526, 8742.431741936754, 8571.571421428904, 8575.442596231225, 8576.399652024298, 8671.609777560025, 8710.912890871137, 3749.5875453700073, 4565.497795242521, 4576.0, 4583.908321833612, 4634.21218392874, 1923.557581756204, 1967.2131147541097, 1976.2984140630156, 1991.442396129086, 2003.7795842457317, 884.3014018924977, 903.516618609041, 930.4928813796497, 1033.7312298802365, 1046.1682962045193, 19741.012949352476, 20229.0, 20332.796672033386, 20485.48786280325, 20523.897380513, 925.319889880937, 968.6900191938512, 981.3964411886781, 999.0808456220295, 1031.5873650539845, 10757.085647719874, 11004.0, 11036.613718519813, 11060.612878549213, 11082.722931926604, 517339.0, 523984.0, 524171.72025216825, 585306.0, 598033.009834948, 129494.0, 138416.30791846177, 149507.25246373916, 153795.0, 154741.2262938678, 449.7301619028622, 469.1508369850649, 472.6242637103511, 473.81521206729406, 474.122872685529, 10815.945920270347, 11149.163812714241, 11172.273802202832, 11245.100391968459, 11367.0, 53667.46332536625, 53875.46124538704, 54854.0, 55871.0, 56128.43871561232, 70478.64760676163, 71646.64176679084, 73351.63324183345, 78039.0, 82323.0, 7896.7236146734585, 7961.482503637245, 8051.597420129087, 8063.959680201796, 8497.405181637227, 9029.0, 11822.586209482972, 12272.938635306766, 12486.313252771184, 12754.872451275369, 10767.030967212988, 10869.782604348027, 11130.833037504606, 11130.888691113309, 11250.268732532686, 10650.946745266225, 10707.732306692395, 10784.406857622593, 10868.510917008596, 11015.779684406427, 11081.667549973514, 11111.222214444912, 11112.555497779998, 11332.036776874058, 11508.251963622337, 7720.459567830198, 8056.718014869686, 8148.470349427268, 8477.74566762998, 8552.529610871461, 10574.0, 10855.348679079281, 11022.779544409228, 11033.282836615594, 11184.720381990515], "value": 9302.986839762665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.660481484444, 1596.5529651697525, 1602.0868105180045, 1889.3009586453015, 2027.300581299451, 7865.213478652139, 8560.229579337858, 8785.692500762458, 8787.868181977276, 8796.340274479415, 8074.555899425529, 8273.793155171124, 8281.792955176124, 8282.37882158839, 8286.337093032558, 2430.5989511730586, 2459.077845807822, 2462.101333013446, 2463.7659422354845, 2466.3587467258553, 2387.6537902004197, 2454.104251948044, 2456.496418234258, 2461.778439940405, 2466.3094333586537, 2393.365758074112, 2434.9756502435016, 2439.9146029888957, 2463.4334103156284, 2473.0355161487037, 2397.11306816479, 2410.758924107584, 2419.7822196002357, 2419.9957017837664, 2424.248482970285, 2380.833341666084, 2400.5198960208027, 2407.8194135439912, 2408.229366602688, 2420.6006009008493, 8618.827623447496, 8652.8702069469, 8695.08701586337, 8723.214910658007, 8741.038485766561, 5191.273221748958, 5408.486193811582, 5432.945670543323, 5451.182322651594, 5455.863603409946, 3422.435298175798, 3485.466394786286, 3542.769719968194, 3549.5208146900354, 3560.305740380627, 977.9608815647366, 995.144176008632, 997.2321312589338, 1003.8845532763679, 1006.5269323418055, 11353.921377469127, 11727.765444691144, 11733.589324373692, 11749.412529373498, 11767.293962362286, 4502.864914052584, 4505.7521836298665, 4686.109639168547, 4717.103750287434, 4729.006908549243, 9186.81626367469, 9197.586108625128, 9205.401648892801, 9208.26333893287, 9215.631374744935, 5278.155495120763, 5281.39263984639, 5281.551068159172, 5289.973550132225, 5293.391260005072, 4281.957180428218, 4368.803403846835, 4387.508247195969, 4820.758962051954, 4936.531029552187, 3820.503334566489, 3851.7111216658864, 3914.2954268231424, 3923.6664883485223, 3966.087799806061, 1207.3359652191434, 1207.5169932027259, 1208.4259976511262, 1219.3415555599877, 1225.1240363140398, 920.6593560382702, 935.7613808478794, 940.6801687426308, 950.3680052764972, 978.882534095913, 5841.240638716939, 5874.181781841837, 5940.46535811779, 6132.110843927606, 6202.193714817045, 893.8748575199477, 931.6739141300626, 952.2619969523536, 985.84719368498, 1019.0828254570797, 3836.0793409582056, 4052.3313653247474, 4056.2571355924433, 4091.57038510958, 4107.938380924288, 10405.115565176893, 10806.189535784848, 10916.345019298868, 10934.617288395028, 10952.507137178845, 10759.623413180658, 10811.081058110149, 10864.891351086546, 10880.455977201267, 10896.78206435867, 8191.549464779494, 8266.421350505527, 8348.582570871433, 8350.248477637164, 8365.99608047026, 5678.4605462480995, 5890.970545147246, 5987.7604895805425, 5998.25021872266, 6058.636481811105, 9472.715818525456, 9539.618415263589, 9846.064623860722, 9849.064338887794, 9854.359466634645, 9796.65711700087, 9930.602775888883, 9949.850752238866, 9953.253505987224, 10067.949660251652, 5807.361190269152, 7141.64291785419, 7154.821129471805, 7162.7493037743425, 7162.89255661176, 8137.715179968673, 8426.325893928593, 8507.829843402973, 8524.849145365297, 8544.2310192083, 7129.82175445618, 7386.965824784642, 7425.554466732014, 7764.534127952341, 7882.09355924076, 7473.0, 7546.471746977879, 7558.811029724301, 7640.388768898586, 7741.690332386642, 6987.02181694553, 7026.0, 7079.716811327489, 7088.0, 7093.964530177316, 4924.409070911371, 4935.383077115361, 5102.617303702163, 5228.790848366023, 5232.816851410183, 5239.921401178909, 5273.367195936512, 5349.786008559614, 5386.676799392049, 5445.2104444854485], "value": 4601.78123181193, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.86745633105926, 315.3582459694521, 325.94621887388575, 331.8025774664074, 332.2507745034946, 5125.564327032207, 5598.0, 5651.1523271509295, 5945.197398351233, 5980.192673989016, 5796.739146738396, 5868.3838196989345, 5874.236349274599, 5881.470667639912, 5911.497522710564, 3950.42718805773, 4336.6989903029125, 4490.910181796354, 4528.705634133771, 4558.922727159171, 4408.448943882015, 4463.24373033419, 4516.887077823048, 4526.162659907911, 4545.909081818361, 3737.9346886137455, 4409.29451287794, 4441.156180325744, 4458.0, 4473.552644735517, 4448.822047118111, 4464.977675111636, 4470.955290447087, 4471.262241730111, 4483.39474170986, 3117.953230701541, 3541.6458354164633, 3886.3587508061137, 3894.383830710252, 3902.414637804352, 4948.505149485059, 5655.66066036035, 5664.320281566197, 5673.432656734335, 5686.54507639392, 5103.0, 5156.716380599066, 5159.561437277798, 5166.535011848951, 5173.224016397533, 5231.9476805232225, 5990.880182396328, 6295.811125666238, 6327.303996560375, 6342.0, 3840.9629400061826, 3947.7631342119566, 4266.786660666954, 4273.67947403946, 4293.742375457451, 5759.625624334405, 5980.28236611605, 6232.906506402408, 6615.272320044791, 6674.96289188423, 3940.566537680853, 4256.489221293403, 4280.743155410685, 4336.241157797436, 4341.0, 4435.423394958635, 4444.933326000112, 4455.376247325377, 4462.620677242406, 4463.776811159429, 5139.177731562932, 5209.140491818884, 5223.764930578133, 5225.8905950959115, 5228.55557277628, 6051.757929682852, 6102.237220347457, 6367.554271200971, 6378.585391949508, 6420.133282006869, 1439.4673970630724, 1531.4869518711357, 1535.431890200633, 1541.4758981946193, 1546.8917175797585, 989.737719504332, 1020.4438580973388, 1024.2522958240527, 1036.9066783989476, 1040.6461802987021, 4859.854204373874, 5854.443827836349, 5865.944130056546, 5874.823755287348, 5888.587798854039, 5523.834284971457, 6095.603785753912, 6441.3880681335195, 6494.610323380518, 6568.310327415648, 4852.902941941142, 5403.540699040547, 5844.6785426801935, 5908.645481271137, 5909.084091965752, 3120.937581248362, 3444.6727560881677, 3449.413599688059, 3468.843902024415, 3503.2292895562937, 6534.673266336664, 6840.486963477759, 6873.621950792654, 6884.51808373409, 6966.651667416609, 6026.608270462406, 6382.266039405435, 6734.393904548614, 6779.593224406552, 6787.219469760942, 5938.495227905676, 6029.974904266285, 6693.966530167416, 6794.796156115324, 6893.517453778288, 6523.151990241238, 6547.738090476328, 6549.0, 6558.1802274715665, 6559.540832141895, 6466.773662921775, 6481.449076828522, 6502.187226596676, 6514.902276465951, 6516.706748196438, 6064.757409703562, 6595.274519802914, 6984.546004509691, 7003.614801185984, 7008.264132266038, 3738.4018557030745, 3778.6977041837135, 4032.8790136295956, 4326.891827704207, 4354.301822289373, 5048.864005598711, 6420.101185834081, 6449.064885591657, 6654.0, 6684.799456016327, 5835.503982161564, 6434.077930383167, 6443.0, 6443.96778016107, 6453.9354606453335, 5668.716564171693, 6141.385861413823, 6187.659678717714, 6427.903581446189, 6448.613083215022, 5219.739013049409, 6238.532110091852, 6259.96870015647, 6283.7800676976085, 6284.214473190851, 6394.744210231539, 6408.231012278557, 6411.319736592407, 6420.6147631142285, 6427.035944608346, 5431.0, 5899.430844339113, 6269.435750782452, 6271.498280137487, 6296.464800492009], "value": 4614.224272130525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.7900734742841, 655.6492276632002, 661.5270081891447, 746.8095635612918, 749.1493722267546, 14662.12027278364, 14800.407983680654, 15109.320080596372, 15238.542843714693, 15271.770923436157, 13812.033157678967, 13838.169709817417, 13928.233947132929, 13949.372278247478, 13994.650133746662, 738.6897503048732, 760.1448370583093, 916.8670542771295, 920.2821798996794, 923.3536524432924, 906.365544119116, 939.3940908114262, 942.6041062753626, 942.8585712143165, 945.125758673228, 984.620920945434, 992.5483904823324, 993.4635296939643, 993.5181437003048, 994.6916455898695, 756.791882232385, 940.8729821474082, 981.381729510411, 983.6901376066552, 986.5708416838662, 725.5247812682701, 730.3135053050116, 730.4193166432646, 731.1007460823164, 733.1495465260273, 14802.481913132988, 14879.33043013067, 14882.851171488255, 14887.851121488755, 14890.553283401516, 12379.504819807196, 12385.814212786816, 12425.44085516154, 12707.809382859265, 12743.681407964783, 9864.704058878173, 10025.949870250675, 10042.598296068149, 10124.34191777532, 10554.997275258915, 3402.2344972381266, 3475.131217195701, 3514.947275790865, 3535.681788639009, 3583.3549961007006, 71978.0, 74708.62645686792, 75074.24925750727, 75141.62429187873, 76537.61731191364, 9229.723108306762, 9487.715368538955, 9604.471754053595, 9628.518574071268, 9682.0, 16941.83058169427, 21550.461238469165, 21613.0, 21622.459438513848, 21645.67531487029, 7354.458584438785, 7405.925940740632, 7453.329200371993, 7480.3641690456925, 7549.584772837547, 4078.6329230368815, 4140.337545992687, 4144.7513149211145, 4147.502299724052, 4149.107941792529, 3590.3537363274863, 3664.59689434162, 3664.8350824212976, 3671.0271777978855, 3677.062349100962, 642.2966851297822, 659.4691273524762, 660.2044536333707, 663.4128796015561, 664.4518272425269, 490.9779059942311, 545.9481349271813, 578.3580225949192, 605.2736715940889, 614.6035806904522, 7644.80887977805, 7786.7663970080985, 7811.414143939227, 7866.0, 7903.36773058154, 565.191775760658, 566.2723400430509, 584.065073008129, 586.305228304452, 593.1991811055041, 3751.174741556853, 3769.849206031783, 3790.677792387678, 3841.8271177797064, 3859.6333348331864, 82182.0, 83720.0, 84315.0, 85958.57020714856, 86676.13323866681, 60730.0, 65106.348936510025, 66339.66830165818, 68189.0, 68645.3135468649, 60067.0, 64082.67958660177, 64410.67794660997, 64417.0, 64662.35337646658, 7279.344858962507, 7320.96339518299, 7485.962570187115, 7518.511296765692, 7572.318491335806, 40754.18491630092, 41481.58518414898, 42248.36627450652, 42355.72932811897, 43160.78419607882, 46855.062898742515, 55193.0, 60548.39451605428, 60670.0, 61040.694796525735, 10097.495125243562, 10118.392896426238, 10368.844467332845, 10392.532336045047, 10723.03492685662, 10615.840762388725, 10780.407077610493, 10877.619283325046, 10938.835917461085, 11186.161037922026, 8241.752747417588, 10046.296759227076, 10077.445740484343, 10279.126274266771, 10597.523111459848, 10111.140553053074, 10220.335678180894, 10239.027292407209, 10518.78962420732, 10560.947195263974, 9965.451900145272, 10183.490825458846, 10270.794584108426, 10314.793704126025, 10443.582256709646, 8678.392512524062, 8782.824343512966, 8888.866666999871, 9142.497162656118, 9227.907720922705, 8596.441231319945, 8629.913700863162, 8727.345449091217, 8894.33292503052, 8908.287337013153], "value": 6595.479322638107, "unit": "score"}], "name": "dromaeo_css", "value": 7191.804982424207, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 108.0, 111.0, 114.0, 114.0, 113.0, 114.0, 104.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 144.0, 173.0, 119.0, 150.0, 156.0, 148.0, 175.0, 122.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 188.0, 185.0, 185.0, 187.0, 190.0, 259.0, 185.0, 182.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 90.0, 91.0, 56.0, 58.0, 59.0, 83.0, 110.0, 83.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 135.0, 78.0, 81.0, 79.0, 128.0, 80.0, 79.0, 127.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 90.0, 85.0, 87.0, 82.0, 87.0, 84.0, 86.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 91.0], "value": 92.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 84.0, 83.0, 85.0, 84.0, 86.0, 87.0, 86.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 59.0, 58.0, 58.0, 58.0, 60.0, 81.0, 57.0, 58.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 75.0, 90.0, 73.0, 69.0, 66.0, 66.0, 67.0, 68.0, 68.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 83.0, 82.0, 82.0, 108.0, 70.0, 94.0, 105.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 150.0, 142.0, 135.0, 136.0, 137.0, 138.0, 176.0, 145.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 165.0, 193.0, 249.0, 191.0, 167.0, 170.0, 172.0, 170.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 68.0, 72.0, 70.0, 68.0, 67.0, 67.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1504.3999999999999, "alertThreshold": 2.0}]} 09:18:19 INFO - Return code: 0 09:18:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:18:19 ERROR - # TBPL SUCCESS # 09:18:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:19 INFO - 2016-04-23 09:18:19.770 screenresolution[1947:22281] starting screenresolution argv=screenresolution get 09:18:19 INFO - 2016-04-23 09:18:19.772 screenresolution[1947:22281] Display 0: 1600x1200x32@60 09:18:19 INFO - 2016-04-23 09:18:19.778 screenresolution[1948:22284] starting screenresolution argv=screenresolution list 09:18:19 INFO - Available Modes on Display 0 09:18:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:20 INFO - Intel Iris: 09:18:20 INFO - Chipset Model: Intel Iris 09:18:20 INFO - Type: GPU 09:18:20 INFO - Bus: Built-In 09:18:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:20 INFO - Vendor: Intel (0x8086) 09:18:20 INFO - Device ID: 0x0a2e 09:18:20 INFO - Revision ID: 0x0009 09:18:20 INFO - Displays: 09:18:20 INFO - Display: 09:18:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:20 INFO - Display Serial Number: 1600x1200 60 09:18:20 INFO - Main Display: Yes 09:18:20 INFO - Mirror: Off 09:18:20 INFO - Online: Yes 09:18:20 INFO - Rotation: Supported 09:18:20 INFO - Return code: 0 09:18:20 INFO - Running post-action listener: _resource_record_post_action 09:18:20 INFO - Running post-run listener: _resource_record_post_run 09:18:21 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 274432; Write bytes: 695583744; Read time: 3; Write time: 1970 09:18:21 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388450816; Write bytes: 382985728; Read time: 28692; Write time: 709 09:18:21 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 274432; Write bytes: 297000448; Read time: 3; Write time: 1223 09:18:21 INFO - Running post-run listener: _upload_blobber_files 09:18:21 INFO - Blob upload gear active. 09:18:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:18:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:18:21 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:18:21 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:18:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:18:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:18:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:18:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:22 INFO - (blobuploader) - INFO - Done attempting. 09:18:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:18:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:18:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:23 INFO - (blobuploader) - INFO - Done attempting. 09:18:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:18:23 INFO - Return code: 0 09:18:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:18:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:18:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5dd7d7fdeca2b66813f6eda5053b7d2c1a5f7067f0ca93d55e4515596aa030184a560e917d9f239e961487b84ad9635825e3b4ebb85be9034295d985debe181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76a590ac08aca19fc2f3506b9027baaf1a4de149f6b09d47c28022a9e910cbc9f870ccb57cc77a8cd37bc9bd56e375d18a8ef73c94a6e34edf724de049886aaa"} 09:18:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:18:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:18:23 INFO - Contents: 09:18:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5dd7d7fdeca2b66813f6eda5053b7d2c1a5f7067f0ca93d55e4515596aa030184a560e917d9f239e961487b84ad9635825e3b4ebb85be9034295d985debe181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76a590ac08aca19fc2f3506b9027baaf1a4de149f6b09d47c28022a9e910cbc9f870ccb57cc77a8cd37bc9bd56e375d18a8ef73c94a6e34edf724de049886aaa"} 09:18:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:18:23 INFO - Copying logs to upload dir... 09:18:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:18:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.631848 ========= master_lag: 7.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-23 09:18:30.365143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-23 09:18:30.373215) ========= 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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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/Fx-Team/sha512/f5dd7d7fdeca2b66813f6eda5053b7d2c1a5f7067f0ca93d55e4515596aa030184a560e917d9f239e961487b84ad9635825e3b4ebb85be9034295d985debe181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76a590ac08aca19fc2f3506b9027baaf1a4de149f6b09d47c28022a9e910cbc9f870ccb57cc77a8cd37bc9bd56e375d18a8ef73c94a6e34edf724de049886aaa"} build_url:https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011462 build_url: 'https://queue.taskcluster.net/v1/task/RBRfCEXNQQePSRwvZixiRQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5dd7d7fdeca2b66813f6eda5053b7d2c1a5f7067f0ca93d55e4515596aa030184a560e917d9f239e961487b84ad9635825e3b4ebb85be9034295d985debe181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76a590ac08aca19fc2f3506b9027baaf1a4de149f6b09d47c28022a9e910cbc9f870ccb57cc77a8cd37bc9bd56e375d18a8ef73c94a6e34edf724de049886aaa"}' ========= master_lag: 7.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-23 09:18:37.645569) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-04-23 09:18:37.645927) ========= 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.Ev1T6EBLxl/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.Kk8nIA86N8/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005544 ========= master_lag: 19.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-04-23 09:18:56.680624) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-04-23 09:18:56.683219) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-04-23 09:19:03.929016) ========= ========= Total master_lag: 45.94 =========