builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1461253878.38 results: success (0) buildid: 20160421072021 builduid: db4c775fd49a41908af68c84f0e47439 revision: be6f45632b33c2578b1daca6d09c9be0df142e96 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:18.378678) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:18.379077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:18.379358) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:18.493188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 08:51:18.493649) ========= 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.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-21 08:51:18-- 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% 152M=0s 2016-04-21 08:51:18 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112238 ========= master_lag: 1.78 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 08:51:20.384851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 08:51:20.385150) ========= 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.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.019671 ========= master_lag: 1.15 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 08:51:21.553093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-21 08:51:21.553414) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev be6f45632b33c2578b1daca6d09c9be0df142e96 --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 be6f45632b33c2578b1daca6d09c9be0df142e96 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-21 08:51:21,648 truncating revision to first 12 chars 2016-04-21 08:51:21,648 Setting DEBUG logging. 2016-04-21 08:51:21,648 attempt 1/10 2016-04-21 08:51:21,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/be6f45632b33?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 08:51:21,889 unpacking tar archive at: fx-team-be6f45632b33/testing/mozharness/ program finished with exit code 0 elapsedTime=0.487792 ========= master_lag: 5.46 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-21 08:51:27.503268) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:27.505623) ========= script_repo_revision: be6f45632b33c2578b1daca6d09c9be0df142e96 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 08:51:27.506034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-21 08:51:27.506330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-21 08:51:34.635886) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-21 08:51:34.636178) ========= /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.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:51:34 INFO - MultiFileLogger online at 20160421 08:51:34 in /builds/slave/test 08:51:34 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:51:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:34 INFO - {'append_to_log': False, 08:51:34 INFO - 'base_work_dir': '/builds/slave/test', 08:51:34 INFO - 'blob_upload_branch': 'Fx-Team', 08:51:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:34 INFO - 'branch': 'Fx-Team', 08:51:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:34 INFO - 'config_files': ('talos/mac_config.py',), 08:51:34 INFO - 'default_actions': ('clobber', 08:51:34 INFO - 'read-buildbot-config', 08:51:34 INFO - 'download-and-extract', 08:51:34 INFO - 'populate-webroot', 08:51:34 INFO - 'create-virtualenv', 08:51:34 INFO - 'install', 08:51:34 INFO - 'run-tests'), 08:51:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:34 INFO - 'download_minidump_stackwalk': True, 08:51:34 INFO - 'download_symbols': 'ondemand', 08:51:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:34 INFO - 'installer_path': 'installer.exe', 08:51:34 INFO - 'log_level': 'info', 08:51:34 INFO - 'log_name': 'talos', 08:51:34 INFO - 'log_to_console': True, 08:51:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:51:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:51:34 INFO - 'opt_config_files': (), 08:51:34 INFO - 'pip_index': False, 08:51:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:51:34 INFO - 'cmd': ('bash', 08:51:34 INFO - '-c', 08:51:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:51:34 INFO - 'enabled': True, 08:51:34 INFO - 'halt_on_failure': False, 08:51:34 INFO - 'name': 'check_screen_resolution'},), 08:51:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:51:34 INFO - 'cmd': ('bash', 08:51:34 INFO - '-c', 08:51:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:51:34 INFO - 'enabled': True, 08:51:34 INFO - 'halt_on_failure': False, 08:51:34 INFO - 'name': 'check_screen_resolution'},), 08:51:34 INFO - 'run_cmd_checks_enabled': True, 08:51:34 INFO - 'sps_profile': False, 08:51:34 INFO - 'sps_profile_interval': 0, 08:51:34 INFO - 'suite': 'dromaeojs', 08:51:34 INFO - 'system_bits': '32', 08:51:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:34 INFO - 'title': 't-yosemite-r7-0052', 08:51:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:34 INFO - 'use_talos_json': True, 08:51:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:34 INFO - 'work_dir': 'build'} 08:51:34 INFO - ##### 08:51:34 INFO - ##### Running clobber step. 08:51:34 INFO - ##### 08:51:34 INFO - Running pre-action listener: _resource_record_pre_action 08:51:34 INFO - Running main action method: clobber 08:51:34 INFO - rmtree: /builds/slave/test/build 08:51:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:36 INFO - Running post-action listener: _resource_record_post_action 08:51:36 INFO - ##### 08:51:36 INFO - ##### Running read-buildbot-config step. 08:51:36 INFO - ##### 08:51:36 INFO - Running pre-action listener: _resource_record_pre_action 08:51:36 INFO - Running main action method: read_buildbot_config 08:51:36 INFO - Using buildbot properties: 08:51:36 INFO - { 08:51:36 INFO - "project": "", 08:51:36 INFO - "product": "firefox", 08:51:36 INFO - "script_repo_revision": "production", 08:51:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:51:36 INFO - "repository": "", 08:51:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:51:36 INFO - "buildid": "20160421072021", 08:51:36 INFO - "pgo_build": "False", 08:51:36 INFO - "basedir": "/builds/slave/test", 08:51:36 INFO - "buildnumber": 748, 08:51:36 INFO - "slavename": "t-yosemite-r7-0052", 08:51:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:51:36 INFO - "platform": "yosemite_r7", 08:51:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:51:36 INFO - "branch": "fx-team", 08:51:36 INFO - "repo_path": "integration/fx-team", 08:51:36 INFO - "revision": "be6f45632b33c2578b1daca6d09c9be0df142e96", 08:51:36 INFO - "stage_platform": "macosx64", 08:51:36 INFO - "builduid": "db4c775fd49a41908af68c84f0e47439", 08:51:36 INFO - "slavebuilddir": "test" 08:51:36 INFO - } 08:51:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 08:51:36 INFO - Running post-action listener: _resource_record_post_action 08:51:36 INFO - ##### 08:51:36 INFO - ##### Running download-and-extract step. 08:51:36 INFO - ##### 08:51:36 INFO - Running pre-action listener: _resource_record_pre_action 08:51:36 INFO - Running main action method: download_and_extract 08:51:36 INFO - mkdir: /builds/slave/test/build/tests 08:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:51:36 INFO - https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:51:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:51:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:51:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:39 INFO - Downloaded 1199 bytes. 08:51:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:51:39 INFO - Using the following test package requirements: 08:51:39 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:51:39 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 08:51:39 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 08:51:39 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'jsshell-mac.zip'], 08:51:39 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 08:51:39 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:51:39 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 08:51:39 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 08:51:39 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 08:51:39 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:51:39 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 08:51:39 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:51:39 INFO - https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:51:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:51:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:51:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:41 INFO - Downloaded 18099892 bytes. 08:51:41 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:51:41 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:51:44 INFO - Return code: 0 08:51:44 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:51: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:51:44 INFO - https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:51:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:51:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:51:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:47 INFO - Downloaded 18099892 bytes. 08:51:47 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:51:47 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:51:50 INFO - Return code: 0 08:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:51:50 INFO - https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:51:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:51:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:51:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:52 INFO - Downloaded 10845952 bytes. 08:51:52 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:51:52 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:51:52 INFO - Return code: 0 08:51: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:51:52 INFO - https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:51:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:51:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:51:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 08:51:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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:51:55 INFO - Downloaded 109956921 bytes. 08:51:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:51:55 INFO - mkdir: /builds/slave/test/properties 08:51:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:51:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:51:55 INFO - Contents: 08:51:55 INFO - build_url:https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:51:57 INFO - Running post-action listener: _resource_record_post_action 08:51:57 INFO - Running post-action listener: set_extra_try_arguments 08:51:57 INFO - ##### 08:51:57 INFO - ##### Running populate-webroot step. 08:51:57 INFO - ##### 08:51:57 INFO - Running pre-action listener: _resource_record_pre_action 08:51:57 INFO - Running main action method: populate_webroot 08:51:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:51:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:51:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:51:57 INFO - '--tppagecycles', 08:51:57 INFO - '7'], 08:51:57 INFO - 'tests': ['tsvgm']}}, 08:51:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:51:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:51:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:51:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:51:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['damp', 'tps']}, 08:51:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'tests': ['damp', 'tps']}, 08:51:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['dromaeo_dom']}, 08:51:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:51:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tsvgx', 08:51:57 INFO - 'tsvgr_opacity', 08:51:57 INFO - 'tart', 08:51:57 INFO - 'tscrollx', 08:51:57 INFO - 'cart']}, 08:51:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:51:57 INFO - 'tsvgr_opacity', 08:51:57 INFO - 'tart', 08:51:57 INFO - 'tscrollx', 08:51:57 INFO - 'cart']}, 08:51:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tp5o']}, 08:51:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'tests': ['tp5o']}, 08:51:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s', 08:51:57 INFO - '--xperf_path', 08:51:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:57 INFO - 'tests': ['tp5n']}, 08:51:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:57 INFO - 'talos_options': ['--xperf_path', 08:51:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:57 INFO - 'tests': ['tp5n']}}, 08:51:57 INFO - 'talos.zip': {'path': '', 08:51:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:51:57 INFO - Running post-action listener: _resource_record_post_action 08:51:57 INFO - ##### 08:51:57 INFO - ##### Running create-virtualenv step. 08:51:57 INFO - ##### 08:51:57 INFO - Running pre-action listener: _resource_record_pre_action 08:51:57 INFO - Running main action method: create_virtualenv 08:51:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:51:57 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:51:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:51:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:51:57 INFO - Using real prefix '/tools/python27' 08:51:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:51:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:00 INFO - Installing pip.................done. 08:52:00 INFO - Return code: 0 08:52:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:00 INFO - 'HOME': '/Users/cltbld', 08:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:00 INFO - 'LOGNAME': 'cltbld', 08:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:00 INFO - 'MOZ_NO_REMOTE': '1', 08:52:00 INFO - 'NO_EM_RESTART': '1', 08:52:00 INFO - 'PAGER': '/bin/cat', 08:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:00 INFO - 'PWD': '/builds/slave/test', 08:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:00 INFO - 'SHELL': '/bin/bash', 08:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:00 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:00 INFO - 'USER': 'cltbld', 08:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:00 INFO - 'XPC_FLAGS': '0x0', 08:52:00 INFO - 'XPC_SERVICE_NAME': '0', 08:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:00 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:52:00 INFO - Cleaning up... 08:52:00 INFO - Return code: 0 08:52:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:00 INFO - 'HOME': '/Users/cltbld', 08:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:00 INFO - 'LOGNAME': 'cltbld', 08:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:00 INFO - 'MOZ_NO_REMOTE': '1', 08:52:00 INFO - 'NO_EM_RESTART': '1', 08:52:00 INFO - 'PAGER': '/bin/cat', 08:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:00 INFO - 'PWD': '/builds/slave/test', 08:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:00 INFO - 'SHELL': '/bin/bash', 08:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:00 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:00 INFO - 'USER': 'cltbld', 08:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:00 INFO - 'XPC_FLAGS': '0x0', 08:52:00 INFO - 'XPC_SERVICE_NAME': '0', 08:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:01 INFO - Downloading/unpacking psutil>=0.7.1 08:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:04 INFO - Installing collected packages: psutil 08:52:04 INFO - Running setup.py install for psutil 08:52:04 INFO - building 'psutil._psutil_osx' extension 08:52:04 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:52:04 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:52:04 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:52:05 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:52:05 INFO - building 'psutil._psutil_posix' extension 08:52:05 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:52:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:52:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:52:05 INFO - ^ 08:52:05 INFO - 1 warning generated. 08:52:05 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:52:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:05 INFO - Successfully installed psutil 08:52:05 INFO - Cleaning up... 08:52:05 INFO - Return code: 0 08:52:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:52: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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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', '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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:52: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 mozsystemmonitor==0.0.0 08:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:05 INFO - 'HOME': '/Users/cltbld', 08:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:05 INFO - 'LOGNAME': 'cltbld', 08:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:05 INFO - 'MOZ_NO_REMOTE': '1', 08:52:05 INFO - 'NO_EM_RESTART': '1', 08:52:05 INFO - 'PAGER': '/bin/cat', 08:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:05 INFO - 'PWD': '/builds/slave/test', 08:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:05 INFO - 'SHELL': '/bin/bash', 08:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:05 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:05 INFO - 'USER': 'cltbld', 08:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:05 INFO - 'XPC_FLAGS': '0x0', 08:52:05 INFO - 'XPC_SERVICE_NAME': '0', 08:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:52: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:52: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:52: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:52: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:52: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:52: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:52:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:52:08 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:52:08 INFO - Installing collected packages: mozsystemmonitor 08:52:08 INFO - Running setup.py install for mozsystemmonitor 08:52:08 INFO - Successfully installed mozsystemmonitor 08:52:08 INFO - Cleaning up... 08:52:08 INFO - Return code: 0 08:52:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:52:08 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:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 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:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:08 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:52:08 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:52:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:08 INFO - 'HOME': '/Users/cltbld', 08:52:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:08 INFO - 'LOGNAME': 'cltbld', 08:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:08 INFO - 'MOZ_NO_REMOTE': '1', 08:52:08 INFO - 'NO_EM_RESTART': '1', 08:52:08 INFO - 'PAGER': '/bin/cat', 08:52:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:08 INFO - 'PWD': '/builds/slave/test', 08:52:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:08 INFO - 'SHELL': '/bin/bash', 08:52:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:08 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:08 INFO - 'USER': 'cltbld', 08:52:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:08 INFO - 'XPC_FLAGS': '0x0', 08:52:08 INFO - 'XPC_SERVICE_NAME': '0', 08:52:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:08 INFO - Downloading/unpacking blobuploader==1.2.4 08:52: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:52: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:52: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:52: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:52: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:52: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:52:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:52:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:52:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:12 INFO - Downloading docopt-0.6.1.tar.gz 08:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:52:12 INFO - Installing collected packages: blobuploader, requests, docopt 08:52:12 INFO - Running setup.py install for blobuploader 08:52:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:52:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:52:12 INFO - Running setup.py install for requests 08:52:12 INFO - Running setup.py install for docopt 08:52:13 INFO - Successfully installed blobuploader requests docopt 08:52:13 INFO - Cleaning up... 08:52:13 INFO - Return code: 0 08:52:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:13 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:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:13 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:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:13 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:13 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:52:13 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:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:13 INFO - 'HOME': '/Users/cltbld', 08:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:13 INFO - 'LOGNAME': 'cltbld', 08:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:13 INFO - 'MOZ_NO_REMOTE': '1', 08:52:13 INFO - 'NO_EM_RESTART': '1', 08:52:13 INFO - 'PAGER': '/bin/cat', 08:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:13 INFO - 'PWD': '/builds/slave/test', 08:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:13 INFO - 'SHELL': '/bin/bash', 08:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:13 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:13 INFO - 'USER': 'cltbld', 08:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:13 INFO - 'XPC_FLAGS': '0x0', 08:52:13 INFO - 'XPC_SERVICE_NAME': '0', 08:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-o4zN4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ov_LUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-K9V8Mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-wm1nbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-B5hBvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-MaWIrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-LXTjkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3ny5fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-G01jXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-tK2MKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-EkFRpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3_8VQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ETfYs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-l8lmXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Dj8n3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-P22vcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-36kHeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:52:15 INFO - Running setup.py install for manifestparser 08:52:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:52:15 INFO - Running setup.py install for mozcrash 08:52:15 INFO - Running setup.py install for mozdebug 08:52:15 INFO - Running setup.py install for mozdevice 08:52:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:52:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:52:15 INFO - Running setup.py install for mozfile 08:52:15 INFO - Running setup.py install for mozhttpd 08:52:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Running setup.py install for mozinfo 08:52:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Running setup.py install for mozInstall 08:52:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Running setup.py install for mozleak 08:52:16 INFO - Running setup.py install for mozlog 08:52:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Running setup.py install for moznetwork 08:52:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:52:16 INFO - Running setup.py install for mozprocess 08:52:16 INFO - Running setup.py install for mozprofile 08:52:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:52:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:52:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:52:17 INFO - Running setup.py install for mozrunner 08:52:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:52:17 INFO - Running setup.py install for mozscreenshot 08:52:17 INFO - Running setup.py install for moztest 08:52:17 INFO - Running setup.py install for mozversion 08:52:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:52:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:52:17 INFO - Cleaning up... 08:52:17 INFO - Return code: 0 08:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:17 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:17 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:52:17 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:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:17 INFO - 'HOME': '/Users/cltbld', 08:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:17 INFO - 'LOGNAME': 'cltbld', 08:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:17 INFO - 'MOZ_NO_REMOTE': '1', 08:52:17 INFO - 'NO_EM_RESTART': '1', 08:52:17 INFO - 'PAGER': '/bin/cat', 08:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:17 INFO - 'PWD': '/builds/slave/test', 08:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:17 INFO - 'SHELL': '/bin/bash', 08:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:17 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:17 INFO - 'USER': 'cltbld', 08:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:17 INFO - 'XPC_FLAGS': '0x0', 08:52:17 INFO - 'XPC_SERVICE_NAME': '0', 08:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:17 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-mQWlCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ma0F7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-l6BSWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-bE_Z46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-rmniev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3faZEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CQaWnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-bbNHVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-fXxWXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:18 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:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-1KDDSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-j3vOUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-NMlV6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vq0B7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-zBdPFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-BfECe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-9wkCAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:19 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:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Zr4Td3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:19 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:52:19 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:52:19 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:52:19 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:52:19 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:52:19 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:52:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:22 INFO - Downloading blessings-1.6.tar.gz 08:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:52:22 INFO - Installing collected packages: blessings 08:52:22 INFO - Running setup.py install for blessings 08:52:22 INFO - Successfully installed blessings 08:52:22 INFO - Cleaning up... 08:52:22 INFO - Return code: 0 08:52:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:22 INFO - Reading from file tmpfile_stdout 08:52:22 INFO - Current package versions: 08:52:22 INFO - blessings == 1.6 08:52:22 INFO - blobuploader == 1.2.4 08:52:22 INFO - docopt == 0.6.1 08:52:22 INFO - manifestparser == 1.1 08:52:22 INFO - mozInstall == 1.12 08:52:22 INFO - mozcrash == 0.17 08:52:22 INFO - mozdebug == 0.1 08:52:22 INFO - mozdevice == 0.48 08:52:22 INFO - mozfile == 1.2 08:52:22 INFO - mozhttpd == 0.7 08:52:22 INFO - mozinfo == 0.9 08:52:22 INFO - mozleak == 0.1 08:52:22 INFO - mozlog == 3.1 08:52:22 INFO - moznetwork == 0.27 08:52:22 INFO - mozprocess == 0.22 08:52:22 INFO - mozprofile == 0.28 08:52:22 INFO - mozrunner == 6.11 08:52:22 INFO - mozscreenshot == 0.1 08:52:22 INFO - mozsystemmonitor == 0.0 08:52:22 INFO - moztest == 0.7 08:52:22 INFO - mozversion == 1.4 08:52:22 INFO - psutil == 3.1.1 08:52:22 INFO - requests == 1.2.3 08:52:22 INFO - wsgiref == 0.1.2 08:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:22 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:22 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:52:22 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:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:22 INFO - 'HOME': '/Users/cltbld', 08:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:22 INFO - 'LOGNAME': 'cltbld', 08:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:22 INFO - 'MOZ_NO_REMOTE': '1', 08:52:22 INFO - 'NO_EM_RESTART': '1', 08:52:22 INFO - 'PAGER': '/bin/cat', 08:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:22 INFO - 'PWD': '/builds/slave/test', 08:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:22 INFO - 'SHELL': '/bin/bash', 08:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:22 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:22 INFO - 'USER': 'cltbld', 08:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:22 INFO - 'XPC_FLAGS': '0x0', 08:52:22 INFO - 'XPC_SERVICE_NAME': '0', 08:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 INFO - Cleaning up... 08:52:23 INFO - Return code: 0 08:52:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:52: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:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:23 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:23 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:52:23 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:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:23 INFO - 'HOME': '/Users/cltbld', 08:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:23 INFO - 'LOGNAME': 'cltbld', 08:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:23 INFO - 'MOZ_NO_REMOTE': '1', 08:52:23 INFO - 'NO_EM_RESTART': '1', 08:52:23 INFO - 'PAGER': '/bin/cat', 08:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:23 INFO - 'PWD': '/builds/slave/test', 08:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:23 INFO - 'SHELL': '/bin/bash', 08:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:23 INFO - 'USER': 'cltbld', 08:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:23 INFO - 'XPC_FLAGS': '0x0', 08:52:23 INFO - 'XPC_SERVICE_NAME': '0', 08:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:23 INFO - Downloading/unpacking jsonschema 08:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:23 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:52:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:52:25 INFO - Downloading/unpacking functools32 (from jsonschema) 08:52:25 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:52:25 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:52:25 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:52:25 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:52:25 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:52:25 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:52:26 INFO - Downloading functools32-3.2.3-2.tar.gz 08:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:52:26 INFO - warning: no files found matching '*.txt' 08:52:26 INFO - no previously-included directories found matching 'build' 08:52:26 INFO - no previously-included directories found matching 'dist' 08:52:26 INFO - no previously-included directories found matching '.git*' 08:52:26 INFO - Installing collected packages: jsonschema, functools32 08:52:26 INFO - Running setup.py install for functools32 08:52:26 INFO - warning: no files found matching '*.txt' 08:52:26 INFO - no previously-included directories found matching 'build' 08:52:26 INFO - no previously-included directories found matching 'dist' 08:52:26 INFO - no previously-included directories found matching '.git*' 08:52:26 INFO - Successfully installed jsonschema functools32 08:52:26 INFO - Cleaning up... 08:52:26 INFO - Return code: 0 08:52:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:26 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 0x102109a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102119420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd188495d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, '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.bA5zIqwCji/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.8BcGW3QQcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:52:26 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:52:26 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:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:26 INFO - 'HOME': '/Users/cltbld', 08:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:26 INFO - 'LOGNAME': 'cltbld', 08:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:26 INFO - 'MOZ_NO_REMOTE': '1', 08:52:26 INFO - 'NO_EM_RESTART': '1', 08:52:26 INFO - 'PAGER': '/bin/cat', 08:52:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:26 INFO - 'PWD': '/builds/slave/test', 08:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:26 INFO - 'SHELL': '/bin/bash', 08:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:52:26 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:26 INFO - 'USER': 'cltbld', 08:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:26 INFO - 'XPC_FLAGS': '0x0', 08:52:26 INFO - 'XPC_SERVICE_NAME': '0', 08:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:26 INFO - Downloading/unpacking flake8 08:52:26 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:52:26 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:52:26 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:52:26 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:52:26 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:52:26 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:52:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:52:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:52:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:29 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:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:52:30 INFO - no previously-included directories found matching 'docs/_build' 08:52:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:52:30 INFO - Running setup.py install for pep8 08:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:52:30 INFO - no previously-included directories found matching 'docs/_build' 08:52:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:52:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:52:30 INFO - Cleaning up... 08:52:30 INFO - Return code: 0 08:52:30 INFO - Running post-action listener: _resource_record_post_action 08:52:30 INFO - Running post-action listener: _start_resource_monitoring 08:52:30 INFO - Starting resource monitoring. 08:52:30 INFO - ##### 08:52:30 INFO - ##### Running install step. 08:52:30 INFO - ##### 08:52:30 INFO - Running pre-action listener: _resource_record_pre_action 08:52:30 INFO - Running main action method: install 08:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:30 INFO - Reading from file tmpfile_stdout 08:52:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:52:31 INFO - Reading from file tmpfile_stdout 08:52:31 INFO - Output received: 08:52:31 INFO - Usage: mozinstall [options] installer 08:52:31 INFO - Options: 08:52:31 INFO - -h, --help show this help message and exit 08:52:31 INFO - -d DEST, --destination=DEST 08:52:31 INFO - Directory to install application into. [default: 08:52:31 INFO - "/builds/slave/test"] 08:52:31 INFO - --app=APP Application being installed. [default: firefox] 08:52:31 INFO - mkdir: /builds/slave/test/build/application 08:52:31 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:52:31 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:53:04 INFO - Reading from file tmpfile_stdout 08:53:04 INFO - Output received: 08:53:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:53:04 INFO - Running post-action listener: _resource_record_post_action 08:53:04 INFO - ##### 08:53:04 INFO - ##### Running run-tests step. 08:53:04 INFO - ##### 08:53:04 INFO - Running pre-action listener: _resource_record_pre_action 08:53:04 INFO - Running main action method: run_tests 08:53:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:04 INFO - 2016-04-21 08:53:04.190 screenresolution[1934:11167] starting screenresolution argv=screenresolution get 08:53:04 INFO - 2016-04-21 08:53:04.192 screenresolution[1934:11167] Display 0: 1600x1200x32@60 08:53:04 INFO - 2016-04-21 08:53:04.198 screenresolution[1935:11169] starting screenresolution argv=screenresolution list 08:53:04 INFO - Available Modes on Display 0 08:53:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:04 INFO - Intel Iris: 08:53:04 INFO - Chipset Model: Intel Iris 08:53:04 INFO - Type: GPU 08:53:04 INFO - Bus: Built-In 08:53:04 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:04 INFO - Vendor: Intel (0x8086) 08:53:04 INFO - Device ID: 0x0a2e 08:53:04 INFO - Revision ID: 0x0009 08:53:04 INFO - Displays: 08:53:04 INFO - Display: 08:53:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:04 INFO - Display Serial Number: 1600x1200 60 08:53:04 INFO - Main Display: Yes 08:53:04 INFO - Mirror: Off 08:53:04 INFO - Online: Yes 08:53:04 INFO - Rotation: Supported 08:53:04 INFO - Return code: 0 08:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:04 INFO - Python 2.7.3 08:53:04 INFO - Return code: 0 08:53:04 INFO - grabbing minidump binary from tooltool 08:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:04 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 0x101fa8e00>, '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 0x101dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fa3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:04 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:53:04 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:53:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:04 INFO - Return code: 0 08:53:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:53:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:53:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:53:05 INFO - Return code: 0 08:53:05 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/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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:53:05 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/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA5zIqwCji/Render', 08:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:05 INFO - 'HOME': '/Users/cltbld', 08:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:05 INFO - 'LOGNAME': 'cltbld', 08:53:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:05 INFO - 'MOZ_NO_REMOTE': '1', 08:53:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:05 INFO - 'NO_EM_RESTART': '1', 08:53:05 INFO - 'PAGER': '/bin/cat', 08:53:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:05 INFO - 'PWD': '/builds/slave/test', 08:53:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:05 INFO - 'SHELL': '/bin/bash', 08:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8BcGW3QQcm/Listeners', 08:53:05 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 08:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:05 INFO - 'USER': 'cltbld', 08:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:05 INFO - 'XPC_FLAGS': '0x0', 08:53:05 INFO - 'XPC_SERVICE_NAME': '0', 08:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:05 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/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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:53:05 INFO - mozversion application_buildid: 20160421072021 08:53:05 INFO - mozversion application_changeset: be6f45632b33c2578b1daca6d09c9be0df142e96 08:53:05 INFO - mozversion application_display_name: Nightly 08:53:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:05 INFO - mozversion application_name: Firefox 08:53:05 INFO - mozversion application_remotingname: firefox 08:53:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:53:05 INFO - mozversion application_vendor: Mozilla 08:53:05 INFO - mozversion application_version: 48.0a1 08:53:05 INFO - mozversion platform_buildid: 20160421072021 08:53:05 INFO - mozversion platform_changeset: be6f45632b33c2578b1daca6d09c9be0df142e96 08:53:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:53:05 INFO - mozversion platform_version: 48.0a1 08:53:05 INFO - using testdate: 1461253985 08:53:05 INFO - actual date: 1461253985 08:53:05 INFO - starting webserver on 'localhost:50277' 08:53:05 INFO - SUITE-START | Running 2 tests 08:53:05 INFO - TEST-START | dromaeo_css 08:53:05 INFO - operating with platform_type : mac_ 08:53:05 INFO - Initialising browser for dromaeo_css test... 08:53:05 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmptY4WxN/profile http://localhost:50277/getInfo.html) 08:53:08 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 08:53:08 INFO - PROCESS | 1953 | colorDepth:24 08:53:08 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 08:53:08 INFO - PROCESS | 1953 | __metrics 08:53:08 INFO - PROCESS | 1953 | 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:53:08 ERROR - PROCESS | 1953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:53:09 INFO - TEST-INFO | 1953: exit 0 08:53:09 INFO - Browser initialized. 08:53:09 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:09 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmptY4WxN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:21 INFO - PROCESS | 1955 | RSS: Main: 238960640 08:53:21 INFO - PROCESS | 1955 | 08:54:12 INFO - PROCESS | 1955 | 1461254052706 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] 08:56:22 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50277/tests/dromaeo/cssquery-ext.html) 08:56:24 INFO - PROCESS | 1955 | RSS: Main: 423014400 08:56:24 INFO - PROCESS | 1955 | 08:59:28 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-ext.html (next: http://localhost:50277/tests/dromaeo/cssquery-jquery.html) 08:59:28 INFO - PROCESS | 1955 | RSS: Main: 378970112 08:59:28 INFO - PROCESS | 1955 | 09:02:30 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50277/tests/dromaeo/cssquery-mootools.html) 09:02:31 INFO - PROCESS | 1955 | RSS: Main: 379817984 09:02:31 INFO - PROCESS | 1955 | 09:05:33 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50277/tests/dromaeo/cssquery-prototype.html) 09:05:34 INFO - PROCESS | 1955 | RSS: Main: 376279040 09:05:34 INFO - PROCESS | 1955 | 09:08:37 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50277/tests/dromaeo/cssquery-yui.html) 09:08:38 INFO - PROCESS | 1955 | RSS: Main: 347402240 09:08:38 INFO - PROCESS | 1955 | 09:11:40 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:50277/tests/dromaeo/cssquery-yui.html (next: http://localhost:50277/tests/dromaeo/cssquery-dojo.html) 09:11:41 INFO - PROCESS | 1955 | RSS: Main: 384479232 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | __start_tp_report 09:11:41 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 09:11:41 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 09:11:41 INFO - PROCESS | 1955 | |i|pagename|runs| 09:11:41 INFO - PROCESS | 1955 | |0;dojo.html;3380.9999999999995;3411.4030044742162;3447.0692912913514;3454.464557993512;3473.426884564047;5595.4124816894255;5681.687507187108;5686.175504551838;5711.857203569908;5731.627444216123;4912.771807048238;5201.973990130044;5712.22884910537;5754.136879468082;5823.67969761663;5097.694138351692;5117.872053198663;5155.71643559604;5171.715555644457;5228;5250.842474725764;5258.395284542288;5271.051210782058;5279.260903473517;5335.679859208441;5104.030234255498;5164.92252616211;5201.323827902386;5210.062188806013;5211.218317252405;5162.716050617215;5211.5049070338255;5215.217717342429;5217.58259339252;5249.16013437852;5131.332926719502;5149.407818100929;5156.793728250903;5187.610929180327;5227.26818245446;5526.392096869341;5661.980843448177;5663.603547751659;5679.4036626154075;5686.630369026;6081.986735827749;6172.506199504029;6181.876362472771;6192.87614247717;6212.471939885115;6247.4377306042215;6287.868183726927;6296.212973378328;6297.370262973712;6315.021171718391;5567.137093750475;5604.971975140098;5775.1337299405;5794.217780599607;5803.071508558611;6207.751689932352;6331.145295385156;6335.208098987627;6336.714847831859;6343.809685709436;6126.969365153146;6139.723712432951;6164.383561643889;6175.042868355412;6183.196184495987;6133.29467111279;6220.253569571681;6290.748370065237;6303.905441418382;6321.494280457552;6100.005955098508;6103.640733310809;6153.969230153821;6215.409536094155;6217.440430361289;6211.378862113843;6230.096635987757;6231.813045608639;6238.469730072904;6243.8126856194385;4821.734804585712;4887.973525559672;4890.217565189552;4898.069366820293;4932.605391568666;3592.4072528033103;4398.450193725785;4416.646668266531;4480.059187570581;4514.593686568224;5595.18869763882;5604.299462567179;5607.859803504945;5619.241402410704;5626.465485778926;6108.37242585679;6228.252609686867;6278.277998030194;6293.244810622755;6295.5907865988565;5557.916631250535;5569.220309156722;5579.609427340129;5650.886982260332;5695.231143795618;5338.813141540105;5350.571954243651;5361.08861493547;5369.40936496985;5376.78492860289;6199.163112979871;6311.274203466568;6328.208973878265;6333.936660633335;6346.365363463617;6193.566450348432;6297.4017468340435;6320.4311611955145;6323.114763933145;6331.081993110881;6298.433141017331;6307;6321.905171422341;6333.20834895638;6337.90493142594;6021.488173505301;6128.540359473147;6189.597676151036;6190.876182476415;6214.316425193316;6176.938230617816;6182.752689892354;6204.968975155276;6208.4722798562625;6231.2522497298805;6005.6396616203165;6039.969800150971;6041;6042.063480160582;6053.576249662481;6010.939890601038;6017.578769486094;6057.545684073624;6061.333253342041;6072.78745243932;5734.7132643368495;5771.307443106854;5805.158252053344;5827.621204621686;5855.7364918579615;5882.294124705063;5978.521718262616;5979.22270104901;5992.460678538952;6009.158717779602;5803.3616302207565;5853.268341457318;5946.702664866826;5957.4042595740075;5976.402359763989 09:11:41 INFO - PROCESS | 1955 | |1;ext.html;491.9483454237305;519.3819354967588;519.8960207958407;534.3080710479928;575.1516513143112;24847.37881552962;31290.68709312906;31333.68666313336;37784.43323350145;39153;41767.58232417675;42341;42575.148497030044;42580.93547661302;42626.573734262645;9370.250379969602;9622.278329125344;9676;9677.951610241938;9700.854487182663;8226.83546329073;8546.70086546971;8830.337724670644;11294.830577541343;11713.414329283545;9716.75708107296;11384.032357249602;11507.482163302671;11510.424478776069;11521.827172592419;11367.204295699305;11496.252743571724;11504.654860354161;11512.712182195428;11531.077513798877;8565.614547345322;11284.717882052932;11618.477168527437;11622.360770157638;11648.359340236284;38281.85154445371;38844;38903.27677062607;38954.02614934621;39050.609493905264;3201.2477067889026;3233.2725136844024;3454.7063499602564;3702;4072.9592704073175;27791.86104069487;27904.581431278548;28598.571021434695;28634.713652863622;29597.96407125762;2713.592961055838;2736.904208352718;2748.175547335811;2790.330320723012;2860.4851126797166;508303;509021;510297.24256893503;511677;512226;59191.70404147952;59671.70164149151;59671.701641492386;59811.40188598145;59877.700611496664;63978.6801066001;64098;64112.35887641064;64193;64257.357426426075;15767;16015.199240038184;16024.19879006045;16049.919750401406;16066.83097781808;11080.722981925517;11542.538298467967;12427.44076516559;12509.74980500403;12523.561675341502;15798.289076991563;15969.760453592973;16005.919970400073;16057.956232844828;16301.266443009856;6009.978303688384;6128.693565321716;6273.247210334789;6948.895766563505;6964.477664175207;1021.9086520608986;1042.3276986343765;1064.3188359449803;1111.8485730112259;1166.8833116688434;29087.7091229085;29800.95696650607;30016.54975175374;30211.857480396902;30213.09360719182;1090.345792524479;1153.5501154549731;1196.342011893455;1197.4431889171576;1235.9196652217577;15215;15338.463153789564;15401.278781314411;15718.214089295718;15807.841921580637;627016;631553;632212;635803.8517688954;637212;60734.39265607376;61391.6930415354;61411.38588614057;61475.07787383193;61514.69242653758;1318.9076764626384;1369.7671395862528;1419.3258202354007;1460.664047269134;1464.1727424005403;28851;29099.7090029097;29373;29515;30487.695123049372;48998.26502602535;49073;49275.75362123166;49502.75248623734;49519.50480495149;422627;423507;424812.87593561836;424843;425284;26264.4747105053;26770.19689409321;27256.727432726213;27362.5895611562;37984.430233545965;45213.773931130134;45692.54307456883;45985.08029839318;46612.53387466082;47048.94181878304;36252.09369765777;37807.81096094502;38305;38477.6152238474;38577.61422385852;37237.6276237245;38294.617053829104;38373.61626383701;39370.212595747354;39395.21209575735;36254.09364765902;37183.07042323964;38633.42049869311;39461.605383945796;39520.616778412616;29092.854535727187;29243.561346580245;30796.076117716504;32065.67934320627;32240.677593224707;20658.69011964852;24455.75544244606;24607.384815379042;24905.38493458161;25519.6172057423 09:11:41 INFO - PROCESS | 1955 | |2;jquery.html;333.1238841845945;386.9380899056152;395.5312954149333;396.70841931180587;419.5112693711826;19320.516987075312;19740.70388944167;21506.677399838994;21510.784892151074;21520.784792152073;19460;19561.510962225915;20074.899625501854;21070.789292107114;21079;4736.621070314367;5374.865628359284;5590.888182236353;5757.481826635602;6144.324124346318;5850.385709500484;6056.4851987581105;6075.270967483886;6104.206453161083;6110.236220472441;5489;5489.094299440588;5495.917561236584;5511.145772405263;5904.7047647617665;5248.107821670325;5421.861409104093;5479.863003424906;5507.311586051724;5511.641743286674;4046.6560342370935;5350.973245133789;5353.411124776271;5353.982743278764;5355.196720491918;13234.205947643171;13359.799603005962;13385.732285354336;13577.389017494237;13763.31183440834;1849.9537511562185;2229.710137682091;2233.0063121910757;2233.7877901599322;2253.290213582716;9766.706998790047;9892.703218903445;9907.455089970048;10012.59949602015;10044.19646428284;1468.4419920430169;1702.3701230544777;1764.6029643330148;1771.5748220427133;1792.0860361215744;88277.55861220717;89412;114098;118148;127318.36340818329;9351.345405821527;10537.311940820651;11609.187356884937;11611.361375124281;11733.647990560297;13492.797608035888;13958;14041.85958140426;14328.713425731636;15228.46700365482;7037.612931288726;7949.801254968672;8467.237948584658;8534.231919127309;8594.699185528476;7561.432892533082;8347.207015333533;8366.703161010053;8495.36284778644;8496.617652205665;4523.0324808707655;4601.3558101865765;4610.884727881763;4701.78841952113;4705.905881882343;1581.3358389476448;1962.3229985654925;1962.5682349883289;1978.1790556919004;1981.5343394302326;848.3128665780737;856.2850020233051;883.4081165619051;923.7044145873256;1005.0954141272766;17679.911600441916;19636.410907672507;20144.39566812998;20415;20417.795822041888;832.5961908474385;835.7450977451936;936.8501039833594;967.1634036558327;985.2462865907586;7919.722809701748;10548.947255263827;10771.138308935266;10798.838017429745;10805.567777288978;594392;605291;606455.9354406488;614982;615693;121472;124433.75566244221;128647.07029394567;129050;131563.3421832904;871.8997315308694;1012.7771890184148;1027.0448482910965;1032.829583118777;1037.7509397744486;17473.82526174722;17481.56296092608;17758.283918732275;18066.728999065286;18210.817891820912;48407.757961209965;48588;51451.48548514467;51892.740536297075;52592.737036314575;56242.1563676537;68774.28064298432;73834.63082684552;75063;76222.61888690521;8100.71647492335;8134.267915887599;8167.224113709188;8193.262606365455;8557.44376615518;11718.648440546456;12333.198342107735;12504.562340318045;12614.873851261369;12784.169029013085;6864.347886950732;8620.267277281502;10875.945620271848;11028.172887033665;11271.943640282074;10598.417087060287;10763;10901.34591924487;10924.126069914546;11169.994700476997;10838;11166.49750761234;11305.15211359135;11423.143264255381;11529.962303392735;7056.364927156581;7264.273572642694;8057.274845263954;8066.475679080841;8145.918540814516;9488.430694158373;10938.289011214245;11009.889901100887;11110.000099991039;11260.774784504429 09:11:41 INFO - PROCESS | 1955 | |3;mootools.html;1361.1968938326386;1602.8717702583795;1622.2699785096709;1886.3397810766235;2014.0835919656558;7001.229864714884;8520.275776558992;8691.56542172892;8709.954805423351;8710;8419.663213471455;8508.319334453228;8568.957155214215;8577.957110214456;8581.914180858204;2131.8614290071096;2373.3947843299934;2413.263954493883;2443.2792326263707;2454.214651311581;2054.763702174254;2480.6651102101164;2483.205374280231;2490.1906880263896;2492.439201179738;2087.2381580723077;2399.1962692497946;2420.225527831095;2426.1751004658413;2439.85360878347;2126.1070350452846;2338.3218866528687;2385.2605692235375;2404.915827946022;2429.6598476213344;2417.62526808345;2445.510897820443;2449.4856080223176;2450.754924507535;2450.8652024138664;8516.574171291473;8587.957060214721;8596.484210947363;8602.827943441096;8620.051794302623;5065.1895696688725;5270.314859068335;5271.499207575274;5278.577713782888;5285.101532739443;3318.3363327334387;3414.9146271343166;3543.326767914088;3549.6450354964295;3550.5916819565823;1021.851831484438;1024.9743756406076;1033.6020632056636;1034.1209971524145;1042.1089968077335;11489.185149638224;11527.596534121349;11566.363849988247;11579.826302605468;11608.4195790211;4610.815567377267;4669.976650116728;4895.951040489621;4896.36347274852;4898.21628539432;9801.558929848174;9842.704718858446;9846.310758246855;9850.852237216448;9859.605615775288;5351.81268655603;5368.979893820162;5369.6509726867625;5378.542823859937;5384.730763461811;4379.167958087953;4427.46870375557;4910.2880082388565;4938.481459446778;4949.307097006422;3978.224246271949;3986.940195897064;4102.097538541516;4110.095778928631;4110.979445102816;1339.7856342985076;1354.3905242640851;1356.8371795384617;1363.9318034098453;1364.7543442180304;855.8331125430606;871.6164887449502;880.859062549989;928.3176865004217;950.5104870991445;4470.955290447119;5781.682007489629;5808.157817116495;5862.413758624189;5970.940290597126;454.45238487622333;466.4682262221071;469.11571687369553;476.4497005958124;486.6642017008253;3844.9807750961068;3900.1224724437343;3971.0270983609203;3976.5228172619472;3978.6021397860563;10159.238057145743;10184.949075254577;10649.414282214397;10656.360618362924;10661.84007239892;10470.371777693363;10579.84130238047;10589.57641694339;10614.522346494427;10617.044465998097;7947.721829735931;8104.756857294291;8146.144654811278;8184.386171037196;8201.95899020513;5699.689071513492;5730.312362516524;5743.483086522233;5794.565407594363;5832.037713777179;9539.523023848918;9824.968378320173;9907.306488545733;9907.801843963225;9909.355891867006;9512.762180945532;9946.602135914482;10005.299629025898;10057.949710251694;10068.144207742424;6273.654948977667;6564;7327.963360183165;7414.962925185555;7434.814129646586;8144.511329320259;8179.713710020121;8247.876281855897;8268.83462330762;8275.255227029596;7184.640767961686;7251.274872512707;7269;7419.369353605004;7439.404847612286;7074.398676112588;7175.425965922818;7208.819779505554;7281.980522726716;7292.234315397019;6873.2783057778215;6970.756023539355;6977.302269772982;6989.790306290819;7003.29967003316;4029.37544680575;4823.541763532458;4860.270959356125;4885.487023862585;4948.8762780930765;4935.490273918193;4949.950500494949;5007.924881126713;5042.520960508746;5113.4375218726655 09:11:41 INFO - PROCESS | 1955 | |4;prototype.html;317.68390451500755;321.9275662975829;328.0731932291277;330.90900002499325;334.9162709322669;5029.673071250366;5736.770529178828;5822.126680997847;5872.295324561053;5887.2346594942655;5062.16474281744;5607.299087614048;5810.651360918348;5840.415958404157;5852.76588936442;3976.6023397660147;4128.814203360848;4266.74131131317;4357.433533640622;4385.539518350575;4314.460692413449;4347.60871521563;4373.497047839507;4383.868483945486;4388.363687265344;4320.265554855667;4358.237308471021;4378.299472084467;4382.167388196232;4397.670174736892;3805.9619403806023;4135.8966025849295;4417.337399390085;4418.315161150027;4422.71252368595;3517.7889326640484;3791.791451470168;3821.9808900955595;3830.157365379612;3832.8658496952753;4913.70517768935;4936.679115857493;5114.948850511521;5156.381234251876;5196.29849970253;4609.516000819933;4620.884477888046;4652.9996050848895;4895.510448955111;5012.172991456406;5101.7194054327;6146.508279337642;6166.84582885427;6173.81478555644;6178.242753080103;3613.8012409317475;3657.5976642569294;4102.466679331697;4120.278951183531;4123.8556650517385;5401.405845357008;6085.756569737249;6481;6536.673166341664;6589.11242367001;3454.5854497459964;4094.1402305516176;4145.937810932838;4153.584641535822;4159.9792001039805;4231.153769246163;4233.73407357107;4234.513031001475;4235.983363992619;4242.85150019754;4346.457007762262;4537.4328208973875;4732.716037037789;4897.706137631753;4997.750112494361;6138.294096178996;6161.630302181794;6166.64333846553;6180.227471566054;6215.81352559424;1434.9713005739827;1451.3831621560794;1452.7167202395642;1453.62932452224;1453.7964684944116;930.8277968575835;943.1794338925179;952.9332946693803;953.7997020625746;969.340848223215;5582.553395728332;5592.769590690042;5661.688607126647;5708.686022268732;5741.224934633771;5090.618203634742;5207.58902918589;6251.467451305627;6316.30520642729;6443.548951573345;4557.5898169164275;5403.459654034644;5435.483629055233;5697.658140511582;5748.741306641211;3346.9330613387597;3356;3359.916002099967;3369.713574346208;3378.915527111842;5352.732363381815;6385.457236134887;6550.248895463917;6615.17310336208;6647.4349680276755;5401.6218864679095;5693.857653558694;6496.415322620986;6500.122483464672;6527.836804079936;5870.5010074144175;5919.970400147971;6009.45914867664;6533.607983521004;6590.736370545124;5408.972955135199;6082.78710245157;6427.389398007182;6473.708683109366;6480.967595161994;5898.852528686645;6261.906071408841;6340.29092980593;6379.393957574023;6391.265004524539;5757.251557297524;6570.85010123236;6698.975768173869;6714.83212919681;6736.562123461959;4145.523264824463;4156.272652285898;4158.708890377766;4170.624643782075;4189.87430377077;6109;6215.762325562703;6411.4870810336;6429.678516074271;6437.25971513282;5481.972590137024;5590.8043218487155;6018.127371531191;6060.454559089703;6173.351798061139;5141.740273632984;6208.534359923115;6234.937650623436;6254.562180647311;6272.2159730033745;5342.439043900335;5813.273340832396;6095.573309868444;6109.2974307955155;6112.510999119971;6187.133801267736;6201.348858369806;6206.548413376781;6216.502679785697;6218.81343559694;5129.512696293846;5837.328707198726;6093;6107.358727333566;6110.564017455806 09:11:41 INFO - PROCESS | 1955 | |5;yui.html;697.8953157026446;700.8668353012927;707.9893801592974;719.5574721546244;786.2373497707225;14784.77822832657;14928.029678070945;15219.923900380483;15229.771553426694;15249.161296128708;11795;14016.64958376041;14296.71406571868;14313.78529322061;14319.498817541387;756.3457609168057;777.4441274488739;951.9385884738501;968.8449848024318;968.9224862011022;935.8409070458005;987.4717026390847;991.023841516106;991.3110388280148;991.5587563534194;1016.4460369098858;1019.5768755966266;1023.2939271902401;1024.692592222334;1025.2208321675544;780.2626517940548;977.7751117243002;1009.5507499162876;1011.3224139826334;1012.4431562640518;757.428141752973;758.4577027425362;760.5170716594947;760.8744557148063;761.6496411650647;15167.62080947974;15228.314725837365;15238.847611523854;15241.390344386211;15259.08445493274;12608.306543140123;12803.295818729966;12865.871341286655;12988.28564428956;13108.410121544459;8679.566021698889;9927.453990030617;10045.045720656526;10187;10392.064714175685;3501.352249833789;3517.9472307915403;3529.417646088418;3611.20553478237;3628.310620982005;77358.61320693417;78443;87420.56289718511;87794.56102719446;88176.55911720464;8219.424640275243;9562.235021198427;9577.281703872237;9705.864709763982;9815.852762208573;22997.770022299563;23015.53968920612;23025.19411820578;23042.539149217257;23076.423089422562;7394.260573942563;7404.1855395906405;7471.4396420268695;7799.298063174341;7804.024496937883;3936.3111455495173;3951.8616848410743;4000.099977505038;4026.0337518995816;4055.59444055598;3603.873864414733;3676.5771936227143;3677.3380791457794;3693.1136527233807;3696.1129328960856;609.4910749524108;641.6086187425667;643.8197304754676;662.5858838226109;669.5580916595023;577.9829291100449;581.7178668345849;605.8879107365152;613.190588423277;615.8645098078416;6557.409833115043;7887.408444366695;7997.560134192675;8042.9597852010365;8246.216609422096;577.9855503612359;601.8645804693996;602.6173379903793;608.3794529579892;612.4334990134108;3244.935101297961;3774.584795672474;3894.4937158169823;3894.90262743428;4007.479027726435;90235.64646530307;90515;91787;93528.59707104399;93685.53157234307;81849.3630127406;82028;82028;82141.17858821455;82143;70742;72636;73042.63478682573;73226.63386683032;74414;6383.297837237993;7418.851622967619;7711.730089446843;7764.184760600281;7804.726834560764;43550.12899741924;44680.96784978336;45918.08163836638;46011.53988460072;46180.53819461897;64271;65553.01670474852;65597.67201163963;65947.6702616503;65997.01004485032;10321.845172322572;10332.328398654063;11074.335539867634;11293.209475336647;11364.977152056355;9501.619935202514;11658.242214256046;11909;11976.880231197576;12152.75694486123;11371.829422558834;11399.772004559696;11440.656780296604;11569;11924.344161071225;9171.40385874916;9961;10195.541200645841;10364.896351036394;10391.58433662645;10382;10392.324498907547;10700;10703.732406689895;10851.294665846695;7704.229577042251;9432.764180895532;9694.612215511583;9986;10066.194704423775;6660.467162627075;9324.95337523308;9689.41863488193;9843.212542996685;9869.210463163074 09:11:41 INFO - PROCESS | 1955 | __end_tp_report 09:11:41 INFO - PROCESS | 1955 | __start_cc_report 09:11:41 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1803 09:11:41 INFO - PROCESS | 1955 | __end_cc_report 09:11:41 INFO - PROCESS | 1955 | __startTimestamp1461255101207__endTimestamp 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | ------- Summary: start ------- 09:11:41 INFO - PROCESS | 1955 | Number of tests: 6 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5712.73 Median:5882.29 stddev:646.86 (11.0%) stddev-sans-first:622.45 09:11:41 INFO - PROCESS | 1955 | Values: 3381.0 3411.4 3447.1 3454.5 3473.4 5595.4 5681.7 5686.2 5711.9 5731.6 4912.8 5202.0 5712.2 5754.1 5823.7 5097.7 5117.9 5155.7 5171.7 5228.0 5250.8 5258.4 5271.1 5279.3 5335.7 5104.0 5164.9 5201.3 5210.1 5211.2 5162.7 5211.5 5215.2 5217.6 5249.2 5131.3 5149.4 5156.8 5187.6 5227.3 5526.4 5662.0 5663.6 5679.4 5686.6 6082.0 6172.5 6181.9 6192.9 6212.5 6247.4 6287.9 6296.2 6297.4 6315.0 5567.1 5605.0 5775.1 5794.2 5803.1 6207.8 6331.1 6335.2 6336.7 6343.8 6127.0 6139.7 6164.4 6175.0 6183.2 6133.3 6220.3 6290.7 6303.9 6321.5 6100.0 6103.6 6154.0 6215.4 6217.4 6211.4 6230.1 6231.8 6238.5 6243.8 4821.7 4888.0 4890.2 4898.1 4932.6 3592.4 4398.5 4416.6 4480.1 4514.6 5595.2 5604.3 5607.9 5619.2 5626.5 6108.4 6228.3 6278.3 6293.2 6295.6 5557.9 5569.2 5579.6 5650.9 5695.2 5338.8 5350.6 5361.1 5369.4 5376.8 6199.2 6311.3 6328.2 6333.9 6346.4 6193.6 6297.4 6320.4 6323.1 6331.1 6298.4 6307.0 6321.9 6333.2 6337.9 6021.5 6128.5 6189.6 6190.9 6214.3 6176.9 6182.8 6205.0 6208.5 6231.3 6005.6 6040.0 6041.0 6042.1 6053.6 6010.9 6017.6 6057.5 6061.3 6072.8 5734.7 5771.3 5805.2 5827.6 5855.7 5882.3 5978.5 5979.2 5992.5 6009.2 5803.4 5853.3 5946.7 5957.4 5976.4 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:66041.45 Median:36253.09 stddev:141325.46 (389.8%) stddev-sans-first:141636.22 09:11:41 INFO - PROCESS | 1955 | Values: 491.9 519.4 519.9 534.3 575.2 24847.4 31290.7 31333.7 37784.4 39153.0 41767.6 42341.0 42575.1 42580.9 42626.6 9370.3 9622.3 9676.0 9678.0 9700.9 8226.8 8546.7 8830.3 11294.8 11713.4 9716.8 11384.0 11507.5 11510.4 11521.8 11367.2 11496.3 11504.7 11512.7 11531.1 8565.6 11284.7 11618.5 11622.4 11648.4 38281.9 38844.0 38903.3 38954.0 39050.6 3201.2 3233.3 3454.7 3702.0 4073.0 27791.9 27904.6 28598.6 28634.7 29598.0 2713.6 2736.9 2748.2 2790.3 2860.5 508303.0 509021.0 510297.2 511677.0 512226.0 59191.7 59671.7 59671.7 59811.4 59877.7 63978.7 64098.0 64112.4 64193.0 64257.4 15767.0 16015.2 16024.2 16049.9 16066.8 11080.7 11542.5 12427.4 12509.7 12523.6 15798.3 15969.8 16005.9 16058.0 16301.3 6010.0 6128.7 6273.2 6948.9 6964.5 1021.9 1042.3 1064.3 1111.8 1166.9 29087.7 29801.0 30016.5 30211.9 30213.1 1090.3 1153.6 1196.3 1197.4 1235.9 15215.0 15338.5 15401.3 15718.2 15807.8 627016.0 631553.0 632212.0 635803.9 637212.0 60734.4 61391.7 61411.4 61475.1 61514.7 1318.9 1369.8 1419.3 1460.7 1464.2 28851.0 29099.7 29373.0 29515.0 30487.7 48998.3 49073.0 49275.8 49502.8 49519.5 422627.0 423507.0 424812.9 424843.0 425284.0 26264.5 26770.2 27256.7 27362.6 37984.4 45213.8 45692.5 45985.1 46612.5 47048.9 36252.1 37807.8 38305.0 38477.6 38577.6 37237.6 38294.6 38373.6 39370.2 39395.2 36254.1 37183.1 38633.4 39461.6 39520.6 29092.9 29243.6 30796.1 32065.7 32240.7 20658.7 24455.8 24607.4 24905.4 25519.6 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:34116.49 Median:360.03 stddev:101087.71 (28077.5%) stddev-sans-first:101339.46 09:11:41 INFO - PROCESS | 1955 | Values: 333.1 386.9 395.5 396.7 419.5 19320.5 19740.7 21506.7 21510.8 21520.8 19460.0 19561.5 20074.9 21070.8 21079.0 4736.6 5374.9 5590.9 5757.5 6144.3 5850.4 6056.5 6075.3 6104.2 6110.2 5489.0 5489.1 5495.9 5511.1 5904.7 5248.1 5421.9 5479.9 5507.3 5511.6 4046.7 5351.0 5353.4 5354.0 5355.2 13234.2 13359.8 13385.7 13577.4 13763.3 1850.0 2229.7 2233.0 2233.8 2253.3 9766.7 9892.7 9907.5 10012.6 10044.2 1468.4 1702.4 1764.6 1771.6 1792.1 88277.6 89412.0 114098.0 118148.0 127318.4 9351.3 10537.3 11609.2 11611.4 11733.6 13492.8 13958.0 14041.9 14328.7 15228.5 7037.6 7949.8 8467.2 8534.2 8594.7 7561.4 8347.2 8366.7 8495.4 8496.6 4523.0 4601.4 4610.9 4701.8 4705.9 1581.3 1962.3 1962.6 1978.2 1981.5 848.3 856.3 883.4 923.7 1005.1 17679.9 19636.4 20144.4 20415.0 20417.8 832.6 835.7 936.9 967.2 985.2 7919.7 10548.9 10771.1 10798.8 10805.6 594392.0 605291.0 606455.9 614982.0 615693.0 121472.0 124433.8 128647.1 129050.0 131563.3 871.9 1012.8 1027.0 1032.8 1037.8 17473.8 17481.6 17758.3 18066.7 18210.8 48407.8 48588.0 51451.5 51892.7 52592.7 56242.2 68774.3 73834.6 75063.0 76222.6 8100.7 8134.3 8167.2 8193.3 8557.4 11718.6 12333.2 12504.6 12614.9 12784.2 6864.3 8620.3 10875.9 11028.2 11271.9 10598.4 10763.0 10901.3 10924.1 11170.0 10838.0 11166.5 11305.2 11423.1 11530.0 7056.4 7264.3 8057.3 8066.5 8145.9 9488.4 10938.3 11009.9 11110.0 11260.8 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5623.09 Median:4922.89 stddev:3144.95 (63.9%) stddev-sans-first:3137.46 09:11:41 INFO - PROCESS | 1955 | Values: 1361.2 1602.9 1622.3 1886.3 2014.1 7001.2 8520.3 8691.6 8710.0 8710.0 8419.7 8508.3 8569.0 8578.0 8581.9 2131.9 2373.4 2413.3 2443.3 2454.2 2054.8 2480.7 2483.2 2490.2 2492.4 2087.2 2399.2 2420.2 2426.2 2439.9 2126.1 2338.3 2385.3 2404.9 2429.7 2417.6 2445.5 2449.5 2450.8 2450.9 8516.6 8588.0 8596.5 8602.8 8620.1 5065.2 5270.3 5271.5 5278.6 5285.1 3318.3 3414.9 3543.3 3549.6 3550.6 1021.9 1025.0 1033.6 1034.1 1042.1 11489.2 11527.6 11566.4 11579.8 11608.4 4610.8 4670.0 4896.0 4896.4 4898.2 9801.6 9842.7 9846.3 9850.9 9859.6 5351.8 5369.0 5369.7 5378.5 5384.7 4379.2 4427.5 4910.3 4938.5 4949.3 3978.2 3986.9 4102.1 4110.1 4111.0 1339.8 1354.4 1356.8 1363.9 1364.8 855.8 871.6 880.9 928.3 950.5 4471.0 5781.7 5808.2 5862.4 5970.9 454.5 466.5 469.1 476.4 486.7 3845.0 3900.1 3971.0 3976.5 3978.6 10159.2 10184.9 10649.4 10656.4 10661.8 10470.4 10579.8 10589.6 10614.5 10617.0 7947.7 8104.8 8146.1 8184.4 8202.0 5699.7 5730.3 5743.5 5794.6 5832.0 9539.5 9825.0 9907.3 9907.8 9909.4 9512.8 9946.6 10005.3 10057.9 10068.1 6273.7 6564.0 7328.0 7415.0 7434.8 8144.5 8179.7 8247.9 8268.8 8275.3 7184.6 7251.3 7269.0 7419.4 7439.4 7074.4 7175.4 7208.8 7282.0 7292.2 6873.3 6970.8 6977.3 6989.8 7003.3 4029.4 4823.5 4860.3 4885.5 4948.9 4935.5 4950.0 5007.9 5042.5 5113.4 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4948.15 Median:5532.26 stddev:1544.89 (27.9%) stddev-sans-first:1509.62 09:11:41 INFO - PROCESS | 1955 | Values: 317.7 321.9 328.1 330.9 334.9 5029.7 5736.8 5822.1 5872.3 5887.2 5062.2 5607.3 5810.7 5840.4 5852.8 3976.6 4128.8 4266.7 4357.4 4385.5 4314.5 4347.6 4373.5 4383.9 4388.4 4320.3 4358.2 4378.3 4382.2 4397.7 3806.0 4135.9 4417.3 4418.3 4422.7 3517.8 3791.8 3822.0 3830.2 3832.9 4913.7 4936.7 5114.9 5156.4 5196.3 4609.5 4620.9 4653.0 4895.5 5012.2 5101.7 6146.5 6166.8 6173.8 6178.2 3613.8 3657.6 4102.5 4120.3 4123.9 5401.4 6085.8 6481.0 6536.7 6589.1 3454.6 4094.1 4145.9 4153.6 4160.0 4231.2 4233.7 4234.5 4236.0 4242.9 4346.5 4537.4 4732.7 4897.7 4997.8 6138.3 6161.6 6166.6 6180.2 6215.8 1435.0 1451.4 1452.7 1453.6 1453.8 930.8 943.2 952.9 953.8 969.3 5582.6 5592.8 5661.7 5708.7 5741.2 5090.6 5207.6 6251.5 6316.3 6443.5 4557.6 5403.5 5435.5 5697.7 5748.7 3346.9 3356.0 3359.9 3369.7 3378.9 5352.7 6385.5 6550.2 6615.2 6647.4 5401.6 5693.9 6496.4 6500.1 6527.8 5870.5 5920.0 6009.5 6533.6 6590.7 5409.0 6082.8 6427.4 6473.7 6481.0 5898.9 6261.9 6340.3 6379.4 6391.3 5757.3 6570.9 6699.0 6714.8 6736.6 4145.5 4156.3 4158.7 4170.6 4189.9 6109.0 6215.8 6411.5 6429.7 6437.3 5482.0 5590.8 6018.1 6060.5 6173.4 5141.7 6208.5 6234.9 6254.6 6272.2 5342.4 5813.3 6095.6 6109.3 6112.5 6187.1 6201.3 6206.5 6216.5 6218.8 5129.5 5837.3 6093.0 6107.4 6110.6 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:18288.77 Median:608.94 stddev:26093.26 (4285.1%) stddev-sans-first:26133.03 09:11:41 INFO - PROCESS | 1955 | Values: 697.9 700.9 708.0 719.6 786.2 14784.8 14928.0 15219.9 15229.8 15249.2 11795.0 14016.6 14296.7 14313.8 14319.5 756.3 777.4 951.9 968.8 968.9 935.8 987.5 991.0 991.3 991.6 1016.4 1019.6 1023.3 1024.7 1025.2 780.3 977.8 1009.6 1011.3 1012.4 757.4 758.5 760.5 760.9 761.6 15167.6 15228.3 15238.8 15241.4 15259.1 12608.3 12803.3 12865.9 12988.3 13108.4 8679.6 9927.5 10045.0 10187.0 10392.1 3501.4 3517.9 3529.4 3611.2 3628.3 77358.6 78443.0 87420.6 87794.6 88176.6 8219.4 9562.2 9577.3 9705.9 9815.9 22997.8 23015.5 23025.2 23042.5 23076.4 7394.3 7404.2 7471.4 7799.3 7804.0 3936.3 3951.9 4000.1 4026.0 4055.6 3603.9 3676.6 3677.3 3693.1 3696.1 609.5 641.6 643.8 662.6 669.6 578.0 581.7 605.9 613.2 615.9 6557.4 7887.4 7997.6 8043.0 8246.2 578.0 601.9 602.6 608.4 612.4 3244.9 3774.6 3894.5 3894.9 4007.5 90235.6 90515.0 91787.0 93528.6 93685.5 81849.4 82028.0 82028.0 82141.2 82143.0 70742.0 72636.0 73042.6 73226.6 74414.0 6383.3 7418.9 7711.7 7764.2 7804.7 43550.1 44681.0 45918.1 46011.5 46180.5 64271.0 65553.0 65597.7 65947.7 65997.0 10321.8 10332.3 11074.3 11293.2 11365.0 9501.6 11658.2 11909.0 11976.9 12152.8 11371.8 11399.8 11440.7 11569.0 11924.3 9171.4 9961.0 10195.5 10364.9 10391.6 10382.0 10392.3 10700.0 10703.7 10851.3 7704.2 9432.8 9694.6 9986.0 10066.2 6660.5 9325.0 9689.4 9843.2 9869.2 09:11:41 INFO - PROCESS | 1955 | -------- Summary: end -------- 09:11:41 INFO - PROCESS | 1955 | 09:11:41 INFO - TEST-INFO | 1955: exit 0 09:11:41 INFO - TEST-OK | dromaeo_css | took 1115845ms 09:11:41 INFO - TEST-START | kraken 09:11:41 INFO - operating with platform_type : mac_ 09:11:41 INFO - Initialising browser for kraken test... 09:11:41 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpOofHdp/profile http://localhost:50277/getInfo.html) 09:11:43 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 09:11:43 INFO - PROCESS | 1959 | colorDepth:24 09:11:43 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 09:11:43 INFO - PROCESS | 1959 | __metrics 09:11:44 INFO - PROCESS | 1959 | 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:11:44 ERROR - PROCESS | 1959 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:11:44 INFO - TEST-INFO | 1959: exit 0 09:11:44 INFO - Browser initialized. 09:11:44 INFO - Running cycle 1/1 for kraken test... 09:11:44 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpOofHdp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:56 INFO - PROCESS | 1961 | RSS: Main: 238899200 09:11:56 INFO - PROCESS | 1961 | 09:11:59 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50277/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:00 INFO - PROCESS | 1961 | RSS: Main: 285790208 09:12:00 INFO - PROCESS | 1961 | 09:12:03 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50277/tests/kraken/driver.html?testName=audio-dft) 09:12:03 INFO - PROCESS | 1961 | RSS: Main: 299380736 09:12:03 INFO - PROCESS | 1961 | 09:12:07 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50277/tests/kraken/driver.html?testName=audio-fft) 09:12:07 INFO - PROCESS | 1961 | RSS: Main: 384446464 09:12:07 INFO - PROCESS | 1961 | 09:12:10 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50277/tests/kraken/driver.html?testName=audio-oscillator) 09:12:10 INFO - PROCESS | 1961 | RSS: Main: 285519872 09:12:10 INFO - PROCESS | 1961 | 09:12:12 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:13 INFO - PROCESS | 1961 | RSS: Main: 291700736 09:12:13 INFO - PROCESS | 1961 | 09:12:16 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50277/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:16 INFO - PROCESS | 1961 | RSS: Main: 328273920 09:12:16 INFO - PROCESS | 1961 | 09:12:19 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50277/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:20 INFO - PROCESS | 1961 | RSS: Main: 330825728 09:12:20 INFO - PROCESS | 1961 | 09:12:23 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50277/tests/kraken/driver.html?testName=json-parse-financial) 09:12:23 INFO - PROCESS | 1961 | RSS: Main: 326111232 09:12:23 INFO - PROCESS | 1961 | 09:12:25 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:25 INFO - PROCESS | 1961 | RSS: Main: 320110592 09:12:25 INFO - PROCESS | 1961 | 09:12:27 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:28 INFO - PROCESS | 1961 | RSS: Main: 327589888 09:12:28 INFO - PROCESS | 1961 | 09:12:30 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:12:31 INFO - PROCESS | 1961 | RSS: Main: 329781248 09:12:31 INFO - PROCESS | 1961 | 09:12:33 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:12:34 INFO - PROCESS | 1961 | RSS: Main: 334663680 09:12:34 INFO - PROCESS | 1961 | 09:12:37 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:12:38 INFO - PROCESS | 1961 | RSS: Main: 332451840 09:12:38 INFO - PROCESS | 1961 | 09:12:39 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50277/tests/kraken/driver.html?testName=ai-astar) 09:12:40 INFO - PROCESS | 1961 | RSS: Main: 397053952 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | __start_tp_report 09:12:40 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 09:12:40 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 09:12:40 INFO - PROCESS | 1961 | |i|pagename|runs| 09:12:40 INFO - PROCESS | 1961 | |0;ai-astar;110;109;111;112;111;114;119;111;112;110 09:12:40 INFO - PROCESS | 1961 | |1;audio-beat-detection;157;120;143;165;117;141;136;117;169;118 09:12:40 INFO - PROCESS | 1961 | |2;audio-dft;186;185;183;183;183;193;258;185;182;182 09:12:40 INFO - PROCESS | 1961 | |3;audio-fft;74;75;96;119;82;62;57;84;96;81 09:12:40 INFO - PROCESS | 1961 | |4;audio-oscillator;92;99;138;81;79;81;124;80;77;128 09:12:40 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;86;89;135;111;87;85;135;83;85;84 09:12:40 INFO - PROCESS | 1961 | |6;imaging-darkroom;92;92;92;92;92;92;92;92;92;92 09:12:40 INFO - PROCESS | 1961 | |7;imaging-desaturate;86;86;84;87;85;84;84;86;85;84 09:12:40 INFO - PROCESS | 1961 | |8;json-parse-financial;59;60;59;57;57;58;56;77;57;57 09:12:40 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;65;68;74;71;70;67;66;69;68;65 09:12:40 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;81;80;82;82;82;80;81;80;84;81 09:12:40 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;142;140;154;151;141;138;134;134;173;141 09:12:40 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;172;169;169;176;170;170;167;170;167;165 09:12:40 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;69;67;69;67;68;67;71;98;98;97 09:12:40 INFO - PROCESS | 1961 | __end_tp_report 09:12:40 INFO - PROCESS | 1961 | __start_cc_report 09:12:40 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1918 09:12:40 INFO - PROCESS | 1961 | __end_cc_report 09:12:40 INFO - PROCESS | 1961 | __startTimestamp1461255160117__endTimestamp 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | ------- Summary: start ------- 09:12:40 INFO - PROCESS | 1961 | Number of tests: 14 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:2.85 (2.6%) stddev-sans-first:2.93 09:12:40 INFO - PROCESS | 1961 | Values: 110.0 109.0 111.0 112.0 111.0 114.0 119.0 111.0 112.0 110.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:142.00 stddev:20.26 (14.3%) stddev-sans-first:20.33 09:12:40 INFO - PROCESS | 1961 | Values: 157.0 120.0 143.0 165.0 117.0 141.0 136.0 117.0 169.0 118.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:192.00 Median:185.00 stddev:23.41 (12.7%) stddev-sans-first:24.73 09:12:40 INFO - PROCESS | 1961 | Values: 186.0 185.0 183.0 183.0 183.0 193.0 258.0 185.0 182.0 182.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:82.60 Median:81.50 stddev:17.95 (22.0%) stddev-sans-first:18.77 09:12:40 INFO - PROCESS | 1961 | Values: 74.0 75.0 96.0 119.0 82.0 62.0 57.0 84.0 96.0 81.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:80.50 stddev:23.37 (29.0%) stddev-sans-first:24.69 09:12:40 INFO - PROCESS | 1961 | Values: 92.0 99.0 138.0 81.0 79.0 81.0 124.0 80.0 77.0 128.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:98.00 Median:85.00 stddev:21.11 (24.8%) stddev-sans-first:21.94 09:12:40 INFO - PROCESS | 1961 | Values: 86.0 89.0 135.0 111.0 87.0 85.0 135.0 83.0 85.0 84.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:92.00 Median:92.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:12:40 INFO - PROCESS | 1961 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.10 (1.3%) stddev-sans-first:1.12 09:12:40 INFO - PROCESS | 1961 | Values: 86.0 86.0 84.0 87.0 85.0 84.0 84.0 86.0 85.0 84.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:58.50 stddev:6.20 (10.6%) stddev-sans-first:6.57 09:12:40 INFO - PROCESS | 1961 | Values: 59.0 60.0 59.0 57.0 57.0 58.0 56.0 77.0 57.0 57.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.50 stddev:2.83 (4.1%) stddev-sans-first:2.74 09:12:40 INFO - PROCESS | 1961 | Values: 65.0 68.0 74.0 71.0 70.0 67.0 66.0 69.0 68.0 65.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.25 (1.5%) stddev-sans-first:1.32 09:12:40 INFO - PROCESS | 1961 | Values: 81.0 80.0 82.0 82.0 82.0 80.0 81.0 80.0 84.0 81.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:141.50 stddev:11.82 (8.4%) stddev-sans-first:12.49 09:12:40 INFO - PROCESS | 1961 | Values: 142.0 140.0 154.0 151.0 141.0 138.0 134.0 134.0 173.0 141.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:170.00 stddev:3.03 (1.8%) stddev-sans-first:3.07 09:12:40 INFO - PROCESS | 1961 | Values: 172.0 169.0 169.0 176.0 170.0 170.0 167.0 170.0 167.0 165.0 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:70.00 stddev:14.25 (20.4%) stddev-sans-first:14.81 09:12:40 INFO - PROCESS | 1961 | Values: 69.0 67.0 69.0 67.0 68.0 67.0 71.0 98.0 98.0 97.0 09:12:40 INFO - PROCESS | 1961 | -------- Summary: end -------- 09:12:40 INFO - PROCESS | 1961 | 09:12:40 INFO - TEST-INFO | 1961: exit 0 09:12:40 INFO - TEST-OK | kraken | took 59121ms 09:12:40 INFO - SUITE-END | took 1174s 09:12:41 INFO - Completed test suite (00:19:35) 09:12:41 INFO - javascript benchmark 09:12:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.9999999999995, 3411.4030044742162, 3447.0692912913514, 3454.464557993512, 3473.426884564047, 5595.4124816894255, 5681.687507187108, 5686.175504551838, 5711.857203569908, 5731.627444216123, 4912.771807048238, 5201.973990130044, 5712.22884910537, 5754.136879468082, 5823.67969761663, 5097.694138351692, 5117.872053198663, 5155.71643559604, 5171.715555644457, 5228.0, 5250.842474725764, 5258.395284542288, 5271.051210782058, 5279.260903473517, 5335.679859208441, 5104.030234255498, 5164.92252616211, 5201.323827902386, 5210.062188806013, 5211.218317252405, 5162.716050617215, 5211.5049070338255, 5215.217717342429, 5217.58259339252, 5249.16013437852, 5131.332926719502, 5149.407818100929, 5156.793728250903, 5187.610929180327, 5227.26818245446, 5526.392096869341, 5661.980843448177, 5663.603547751659, 5679.4036626154075, 5686.630369026, 6081.986735827749, 6172.506199504029, 6181.876362472771, 6192.87614247717, 6212.471939885115, 6247.4377306042215, 6287.868183726927, 6296.212973378328, 6297.370262973712, 6315.021171718391, 5567.137093750475, 5604.971975140098, 5775.1337299405, 5794.217780599607, 5803.071508558611, 6207.751689932352, 6331.145295385156, 6335.208098987627, 6336.714847831859, 6343.809685709436, 6126.969365153146, 6139.723712432951, 6164.383561643889, 6175.042868355412, 6183.196184495987, 6133.29467111279, 6220.253569571681, 6290.748370065237, 6303.905441418382, 6321.494280457552, 6100.005955098508, 6103.640733310809, 6153.969230153821, 6215.409536094155, 6217.440430361289, 6211.378862113843, 6230.096635987757, 6231.813045608639, 6238.469730072904, 6243.8126856194385, 4821.734804585712, 4887.973525559672, 4890.217565189552, 4898.069366820293, 4932.605391568666, 3592.4072528033103, 4398.450193725785, 4416.646668266531, 4480.059187570581, 4514.593686568224, 5595.18869763882, 5604.299462567179, 5607.859803504945, 5619.241402410704, 5626.465485778926, 6108.37242585679, 6228.252609686867, 6278.277998030194, 6293.244810622755, 6295.5907865988565, 5557.916631250535, 5569.220309156722, 5579.609427340129, 5650.886982260332, 5695.231143795618, 5338.813141540105, 5350.571954243651, 5361.08861493547, 5369.40936496985, 5376.78492860289, 6199.163112979871, 6311.274203466568, 6328.208973878265, 6333.936660633335, 6346.365363463617, 6193.566450348432, 6297.4017468340435, 6320.4311611955145, 6323.114763933145, 6331.081993110881, 6298.433141017331, 6307.0, 6321.905171422341, 6333.20834895638, 6337.90493142594, 6021.488173505301, 6128.540359473147, 6189.597676151036, 6190.876182476415, 6214.316425193316, 6176.938230617816, 6182.752689892354, 6204.968975155276, 6208.4722798562625, 6231.2522497298805, 6005.6396616203165, 6039.969800150971, 6041.0, 6042.063480160582, 6053.576249662481, 6010.939890601038, 6017.578769486094, 6057.545684073624, 6061.333253342041, 6072.78745243932, 5734.7132643368495, 5771.307443106854, 5805.158252053344, 5827.621204621686, 5855.7364918579615, 5882.294124705063, 5978.521718262616, 5979.22270104901, 5992.460678538952, 6009.158717779602, 5803.3616302207565, 5853.268341457318, 5946.702664866826, 5957.4042595740075, 5976.402359763989], "value": 5671.058080030446, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.9483454237305, 519.3819354967588, 519.8960207958407, 534.3080710479928, 575.1516513143112, 24847.37881552962, 31290.68709312906, 31333.68666313336, 37784.43323350145, 39153.0, 41767.58232417675, 42341.0, 42575.148497030044, 42580.93547661302, 42626.573734262645, 9370.250379969602, 9622.278329125344, 9676.0, 9677.951610241938, 9700.854487182663, 8226.83546329073, 8546.70086546971, 8830.337724670644, 11294.830577541343, 11713.414329283545, 9716.75708107296, 11384.032357249602, 11507.482163302671, 11510.424478776069, 11521.827172592419, 11367.204295699305, 11496.252743571724, 11504.654860354161, 11512.712182195428, 11531.077513798877, 8565.614547345322, 11284.717882052932, 11618.477168527437, 11622.360770157638, 11648.359340236284, 38281.85154445371, 38844.0, 38903.27677062607, 38954.02614934621, 39050.609493905264, 3201.2477067889026, 3233.2725136844024, 3454.7063499602564, 3702.0, 4072.9592704073175, 27791.86104069487, 27904.581431278548, 28598.571021434695, 28634.713652863622, 29597.96407125762, 2713.592961055838, 2736.904208352718, 2748.175547335811, 2790.330320723012, 2860.4851126797166, 508303.0, 509021.0, 510297.24256893503, 511677.0, 512226.0, 59191.70404147952, 59671.70164149151, 59671.701641492386, 59811.40188598145, 59877.700611496664, 63978.6801066001, 64098.0, 64112.35887641064, 64193.0, 64257.357426426075, 15767.0, 16015.199240038184, 16024.19879006045, 16049.919750401406, 16066.83097781808, 11080.722981925517, 11542.538298467967, 12427.44076516559, 12509.74980500403, 12523.561675341502, 15798.289076991563, 15969.760453592973, 16005.919970400073, 16057.956232844828, 16301.266443009856, 6009.978303688384, 6128.693565321716, 6273.247210334789, 6948.895766563505, 6964.477664175207, 1021.9086520608986, 1042.3276986343765, 1064.3188359449803, 1111.8485730112259, 1166.8833116688434, 29087.7091229085, 29800.95696650607, 30016.54975175374, 30211.857480396902, 30213.09360719182, 1090.345792524479, 1153.5501154549731, 1196.342011893455, 1197.4431889171576, 1235.9196652217577, 15215.0, 15338.463153789564, 15401.278781314411, 15718.214089295718, 15807.841921580637, 627016.0, 631553.0, 632212.0, 635803.8517688954, 637212.0, 60734.39265607376, 61391.6930415354, 61411.38588614057, 61475.07787383193, 61514.69242653758, 1318.9076764626384, 1369.7671395862528, 1419.3258202354007, 1460.664047269134, 1464.1727424005403, 28851.0, 29099.7090029097, 29373.0, 29515.0, 30487.695123049372, 48998.26502602535, 49073.0, 49275.75362123166, 49502.75248623734, 49519.50480495149, 422627.0, 423507.0, 424812.87593561836, 424843.0, 425284.0, 26264.4747105053, 26770.19689409321, 27256.727432726213, 27362.5895611562, 37984.430233545965, 45213.773931130134, 45692.54307456883, 45985.08029839318, 46612.53387466082, 47048.94181878304, 36252.09369765777, 37807.81096094502, 38305.0, 38477.6152238474, 38577.61422385852, 37237.6276237245, 38294.617053829104, 38373.61626383701, 39370.212595747354, 39395.21209575735, 36254.09364765902, 37183.07042323964, 38633.42049869311, 39461.605383945796, 39520.616778412616, 29092.854535727187, 29243.561346580245, 30796.076117716504, 32065.67934320627, 32240.677593224707, 20658.69011964852, 24455.75544244606, 24607.384815379042, 24905.38493458161, 25519.6172057423], "value": 19758.224237186263, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.1238841845945, 386.9380899056152, 395.5312954149333, 396.70841931180587, 419.5112693711826, 19320.516987075312, 19740.70388944167, 21506.677399838994, 21510.784892151074, 21520.784792152073, 19460.0, 19561.510962225915, 20074.899625501854, 21070.789292107114, 21079.0, 4736.621070314367, 5374.865628359284, 5590.888182236353, 5757.481826635602, 6144.324124346318, 5850.385709500484, 6056.4851987581105, 6075.270967483886, 6104.206453161083, 6110.236220472441, 5489.0, 5489.094299440588, 5495.917561236584, 5511.145772405263, 5904.7047647617665, 5248.107821670325, 5421.861409104093, 5479.863003424906, 5507.311586051724, 5511.641743286674, 4046.6560342370935, 5350.973245133789, 5353.411124776271, 5353.982743278764, 5355.196720491918, 13234.205947643171, 13359.799603005962, 13385.732285354336, 13577.389017494237, 13763.31183440834, 1849.9537511562185, 2229.710137682091, 2233.0063121910757, 2233.7877901599322, 2253.290213582716, 9766.706998790047, 9892.703218903445, 9907.455089970048, 10012.59949602015, 10044.19646428284, 1468.4419920430169, 1702.3701230544777, 1764.6029643330148, 1771.5748220427133, 1792.0860361215744, 88277.55861220717, 89412.0, 114098.0, 118148.0, 127318.36340818329, 9351.345405821527, 10537.311940820651, 11609.187356884937, 11611.361375124281, 11733.647990560297, 13492.797608035888, 13958.0, 14041.85958140426, 14328.713425731636, 15228.46700365482, 7037.612931288726, 7949.801254968672, 8467.237948584658, 8534.231919127309, 8594.699185528476, 7561.432892533082, 8347.207015333533, 8366.703161010053, 8495.36284778644, 8496.617652205665, 4523.0324808707655, 4601.3558101865765, 4610.884727881763, 4701.78841952113, 4705.905881882343, 1581.3358389476448, 1962.3229985654925, 1962.5682349883289, 1978.1790556919004, 1981.5343394302326, 848.3128665780737, 856.2850020233051, 883.4081165619051, 923.7044145873256, 1005.0954141272766, 17679.911600441916, 19636.410907672507, 20144.39566812998, 20415.0, 20417.795822041888, 832.5961908474385, 835.7450977451936, 936.8501039833594, 967.1634036558327, 985.2462865907586, 7919.722809701748, 10548.947255263827, 10771.138308935266, 10798.838017429745, 10805.567777288978, 594392.0, 605291.0, 606455.9354406488, 614982.0, 615693.0, 121472.0, 124433.75566244221, 128647.07029394567, 129050.0, 131563.3421832904, 871.8997315308694, 1012.7771890184148, 1027.0448482910965, 1032.829583118777, 1037.7509397744486, 17473.82526174722, 17481.56296092608, 17758.283918732275, 18066.728999065286, 18210.817891820912, 48407.757961209965, 48588.0, 51451.48548514467, 51892.740536297075, 52592.737036314575, 56242.1563676537, 68774.28064298432, 73834.63082684552, 75063.0, 76222.61888690521, 8100.71647492335, 8134.267915887599, 8167.224113709188, 8193.262606365455, 8557.44376615518, 11718.648440546456, 12333.198342107735, 12504.562340318045, 12614.873851261369, 12784.169029013085, 6864.347886950732, 8620.267277281502, 10875.945620271848, 11028.172887033665, 11271.943640282074, 10598.417087060287, 10763.0, 10901.34591924487, 10924.126069914546, 11169.994700476997, 10838.0, 11166.49750761234, 11305.15211359135, 11423.143264255381, 11529.962303392735, 7056.364927156581, 7264.273572642694, 8057.274845263954, 8066.475679080841, 8145.918540814516, 9488.430694158373, 10938.289011214245, 11009.889901100887, 11110.000099991039, 11260.774784504429], "value": 9092.682289287044, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.1968938326386, 1602.8717702583795, 1622.2699785096709, 1886.3397810766235, 2014.0835919656558, 7001.229864714884, 8520.275776558992, 8691.56542172892, 8709.954805423351, 8710.0, 8419.663213471455, 8508.319334453228, 8568.957155214215, 8577.957110214456, 8581.914180858204, 2131.8614290071096, 2373.3947843299934, 2413.263954493883, 2443.2792326263707, 2454.214651311581, 2054.763702174254, 2480.6651102101164, 2483.205374280231, 2490.1906880263896, 2492.439201179738, 2087.2381580723077, 2399.1962692497946, 2420.225527831095, 2426.1751004658413, 2439.85360878347, 2126.1070350452846, 2338.3218866528687, 2385.2605692235375, 2404.915827946022, 2429.6598476213344, 2417.62526808345, 2445.510897820443, 2449.4856080223176, 2450.754924507535, 2450.8652024138664, 8516.574171291473, 8587.957060214721, 8596.484210947363, 8602.827943441096, 8620.051794302623, 5065.1895696688725, 5270.314859068335, 5271.499207575274, 5278.577713782888, 5285.101532739443, 3318.3363327334387, 3414.9146271343166, 3543.326767914088, 3549.6450354964295, 3550.5916819565823, 1021.851831484438, 1024.9743756406076, 1033.6020632056636, 1034.1209971524145, 1042.1089968077335, 11489.185149638224, 11527.596534121349, 11566.363849988247, 11579.826302605468, 11608.4195790211, 4610.815567377267, 4669.976650116728, 4895.951040489621, 4896.36347274852, 4898.21628539432, 9801.558929848174, 9842.704718858446, 9846.310758246855, 9850.852237216448, 9859.605615775288, 5351.81268655603, 5368.979893820162, 5369.6509726867625, 5378.542823859937, 5384.730763461811, 4379.167958087953, 4427.46870375557, 4910.2880082388565, 4938.481459446778, 4949.307097006422, 3978.224246271949, 3986.940195897064, 4102.097538541516, 4110.095778928631, 4110.979445102816, 1339.7856342985076, 1354.3905242640851, 1356.8371795384617, 1363.9318034098453, 1364.7543442180304, 855.8331125430606, 871.6164887449502, 880.859062549989, 928.3176865004217, 950.5104870991445, 4470.955290447119, 5781.682007489629, 5808.157817116495, 5862.413758624189, 5970.940290597126, 454.45238487622333, 466.4682262221071, 469.11571687369553, 476.4497005958124, 486.6642017008253, 3844.9807750961068, 3900.1224724437343, 3971.0270983609203, 3976.5228172619472, 3978.6021397860563, 10159.238057145743, 10184.949075254577, 10649.414282214397, 10656.360618362924, 10661.84007239892, 10470.371777693363, 10579.84130238047, 10589.57641694339, 10614.522346494427, 10617.044465998097, 7947.721829735931, 8104.756857294291, 8146.144654811278, 8184.386171037196, 8201.95899020513, 5699.689071513492, 5730.312362516524, 5743.483086522233, 5794.565407594363, 5832.037713777179, 9539.523023848918, 9824.968378320173, 9907.306488545733, 9907.801843963225, 9909.355891867006, 9512.762180945532, 9946.602135914482, 10005.299629025898, 10057.949710251694, 10068.144207742424, 6273.654948977667, 6564.0, 7327.963360183165, 7414.962925185555, 7434.814129646586, 8144.511329320259, 8179.713710020121, 8247.876281855897, 8268.83462330762, 8275.255227029596, 7184.640767961686, 7251.274872512707, 7269.0, 7419.369353605004, 7439.404847612286, 7074.398676112588, 7175.425965922818, 7208.819779505554, 7281.980522726716, 7292.234315397019, 6873.2783057778215, 6970.756023539355, 6977.302269772982, 6989.790306290819, 7003.29967003316, 4029.37544680575, 4823.541763532458, 4860.270959356125, 4885.487023862585, 4948.8762780930765, 4935.490273918193, 4949.950500494949, 5007.924881126713, 5042.520960508746, 5113.4375218726655], "value": 4472.561592790172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.68390451500755, 321.9275662975829, 328.0731932291277, 330.90900002499325, 334.9162709322669, 5029.673071250366, 5736.770529178828, 5822.126680997847, 5872.295324561053, 5887.2346594942655, 5062.16474281744, 5607.299087614048, 5810.651360918348, 5840.415958404157, 5852.76588936442, 3976.6023397660147, 4128.814203360848, 4266.74131131317, 4357.433533640622, 4385.539518350575, 4314.460692413449, 4347.60871521563, 4373.497047839507, 4383.868483945486, 4388.363687265344, 4320.265554855667, 4358.237308471021, 4378.299472084467, 4382.167388196232, 4397.670174736892, 3805.9619403806023, 4135.8966025849295, 4417.337399390085, 4418.315161150027, 4422.71252368595, 3517.7889326640484, 3791.791451470168, 3821.9808900955595, 3830.157365379612, 3832.8658496952753, 4913.70517768935, 4936.679115857493, 5114.948850511521, 5156.381234251876, 5196.29849970253, 4609.516000819933, 4620.884477888046, 4652.9996050848895, 4895.510448955111, 5012.172991456406, 5101.7194054327, 6146.508279337642, 6166.84582885427, 6173.81478555644, 6178.242753080103, 3613.8012409317475, 3657.5976642569294, 4102.466679331697, 4120.278951183531, 4123.8556650517385, 5401.405845357008, 6085.756569737249, 6481.0, 6536.673166341664, 6589.11242367001, 3454.5854497459964, 4094.1402305516176, 4145.937810932838, 4153.584641535822, 4159.9792001039805, 4231.153769246163, 4233.73407357107, 4234.513031001475, 4235.983363992619, 4242.85150019754, 4346.457007762262, 4537.4328208973875, 4732.716037037789, 4897.706137631753, 4997.750112494361, 6138.294096178996, 6161.630302181794, 6166.64333846553, 6180.227471566054, 6215.81352559424, 1434.9713005739827, 1451.3831621560794, 1452.7167202395642, 1453.62932452224, 1453.7964684944116, 930.8277968575835, 943.1794338925179, 952.9332946693803, 953.7997020625746, 969.340848223215, 5582.553395728332, 5592.769590690042, 5661.688607126647, 5708.686022268732, 5741.224934633771, 5090.618203634742, 5207.58902918589, 6251.467451305627, 6316.30520642729, 6443.548951573345, 4557.5898169164275, 5403.459654034644, 5435.483629055233, 5697.658140511582, 5748.741306641211, 3346.9330613387597, 3356.0, 3359.916002099967, 3369.713574346208, 3378.915527111842, 5352.732363381815, 6385.457236134887, 6550.248895463917, 6615.17310336208, 6647.4349680276755, 5401.6218864679095, 5693.857653558694, 6496.415322620986, 6500.122483464672, 6527.836804079936, 5870.5010074144175, 5919.970400147971, 6009.45914867664, 6533.607983521004, 6590.736370545124, 5408.972955135199, 6082.78710245157, 6427.389398007182, 6473.708683109366, 6480.967595161994, 5898.852528686645, 6261.906071408841, 6340.29092980593, 6379.393957574023, 6391.265004524539, 5757.251557297524, 6570.85010123236, 6698.975768173869, 6714.83212919681, 6736.562123461959, 4145.523264824463, 4156.272652285898, 4158.708890377766, 4170.624643782075, 4189.87430377077, 6109.0, 6215.762325562703, 6411.4870810336, 6429.678516074271, 6437.25971513282, 5481.972590137024, 5590.8043218487155, 6018.127371531191, 6060.454559089703, 6173.351798061139, 5141.740273632984, 6208.534359923115, 6234.937650623436, 6254.562180647311, 6272.2159730033745, 5342.439043900335, 5813.273340832396, 6095.573309868444, 6109.2974307955155, 6112.510999119971, 6187.133801267736, 6201.348858369806, 6206.548413376781, 6216.502679785697, 6218.81343559694, 5129.512696293846, 5837.328707198726, 6093.0, 6107.358727333566, 6110.564017455806], "value": 4454.538260304828, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.8953157026446, 700.8668353012927, 707.9893801592974, 719.5574721546244, 786.2373497707225, 14784.77822832657, 14928.029678070945, 15219.923900380483, 15229.771553426694, 15249.161296128708, 11795.0, 14016.64958376041, 14296.71406571868, 14313.78529322061, 14319.498817541387, 756.3457609168057, 777.4441274488739, 951.9385884738501, 968.8449848024318, 968.9224862011022, 935.8409070458005, 987.4717026390847, 991.023841516106, 991.3110388280148, 991.5587563534194, 1016.4460369098858, 1019.5768755966266, 1023.2939271902401, 1024.692592222334, 1025.2208321675544, 780.2626517940548, 977.7751117243002, 1009.5507499162876, 1011.3224139826334, 1012.4431562640518, 757.428141752973, 758.4577027425362, 760.5170716594947, 760.8744557148063, 761.6496411650647, 15167.62080947974, 15228.314725837365, 15238.847611523854, 15241.390344386211, 15259.08445493274, 12608.306543140123, 12803.295818729966, 12865.871341286655, 12988.28564428956, 13108.410121544459, 8679.566021698889, 9927.453990030617, 10045.045720656526, 10187.0, 10392.064714175685, 3501.352249833789, 3517.9472307915403, 3529.417646088418, 3611.20553478237, 3628.310620982005, 77358.61320693417, 78443.0, 87420.56289718511, 87794.56102719446, 88176.55911720464, 8219.424640275243, 9562.235021198427, 9577.281703872237, 9705.864709763982, 9815.852762208573, 22997.770022299563, 23015.53968920612, 23025.19411820578, 23042.539149217257, 23076.423089422562, 7394.260573942563, 7404.1855395906405, 7471.4396420268695, 7799.298063174341, 7804.024496937883, 3936.3111455495173, 3951.8616848410743, 4000.099977505038, 4026.0337518995816, 4055.59444055598, 3603.873864414733, 3676.5771936227143, 3677.3380791457794, 3693.1136527233807, 3696.1129328960856, 609.4910749524108, 641.6086187425667, 643.8197304754676, 662.5858838226109, 669.5580916595023, 577.9829291100449, 581.7178668345849, 605.8879107365152, 613.190588423277, 615.8645098078416, 6557.409833115043, 7887.408444366695, 7997.560134192675, 8042.9597852010365, 8246.216609422096, 577.9855503612359, 601.8645804693996, 602.6173379903793, 608.3794529579892, 612.4334990134108, 3244.935101297961, 3774.584795672474, 3894.4937158169823, 3894.90262743428, 4007.479027726435, 90235.64646530307, 90515.0, 91787.0, 93528.59707104399, 93685.53157234307, 81849.3630127406, 82028.0, 82028.0, 82141.17858821455, 82143.0, 70742.0, 72636.0, 73042.63478682573, 73226.63386683032, 74414.0, 6383.297837237993, 7418.851622967619, 7711.730089446843, 7764.184760600281, 7804.726834560764, 43550.12899741924, 44680.96784978336, 45918.08163836638, 46011.53988460072, 46180.53819461897, 64271.0, 65553.01670474852, 65597.67201163963, 65947.6702616503, 65997.01004485032, 10321.845172322572, 10332.328398654063, 11074.335539867634, 11293.209475336647, 11364.977152056355, 9501.619935202514, 11658.242214256046, 11909.0, 11976.880231197576, 12152.75694486123, 11371.829422558834, 11399.772004559696, 11440.656780296604, 11569.0, 11924.344161071225, 9171.40385874916, 9961.0, 10195.541200645841, 10364.896351036394, 10391.58433662645, 10382.0, 10392.324498907547, 10700.0, 10703.732406689895, 10851.294665846695, 7704.229577042251, 9432.764180895532, 9694.612215511583, 9986.0, 10066.194704423775, 6660.467162627075, 9324.95337523308, 9689.41863488193, 9843.212542996685, 9869.210463163074], "value": 6880.662072299347, "unit": "score"}], "name": "dromaeo_css", "value": 7203.152201031619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 111.0, 112.0, 111.0, 114.0, 119.0, 111.0, 112.0, 110.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 143.0, 165.0, 117.0, 141.0, 136.0, 117.0, 169.0, 118.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 183.0, 183.0, 183.0, 193.0, 258.0, 185.0, 182.0, 182.0], "value": 192.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 96.0, 119.0, 82.0, 62.0, 57.0, 84.0, 96.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 138.0, 81.0, 79.0, 81.0, 124.0, 80.0, 77.0, 128.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 135.0, 111.0, 87.0, 85.0, 135.0, 83.0, 85.0, 84.0], "value": 98.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0], "value": 92.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 87.0, 85.0, 84.0, 84.0, 86.0, 85.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 57.0, 57.0, 58.0, 56.0, 77.0, 57.0, 57.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 74.0, 71.0, 70.0, 67.0, 66.0, 69.0, 68.0, 65.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 82.0, 82.0, 82.0, 80.0, 81.0, 80.0, 84.0, 81.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 154.0, 151.0, 141.0, 138.0, 134.0, 134.0, 173.0, 141.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 169.0, 176.0, 170.0, 170.0, 167.0, 170.0, 167.0, 165.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 67.0, 68.0, 67.0, 71.0, 98.0, 98.0, 97.0], "value": 77.1, "unit": "score"}], "name": "kraken", "value": 1498.5, "alertThreshold": 2.0}]} 09:12:41 INFO - Return code: 0 09:12:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:12:41 ERROR - # TBPL SUCCESS # 09:12:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:12:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:12:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:12:41 INFO - 2016-04-21 09:12:41.364 screenresolution[1965:13083] starting screenresolution argv=screenresolution get 09:12:41 INFO - 2016-04-21 09:12:41.367 screenresolution[1965:13083] Display 0: 1600x1200x32@60 09:12:41 INFO - 2016-04-21 09:12:41.373 screenresolution[1966:13086] starting screenresolution argv=screenresolution list 09:12:41 INFO - Available Modes on Display 0 09:12:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:12:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:12:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:12:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:12:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:12:41 INFO - Intel Iris: 09:12:41 INFO - Chipset Model: Intel Iris 09:12:41 INFO - Type: GPU 09:12:41 INFO - Bus: Built-In 09:12:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:12:41 INFO - Vendor: Intel (0x8086) 09:12:41 INFO - Device ID: 0x0a2e 09:12:41 INFO - Revision ID: 0x0009 09:12:41 INFO - Displays: 09:12:41 INFO - Display: 09:12:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:12:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:12:41 INFO - Display Serial Number: 1600x1200 60 09:12:41 INFO - Main Display: Yes 09:12:41 INFO - Mirror: Off 09:12:41 INFO - Online: Yes 09:12:41 INFO - Rotation: Supported 09:12:41 INFO - Return code: 0 09:12:41 INFO - Running post-action listener: _resource_record_post_action 09:12:41 INFO - Running post-run listener: _resource_record_post_run 09:12:42 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 884736; Write bytes: 694822400; Read time: 13; Write time: 2041 09:12:42 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388051456; Write bytes: 381470208; Read time: 28519; Write time: 753 09:12:42 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 884736; Write bytes: 295616512; Read time: 13; Write time: 1252 09:12:42 INFO - Running post-run listener: _upload_blobber_files 09:12:42 INFO - Blob upload gear active. 09:12:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:12:42 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:12:42 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:12:42 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:12:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:12:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:12:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:12:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:44 INFO - (blobuploader) - INFO - Done attempting. 09:12:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:12:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:12:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:45 INFO - (blobuploader) - INFO - Done attempting. 09:12:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:12:45 INFO - Return code: 0 09:12:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:12:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:12:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bca1cfe9562eac7bda666e72bde1532b72e3f14ca115019ce545d7124f90ed4418d1c2b7c876caaef2b383d146a06cde24d4b45a97c8f5a4228de2a16ea0e40f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e4deca05f52dcc9d9bb75e4a34f8609299dd22a66c140ce0e4722756d3afba189b06ffaff1c3badb747eb2296327269fe0f70af5d335c3dc858765469c4b4e1"} 09:12:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:12:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:12:45 INFO - Contents: 09:12:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bca1cfe9562eac7bda666e72bde1532b72e3f14ca115019ce545d7124f90ed4418d1c2b7c876caaef2b383d146a06cde24d4b45a97c8f5a4228de2a16ea0e40f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e4deca05f52dcc9d9bb75e4a34f8609299dd22a66c140ce0e4722756d3afba189b06ffaff1c3badb747eb2296327269fe0f70af5d335c3dc858765469c4b4e1"} 09:12:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:12:45 INFO - Copying logs to upload dir... 09:12:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:12:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.468694 ========= master_lag: 9.73 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-21 09:12:54.837386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-21 09:12:54.840542) ========= 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.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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/bca1cfe9562eac7bda666e72bde1532b72e3f14ca115019ce545d7124f90ed4418d1c2b7c876caaef2b383d146a06cde24d4b45a97c8f5a4228de2a16ea0e40f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e4deca05f52dcc9d9bb75e4a34f8609299dd22a66c140ce0e4722756d3afba189b06ffaff1c3badb747eb2296327269fe0f70af5d335c3dc858765469c4b4e1"} build_url:https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/CZ_pmHCsTuu0lX7fjxuIsw/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/bca1cfe9562eac7bda666e72bde1532b72e3f14ca115019ce545d7124f90ed4418d1c2b7c876caaef2b383d146a06cde24d4b45a97c8f5a4228de2a16ea0e40f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e4deca05f52dcc9d9bb75e4a34f8609299dd22a66c140ce0e4722756d3afba189b06ffaff1c3badb747eb2296327269fe0f70af5d335c3dc858765469c4b4e1"}' ========= master_lag: 5.23 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-21 09:13:00.079023) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-21 09:13:00.079315) ========= 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.bA5zIqwCji/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.8BcGW3QQcm/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005165 ========= master_lag: 9.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-21 09:13:09.273689) ========= ========= Started reboot skipped (results: 3, elapsed: 9 secs) (at 2016-04-21 09:13:09.273991) ========= ========= Finished reboot skipped (results: 3, elapsed: 9 secs) (at 2016-04-21 09:13:18.480746) ========= ========= Total master_lag: 32.54 =========