builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0002 starttime: 1461754153.61 results: success (0) buildid: 20160425042155 builduid: 3cafbae6ee4445cbb07d61c2d398d690 revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.611981) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.612409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.612683) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.635115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.635509) ========= 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.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-27 03:49:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-04-27 03:49:13 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099521 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.753187) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.753518) ========= 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.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.020367 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:13.802555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:49:13.805921) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f328ac32af6a3d0ce1593c41fd18edf7216a1652 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f328ac32af6a3d0ce1593c41fd18edf7216a1652 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-27 03:49:13,882 truncating revision to first 12 chars 2016-04-27 03:49:13,882 Setting DEBUG logging. 2016-04-27 03:49:13,882 attempt 1/10 2016-04-27 03:49:13,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f328ac32af6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 03:49:15,358 unpacking tar archive at: mozilla-aurora-f328ac32af6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.725884 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:49:15.548686) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:15.552076) ========= script_repo_revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:15.552460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:15.552719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:15.570346) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-27 03:49:15.570629) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:15 INFO - MultiFileLogger online at 20160427 03:49:15 in /builds/slave/test 03:49:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:49:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:49:15 INFO - {'append_to_log': False, 03:49:15 INFO - 'base_work_dir': '/builds/slave/test', 03:49:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:49:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:49:15 INFO - 'branch': 'Mozilla-Aurora', 03:49:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:15 INFO - 'config_files': ('talos/mac_config.py',), 03:49:15 INFO - 'default_actions': ('clobber', 03:49:15 INFO - 'read-buildbot-config', 03:49:15 INFO - 'download-and-extract', 03:49:15 INFO - 'populate-webroot', 03:49:15 INFO - 'create-virtualenv', 03:49:15 INFO - 'install', 03:49:15 INFO - 'run-tests'), 03:49:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:15 INFO - 'download_minidump_stackwalk': True, 03:49:15 INFO - 'download_symbols': 'ondemand', 03:49:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:49:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:49:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:49:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:49:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:15 INFO - 'installer_path': 'installer.exe', 03:49:15 INFO - 'log_level': 'info', 03:49:15 INFO - 'log_name': 'talos', 03:49:15 INFO - 'log_to_console': True, 03:49:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:49:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:49:15 INFO - 'opt_config_files': (), 03:49:15 INFO - 'pip_index': False, 03:49:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:15 INFO - 'cmd': ('bash', 03:49:15 INFO - '-c', 03:49:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:15 INFO - 'enabled': True, 03:49:15 INFO - 'halt_on_failure': False, 03:49:15 INFO - 'name': 'check_screen_resolution'},), 03:49:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:15 INFO - 'cmd': ('bash', 03:49:15 INFO - '-c', 03:49:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:15 INFO - 'enabled': True, 03:49:15 INFO - 'halt_on_failure': False, 03:49:15 INFO - 'name': 'check_screen_resolution'},), 03:49:15 INFO - 'run_cmd_checks_enabled': True, 03:49:15 INFO - 'sps_profile': False, 03:49:15 INFO - 'sps_profile_interval': 0, 03:49:15 INFO - 'suite': 'dromaeojs', 03:49:15 INFO - 'system_bits': '32', 03:49:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:15 INFO - 'title': 't-yosemite-r7-0002', 03:49:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:49:15 INFO - 'use_talos_json': True, 03:49:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:49:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:15 INFO - 'work_dir': 'build'} 03:49:15 INFO - ##### 03:49:15 INFO - ##### Running clobber step. 03:49:15 INFO - ##### 03:49:15 INFO - Running pre-action listener: _resource_record_pre_action 03:49:15 INFO - Running main action method: clobber 03:49:15 INFO - rmtree: /builds/slave/test/build 03:49:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:49:17 INFO - Running post-action listener: _resource_record_post_action 03:49:17 INFO - ##### 03:49:17 INFO - ##### Running read-buildbot-config step. 03:49:17 INFO - ##### 03:49:17 INFO - Running pre-action listener: _resource_record_pre_action 03:49:17 INFO - Running main action method: read_buildbot_config 03:49:17 INFO - Using buildbot properties: 03:49:17 INFO - { 03:49:17 INFO - "project": "", 03:49:17 INFO - "product": "firefox", 03:49:17 INFO - "script_repo_revision": "production", 03:49:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:49:17 INFO - "repository": "", 03:49:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:49:17 INFO - "buildid": "20160425042155", 03:49:17 INFO - "pgo_build": "False", 03:49:17 INFO - "basedir": "/builds/slave/test", 03:49:17 INFO - "buildnumber": 326, 03:49:17 INFO - "slavename": "t-yosemite-r7-0002", 03:49:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:49:17 INFO - "platform": "yosemite_r7", 03:49:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:49:17 INFO - "branch": "mozilla-aurora", 03:49:17 INFO - "repo_path": "releases/mozilla-aurora", 03:49:17 INFO - "revision": "f328ac32af6a3d0ce1593c41fd18edf7216a1652", 03:49:17 INFO - "stage_platform": "macosx64", 03:49:17 INFO - "builduid": "3cafbae6ee4445cbb07d61c2d398d690", 03:49:17 INFO - "slavebuilddir": "test" 03:49:17 INFO - } 03:49:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:49:17 INFO - Running post-action listener: _resource_record_post_action 03:49:17 INFO - ##### 03:49:17 INFO - ##### Running download-and-extract step. 03:49:17 INFO - ##### 03:49:17 INFO - Running pre-action listener: _resource_record_pre_action 03:49:17 INFO - Running main action method: download_and_extract 03:49:17 INFO - mkdir: /builds/slave/test/build/tests 03:49: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')]} 03:49:17 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:49:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:49:18 INFO - Downloaded 1277 bytes. 03:49:18 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:18 INFO - Using the following test package requirements: 03:49:18 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:18 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:49:18 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:49:18 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'jsshell-mac.zip'], 03:49:18 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:49:18 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:18 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:49:18 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:49:18 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:49:18 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:18 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:18 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:49:18 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:18 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:49:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:49:20 INFO - Downloaded 17919003 bytes. 03:49:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:49:23 INFO - Return code: 0 03:49:23 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:49: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')]} 03:49:23 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:49:25 INFO - Downloaded 17919003 bytes. 03:49:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:49:28 INFO - Return code: 0 03:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:28 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:49:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:49:29 INFO - Downloaded 10845194 bytes. 03:49:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:49:29 INFO - Return code: 0 03:49:29 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')]} 03:49:29 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:49:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:49:32 INFO - Downloaded 115766844 bytes. 03:49:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:32 INFO - mkdir: /builds/slave/test/properties 03:49:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:49:32 INFO - Writing to file /builds/slave/test/properties/build_url 03:49:32 INFO - Contents: 03:49:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:34 INFO - Running post-action listener: _resource_record_post_action 03:49:34 INFO - Running post-action listener: set_extra_try_arguments 03:49:34 INFO - ##### 03:49:34 INFO - ##### Running populate-webroot step. 03:49:34 INFO - ##### 03:49:34 INFO - Running pre-action listener: _resource_record_pre_action 03:49:34 INFO - Running main action method: populate_webroot 03:49:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:49:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:49:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:49:34 INFO - '--tppagecycles', 03:49:34 INFO - '7'], 03:49:34 INFO - 'tests': ['tsvgm']}}, 03:49:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:49:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:49:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:49:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:49:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'tests': ['damp', 'tps']}, 03:49:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['damp', 'tps']}, 03:49:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:49:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:49:34 INFO - 'other': {'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'other_l64': {'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'other_nol64': {'tests': ['a11yr', 03:49:34 INFO - 'ts_paint', 03:49:34 INFO - 'tpaint', 03:49:34 INFO - 'sessionrestore', 03:49:34 INFO - 'sessionrestore_no_auto_restore', 03:49:34 INFO - 'tabpaint']}, 03:49:34 INFO - 'svgr': {'tests': ['tsvgx', 03:49:34 INFO - 'tsvgr_opacity', 03:49:34 INFO - 'tart', 03:49:34 INFO - 'tscrollx', 03:49:34 INFO - 'cart']}, 03:49:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['tsvgx', 03:49:34 INFO - 'tsvgr_opacity', 03:49:34 INFO - 'tart', 03:49:34 INFO - 'tscrollx', 03:49:34 INFO - 'cart']}, 03:49:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'tests': ['tp5o']}, 03:49:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'talos_options': ['--e10s'], 03:49:34 INFO - 'tests': ['tp5o']}, 03:49:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'talos_options': ['--xperf_path', 03:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:34 INFO - 'tests': ['tp5n']}, 03:49:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:34 INFO - 'talos_options': ['--e10s', 03:49:34 INFO - '--xperf_path', 03:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:34 INFO - 'tests': ['tp5n']}}, 03:49:34 INFO - 'talos.zip': {'path': '', 03:49:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:49:34 INFO - Running post-action listener: _resource_record_post_action 03:49:34 INFO - ##### 03:49:34 INFO - ##### Running create-virtualenv step. 03:49:34 INFO - ##### 03:49:34 INFO - Running pre-action listener: _resource_record_pre_action 03:49:34 INFO - Running main action method: create_virtualenv 03:49:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:49:34 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 03:49:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:49:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:49:34 INFO - Using real prefix '/tools/python27' 03:49:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:49:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:49:38 INFO - Installing pip.................done. 03:49:38 INFO - Return code: 0 03:49:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:49:38 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')]} 03:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:38 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')]} 03:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:38 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 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:38 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 03:49:38 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 03:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:38 INFO - 'HOME': '/Users/cltbld', 03:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:38 INFO - 'LOGNAME': 'cltbld', 03:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:38 INFO - 'MOZ_NO_REMOTE': '1', 03:49:38 INFO - 'NO_EM_RESTART': '1', 03:49:38 INFO - 'PAGER': '/bin/cat', 03:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:38 INFO - 'PWD': '/builds/slave/test', 03:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:38 INFO - 'SHELL': '/bin/bash', 03:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:38 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:38 INFO - 'USER': 'cltbld', 03:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:38 INFO - 'XPC_FLAGS': '0x0', 03:49:38 INFO - 'XPC_SERVICE_NAME': '0', 03:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:38 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 03:49:38 INFO - Cleaning up... 03:49:38 INFO - Return code: 0 03:49:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:49:38 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')]} 03:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:38 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')]} 03:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:38 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 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:38 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 03:49:38 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 03:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:38 INFO - 'HOME': '/Users/cltbld', 03:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:38 INFO - 'LOGNAME': 'cltbld', 03:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:38 INFO - 'MOZ_NO_REMOTE': '1', 03:49:38 INFO - 'NO_EM_RESTART': '1', 03:49:38 INFO - 'PAGER': '/bin/cat', 03:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:38 INFO - 'PWD': '/builds/slave/test', 03:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:38 INFO - 'SHELL': '/bin/bash', 03:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:38 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:38 INFO - 'USER': 'cltbld', 03:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:38 INFO - 'XPC_FLAGS': '0x0', 03:49:38 INFO - 'XPC_SERVICE_NAME': '0', 03:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:38 INFO - Downloading/unpacking psutil>=0.7.1 03:49:38 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 03:49:38 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 03:49:38 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 03:49:38 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 03:49:38 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 03:49:38 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 03:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:49:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:41 INFO - Installing collected packages: psutil 03:49:41 INFO - Running setup.py install for psutil 03:49:41 INFO - building 'psutil._psutil_osx' extension 03:49:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:49:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:49:42 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 03:49:42 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 03:49:42 INFO - building 'psutil._psutil_posix' extension 03:49:42 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 03:49:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:49:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:49:42 INFO - ^ 03:49:42 INFO - 1 warning generated. 03:49:42 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 03:49:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:42 INFO - Successfully installed psutil 03:49:42 INFO - Cleaning up... 03:49:42 INFO - Return code: 0 03:49:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:42 INFO - 'HOME': '/Users/cltbld', 03:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:42 INFO - 'LOGNAME': 'cltbld', 03:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:42 INFO - 'MOZ_NO_REMOTE': '1', 03:49:42 INFO - 'NO_EM_RESTART': '1', 03:49:42 INFO - 'PAGER': '/bin/cat', 03:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:42 INFO - 'PWD': '/builds/slave/test', 03:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:42 INFO - 'SHELL': '/bin/bash', 03:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:42 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:42 INFO - 'USER': 'cltbld', 03:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:42 INFO - 'XPC_FLAGS': '0x0', 03:49:42 INFO - 'XPC_SERVICE_NAME': '0', 03:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:49:45 INFO - Installing collected packages: mozsystemmonitor 03:49:45 INFO - Running setup.py install for mozsystemmonitor 03:49:45 INFO - Successfully installed mozsystemmonitor 03:49:45 INFO - Cleaning up... 03:49:45 INFO - Return code: 0 03:49:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:45 INFO - 'HOME': '/Users/cltbld', 03:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:45 INFO - 'LOGNAME': 'cltbld', 03:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:45 INFO - 'MOZ_NO_REMOTE': '1', 03:49:45 INFO - 'NO_EM_RESTART': '1', 03:49:45 INFO - 'PAGER': '/bin/cat', 03:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:45 INFO - 'PWD': '/builds/slave/test', 03:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:45 INFO - 'SHELL': '/bin/bash', 03:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:45 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:45 INFO - 'USER': 'cltbld', 03:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:45 INFO - 'XPC_FLAGS': '0x0', 03:49:45 INFO - 'XPC_SERVICE_NAME': '0', 03:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:49:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:49:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:49 INFO - Downloading docopt-0.6.1.tar.gz 03:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:49:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:49:49 INFO - Running setup.py install for blobuploader 03:49:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:49:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:49:49 INFO - Running setup.py install for requests 03:49:50 INFO - Running setup.py install for docopt 03:49:50 INFO - Successfully installed blobuploader requests docopt 03:49:50 INFO - Cleaning up... 03:49:50 INFO - Return code: 0 03:49:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49: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')]} 03:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49: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')]} 03:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:49:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:50 INFO - 'HOME': '/Users/cltbld', 03:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:50 INFO - 'LOGNAME': 'cltbld', 03:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:50 INFO - 'MOZ_NO_REMOTE': '1', 03:49:50 INFO - 'NO_EM_RESTART': '1', 03:49:50 INFO - 'PAGER': '/bin/cat', 03:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:50 INFO - 'PWD': '/builds/slave/test', 03:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:50 INFO - 'SHELL': '/bin/bash', 03:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:50 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:50 INFO - 'USER': 'cltbld', 03:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:50 INFO - 'XPC_FLAGS': '0x0', 03:49:50 INFO - 'XPC_SERVICE_NAME': '0', 03:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-sslnxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-DlOEvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-79i4V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Gxesog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-AClYhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-k_t9ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-LxwTz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-vSjlGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-6qeQHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-fZFjZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-E69dmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-8GF_Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-J6qDLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-OpNpiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-o6s7go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-FnpdF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-AUE0Wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:49:52 INFO - Running setup.py install for manifestparser 03:49:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:49:52 INFO - Running setup.py install for mozcrash 03:49:52 INFO - Running setup.py install for mozdebug 03:49:52 INFO - Running setup.py install for mozdevice 03:49:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Running setup.py install for mozfile 03:49:53 INFO - Running setup.py install for mozhttpd 03:49:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Running setup.py install for mozinfo 03:49:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Running setup.py install for mozInstall 03:49:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Running setup.py install for mozleak 03:49:53 INFO - Running setup.py install for mozlog 03:49:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:49:53 INFO - Running setup.py install for moznetwork 03:49:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:49:54 INFO - Running setup.py install for mozprocess 03:49:54 INFO - Running setup.py install for mozprofile 03:49:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:49:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:49:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:49:54 INFO - Running setup.py install for mozrunner 03:49:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:49:54 INFO - Running setup.py install for mozscreenshot 03:49:54 INFO - Running setup.py install for moztest 03:49:54 INFO - Running setup.py install for mozversion 03:49:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:49:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:49:55 INFO - Cleaning up... 03:49:55 INFO - Return code: 0 03:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:55 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')]} 03:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:55 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')]} 03:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:55 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 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:49:55 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 03:49:55 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 03:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:55 INFO - 'HOME': '/Users/cltbld', 03:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:55 INFO - 'LOGNAME': 'cltbld', 03:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:55 INFO - 'MOZ_NO_REMOTE': '1', 03:49:55 INFO - 'NO_EM_RESTART': '1', 03:49:55 INFO - 'PAGER': '/bin/cat', 03:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:55 INFO - 'PWD': '/builds/slave/test', 03:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:55 INFO - 'SHELL': '/bin/bash', 03:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:49:55 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:55 INFO - 'USER': 'cltbld', 03:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:55 INFO - 'XPC_FLAGS': '0x0', 03:49:55 INFO - 'XPC_SERVICE_NAME': '0', 03:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-WNsynx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gizg69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-CAenZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-0etBLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-2ICRMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-aY7UtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-b7sRS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:56 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)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-W3yEk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:56 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)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-PNLRW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:56 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)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-ewVIsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:56 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)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-RLecYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-7ulrRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-JiJfJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-s7kfBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-sJRDPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-wl5DLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:57 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)) 03:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:57 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-SLu8CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:57 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)) 03:49:57 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)) 03:49:57 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)) 03:49:57 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)) 03:49:57 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)) 03:49:57 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)) 03:49:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:59 INFO - Downloading blessings-1.6.tar.gz 03:49:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:49:59 INFO - Installing collected packages: blessings 03:49:59 INFO - Running setup.py install for blessings 03:49:59 INFO - Successfully installed blessings 03:49:59 INFO - Cleaning up... 03:49:59 INFO - Return code: 0 03:49:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:49:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:49:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:00 INFO - Reading from file tmpfile_stdout 03:50:00 INFO - Current package versions: 03:50:00 INFO - blessings == 1.6 03:50:00 INFO - blobuploader == 1.2.4 03:50:00 INFO - docopt == 0.6.1 03:50:00 INFO - manifestparser == 1.1 03:50:00 INFO - mozInstall == 1.12 03:50:00 INFO - mozcrash == 0.17 03:50:00 INFO - mozdebug == 0.1 03:50:00 INFO - mozdevice == 0.48 03:50:00 INFO - mozfile == 1.2 03:50:00 INFO - mozhttpd == 0.7 03:50:00 INFO - mozinfo == 0.9 03:50:00 INFO - mozleak == 0.1 03:50:00 INFO - mozlog == 3.1 03:50:00 INFO - moznetwork == 0.27 03:50:00 INFO - mozprocess == 0.22 03:50:00 INFO - mozprofile == 0.28 03:50:00 INFO - mozrunner == 6.11 03:50:00 INFO - mozscreenshot == 0.1 03:50:00 INFO - mozsystemmonitor == 0.0 03:50:00 INFO - moztest == 0.7 03:50:00 INFO - mozversion == 1.4 03:50:00 INFO - psutil == 3.1.1 03:50:00 INFO - requests == 1.2.3 03:50:00 INFO - wsgiref == 0.1.2 03:50:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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')]} 03:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:00 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 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:50:00 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 03:50:00 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 03:50:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:50:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:00 INFO - 'HOME': '/Users/cltbld', 03:50:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:00 INFO - 'LOGNAME': 'cltbld', 03:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:00 INFO - 'MOZ_NO_REMOTE': '1', 03:50:00 INFO - 'NO_EM_RESTART': '1', 03:50:00 INFO - 'PAGER': '/bin/cat', 03:50:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:00 INFO - 'PWD': '/builds/slave/test', 03:50:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:00 INFO - 'SHELL': '/bin/bash', 03:50:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:50:00 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:50:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:00 INFO - 'USER': 'cltbld', 03:50:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:00 INFO - 'XPC_FLAGS': '0x0', 03:50:00 INFO - 'XPC_SERVICE_NAME': '0', 03:50:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:50:00 INFO - Cleaning up... 03:50:00 INFO - Return code: 0 03:50:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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')]} 03:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:50:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:50:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:00 INFO - 'HOME': '/Users/cltbld', 03:50:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:00 INFO - 'LOGNAME': 'cltbld', 03:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:00 INFO - 'MOZ_NO_REMOTE': '1', 03:50:00 INFO - 'NO_EM_RESTART': '1', 03:50:00 INFO - 'PAGER': '/bin/cat', 03:50:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:00 INFO - 'PWD': '/builds/slave/test', 03:50:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:00 INFO - 'SHELL': '/bin/bash', 03:50:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:50:00 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:50:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:00 INFO - 'USER': 'cltbld', 03:50:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:00 INFO - 'XPC_FLAGS': '0x0', 03:50:00 INFO - 'XPC_SERVICE_NAME': '0', 03:50:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:00 INFO - Downloading/unpacking jsonschema 03:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:50:03 INFO - Downloading/unpacking functools32 (from jsonschema) 03:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:50:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:50:03 INFO - warning: no files found matching '*.txt' 03:50:03 INFO - no previously-included directories found matching 'build' 03:50:03 INFO - no previously-included directories found matching 'dist' 03:50:03 INFO - no previously-included directories found matching '.git*' 03:50:03 INFO - Installing collected packages: jsonschema, functools32 03:50:03 INFO - Running setup.py install for functools32 03:50:04 INFO - warning: no files found matching '*.txt' 03:50:04 INFO - no previously-included directories found matching 'build' 03:50:04 INFO - no previously-included directories found matching 'dist' 03:50:04 INFO - no previously-included directories found matching '.git*' 03:50:04 INFO - Successfully installed jsonschema functools32 03:50:04 INFO - Cleaning up... 03:50:04 INFO - Return code: 0 03:50:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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')]} 03:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:04 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 0x10378ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037924c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba3af12590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, '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.74zCQKx2Sc/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.Voqjanta39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:50:04 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 03:50:04 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 03:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:04 INFO - 'HOME': '/Users/cltbld', 03:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:04 INFO - 'LOGNAME': 'cltbld', 03:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:04 INFO - 'MOZ_NO_REMOTE': '1', 03:50:04 INFO - 'NO_EM_RESTART': '1', 03:50:04 INFO - 'PAGER': '/bin/cat', 03:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:04 INFO - 'PWD': '/builds/slave/test', 03:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:04 INFO - 'SHELL': '/bin/bash', 03:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:50:04 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:04 INFO - 'USER': 'cltbld', 03:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:04 INFO - 'XPC_FLAGS': '0x0', 03:50:04 INFO - 'XPC_SERVICE_NAME': '0', 03:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:04 INFO - Downloading/unpacking flake8 03:50:04 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 03:50:04 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 03:50:04 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 03:50:04 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 03:50:04 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 03:50:04 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 03:50:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:50:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:50:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:50:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:50:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:50:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:50:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:50:08 INFO - no previously-included directories found matching 'docs/_build' 03:50:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:50:08 INFO - Running setup.py install for pep8 03:50:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:50:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:50:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:50:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:50:08 INFO - no previously-included directories found matching 'docs/_build' 03:50:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:50:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:50:08 INFO - Cleaning up... 03:50:08 INFO - Return code: 0 03:50:08 INFO - Running post-action listener: _resource_record_post_action 03:50:08 INFO - Running post-action listener: _start_resource_monitoring 03:50:08 INFO - Starting resource monitoring. 03:50:08 INFO - ##### 03:50:08 INFO - ##### Running install step. 03:50:08 INFO - ##### 03:50:08 INFO - Running pre-action listener: _resource_record_pre_action 03:50:08 INFO - Running main action method: install 03:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:08 INFO - Reading from file tmpfile_stdout 03:50:08 INFO - Detecting whether we're running mozinstall >=1.0... 03:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:50:08 INFO - Reading from file tmpfile_stdout 03:50:08 INFO - Output received: 03:50:08 INFO - Usage: mozinstall [options] installer 03:50:08 INFO - Options: 03:50:08 INFO - -h, --help show this help message and exit 03:50:08 INFO - -d DEST, --destination=DEST 03:50:08 INFO - Directory to install application into. [default: 03:50:08 INFO - "/builds/slave/test"] 03:50:08 INFO - --app=APP Application being installed. [default: firefox] 03:50:08 INFO - mkdir: /builds/slave/test/build/application 03:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:50:41 INFO - Reading from file tmpfile_stdout 03:50:41 INFO - Output received: 03:50:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:50:41 INFO - Running post-action listener: _resource_record_post_action 03:50:41 INFO - ##### 03:50:41 INFO - ##### Running run-tests step. 03:50:41 INFO - ##### 03:50:41 INFO - Running pre-action listener: _resource_record_pre_action 03:50:41 INFO - Running main action method: run_tests 03:50:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:50:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:50:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:50:41 INFO - 2016-04-27 03:50:41.137 screenresolution[2216:20862] starting screenresolution argv=screenresolution get 03:50:41 INFO - 2016-04-27 03:50:41.140 screenresolution[2216:20862] Display 0: 1600x1200x32@60 03:50:41 INFO - 2016-04-27 03:50:41.145 screenresolution[2217:20866] starting screenresolution argv=screenresolution list 03:50:41 INFO - Available Modes on Display 0 03:50:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:50:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:50:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:50:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:50:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:50:41 INFO - Intel Iris: 03:50:41 INFO - Chipset Model: Intel Iris 03:50:41 INFO - Type: GPU 03:50:41 INFO - Bus: Built-In 03:50:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:50:41 INFO - Vendor: Intel (0x8086) 03:50:41 INFO - Device ID: 0x0a2e 03:50:41 INFO - Revision ID: 0x0009 03:50:41 INFO - Displays: 03:50:41 INFO - Display: 03:50:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:50:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:50:41 INFO - Display Serial Number: 1600x1200 60 03:50:41 INFO - Main Display: Yes 03:50:41 INFO - Mirror: Off 03:50:41 INFO - Online: Yes 03:50:41 INFO - Rotation: Supported 03:50:41 INFO - Return code: 0 03:50:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:50:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:50:41 INFO - Python 2.7.3 03:50:41 INFO - Return code: 0 03:50:41 INFO - grabbing minidump binary from tooltool 03:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:41 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 0x10362fd30>, '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 0x103493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103626b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:50:41 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 03:50:41 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 03:50:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:50:41 INFO - Return code: 0 03:50:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:50:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:50:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:50:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:50:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:50:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:50:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:50:42 INFO - Return code: 0 03:50:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--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 03:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --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 03:50:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.74zCQKx2Sc/Render', 03:50:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:42 INFO - 'HOME': '/Users/cltbld', 03:50:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:42 INFO - 'LOGNAME': 'cltbld', 03:50:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:50:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:42 INFO - 'MOZ_NO_REMOTE': '1', 03:50:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:50:42 INFO - 'NO_EM_RESTART': '1', 03:50:42 INFO - 'PAGER': '/bin/cat', 03:50:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:42 INFO - 'PWD': '/builds/slave/test', 03:50:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:50:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:42 INFO - 'SHELL': '/bin/bash', 03:50:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Voqjanta39/Listeners', 03:50:42 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:50:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:42 INFO - 'USER': 'cltbld', 03:50:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:42 INFO - 'XPC_FLAGS': '0x0', 03:50:42 INFO - 'XPC_SERVICE_NAME': '0', 03:50:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--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 03:50:42 INFO - mozversion application_buildid: 20160425042155 03:50:42 INFO - mozversion application_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 03:50:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:50:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:50:42 INFO - mozversion application_name: Firefox 03:50:42 INFO - mozversion application_remotingname: firefox-dev 03:50:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:50:42 INFO - mozversion application_vendor: Mozilla 03:50:42 INFO - mozversion application_version: 47.0a2 03:50:42 INFO - mozversion platform_buildid: 20160425042155 03:50:42 INFO - mozversion platform_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 03:50:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:50:42 INFO - mozversion platform_version: 47.0a2 03:50:42 INFO - using testdate: 1461754242 03:50:42 INFO - actual date: 1461754242 03:50:42 INFO - starting webserver on 'localhost:55553' 03:50:42 INFO - SUITE-START | Running 2 tests 03:50:42 INFO - TEST-START | dromaeo_css 03:50:42 INFO - operating with platform_type : mac_ 03:50:42 INFO - Initialising browser for dromaeo_css test... 03:50:42 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVso8xd/profile http://localhost:55553/getInfo.html) 03:50:44 INFO - PROCESS | 2235 | __metrics Screen width/height:1600/1200 03:50:44 INFO - PROCESS | 2235 | colorDepth:24 03:50:44 INFO - PROCESS | 2235 | Browser inner width/height: 1024/689 03:50:44 INFO - PROCESS | 2235 | __metrics 03:50:45 INFO - PROCESS | 2235 | 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" 03:50:45 INFO - PROCESS | 2235 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:50:45 INFO - TEST-INFO | 2235: exit 0 03:50:45 INFO - Browser initialized. 03:50:45 INFO - Running cycle 1/1 for dromaeo_css test... 03:50:45 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVso8xd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:57 INFO - PROCESS | 2237 | RSS: Main: 208035840 03:50:57 INFO - PROCESS | 2237 | 03:51:48 INFO - PROCESS | 2237 | 1461754308433 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] 03:53:58 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55553/tests/dromaeo/cssquery-ext.html) 03:53:59 INFO - PROCESS | 2237 | RSS: Main: 338403328 03:53:59 INFO - PROCESS | 2237 | 03:57:02 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-ext.html (next: http://localhost:55553/tests/dromaeo/cssquery-jquery.html) 03:57:03 INFO - PROCESS | 2237 | RSS: Main: 348442624 03:57:03 INFO - PROCESS | 2237 | 04:00:05 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55553/tests/dromaeo/cssquery-mootools.html) 04:00:05 INFO - PROCESS | 2237 | RSS: Main: 349450240 04:00:05 INFO - PROCESS | 2237 | 04:03:07 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55553/tests/dromaeo/cssquery-prototype.html) 04:03:08 INFO - PROCESS | 2237 | RSS: Main: 306876416 04:03:08 INFO - PROCESS | 2237 | 04:06:11 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55553/tests/dromaeo/cssquery-yui.html) 04:06:12 INFO - PROCESS | 2237 | RSS: Main: 308461568 04:06:12 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | Cycle 1(1): loaded http://localhost:55553/tests/dromaeo/cssquery-yui.html (next: http://localhost:55553/tests/dromaeo/cssquery-dojo.html) 04:09:14 INFO - PROCESS | 2237 | RSS: Main: 357322752 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | __start_tp_report 04:09:14 INFO - PROCESS | 2237 | _x_x_mozilla_page_load 04:09:14 INFO - PROCESS | 2237 | _x_x_mozilla_page_load_details 04:09:14 INFO - PROCESS | 2237 | |i|pagename|runs| 04:09:14 INFO - PROCESS | 2237 | |0;dojo.html;3042.1481985044197;3388.390089783839;3443.655634436558;3467.5492186015813;3530.43513037914;5664.320281566207;5675.318961724599;5679.829605111843;5684.4315568443135;5712.400197979215;5663.631863928852;5689.573282003846;5694.145878118285;5704.087346024637;5705;5091.872703182432;5121.820736274231;5135.512126347991;5156.896862062757;5174.767135478913;5211.5830733541225;5211.921821172686;5221.373435187764;5222.32109825724;5224.085784987631;5169.508896654831;5199.09015922214;5202.03762303975;5225.372955245376;5226.5818734501145;5202.115640341132;5240.60695447841;5246.501582349709;5255.421903590602;5298.178782288752;4980.2280646499485;5141.511556402136;5155.175171972504;5173.534381905609;5173.741312934339;5573.21974923507;5621.887562248732;5632.436756324376;5648.943510564924;5661.1508273758855;6090.051183621243;6209;6253.968730156365;6265.87468250637;6267.811965641038;6166.907496387557;6225;6231.376862313778;6247.593906396115;6249.843753906143;5806.332271788714;5828;5871.412858714137;5875.823725288248;5877.118432235199;6160.969195154085;6229.657368844666;6242.219722534684;6254.186955695731;6255.530835187288;6078.422549857756;6129.509639228851;6140.933729673037;6151.569390142737;6154.876902461926;6222.564420490522;6236.594621349598;6236.656983865931;6258.217722784652;6260.968695156495;6079.331273559905;6184.6916145532205;6233.470155036782;6236.469900058455;6251.374862513712;6127.861529871541;6134.865219626364;6154.169187159766;6215.502759779207;6249.25008998923;4904.975475122602;4914.680545764514;4924.679895806761;4927.531884471041;4934.037862616755;4375.1031038637;4452.065066336041;4456.977715111404;4468.642508599304;4482.820687172477;5640.464155905182;5665.178549110356;5669.177969194444;5687.317521897398;5693.5160511357;6106.06632622643;6243.43809057187;6247.156633854463;6247.750089996349;6257.7184026718905;4821.228603423436;5661.179129026268;5714.11431228152;5761.3950535194035;5768.394318596571;5382.730863456812;5384.273123128328;5395.244665746799;5395.676259424368;5396.325459317585;6205;6288.905666415099;6326.557141000178;6344.460720838781;6359.395857393541;6278.717457714322;6297.2128483939505;6308.779192728416;6310.5898116622275;6319.336469670802;6322.0200868865395;6323.588966717149;6358.300586935341;6365.427111559982;6376.330485299162;6110.832160913912;6138.572327483795;6166.845828854314;6258.468030217483;6260.561760676709;6269.937300627118;6299.149614802126;6304.810855674337;6322;6330.746770129327;6112.235970503687;6117.235345581802;6121.785737499345;6122.7244773984385;6148.1085242640465;5919.378465261258;6045.939540604538;6113.624036504638;6147.9855823788575;6151.0773383992755;4503.594676479132;5932.377100404396;5958.433948774859;5988.161657368059;5992.820215393545;6085.939140608537;6090.23872015998;6107.358727333566;6112.755489780359;6130.785422510191;5767.480926716616;5908.556858235564;5913.940860591339;6010.699465026643;6043.3352331244405 04:09:14 INFO - PROCESS | 2237 | |1;ext.html;557.3479029535443;566.3939584644432;586.9677167755774;604.4590091867778;627.1940556385044;31728.68271317286;32417.351652966925;32418;32444;32492.83753581235;35549.933501994914;42149;42479.78760106195;42608.786956065254;42784.14431711364;9749.07383798538;9949.054839790244;9949.850752238723;9953.25350598708;9963.651272205474;9790.510474476248;9933.205343572534;9949.651762188325;9951;9961.750956226117;9896.356736812084;9978.800423991515;9986.600535978552;10113.292069555135;10118.898811011906;10014.649487267947;10102;10111.241656875764;10113.292069555135;10114.544845481898;9848.753781155458;9943.701688949342;9971.700848974542;9986.800263994752;10023.548940297704;43082.56917430817;43348.783256083836;43430.348544771856;43433.39399636032;43467.78266108681;3269.9346013079844;3518.3315170117594;3735.477033215352;3785.280796648628;3950.1112249743865;29775.70224297751;29806.10581682553;29825.70174298251;29886.103416897527;30052.54921176184;2780.8470534120615;2783.6520434945633;2805.382815780525;2825.4207887383036;2860.854388957215;493030;493120;494400;494447.5277623589;494702;69139.65430172917;69156.6542167286;69715;69738.65130674314;69810.30189698038;73632;73680;77948.61025694835;79166;79209;18233.63532729338;18418.631627367376;18426.63146737058;18430.631387372447;18453.81546184548;12867.613971580868;12885.098043137077;12888.935555322163;12889.54886578984;12891.806622900664;22365.55268894613;22927.770722292564;23192.65211021836;23213.88393058024;23269.76730232676;8865.689700860568;9092.681366251696;9094.636214551343;9101.634754786835;9102.681406150752;959.9136077753036;1025.5487585462372;1041.1566631028738;1062.9043386095288;1112.360392774158;31038.68961310403;31150.68849311523;31238.375232495222;31546.211344716565;31694.366112677617;1101.388729255271;1156.306216270248;1178.4755783676183;1183.46743965216;1205.3189947679532;15856.762148567781;15888.512872730242;15922.442714504938;16146.919265403598;16216.513504594881;540253;541330;544525;547236;547475;51340.22989655158;63784;64195.99999999907;64330.678346608904;64487.355126449074;1327.6017194841509;1351.9589915765002;1531.693661267751;1546.4200924653255;1575.8345373735822;29866.253343666584;30016.549751754177;30053.69946300509;30750;30767;48726.26910596273;59801.70099149476;60331.396686034335;60399.094013588954;60572.39427605668;420097;420124;426172;426395;427945;41127.794361028005;46711.29933050939;47088.764556177;47540;48856.75571622119;48876.75561622169;48943;49361.50638493569;50247.246291306394;50857.237141442165;39250.6074939247;39515;40129.79935100306;40457;41976.790116049226;41300.23510854394;42017.15965680608;42161;42440;42689.57310426856;40017.399739003355;40549.39175912423;40590.39114413345;42262;42283;35626;35871.64128358787;36543.63456365402;36563.26873462569;36780.632193677724;24193;26837.731622684303;27148.1855544334;27229;27231.59152612673 04:09:14 INFO - PROCESS | 2237 | |2;jquery.html;371.515172699627;375.69568649393995;377.27186529997095;378.18123762055154;382.69958082904924;18726.53183670409;20545.691814622773;21375.931203439843;22349.553008939813;22368.776312236892;11780.823287650692;12387.938060309685;12474.81287780684;12499.500019999212;12503.624891253254;4898.799794050454;6122.1735065766;6125.234345706786;6134.4172303631085;6142.907856382158;5947.940520594803;6050.2739671239515;6067.575269731121;6068.908966365486;6112.480439162654;5941.613795103326;6204.348543402945;6206.441420272175;6210.068489726532;6214.782482613109;6115.877682446348;6190.690465476753;6191.969040154793;6196.318404975472;6579.605223686546;5786.566007549409;5917.112433135021;5944.7324870380935;5947.881042379128;5955.404459554054;13741.587752367444;13850.722985540233;13981.370838312301;14512.346944387527;14545.490907818175;2310.560993411263;2528.8735563221767;2585.900992078359;2730.9726902730918;2845.302900789299;9828.312018158731;10196.286259961806;10380.79238415235;10400.32397894142;10735.248532602722;1722.939697110595;1758.4109323376683;1801.8378345948927;1824.078840185711;1850.2044121027952;103913;106050;108354.45822770835;109442.45278773634;109900;10131.290809643255;11000.394978276112;11332.43337833105;11557.47991340375;11653.825192622118;14467;14564.271786410849;14573.125612463286;14580.781288280685;14607.269636518131;8844.425112367675;8881.42270752399;8908.420952638058;8947.507887066275;9021.32340074497;9034.186923176945;9072.04743501936;9116.453012819253;9164.450132991908;9189.218916392048;4585.541445855388;4589.334546490807;4591.012932219589;4600.930986035212;4635.837745678885;1612.403410738011;1795.2549691877725;1822.6795962794274;1930.4787707319226;1958.3439547751377;945.7777422305753;1044.9425281609585;1045.4406892312577;1083.9355197331984;1087.7552550676035;18964.956927369127;20251.696224556643;22300.773457459996;22370.9933053013;22610;965.5461932891529;982.2387649571497;989.6536212325628;990.0792263195298;1087.6410784441116;11299.829803800341;11780.293182409083;11870.465829037714;11952.40237988097;11979.221350612183;653443;665539;666937;670878;670889;125209;125624.71876406143;126204;126949.36525317498;131117;1017.9949100254452;1044.5247412427327;1111.77208672222;1134.160721066405;1135.0692432205676;17342;19582.804171958098;19605.8039419604;19726.210951561778;19801.207951682347;54326.728366357915;54724.726376367864;55331.72334138303;55787.72106139443;55974.44025559692;78994.21005789869;79070.6046469764;79381.60309198417;82400;83375.58312208643;8252.092269850431;8412.369072319478;8442.915570844458;8457.112003239572;8457.83084338322;9499.667511637059;11341.886581134413;12922.418491167731;13531.729365412833;13532.93233533826;11388.145889058189;11567.884321157017;11919.106067044833;11983.101267405154;12021.459034343301;11236.719082023015;11511.942440287745;11607.071434285406;11734.765304694029;11895.702607434883;11647.6505704829;11881.168318217642;11883;12081.577144800242;12119.575814846437;7129.643517824192;8034.196580341919;8262.80247048643;8401.369897257853;8536.957315213633;10750.623728169789;10843.728906777395;11877.346745928711;11988.666197727005;12119.697007574528 04:09:14 INFO - PROCESS | 2237 | |3;mootools.html;1441.7549016667167;1500.459834459595;1647.7940257467817;1915.3487814143189;2075.118074818203;8255.752327430178;8884.955575222131;9068.77328066797;9071.274298056156;9125.771855703602;8215.91784082159;8471.534065626389;8475.40672152948;8485.193906578881;8509.74470765878;2234.1398561553838;2499.7750202481775;2503.5493611149986;2529.8868497860976;2563.705173905006;2172.750133734617;2573.4209802794408;2578.729233430491;2583.134649892279;2584.7673709366154;2501.024600405843;2543.504016716741;2565.307367010906;2565.576679847823;2569.987150064247;2505.3987043109614;2545.8345207561542;2564.4486435416475;2565.320190149593;2565.525377805112;2520.6849143857016;2542.288159315376;2547.923562293134;2556.8338058026357;2567.114345550801;9116.908830911672;9193.35646504745;9201.171894529458;9208.769780755467;9219.17027467531;5560.86097847553;5720.170575266605;5762.07806750922;5763.971180144073;5764.913526297109;3632.8365223565;3693.464447655102;3748.6251374862295;3757.887263382103;3763.8870833875026;1022.8210063238903;1046.931949423285;1062.9946850265776;1063.6808957312776;1081.0324759340424;12501.249925004438;12533.93733031329;12537;12541.372931353488;12557.874421255761;4960.8759781005765;4964.833264182914;5095.490450954875;5097.949020509747;5109.489051094935;10598.682039538826;10610.257281990342;10612.893871061346;10617;10627.734306642396;5758.992176369119;5763.394843541368;5767.221425107557;5773.538116950634;5778.971105144447;4617.376654151713;4715.292706094045;4763.04739052191;4764.404449443819;4965.478624744422;3297.3570153820256;4066.877993660195;4069.5116586009867;4123.299039163349;4129.91740165201;1335.052112999781;1360.6258278973164;1363.8158848555317;1372.6225288045866;1373.574192000463;1024.2471783239314;1168.7253495428567;1184.9940750296194;1191.8927296543352;1194.988050119505;8463.23830855226;8494.532800695897;8554.572271386405;8591.312694984386;8599.269062129664;1265.1966001589058;1269.581038257373;1275.6555729953047;1276.470264840081;1298.539018648385;5429.511343979061;6483.481321494269;6520.119783829217;6613.173353330833;6624.105745724362;10839.67480975572;11228.550857965753;11325.886741132648;11354.48904799286;11357.034652054503;10465.738356540995;10880.401577913133;11145.665630031112;11157.103292440304;11176.552937882556;8529.701460448854;8572.142785721378;8622.534169191253;8675.696350627697;8681.956590217009;7172.533785303938;8386.538740369338;8426.452280601741;8556.016058153393;8563.95718021406;9456.479893605918;9757.51212439361;9834.967328430412;9946.005399459997;10106.343087699275;9039.231665308522;10054;10220.744481388025;10226.386416815016;10247.231457640557;6001.219841420588;7247.383972362198;7762.146163921851;7775.377969762292;7777.766666999999;7975.003124609424;8038.718644847402;8332.16678332162;8549.615267312862;8576.399652024298;6684.63234522106;7577.318041376303;7653.425993050655;7724;7839.725609603636;7459.515131516434;7514.135874374517;7549.245075492627;7562.054743157106;7601.923980760343;7977.401694872791;8002.679892804455;8047.114817369968;8053.8791918120105;8057.113717490954;6289.213848268966;7284.817879553054;7334.119905611361;7375.778726638209;7385.03994480714;7393.55638661682;7396.3343299103335;7452.105747310357;7456.552606843607;7456.7390141344795 04:09:14 INFO - PROCESS | 2237 | |4;prototype.html;325.37040826001686;331.49281599153306;334.04629781972466;335.62410100687225;340.9232922592417;4593.471750748665;6256.3430839761795;6474.255460622018;6477.449416799578;6479.31967143449;6398.072279519467;6399.296077431491;6400.359964003597;6406.135171751812;6424.421802037816;3973.006748312922;4567.954320456803;4638.9536104638855;4689.296605509184;4833.75831208439;4653.115907977491;4747.264174053027;4759.167145749498;4808.158572249859;4838.153323168449;4062.1469491406842;4658.557437043475;4763.714177149365;4783.306420569015;4815.903681926359;4775.212090005145;4775.474697783241;4793.616510679172;4800.663953523254;4810.254410566386;3947.940780888289;4006.358982562805;4108.876733697994;4118.608732170439;4123.690723195772;6092.299385570672;6165.414285642857;6169.784057557964;6182.319944806068;6201.16284301618;5645.717714114319;5652.3217213934595;5662.065759149736;5670.68811215383;5679.971600142014;5520.503154716095;6371.808845734636;6702.564333318319;6803.863922721567;6812.386885180308;4650.372199753057;4655.185342565038;4658.1848176568965;4658.86023419298;4660.790264438108;6167.814965551041;6518.511111666644;7200.063991681101;7241.67412466435;7246.275372462817;3518.612952575215;3946.8026598669953;4493.101379724003;4584.1977653910435;4590.288505281686;4663.650226232979;4671.32265821454;4675.859724208279;4676.532346765296;4677.6257899367965;5468.863278418072;5476.424975377608;5477.780888764405;5488.91766623501;5492.231087647732;5646.858828529237;6845.794626161223;6863.828404289933;6871.175458944858;6878.793636190922;1536.1244090868097;1610.4443966831425;1610.6778644271192;1614.386533117408;1618.4578166314182;1006.104566935428;1029.505837198134;1041.7135287795766;1043.8121138195197;1044.8223801953686;6036.305824830113;6123.571350005546;6183.134361189437;6206.162168107338;6210.689465526705;6855.245922948472;6957.339052789976;6975.965120174468;7057.964710176417;7084.964575177091;6250.874562718641;6427.55007149495;6427.871442571122;6430.0676401921455;6432.871342573122;3702.925941481209;3744.5693745219105;3762.6049264827348;3762.6613604775703;3764.962350376461;7190.64046797658;7416.7033318666645;7418.146913104954;7427.554346739213;7495.737649182362;7333.046703928455;7354.411647068222;7358.484906056525;7363.668634911442;7367.8894816577795;7431.628418579157;7437.925620743723;7441.1070671519765;7445.553266804009;7461.962690186623;7088.149422069385;7119;7125.216226215216;7130.857382852418;7134.429245660231;6712.798616041527;6997.1253593300835;6997.160340759141;7040.415906421219;7099.574025558483;7395.593242371721;7410.073740782403;7416;7431.925680743123;7446.25537446251;4561.452625684939;4620.330052142488;4623.260278355447;4623.815047398067;4629.351890735367;7100.573965562083;7109.755792736147;7118.145822501333;7118.644067796693;7122.145342558719;6569.868602628017;6770.627615480998;6846.246912839484;6848.486363522656;6867.592143610647;6816.182058153054;6822.590644561342;6837.897431538431;6840.144981877265;6852.486063545154;6684.565503242274;6692.297308782508;6698.665066746546;6708.597484150967;6712.026756120433;6813.250542440229;6831.521793474409;6832.795016149524;6845.794626161223;6874.9656251718425;6441.71012304438;6446.355364463704;6688.866222675617;6690.765823196165;6806.387425131564 04:09:14 INFO - PROCESS | 2237 | |5;yui.html;704.475166001329;756.6405957170344;766.6435107674934;785.787837283933;812.2446125103655;15422;15520.068795872241;15606.843931560681;15612.765808512868;15685.058896466191;14233;14308.998370114095;14324.785128223086;14327.928360358184;14334.569962901129;753.9962300188519;758.6510205305566;788.4677842456348;934.8550974598932;966.7583104223944;958.8178246133212;973.4208146153028;974.790420059687;975.4488713876673;976.6191185437685;1024.359775140537;1024.390487659845;1025.912797412217;1025.9487025648689;1026.8613737145465;820.0282665041915;975.765816204113;978.021978021978;978.2076518020426;1006.2453160129866;738.4424759306743;738.6860584251672;739.9223081576465;748.2554857916408;765.2003656179306;15403.922980385027;15437;15438.922805386013;15456.227188640523;15461.5361539154;11591.362475063868;12972.091953563255;13047.412866421033;13070.803937940938;13305.869001134915;10195.49022548877;10316.79366412675;10395.480225988671;10680.62617808373;10782.5686972521;3562;3569.5002699622073;3620.818959052037;3676.283124790667;3839.117003089305;64349;83938;87464.88588537574;87914;88042.55978720129;9901.455419951828;10091.444970526545;10098.848517272247;10257.974202579831;10325.793484130276;23822.285331440085;23866.16468423597;23897;23924.52150957006;23954.88022559876;7623.9618801905635;7717.884231736529;7829.099882842663;7833.33416659579;7849.01887264092;4226.408302837605;4242.469691288589;4254.127903779718;4254.893627659333;4426.756528390905;3774.8864085094415;3815.141593141521;3816.46569480273;3822.694184465236;3826.3877779555137;656.5108993799649;680.6936878404639;687.1204857782047;690.0787448755867;691.97232110715;520.1599416941611;550.6530885542163;551.2613098448098;580.1413907417046;590.2474345209891;7090.432765378758;7142.92857071433;8458.450200736932;8465.61904714277;8470.576471176417;488.26625862265706;510.89271253037276;559.3679142568872;597.6444015810586;602.9819105426844;3940.9014774630864;3973.8807835764974;4006.138680183716;4013.57857424972;4036.287731219688;93787.59318610227;94718;94755;97334;99686.00627987545;83669.74495382574;84698.57650711708;85232.57383713042;85671;86667.13332866717;73423.63288183631;73986.26013739902;74403.62798185974;74412.25587744053;74892.25107748962;8641.481511109103;8681.305495560213;8737.21365077146;8814.250788683034;8891.688790892287;51045.74477127591;52869.73565132303;53394.73302633462;53620;56209.43790562206;66665.66667166633;69150.65424672845;72695.27304726886;73236.63381683058;77430;11315.490802914053;11357.54569817198;11464;11712.70718232051;11937.641870743892;12065.09511786637;12523.561675341683;12621.179623324455;12642.557510487457;12705.936470317589;9555.522223888916;11992.160548761503;12034.398280085785;12130.575429860266;12145.878541214475;11744.823827642422;11970.76058478843;12046.939765301468;12085.093617978862;12088.75822483563;11508.424578771195;11677.357745324089;11794.76410471803;11797.646070617551;12359.3820308986;9581.041895810364;9612.134907858326;9913.603455861685;9924.950375248078;9941.30410871261;8345.290650294794;9072.138146876036;10030.548625312025;10068.44623545712;10098.697039088838 04:09:14 INFO - PROCESS | 2237 | __end_tp_report 04:09:14 INFO - PROCESS | 2237 | __start_cc_report 04:09:14 INFO - PROCESS | 2237 | _x_x_mozilla_cycle_collect,1086 04:09:14 INFO - PROCESS | 2237 | __end_cc_report 04:09:14 INFO - PROCESS | 2237 | __startTimestamp1461755354897__endTimestamp 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | ------- Summary: start ------- 04:09:14 INFO - PROCESS | 2237 | Number of tests: 6 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#0] dojo.html Cycles:165 Average:5731.29 Median:5988.16 stddev:651.72 (10.9%) stddev-sans-first:618.63 04:09:14 INFO - PROCESS | 2237 | Values: 3042.1 3388.4 3443.7 3467.5 3530.4 5664.3 5675.3 5679.8 5684.4 5712.4 5663.6 5689.6 5694.1 5704.1 5705.0 5091.9 5121.8 5135.5 5156.9 5174.8 5211.6 5211.9 5221.4 5222.3 5224.1 5169.5 5199.1 5202.0 5225.4 5226.6 5202.1 5240.6 5246.5 5255.4 5298.2 4980.2 5141.5 5155.2 5173.5 5173.7 5573.2 5621.9 5632.4 5648.9 5661.2 6090.1 6209.0 6254.0 6265.9 6267.8 6166.9 6225.0 6231.4 6247.6 6249.8 5806.3 5828.0 5871.4 5875.8 5877.1 6161.0 6229.7 6242.2 6254.2 6255.5 6078.4 6129.5 6140.9 6151.6 6154.9 6222.6 6236.6 6236.7 6258.2 6261.0 6079.3 6184.7 6233.5 6236.5 6251.4 6127.9 6134.9 6154.2 6215.5 6249.3 4905.0 4914.7 4924.7 4927.5 4934.0 4375.1 4452.1 4457.0 4468.6 4482.8 5640.5 5665.2 5669.2 5687.3 5693.5 6106.1 6243.4 6247.2 6247.8 6257.7 4821.2 5661.2 5714.1 5761.4 5768.4 5382.7 5384.3 5395.2 5395.7 5396.3 6205.0 6288.9 6326.6 6344.5 6359.4 6278.7 6297.2 6308.8 6310.6 6319.3 6322.0 6323.6 6358.3 6365.4 6376.3 6110.8 6138.6 6166.8 6258.5 6260.6 6269.9 6299.1 6304.8 6322.0 6330.7 6112.2 6117.2 6121.8 6122.7 6148.1 5919.4 6045.9 6113.6 6148.0 6151.1 4503.6 5932.4 5958.4 5988.2 5992.8 6085.9 6090.2 6107.4 6112.8 6130.8 5767.5 5908.6 5913.9 6010.7 6043.3 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#1] ext.html Cycles:180 Average:65515.18 Median:41214.01 stddev:129939.43 (315.3%) stddev-sans-first:130212.42 04:09:14 INFO - PROCESS | 2237 | Values: 557.3 566.4 587.0 604.5 627.2 31728.7 32417.4 32418.0 32444.0 32492.8 35549.9 42149.0 42479.8 42608.8 42784.1 9749.1 9949.1 9949.9 9953.3 9963.7 9790.5 9933.2 9949.7 9951.0 9961.8 9896.4 9978.8 9986.6 10113.3 10118.9 10014.6 10102.0 10111.2 10113.3 10114.5 9848.8 9943.7 9971.7 9986.8 10023.5 43082.6 43348.8 43430.3 43433.4 43467.8 3269.9 3518.3 3735.5 3785.3 3950.1 29775.7 29806.1 29825.7 29886.1 30052.5 2780.8 2783.7 2805.4 2825.4 2860.9 493030.0 493120.0 494400.0 494447.5 494702.0 69139.7 69156.7 69715.0 69738.7 69810.3 73632.0 73680.0 77948.6 79166.0 79209.0 18233.6 18418.6 18426.6 18430.6 18453.8 12867.6 12885.1 12888.9 12889.5 12891.8 22365.6 22927.8 23192.7 23213.9 23269.8 8865.7 9092.7 9094.6 9101.6 9102.7 959.9 1025.5 1041.2 1062.9 1112.4 31038.7 31150.7 31238.4 31546.2 31694.4 1101.4 1156.3 1178.5 1183.5 1205.3 15856.8 15888.5 15922.4 16146.9 16216.5 540253.0 541330.0 544525.0 547236.0 547475.0 51340.2 63784.0 64196.0 64330.7 64487.4 1327.6 1352.0 1531.7 1546.4 1575.8 29866.3 30016.5 30053.7 30750.0 30767.0 48726.3 59801.7 60331.4 60399.1 60572.4 420097.0 420124.0 426172.0 426395.0 427945.0 41127.8 46711.3 47088.8 47540.0 48856.8 48876.8 48943.0 49361.5 50247.2 50857.2 39250.6 39515.0 40129.8 40457.0 41976.8 41300.2 42017.2 42161.0 42440.0 42689.6 40017.4 40549.4 40590.4 42262.0 42283.0 35626.0 35871.6 36543.6 36563.3 36780.6 24193.0 26837.7 27148.2 27229.0 27231.6 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#2] jquery.html Cycles:180 Average:36384.84 Median:20398.69 stddev:110358.78 (541.0%) stddev-sans-first:110635.23 04:09:14 INFO - PROCESS | 2237 | Values: 371.5 375.7 377.3 378.2 382.7 18726.5 20545.7 21375.9 22349.6 22368.8 11780.8 12387.9 12474.8 12499.5 12503.6 4898.8 6122.2 6125.2 6134.4 6142.9 5947.9 6050.3 6067.6 6068.9 6112.5 5941.6 6204.3 6206.4 6210.1 6214.8 6115.9 6190.7 6192.0 6196.3 6579.6 5786.6 5917.1 5944.7 5947.9 5955.4 13741.6 13850.7 13981.4 14512.3 14545.5 2310.6 2528.9 2585.9 2731.0 2845.3 9828.3 10196.3 10380.8 10400.3 10735.2 1722.9 1758.4 1801.8 1824.1 1850.2 103913.0 106050.0 108354.5 109442.5 109900.0 10131.3 11000.4 11332.4 11557.5 11653.8 14467.0 14564.3 14573.1 14580.8 14607.3 8844.4 8881.4 8908.4 8947.5 9021.3 9034.2 9072.0 9116.5 9164.5 9189.2 4585.5 4589.3 4591.0 4600.9 4635.8 1612.4 1795.3 1822.7 1930.5 1958.3 945.8 1044.9 1045.4 1083.9 1087.8 18965.0 20251.7 22300.8 22371.0 22610.0 965.5 982.2 989.7 990.1 1087.6 11299.8 11780.3 11870.5 11952.4 11979.2 653443.0 665539.0 666937.0 670878.0 670889.0 125209.0 125624.7 126204.0 126949.4 131117.0 1018.0 1044.5 1111.8 1134.2 1135.1 17342.0 19582.8 19605.8 19726.2 19801.2 54326.7 54724.7 55331.7 55787.7 55974.4 78994.2 79070.6 79381.6 82400.0 83375.6 8252.1 8412.4 8442.9 8457.1 8457.8 9499.7 11341.9 12922.4 13531.7 13532.9 11388.1 11567.9 11919.1 11983.1 12021.5 11236.7 11511.9 11607.1 11734.8 11895.7 11647.7 11881.2 11883.0 12081.6 12119.6 7129.6 8034.2 8262.8 8401.4 8537.0 10750.6 10843.7 11877.3 11988.7 12119.7 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#3] mootools.html Cycles:180 Average:6241.13 Median:4965.16 stddev:3302.93 (66.5%) stddev-sans-first:3292.49 04:09:14 INFO - PROCESS | 2237 | Values: 1441.8 1500.5 1647.8 1915.3 2075.1 8255.8 8885.0 9068.8 9071.3 9125.8 8215.9 8471.5 8475.4 8485.2 8509.7 2234.1 2499.8 2503.5 2529.9 2563.7 2172.8 2573.4 2578.7 2583.1 2584.8 2501.0 2543.5 2565.3 2565.6 2570.0 2505.4 2545.8 2564.4 2565.3 2565.5 2520.7 2542.3 2547.9 2556.8 2567.1 9116.9 9193.4 9201.2 9208.8 9219.2 5560.9 5720.2 5762.1 5764.0 5764.9 3632.8 3693.5 3748.6 3757.9 3763.9 1022.8 1046.9 1063.0 1063.7 1081.0 12501.2 12533.9 12537.0 12541.4 12557.9 4960.9 4964.8 5095.5 5097.9 5109.5 10598.7 10610.3 10612.9 10617.0 10627.7 5759.0 5763.4 5767.2 5773.5 5779.0 4617.4 4715.3 4763.0 4764.4 4965.5 3297.4 4066.9 4069.5 4123.3 4129.9 1335.1 1360.6 1363.8 1372.6 1373.6 1024.2 1168.7 1185.0 1191.9 1195.0 8463.2 8494.5 8554.6 8591.3 8599.3 1265.2 1269.6 1275.7 1276.5 1298.5 5429.5 6483.5 6520.1 6613.2 6624.1 10839.7 11228.6 11325.9 11354.5 11357.0 10465.7 10880.4 11145.7 11157.1 11176.6 8529.7 8572.1 8622.5 8675.7 8682.0 7172.5 8386.5 8426.5 8556.0 8564.0 9456.5 9757.5 9835.0 9946.0 10106.3 9039.2 10054.0 10220.7 10226.4 10247.2 6001.2 7247.4 7762.1 7775.4 7777.8 7975.0 8038.7 8332.2 8549.6 8576.4 6684.6 7577.3 7653.4 7724.0 7839.7 7459.5 7514.1 7549.2 7562.1 7601.9 7977.4 8002.7 8047.1 8053.9 8057.1 6289.2 7284.8 7334.1 7375.8 7385.0 7393.6 7396.3 7452.1 7456.6 7456.7 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#4] prototype.html Cycles:180 Average:5596.34 Median:6314.08 stddev:1782.26 (28.2%) stddev-sans-first:1742.80 04:09:14 INFO - PROCESS | 2237 | Values: 325.4 331.5 334.0 335.6 340.9 4593.5 6256.3 6474.3 6477.4 6479.3 6398.1 6399.3 6400.4 6406.1 6424.4 3973.0 4568.0 4639.0 4689.3 4833.8 4653.1 4747.3 4759.2 4808.2 4838.2 4062.1 4658.6 4763.7 4783.3 4815.9 4775.2 4775.5 4793.6 4800.7 4810.3 3947.9 4006.4 4108.9 4118.6 4123.7 6092.3 6165.4 6169.8 6182.3 6201.2 5645.7 5652.3 5662.1 5670.7 5680.0 5520.5 6371.8 6702.6 6803.9 6812.4 4650.4 4655.2 4658.2 4658.9 4660.8 6167.8 6518.5 7200.1 7241.7 7246.3 3518.6 3946.8 4493.1 4584.2 4590.3 4663.7 4671.3 4675.9 4676.5 4677.6 5468.9 5476.4 5477.8 5488.9 5492.2 5646.9 6845.8 6863.8 6871.2 6878.8 1536.1 1610.4 1610.7 1614.4 1618.5 1006.1 1029.5 1041.7 1043.8 1044.8 6036.3 6123.6 6183.1 6206.2 6210.7 6855.2 6957.3 6976.0 7058.0 7085.0 6250.9 6427.6 6427.9 6430.1 6432.9 3702.9 3744.6 3762.6 3762.7 3765.0 7190.6 7416.7 7418.1 7427.6 7495.7 7333.0 7354.4 7358.5 7363.7 7367.9 7431.6 7437.9 7441.1 7445.6 7462.0 7088.1 7119.0 7125.2 7130.9 7134.4 6712.8 6997.1 6997.2 7040.4 7099.6 7395.6 7410.1 7416.0 7431.9 7446.3 4561.5 4620.3 4623.3 4623.8 4629.4 7100.6 7109.8 7118.1 7118.6 7122.1 6569.9 6770.6 6846.2 6848.5 6867.6 6816.2 6822.6 6837.9 6840.1 6852.5 6684.6 6692.3 6698.7 6708.6 6712.0 6813.3 6831.5 6832.8 6845.8 6875.0 6441.7 6446.4 6688.9 6690.8 6806.4 04:09:14 INFO - PROCESS | 2237 | 04:09:14 INFO - PROCESS | 2237 | [#5] yui.html Cycles:180 Average:19215.06 Median:26694.95 stddev:27155.05 (101.7%) stddev-sans-first:27195.65 04:09:14 INFO - PROCESS | 2237 | Values: 704.5 756.6 766.6 785.8 812.2 15422.0 15520.1 15606.8 15612.8 15685.1 14233.0 14309.0 14324.8 14327.9 14334.6 754.0 758.7 788.5 934.9 966.8 958.8 973.4 974.8 975.4 976.6 1024.4 1024.4 1025.9 1025.9 1026.9 820.0 975.8 978.0 978.2 1006.2 738.4 738.7 739.9 748.3 765.2 15403.9 15437.0 15438.9 15456.2 15461.5 11591.4 12972.1 13047.4 13070.8 13305.9 10195.5 10316.8 10395.5 10680.6 10782.6 3562.0 3569.5 3620.8 3676.3 3839.1 64349.0 83938.0 87464.9 87914.0 88042.6 9901.5 10091.4 10098.8 10258.0 10325.8 23822.3 23866.2 23897.0 23924.5 23954.9 7624.0 7717.9 7829.1 7833.3 7849.0 4226.4 4242.5 4254.1 4254.9 4426.8 3774.9 3815.1 3816.5 3822.7 3826.4 656.5 680.7 687.1 690.1 692.0 520.2 550.7 551.3 580.1 590.2 7090.4 7142.9 8458.5 8465.6 8470.6 488.3 510.9 559.4 597.6 603.0 3940.9 3973.9 4006.1 4013.6 4036.3 93787.6 94718.0 94755.0 97334.0 99686.0 83669.7 84698.6 85232.6 85671.0 86667.1 73423.6 73986.3 74403.6 74412.3 74892.3 8641.5 8681.3 8737.2 8814.3 8891.7 51045.7 52869.7 53394.7 53620.0 56209.4 66665.7 69150.7 72695.3 73236.6 77430.0 11315.5 11357.5 11464.0 11712.7 11937.6 12065.1 12523.6 12621.2 12642.6 12705.9 9555.5 11992.2 12034.4 12130.6 12145.9 11744.8 11970.8 12046.9 12085.1 12088.8 11508.4 11677.4 11794.8 11797.6 12359.4 9581.0 9612.1 9913.6 9925.0 9941.3 8345.3 9072.1 10030.5 10068.4 10098.7 04:09:14 INFO - PROCESS | 2237 | -------- Summary: end -------- 04:09:14 INFO - PROCESS | 2237 | 04:09:15 INFO - TEST-INFO | 2237: exit 0 04:09:15 INFO - TEST-OK | dromaeo_css | took 1112597ms 04:09:15 INFO - TEST-START | kraken 04:09:15 INFO - operating with platform_type : mac_ 04:09:15 INFO - Initialising browser for kraken test... 04:09:15 INFO - TEST-INFO | started process 2244 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp0mnFTm/profile http://localhost:55553/getInfo.html) 04:09:17 INFO - PROCESS | 2244 | __metrics Screen width/height:1600/1200 04:09:17 INFO - PROCESS | 2244 | colorDepth:24 04:09:17 INFO - PROCESS | 2244 | Browser inner width/height: 1024/689 04:09:17 INFO - PROCESS | 2244 | __metrics 04:09:17 INFO - PROCESS | 2244 | 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" 04:09:17 INFO - PROCESS | 2244 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:18 INFO - TEST-INFO | 2244: exit 0 04:09:18 INFO - Browser initialized. 04:09:18 INFO - Running cycle 1/1 for kraken test... 04:09:18 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp0mnFTm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:30 INFO - PROCESS | 2246 | RSS: Main: 207327232 04:09:30 INFO - PROCESS | 2246 | 04:09:32 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55553/tests/kraken/driver.html?testName=audio-beat-detection) 04:09:33 INFO - PROCESS | 2246 | RSS: Main: 262897664 04:09:33 INFO - PROCESS | 2246 | 04:09:35 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55553/tests/kraken/driver.html?testName=audio-dft) 04:09:36 INFO - PROCESS | 2246 | RSS: Main: 273260544 04:09:36 INFO - PROCESS | 2246 | 04:09:39 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55553/tests/kraken/driver.html?testName=audio-fft) 04:09:40 INFO - PROCESS | 2246 | RSS: Main: 263540736 04:09:40 INFO - PROCESS | 2246 | 04:09:42 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55553/tests/kraken/driver.html?testName=audio-oscillator) 04:09:42 INFO - PROCESS | 2246 | RSS: Main: 265121792 04:09:42 INFO - PROCESS | 2246 | 04:09:45 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:09:45 INFO - PROCESS | 2246 | RSS: Main: 264577024 04:09:45 INFO - PROCESS | 2246 | 04:09:48 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55553/tests/kraken/driver.html?testName=imaging-darkroom) 04:09:49 INFO - PROCESS | 2246 | RSS: Main: 266219520 04:09:49 INFO - PROCESS | 2246 | 04:09:51 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55553/tests/kraken/driver.html?testName=imaging-desaturate) 04:09:52 INFO - PROCESS | 2246 | RSS: Main: 265953280 04:09:52 INFO - PROCESS | 2246 | 04:09:55 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55553/tests/kraken/driver.html?testName=json-parse-financial) 04:09:55 INFO - PROCESS | 2246 | RSS: Main: 266190848 04:09:55 INFO - PROCESS | 2246 | 04:09:57 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:09:57 INFO - PROCESS | 2246 | RSS: Main: 299884544 04:09:57 INFO - PROCESS | 2246 | 04:09:59 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:10:00 INFO - PROCESS | 2246 | RSS: Main: 300646400 04:10:00 INFO - PROCESS | 2246 | 04:10:02 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:10:03 INFO - PROCESS | 2246 | RSS: Main: 305680384 04:10:03 INFO - PROCESS | 2246 | 04:10:05 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:10:06 INFO - PROCESS | 2246 | RSS: Main: 307372032 04:10:06 INFO - PROCESS | 2246 | 04:10:08 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:10:09 INFO - PROCESS | 2246 | RSS: Main: 307490816 04:10:09 INFO - PROCESS | 2246 | 04:10:10 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:55553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55553/tests/kraken/driver.html?testName=ai-astar) 04:10:11 INFO - PROCESS | 2246 | RSS: Main: 369680384 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | __start_tp_report 04:10:11 INFO - PROCESS | 2246 | _x_x_mozilla_page_load 04:10:11 INFO - PROCESS | 2246 | _x_x_mozilla_page_load_details 04:10:11 INFO - PROCESS | 2246 | |i|pagename|runs| 04:10:11 INFO - PROCESS | 2246 | |0;ai-astar;109;110;110;110;111;112;119;111;109;109 04:10:11 INFO - PROCESS | 2246 | |1;audio-beat-detection;150;119;155;151;118;137;117;149;152;125 04:10:11 INFO - PROCESS | 2246 | |2;audio-dft;184;185;185;187;182;183;184;185;186;181 04:10:11 INFO - PROCESS | 2246 | |3;audio-fft;74;73;80;133;66;79;82;81;148;57 04:10:11 INFO - PROCESS | 2246 | |4;audio-oscillator;91;94;171;113;114;86;120;76;76;122 04:10:11 INFO - PROCESS | 2246 | |5;imaging-gaussian-blur;89;85;89;87;88;86;90;86;88;86 04:10:11 INFO - PROCESS | 2246 | |6;imaging-darkroom;92;94;93;94;94;94;93;119;110;93 04:10:11 INFO - PROCESS | 2246 | |7;imaging-desaturate;89;87;87;86;88;86;87;88;87;86 04:10:11 INFO - PROCESS | 2246 | |8;json-parse-financial;52;59;58;58;58;57;60;68;50;51 04:10:11 INFO - PROCESS | 2246 | |9;json-stringify-tinderbox;61;64;72;62;73;71;65;67;66;68 04:10:11 INFO - PROCESS | 2246 | |10;stanford-crypto-aes;80;81;83;100;103;104;103;79;79;81 04:10:11 INFO - PROCESS | 2246 | |11;stanford-crypto-ccm;126;127;124;121;129;117;119;117;117;121 04:10:11 INFO - PROCESS | 2246 | |12;stanford-crypto-pbkdf2;132;124;136;134;131;119;134;133;135;133 04:10:11 INFO - PROCESS | 2246 | |13;stanford-crypto-sha256-iterative;66;66;64;66;65;67;65;66;65;65 04:10:11 INFO - PROCESS | 2246 | __end_tp_report 04:10:11 INFO - PROCESS | 2246 | __start_cc_report 04:10:11 INFO - PROCESS | 2246 | _x_x_mozilla_cycle_collect,1243 04:10:11 INFO - PROCESS | 2246 | __end_cc_report 04:10:11 INFO - PROCESS | 2246 | __startTimestamp1461755411207__endTimestamp 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | ------- Summary: start ------- 04:10:11 INFO - PROCESS | 2246 | Number of tests: 14 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.50 stddev:2.98 (2.7%) stddev-sans-first:3.07 04:10:11 INFO - PROCESS | 2246 | Values: 109.0 110.0 110.0 110.0 111.0 112.0 119.0 111.0 109.0 109.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:149.50 stddev:15.94 (10.7%) stddev-sans-first:16.23 04:10:11 INFO - PROCESS | 2246 | Values: 150.0 119.0 155.0 151.0 118.0 137.0 117.0 149.0 152.0 125.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.81 (1.0%) stddev-sans-first:1.92 04:10:11 INFO - PROCESS | 2246 | Values: 184.0 185.0 185.0 187.0 182.0 183.0 184.0 185.0 186.0 181.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#3] audio-fft Cycles:10 Average:87.30 Median:76.50 stddev:29.28 (38.3%) stddev-sans-first:30.66 04:10:11 INFO - PROCESS | 2246 | Values: 74.0 73.0 80.0 133.0 66.0 79.0 82.0 81.0 148.0 57.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#4] audio-oscillator Cycles:10 Average:106.30 Median:76.00 stddev:28.59 (37.6%) stddev-sans-first:29.79 04:10:11 INFO - PROCESS | 2246 | Values: 91.0 94.0 171.0 113.0 114.0 86.0 120.0 76.0 76.0 122.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.65 (1.9%) stddev-sans-first:1.64 04:10:11 INFO - PROCESS | 2246 | Values: 89.0 85.0 89.0 87.0 88.0 86.0 90.0 86.0 88.0 86.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:93.50 stddev:9.18 (9.8%) stddev-sans-first:9.51 04:10:11 INFO - PROCESS | 2246 | Values: 92.0 94.0 93.0 94.0 94.0 94.0 93.0 119.0 110.0 93.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#7] imaging-desaturate Cycles:10 Average:87.10 Median:87.00 stddev:0.99 (1.1%) stddev-sans-first:0.78 04:10:11 INFO - PROCESS | 2246 | Values: 89.0 87.0 87.0 86.0 88.0 86.0 87.0 88.0 87.0 86.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:5.24 (9.0%) stddev-sans-first:5.22 04:10:11 INFO - PROCESS | 2246 | Values: 52.0 59.0 58.0 58.0 58.0 57.0 60.0 68.0 50.0 51.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.50 stddev:4.12 (6.1%) stddev-sans-first:3.78 04:10:11 INFO - PROCESS | 2246 | Values: 61.0 64.0 72.0 62.0 73.0 71.0 65.0 67.0 66.0 68.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:79.50 stddev:11.46 (14.4%) stddev-sans-first:11.65 04:10:11 INFO - PROCESS | 2246 | Values: 80.0 81.0 83.0 100.0 103.0 104.0 103.0 79.0 79.0 81.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:122.50 stddev:4.47 (3.6%) stddev-sans-first:4.47 04:10:11 INFO - PROCESS | 2246 | Values: 126.0 127.0 124.0 121.0 129.0 117.0 119.0 117.0 117.0 121.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.10 Median:133.50 stddev:5.38 (4.0%) stddev-sans-first:5.70 04:10:11 INFO - PROCESS | 2246 | Values: 132.0 124.0 136.0 134.0 131.0 119.0 134.0 133.0 135.0 133.0 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - PROCESS | 2246 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 04:10:11 INFO - PROCESS | 2246 | Values: 66.0 66.0 64.0 66.0 65.0 67.0 65.0 66.0 65.0 65.0 04:10:11 INFO - PROCESS | 2246 | -------- Summary: end -------- 04:10:11 INFO - PROCESS | 2246 | 04:10:11 INFO - TEST-INFO | 2246: exit 0 04:10:11 INFO - TEST-OK | kraken | took 56461ms 04:10:11 INFO - SUITE-END | took 1169s 04:10:12 INFO - Completed test suite (00:19:29) 04:10:12 INFO - javascript benchmark 04:10:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3042.1481985044197, 3388.390089783839, 3443.655634436558, 3467.5492186015813, 3530.43513037914, 5664.320281566207, 5675.318961724599, 5679.829605111843, 5684.4315568443135, 5712.400197979215, 5663.631863928852, 5689.573282003846, 5694.145878118285, 5704.087346024637, 5705.0, 5091.872703182432, 5121.820736274231, 5135.512126347991, 5156.896862062757, 5174.767135478913, 5211.5830733541225, 5211.921821172686, 5221.373435187764, 5222.32109825724, 5224.085784987631, 5169.508896654831, 5199.09015922214, 5202.03762303975, 5225.372955245376, 5226.5818734501145, 5202.115640341132, 5240.60695447841, 5246.501582349709, 5255.421903590602, 5298.178782288752, 4980.2280646499485, 5141.511556402136, 5155.175171972504, 5173.534381905609, 5173.741312934339, 5573.21974923507, 5621.887562248732, 5632.436756324376, 5648.943510564924, 5661.1508273758855, 6090.051183621243, 6209.0, 6253.968730156365, 6265.87468250637, 6267.811965641038, 6166.907496387557, 6225.0, 6231.376862313778, 6247.593906396115, 6249.843753906143, 5806.332271788714, 5828.0, 5871.412858714137, 5875.823725288248, 5877.118432235199, 6160.969195154085, 6229.657368844666, 6242.219722534684, 6254.186955695731, 6255.530835187288, 6078.422549857756, 6129.509639228851, 6140.933729673037, 6151.569390142737, 6154.876902461926, 6222.564420490522, 6236.594621349598, 6236.656983865931, 6258.217722784652, 6260.968695156495, 6079.331273559905, 6184.6916145532205, 6233.470155036782, 6236.469900058455, 6251.374862513712, 6127.861529871541, 6134.865219626364, 6154.169187159766, 6215.502759779207, 6249.25008998923, 4904.975475122602, 4914.680545764514, 4924.679895806761, 4927.531884471041, 4934.037862616755, 4375.1031038637, 4452.065066336041, 4456.977715111404, 4468.642508599304, 4482.820687172477, 5640.464155905182, 5665.178549110356, 5669.177969194444, 5687.317521897398, 5693.5160511357, 6106.06632622643, 6243.43809057187, 6247.156633854463, 6247.750089996349, 6257.7184026718905, 4821.228603423436, 5661.179129026268, 5714.11431228152, 5761.3950535194035, 5768.394318596571, 5382.730863456812, 5384.273123128328, 5395.244665746799, 5395.676259424368, 5396.325459317585, 6205.0, 6288.905666415099, 6326.557141000178, 6344.460720838781, 6359.395857393541, 6278.717457714322, 6297.2128483939505, 6308.779192728416, 6310.5898116622275, 6319.336469670802, 6322.0200868865395, 6323.588966717149, 6358.300586935341, 6365.427111559982, 6376.330485299162, 6110.832160913912, 6138.572327483795, 6166.845828854314, 6258.468030217483, 6260.561760676709, 6269.937300627118, 6299.149614802126, 6304.810855674337, 6322.0, 6330.746770129327, 6112.235970503687, 6117.235345581802, 6121.785737499345, 6122.7244773984385, 6148.1085242640465, 5919.378465261258, 6045.939540604538, 6113.624036504638, 6147.9855823788575, 6151.0773383992755, 4503.594676479132, 5932.377100404396, 5958.433948774859, 5988.161657368059, 5992.820215393545, 6085.939140608537, 6090.23872015998, 6107.358727333566, 6112.755489780359, 6130.785422510191, 5767.480926716616, 5908.556858235564, 5913.940860591339, 6010.699465026643, 6043.3352331244405], "unit": "score", "name": "dojo.html", "value": 5689.507797862391}, {"lowerIsBetter": false, "replicates": [557.3479029535443, 566.3939584644432, 586.9677167755774, 604.4590091867778, 627.1940556385044, 31728.68271317286, 32417.351652966925, 32418.0, 32444.0, 32492.83753581235, 35549.933501994914, 42149.0, 42479.78760106195, 42608.786956065254, 42784.14431711364, 9749.07383798538, 9949.054839790244, 9949.850752238723, 9953.25350598708, 9963.651272205474, 9790.510474476248, 9933.205343572534, 9949.651762188325, 9951.0, 9961.750956226117, 9896.356736812084, 9978.800423991515, 9986.600535978552, 10113.292069555135, 10118.898811011906, 10014.649487267947, 10102.0, 10111.241656875764, 10113.292069555135, 10114.544845481898, 9848.753781155458, 9943.701688949342, 9971.700848974542, 9986.800263994752, 10023.548940297704, 43082.56917430817, 43348.783256083836, 43430.348544771856, 43433.39399636032, 43467.78266108681, 3269.9346013079844, 3518.3315170117594, 3735.477033215352, 3785.280796648628, 3950.1112249743865, 29775.70224297751, 29806.10581682553, 29825.70174298251, 29886.103416897527, 30052.54921176184, 2780.8470534120615, 2783.6520434945633, 2805.382815780525, 2825.4207887383036, 2860.854388957215, 493030.0, 493120.0, 494400.0, 494447.5277623589, 494702.0, 69139.65430172917, 69156.6542167286, 69715.0, 69738.65130674314, 69810.30189698038, 73632.0, 73680.0, 77948.61025694835, 79166.0, 79209.0, 18233.63532729338, 18418.631627367376, 18426.63146737058, 18430.631387372447, 18453.81546184548, 12867.613971580868, 12885.098043137077, 12888.935555322163, 12889.54886578984, 12891.806622900664, 22365.55268894613, 22927.770722292564, 23192.65211021836, 23213.88393058024, 23269.76730232676, 8865.689700860568, 9092.681366251696, 9094.636214551343, 9101.634754786835, 9102.681406150752, 959.9136077753036, 1025.5487585462372, 1041.1566631028738, 1062.9043386095288, 1112.360392774158, 31038.68961310403, 31150.68849311523, 31238.375232495222, 31546.211344716565, 31694.366112677617, 1101.388729255271, 1156.306216270248, 1178.4755783676183, 1183.46743965216, 1205.3189947679532, 15856.762148567781, 15888.512872730242, 15922.442714504938, 16146.919265403598, 16216.513504594881, 540253.0, 541330.0, 544525.0, 547236.0, 547475.0, 51340.22989655158, 63784.0, 64195.99999999907, 64330.678346608904, 64487.355126449074, 1327.6017194841509, 1351.9589915765002, 1531.693661267751, 1546.4200924653255, 1575.8345373735822, 29866.253343666584, 30016.549751754177, 30053.69946300509, 30750.0, 30767.0, 48726.26910596273, 59801.70099149476, 60331.396686034335, 60399.094013588954, 60572.39427605668, 420097.0, 420124.0, 426172.0, 426395.0, 427945.0, 41127.794361028005, 46711.29933050939, 47088.764556177, 47540.0, 48856.75571622119, 48876.75561622169, 48943.0, 49361.50638493569, 50247.246291306394, 50857.237141442165, 39250.6074939247, 39515.0, 40129.79935100306, 40457.0, 41976.790116049226, 41300.23510854394, 42017.15965680608, 42161.0, 42440.0, 42689.57310426856, 40017.399739003355, 40549.39175912423, 40590.39114413345, 42262.0, 42283.0, 35626.0, 35871.64128358787, 36543.63456365402, 36563.26873462569, 36780.632193677724, 24193.0, 26837.731622684303, 27148.1855544334, 27229.0, 27231.59152612673], "unit": "score", "name": "ext.html", "value": 21085.654729659498}, {"lowerIsBetter": false, "replicates": [371.515172699627, 375.69568649393995, 377.27186529997095, 378.18123762055154, 382.69958082904924, 18726.53183670409, 20545.691814622773, 21375.931203439843, 22349.553008939813, 22368.776312236892, 11780.823287650692, 12387.938060309685, 12474.81287780684, 12499.500019999212, 12503.624891253254, 4898.799794050454, 6122.1735065766, 6125.234345706786, 6134.4172303631085, 6142.907856382158, 5947.940520594803, 6050.2739671239515, 6067.575269731121, 6068.908966365486, 6112.480439162654, 5941.613795103326, 6204.348543402945, 6206.441420272175, 6210.068489726532, 6214.782482613109, 6115.877682446348, 6190.690465476753, 6191.969040154793, 6196.318404975472, 6579.605223686546, 5786.566007549409, 5917.112433135021, 5944.7324870380935, 5947.881042379128, 5955.404459554054, 13741.587752367444, 13850.722985540233, 13981.370838312301, 14512.346944387527, 14545.490907818175, 2310.560993411263, 2528.8735563221767, 2585.900992078359, 2730.9726902730918, 2845.302900789299, 9828.312018158731, 10196.286259961806, 10380.79238415235, 10400.32397894142, 10735.248532602722, 1722.939697110595, 1758.4109323376683, 1801.8378345948927, 1824.078840185711, 1850.2044121027952, 103913.0, 106050.0, 108354.45822770835, 109442.45278773634, 109900.0, 10131.290809643255, 11000.394978276112, 11332.43337833105, 11557.47991340375, 11653.825192622118, 14467.0, 14564.271786410849, 14573.125612463286, 14580.781288280685, 14607.269636518131, 8844.425112367675, 8881.42270752399, 8908.420952638058, 8947.507887066275, 9021.32340074497, 9034.186923176945, 9072.04743501936, 9116.453012819253, 9164.450132991908, 9189.218916392048, 4585.541445855388, 4589.334546490807, 4591.012932219589, 4600.930986035212, 4635.837745678885, 1612.403410738011, 1795.2549691877725, 1822.6795962794274, 1930.4787707319226, 1958.3439547751377, 945.7777422305753, 1044.9425281609585, 1045.4406892312577, 1083.9355197331984, 1087.7552550676035, 18964.956927369127, 20251.696224556643, 22300.773457459996, 22370.9933053013, 22610.0, 965.5461932891529, 982.2387649571497, 989.6536212325628, 990.0792263195298, 1087.6410784441116, 11299.829803800341, 11780.293182409083, 11870.465829037714, 11952.40237988097, 11979.221350612183, 653443.0, 665539.0, 666937.0, 670878.0, 670889.0, 125209.0, 125624.71876406143, 126204.0, 126949.36525317498, 131117.0, 1017.9949100254452, 1044.5247412427327, 1111.77208672222, 1134.160721066405, 1135.0692432205676, 17342.0, 19582.804171958098, 19605.8039419604, 19726.210951561778, 19801.207951682347, 54326.728366357915, 54724.726376367864, 55331.72334138303, 55787.72106139443, 55974.44025559692, 78994.21005789869, 79070.6046469764, 79381.60309198417, 82400.0, 83375.58312208643, 8252.092269850431, 8412.369072319478, 8442.915570844458, 8457.112003239572, 8457.83084338322, 9499.667511637059, 11341.886581134413, 12922.418491167731, 13531.729365412833, 13532.93233533826, 11388.145889058189, 11567.884321157017, 11919.106067044833, 11983.101267405154, 12021.459034343301, 11236.719082023015, 11511.942440287745, 11607.071434285406, 11734.765304694029, 11895.702607434883, 11647.6505704829, 11881.168318217642, 11883.0, 12081.577144800242, 12119.575814846437, 7129.643517824192, 8034.196580341919, 8262.80247048643, 8401.369897257853, 8536.957315213633, 10750.623728169789, 10843.728906777395, 11877.346745928711, 11988.666197727005, 12119.697007574528], "unit": "score", "name": "jquery.html", "value": 9570.28631146202}, {"lowerIsBetter": false, "replicates": [1441.7549016667167, 1500.459834459595, 1647.7940257467817, 1915.3487814143189, 2075.118074818203, 8255.752327430178, 8884.955575222131, 9068.77328066797, 9071.274298056156, 9125.771855703602, 8215.91784082159, 8471.534065626389, 8475.40672152948, 8485.193906578881, 8509.74470765878, 2234.1398561553838, 2499.7750202481775, 2503.5493611149986, 2529.8868497860976, 2563.705173905006, 2172.750133734617, 2573.4209802794408, 2578.729233430491, 2583.134649892279, 2584.7673709366154, 2501.024600405843, 2543.504016716741, 2565.307367010906, 2565.576679847823, 2569.987150064247, 2505.3987043109614, 2545.8345207561542, 2564.4486435416475, 2565.320190149593, 2565.525377805112, 2520.6849143857016, 2542.288159315376, 2547.923562293134, 2556.8338058026357, 2567.114345550801, 9116.908830911672, 9193.35646504745, 9201.171894529458, 9208.769780755467, 9219.17027467531, 5560.86097847553, 5720.170575266605, 5762.07806750922, 5763.971180144073, 5764.913526297109, 3632.8365223565, 3693.464447655102, 3748.6251374862295, 3757.887263382103, 3763.8870833875026, 1022.8210063238903, 1046.931949423285, 1062.9946850265776, 1063.6808957312776, 1081.0324759340424, 12501.249925004438, 12533.93733031329, 12537.0, 12541.372931353488, 12557.874421255761, 4960.8759781005765, 4964.833264182914, 5095.490450954875, 5097.949020509747, 5109.489051094935, 10598.682039538826, 10610.257281990342, 10612.893871061346, 10617.0, 10627.734306642396, 5758.992176369119, 5763.394843541368, 5767.221425107557, 5773.538116950634, 5778.971105144447, 4617.376654151713, 4715.292706094045, 4763.04739052191, 4764.404449443819, 4965.478624744422, 3297.3570153820256, 4066.877993660195, 4069.5116586009867, 4123.299039163349, 4129.91740165201, 1335.052112999781, 1360.6258278973164, 1363.8158848555317, 1372.6225288045866, 1373.574192000463, 1024.2471783239314, 1168.7253495428567, 1184.9940750296194, 1191.8927296543352, 1194.988050119505, 8463.23830855226, 8494.532800695897, 8554.572271386405, 8591.312694984386, 8599.269062129664, 1265.1966001589058, 1269.581038257373, 1275.6555729953047, 1276.470264840081, 1298.539018648385, 5429.511343979061, 6483.481321494269, 6520.119783829217, 6613.173353330833, 6624.105745724362, 10839.67480975572, 11228.550857965753, 11325.886741132648, 11354.48904799286, 11357.034652054503, 10465.738356540995, 10880.401577913133, 11145.665630031112, 11157.103292440304, 11176.552937882556, 8529.701460448854, 8572.142785721378, 8622.534169191253, 8675.696350627697, 8681.956590217009, 7172.533785303938, 8386.538740369338, 8426.452280601741, 8556.016058153393, 8563.95718021406, 9456.479893605918, 9757.51212439361, 9834.967328430412, 9946.005399459997, 10106.343087699275, 9039.231665308522, 10054.0, 10220.744481388025, 10226.386416815016, 10247.231457640557, 6001.219841420588, 7247.383972362198, 7762.146163921851, 7775.377969762292, 7777.766666999999, 7975.003124609424, 8038.718644847402, 8332.16678332162, 8549.615267312862, 8576.399652024298, 6684.63234522106, 7577.318041376303, 7653.425993050655, 7724.0, 7839.725609603636, 7459.515131516434, 7514.135874374517, 7549.245075492627, 7562.054743157106, 7601.923980760343, 7977.401694872791, 8002.679892804455, 8047.114817369968, 8053.8791918120105, 8057.113717490954, 6289.213848268966, 7284.817879553054, 7334.119905611361, 7375.778726638209, 7385.03994480714, 7393.55638661682, 7396.3343299103335, 7452.105747310357, 7456.552606843607, 7456.7390141344795], "unit": "score", "name": "mootools.html", "value": 5084.001972255404}, {"lowerIsBetter": false, "replicates": [325.37040826001686, 331.49281599153306, 334.04629781972466, 335.62410100687225, 340.9232922592417, 4593.471750748665, 6256.3430839761795, 6474.255460622018, 6477.449416799578, 6479.31967143449, 6398.072279519467, 6399.296077431491, 6400.359964003597, 6406.135171751812, 6424.421802037816, 3973.006748312922, 4567.954320456803, 4638.9536104638855, 4689.296605509184, 4833.75831208439, 4653.115907977491, 4747.264174053027, 4759.167145749498, 4808.158572249859, 4838.153323168449, 4062.1469491406842, 4658.557437043475, 4763.714177149365, 4783.306420569015, 4815.903681926359, 4775.212090005145, 4775.474697783241, 4793.616510679172, 4800.663953523254, 4810.254410566386, 3947.940780888289, 4006.358982562805, 4108.876733697994, 4118.608732170439, 4123.690723195772, 6092.299385570672, 6165.414285642857, 6169.784057557964, 6182.319944806068, 6201.16284301618, 5645.717714114319, 5652.3217213934595, 5662.065759149736, 5670.68811215383, 5679.971600142014, 5520.503154716095, 6371.808845734636, 6702.564333318319, 6803.863922721567, 6812.386885180308, 4650.372199753057, 4655.185342565038, 4658.1848176568965, 4658.86023419298, 4660.790264438108, 6167.814965551041, 6518.511111666644, 7200.063991681101, 7241.67412466435, 7246.275372462817, 3518.612952575215, 3946.8026598669953, 4493.101379724003, 4584.1977653910435, 4590.288505281686, 4663.650226232979, 4671.32265821454, 4675.859724208279, 4676.532346765296, 4677.6257899367965, 5468.863278418072, 5476.424975377608, 5477.780888764405, 5488.91766623501, 5492.231087647732, 5646.858828529237, 6845.794626161223, 6863.828404289933, 6871.175458944858, 6878.793636190922, 1536.1244090868097, 1610.4443966831425, 1610.6778644271192, 1614.386533117408, 1618.4578166314182, 1006.104566935428, 1029.505837198134, 1041.7135287795766, 1043.8121138195197, 1044.8223801953686, 6036.305824830113, 6123.571350005546, 6183.134361189437, 6206.162168107338, 6210.689465526705, 6855.245922948472, 6957.339052789976, 6975.965120174468, 7057.964710176417, 7084.964575177091, 6250.874562718641, 6427.55007149495, 6427.871442571122, 6430.0676401921455, 6432.871342573122, 3702.925941481209, 3744.5693745219105, 3762.6049264827348, 3762.6613604775703, 3764.962350376461, 7190.64046797658, 7416.7033318666645, 7418.146913104954, 7427.554346739213, 7495.737649182362, 7333.046703928455, 7354.411647068222, 7358.484906056525, 7363.668634911442, 7367.8894816577795, 7431.628418579157, 7437.925620743723, 7441.1070671519765, 7445.553266804009, 7461.962690186623, 7088.149422069385, 7119.0, 7125.216226215216, 7130.857382852418, 7134.429245660231, 6712.798616041527, 6997.1253593300835, 6997.160340759141, 7040.415906421219, 7099.574025558483, 7395.593242371721, 7410.073740782403, 7416.0, 7431.925680743123, 7446.25537446251, 4561.452625684939, 4620.330052142488, 4623.260278355447, 4623.815047398067, 4629.351890735367, 7100.573965562083, 7109.755792736147, 7118.145822501333, 7118.644067796693, 7122.145342558719, 6569.868602628017, 6770.627615480998, 6846.246912839484, 6848.486363522656, 6867.592143610647, 6816.182058153054, 6822.590644561342, 6837.897431538431, 6840.144981877265, 6852.486063545154, 6684.565503242274, 6692.297308782508, 6698.665066746546, 6708.597484150967, 6712.026756120433, 6813.250542440229, 6831.521793474409, 6832.795016149524, 6845.794626161223, 6874.9656251718425, 6441.71012304438, 6446.355364463704, 6688.866222675617, 6690.765823196165, 6806.387425131564], "unit": "score", "name": "prototype.html", "value": 5005.779210868906}, {"lowerIsBetter": false, "replicates": [704.475166001329, 756.6405957170344, 766.6435107674934, 785.787837283933, 812.2446125103655, 15422.0, 15520.068795872241, 15606.843931560681, 15612.765808512868, 15685.058896466191, 14233.0, 14308.998370114095, 14324.785128223086, 14327.928360358184, 14334.569962901129, 753.9962300188519, 758.6510205305566, 788.4677842456348, 934.8550974598932, 966.7583104223944, 958.8178246133212, 973.4208146153028, 974.790420059687, 975.4488713876673, 976.6191185437685, 1024.359775140537, 1024.390487659845, 1025.912797412217, 1025.9487025648689, 1026.8613737145465, 820.0282665041915, 975.765816204113, 978.021978021978, 978.2076518020426, 1006.2453160129866, 738.4424759306743, 738.6860584251672, 739.9223081576465, 748.2554857916408, 765.2003656179306, 15403.922980385027, 15437.0, 15438.922805386013, 15456.227188640523, 15461.5361539154, 11591.362475063868, 12972.091953563255, 13047.412866421033, 13070.803937940938, 13305.869001134915, 10195.49022548877, 10316.79366412675, 10395.480225988671, 10680.62617808373, 10782.5686972521, 3562.0, 3569.5002699622073, 3620.818959052037, 3676.283124790667, 3839.117003089305, 64349.0, 83938.0, 87464.88588537574, 87914.0, 88042.55978720129, 9901.455419951828, 10091.444970526545, 10098.848517272247, 10257.974202579831, 10325.793484130276, 23822.285331440085, 23866.16468423597, 23897.0, 23924.52150957006, 23954.88022559876, 7623.9618801905635, 7717.884231736529, 7829.099882842663, 7833.33416659579, 7849.01887264092, 4226.408302837605, 4242.469691288589, 4254.127903779718, 4254.893627659333, 4426.756528390905, 3774.8864085094415, 3815.141593141521, 3816.46569480273, 3822.694184465236, 3826.3877779555137, 656.5108993799649, 680.6936878404639, 687.1204857782047, 690.0787448755867, 691.97232110715, 520.1599416941611, 550.6530885542163, 551.2613098448098, 580.1413907417046, 590.2474345209891, 7090.432765378758, 7142.92857071433, 8458.450200736932, 8465.61904714277, 8470.576471176417, 488.26625862265706, 510.89271253037276, 559.3679142568872, 597.6444015810586, 602.9819105426844, 3940.9014774630864, 3973.8807835764974, 4006.138680183716, 4013.57857424972, 4036.287731219688, 93787.59318610227, 94718.0, 94755.0, 97334.0, 99686.00627987545, 83669.74495382574, 84698.57650711708, 85232.57383713042, 85671.0, 86667.13332866717, 73423.63288183631, 73986.26013739902, 74403.62798185974, 74412.25587744053, 74892.25107748962, 8641.481511109103, 8681.305495560213, 8737.21365077146, 8814.250788683034, 8891.688790892287, 51045.74477127591, 52869.73565132303, 53394.73302633462, 53620.0, 56209.43790562206, 66665.66667166633, 69150.65424672845, 72695.27304726886, 73236.63381683058, 77430.0, 11315.490802914053, 11357.54569817198, 11464.0, 11712.70718232051, 11937.641870743892, 12065.09511786637, 12523.561675341683, 12621.179623324455, 12642.557510487457, 12705.936470317589, 9555.522223888916, 11992.160548761503, 12034.398280085785, 12130.575429860266, 12145.878541214475, 11744.823827642422, 11970.76058478843, 12046.939765301468, 12085.093617978862, 12088.75822483563, 11508.424578771195, 11677.357745324089, 11794.76410471803, 11797.646070617551, 12359.3820308986, 9581.041895810364, 9612.134907858326, 9913.603455861685, 9924.950375248078, 9941.30410871261, 8345.290650294794, 9072.138146876036, 10030.548625312025, 10068.44623545712, 10098.697039088838], "unit": "score", "name": "yui.html", "value": 7156.245183743089}], "name": "dromaeo_css", "value": 7704.262045729}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 111.0, 112.0, 119.0, 111.0, 109.0, 109.0], "unit": "score", "name": "ai-astar", "value": 111.0}, {"lowerIsBetter": true, "replicates": [150.0, 119.0, 155.0, 151.0, 118.0, 137.0, 117.0, 149.0, 152.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 137.3}, {"lowerIsBetter": true, "replicates": [184.0, 185.0, 185.0, 187.0, 182.0, 183.0, 184.0, 185.0, 186.0, 181.0], "unit": "score", "name": "audio-dft", "value": 184.2}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 80.0, 133.0, 66.0, 79.0, 82.0, 81.0, 148.0, 57.0], "unit": "score", "name": "audio-fft", "value": 87.3}, {"lowerIsBetter": true, "replicates": [91.0, 94.0, 171.0, 113.0, 114.0, 86.0, 120.0, 76.0, 76.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [89.0, 85.0, 89.0, 87.0, 88.0, 86.0, 90.0, 86.0, 88.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.4}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 119.0, 110.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 97.6}, {"lowerIsBetter": true, "replicates": [89.0, 87.0, 87.0, 86.0, 88.0, 86.0, 87.0, 88.0, 87.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 87.1}, {"lowerIsBetter": true, "replicates": [52.0, 59.0, 58.0, 58.0, 58.0, 57.0, 60.0, 68.0, 50.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [61.0, 64.0, 72.0, 62.0, 73.0, 71.0, 65.0, 67.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.9}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 83.0, 100.0, 103.0, 104.0, 103.0, 79.0, 79.0, 81.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.3}, {"lowerIsBetter": true, "replicates": [126.0, 127.0, 124.0, 121.0, 129.0, 117.0, 119.0, 117.0, 117.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.8}, {"lowerIsBetter": true, "replicates": [132.0, 124.0, 136.0, 134.0, 131.0, 119.0, 134.0, 133.0, 135.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.1}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 64.0, 66.0, 65.0, 67.0, 65.0, 66.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.5}], "name": "kraken", "value": 1429.8999999999999}]} 04:10:12 INFO - Return code: 0 04:10:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:10:12 INFO - # TBPL SUCCESS # 04:10:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:10:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:10:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:10:12 INFO - 2016-04-27 04:10:12.525 screenresolution[2250:22747] starting screenresolution argv=screenresolution get 04:10:12 INFO - 2016-04-27 04:10:12.528 screenresolution[2250:22747] Display 0: 1600x1200x32@60 04:10:12 INFO - 2016-04-27 04:10:12.534 screenresolution[2251:22750] starting screenresolution argv=screenresolution list 04:10:12 INFO - Available Modes on Display 0 04:10:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:10:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:10:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:10:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:10:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:10:12 INFO - Intel Iris: 04:10:12 INFO - Chipset Model: Intel Iris 04:10:12 INFO - Type: GPU 04:10:12 INFO - Bus: Built-In 04:10:12 INFO - VRAM (Dynamic, Max): 1536 MB 04:10:12 INFO - Vendor: Intel (0x8086) 04:10:12 INFO - Device ID: 0x0a2e 04:10:12 INFO - Revision ID: 0x0009 04:10:12 INFO - Displays: 04:10:12 INFO - Display: 04:10:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:10:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:10:12 INFO - Display Serial Number: 1600x1200 60 04:10:12 INFO - Main Display: Yes 04:10:12 INFO - Mirror: Off 04:10:12 INFO - Online: Yes 04:10:12 INFO - Rotation: Supported 04:10:12 INFO - Return code: 0 04:10:12 INFO - Running post-action listener: _resource_record_post_action 04:10:12 INFO - Running post-run listener: _resource_record_post_run 04:10:13 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 929792; Write bytes: 664936448; Read time: 12; Write time: 1951 04:10:13 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 356938240; Write bytes: 353990144; Read time: 27891; Write time: 656 04:10:13 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 929792; Write bytes: 299768320; Read time: 12; Write time: 1260 04:10:13 INFO - Running post-run listener: _upload_blobber_files 04:10:13 INFO - Blob upload gear active. 04:10:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:10:13 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 04:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:10:14 INFO - (blobuploader) - INFO - Open directory for files ... 04:10:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:10:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:10:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:14 INFO - (blobuploader) - INFO - Done attempting. 04:10:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:10:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:10:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:15 INFO - (blobuploader) - INFO - Done attempting. 04:10:15 INFO - (blobuploader) - INFO - Iteration through files over. 04:10:15 INFO - Return code: 0 04:10:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:10:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:10:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ae6def183e4e97247cc0466f62498717e9978f54ff12cc8788fb10489c1a7437cd7d2e296013a92b30fc539316e95f9076e67c103f9182180aa36c758a82df8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c99ec2c6d4385a94ed02dcdd79b13d93215e13388bf877a814b9e6d025372f4562397c5287de7242f6ab85a32c5b4c891345c36fff59f11e29d5071ddee6ab"} 04:10:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:10:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:10:15 INFO - Contents: 04:10:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ae6def183e4e97247cc0466f62498717e9978f54ff12cc8788fb10489c1a7437cd7d2e296013a92b30fc539316e95f9076e67c103f9182180aa36c758a82df8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c99ec2c6d4385a94ed02dcdd79b13d93215e13388bf877a814b9e6d025372f4562397c5287de7242f6ab85a32c5b4c891345c36fff59f11e29d5071ddee6ab"} 04:10:15 INFO - Running post-run listener: copy_logs_to_upload_dir 04:10:15 INFO - Copying logs to upload dir... 04:10:15 INFO - mkdir: /builds/slave/test/build/upload/logs 04:10:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.344414 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-27 04:10:16.064215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:16.068332) ========= 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.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ae6def183e4e97247cc0466f62498717e9978f54ff12cc8788fb10489c1a7437cd7d2e296013a92b30fc539316e95f9076e67c103f9182180aa36c758a82df8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c99ec2c6d4385a94ed02dcdd79b13d93215e13388bf877a814b9e6d025372f4562397c5287de7242f6ab85a32c5b4c891345c36fff59f11e29d5071ddee6ab"} build_url:https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010789 build_url: 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ae6def183e4e97247cc0466f62498717e9978f54ff12cc8788fb10489c1a7437cd7d2e296013a92b30fc539316e95f9076e67c103f9182180aa36c758a82df8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c99ec2c6d4385a94ed02dcdd79b13d93215e13388bf877a814b9e6d025372f4562397c5287de7242f6ab85a32c5b4c891345c36fff59f11e29d5071ddee6ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:16.096968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:16.097294) ========= 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.74zCQKx2Sc/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.Voqjanta39/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005301 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:16.146375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:10:16.146817) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:10:16.147430) ========= ========= Total master_lag: 0.27 =========