builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1461588130.29 results: success (0) buildid: 20160425042155 builduid: 3cafbae6ee4445cbb07d61c2d398d690 revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.286818) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.287251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.287520) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.307257) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.307520) ========= 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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-25 05:42:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.9M=0.001s 2016-04-25 05:42:10 (13.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.453078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.453390) ========= 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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.026553 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:10.503198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:42:10.503538) ========= 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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-25 05:42:10,580 truncating revision to first 12 chars 2016-04-25 05:42:10,580 Setting DEBUG logging. 2016-04-25 05:42:10,580 attempt 1/10 2016-04-25 05:42:10,580 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-25 05:42:11,645 unpacking tar archive at: mozilla-aurora-f328ac32af6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:42:11.837782) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:11.838435) ========= script_repo_revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:11.839229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:11.839494) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:42:11.853806) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-04-25 05:42:11.854293) ========= /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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:42:12 INFO - MultiFileLogger online at 20160425 05:42:12 in /builds/slave/test 05:42:12 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 05:42:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:42:12 INFO - {'append_to_log': False, 05:42:12 INFO - 'base_work_dir': '/builds/slave/test', 05:42:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:42:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:42:12 INFO - 'branch': 'Mozilla-Aurora', 05:42:12 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:12 INFO - 'config_files': ('talos/mac_config.py',), 05:42:12 INFO - 'default_actions': ('clobber', 05:42:12 INFO - 'read-buildbot-config', 05:42:12 INFO - 'download-and-extract', 05:42:12 INFO - 'populate-webroot', 05:42:12 INFO - 'create-virtualenv', 05:42:12 INFO - 'install', 05:42:12 INFO - 'run-tests'), 05:42:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:12 INFO - 'download_minidump_stackwalk': True, 05:42:12 INFO - 'download_symbols': 'ondemand', 05:42:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:12 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:12 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:12 INFO - 'installer_path': 'installer.exe', 05:42:12 INFO - 'log_level': 'info', 05:42:12 INFO - 'log_name': 'talos', 05:42:12 INFO - 'log_to_console': True, 05:42:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:42:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:42:12 INFO - 'opt_config_files': (), 05:42:12 INFO - 'pip_index': False, 05:42:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:12 INFO - 'cmd': ('bash', 05:42:12 INFO - '-c', 05:42:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:12 INFO - 'enabled': True, 05:42:12 INFO - 'halt_on_failure': False, 05:42:12 INFO - 'name': 'check_screen_resolution'},), 05:42:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:12 INFO - 'cmd': ('bash', 05:42:12 INFO - '-c', 05:42:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:12 INFO - 'enabled': True, 05:42:12 INFO - 'halt_on_failure': False, 05:42:12 INFO - 'name': 'check_screen_resolution'},), 05:42:12 INFO - 'run_cmd_checks_enabled': True, 05:42:12 INFO - 'sps_profile': False, 05:42:12 INFO - 'sps_profile_interval': 0, 05:42:12 INFO - 'suite': 'dromaeojs', 05:42:12 INFO - 'system_bits': '32', 05:42:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:12 INFO - 'title': 't-yosemite-r7-0132', 05:42:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:12 INFO - 'use_talos_json': True, 05:42:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:42:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:12 INFO - 'work_dir': 'build'} 05:42:12 INFO - ##### 05:42:12 INFO - ##### Running clobber step. 05:42:12 INFO - ##### 05:42:12 INFO - Running pre-action listener: _resource_record_pre_action 05:42:12 INFO - Running main action method: clobber 05:42:12 INFO - rmtree: /builds/slave/test/build 05:42:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:42:13 INFO - Running post-action listener: _resource_record_post_action 05:42:13 INFO - ##### 05:42:13 INFO - ##### Running read-buildbot-config step. 05:42:13 INFO - ##### 05:42:13 INFO - Running pre-action listener: _resource_record_pre_action 05:42:13 INFO - Running main action method: read_buildbot_config 05:42:13 INFO - Using buildbot properties: 05:42:13 INFO - { 05:42:13 INFO - "project": "", 05:42:13 INFO - "product": "firefox", 05:42:13 INFO - "script_repo_revision": "production", 05:42:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:42:13 INFO - "repository": "", 05:42:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:42:13 INFO - "buildid": "20160425042155", 05:42:13 INFO - "pgo_build": "False", 05:42:13 INFO - "basedir": "/builds/slave/test", 05:42:13 INFO - "buildnumber": 320, 05:42:13 INFO - "slavename": "t-yosemite-r7-0132", 05:42:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:42:13 INFO - "platform": "yosemite_r7", 05:42:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:42:13 INFO - "branch": "mozilla-aurora", 05:42:13 INFO - "repo_path": "releases/mozilla-aurora", 05:42:13 INFO - "revision": "f328ac32af6a3d0ce1593c41fd18edf7216a1652", 05:42:13 INFO - "stage_platform": "macosx64", 05:42:13 INFO - "builduid": "3cafbae6ee4445cbb07d61c2d398d690", 05:42:13 INFO - "slavebuilddir": "test" 05:42:13 INFO - } 05:42:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 05:42:13 INFO - Running post-action listener: _resource_record_post_action 05:42:13 INFO - ##### 05:42:13 INFO - ##### Running download-and-extract step. 05:42:13 INFO - ##### 05:42:13 INFO - Running pre-action listener: _resource_record_pre_action 05:42:13 INFO - Running main action method: download_and_extract 05:42:13 INFO - mkdir: /builds/slave/test/build/tests 05:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:13 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 05:42:13 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 05:42:13 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 05:42:13 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 05:42:13 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 05:42:15 INFO - Downloaded 1277 bytes. 05:42:15 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:42:15 INFO - Using the following test package requirements: 05:42:15 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:42:15 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 05:42:15 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 05:42:15 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'jsshell-mac.zip'], 05:42:15 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 05:42:15 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:42:15 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 05:42:15 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 05:42:15 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 05:42:15 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:42:15 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:42:15 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 05:42:15 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:15 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 05:42:15 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 05:42:15 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 05:42:15 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 05:42:15 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 05:42:16 INFO - Downloaded 17919003 bytes. 05:42:16 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'] 05:42:16 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 05:42:19 INFO - Return code: 0 05:42:19 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 05:42:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:19 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 05:42:19 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 05:42:19 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 05:42:19 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 05:42:19 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 05:42:20 INFO - Downloaded 17919003 bytes. 05:42: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'] 05:42: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 05:42:24 INFO - Return code: 0 05:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:24 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 05:42:24 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 05:42:24 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 05:42:24 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 05:42:24 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 05:42:25 INFO - Downloaded 10845194 bytes. 05:42:25 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'] 05:42:25 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 05:42:25 INFO - Return code: 0 05:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:25 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:42:25 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 05:42:25 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 05:42:25 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 05:42:25 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 05:42:29 INFO - Downloaded 115766844 bytes. 05:42:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 05:42:29 INFO - mkdir: /builds/slave/test/properties 05:42:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:42:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:42:29 INFO - Contents: 05:42:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 05:42:30 INFO - Running post-action listener: _resource_record_post_action 05:42:30 INFO - Running post-action listener: set_extra_try_arguments 05:42:30 INFO - ##### 05:42:30 INFO - ##### Running populate-webroot step. 05:42:30 INFO - ##### 05:42:30 INFO - Running pre-action listener: _resource_record_pre_action 05:42:30 INFO - Running main action method: populate_webroot 05:42:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:42:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:42:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:42:30 INFO - '--tppagecycles', 05:42:30 INFO - '7'], 05:42:30 INFO - 'tests': ['tsvgm']}}, 05:42:30 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:42:30 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:42:30 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:42:30 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:42:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'tests': ['damp', 'tps']}, 05:42:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['damp', 'tps']}, 05:42:30 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:42:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:42:30 INFO - 'other': {'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'other_l64': {'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'other_nol64': {'tests': ['a11yr', 05:42:30 INFO - 'ts_paint', 05:42:30 INFO - 'tpaint', 05:42:30 INFO - 'sessionrestore', 05:42:30 INFO - 'sessionrestore_no_auto_restore', 05:42:30 INFO - 'tabpaint']}, 05:42:30 INFO - 'svgr': {'tests': ['tsvgx', 05:42:30 INFO - 'tsvgr_opacity', 05:42:30 INFO - 'tart', 05:42:30 INFO - 'tscrollx', 05:42:30 INFO - 'cart']}, 05:42:30 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['tsvgx', 05:42:30 INFO - 'tsvgr_opacity', 05:42:30 INFO - 'tart', 05:42:30 INFO - 'tscrollx', 05:42:30 INFO - 'cart']}, 05:42:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'tests': ['tp5o']}, 05:42:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'talos_options': ['--e10s'], 05:42:30 INFO - 'tests': ['tp5o']}, 05:42:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'talos_options': ['--xperf_path', 05:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:30 INFO - 'tests': ['tp5n']}, 05:42:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:30 INFO - 'talos_options': ['--e10s', 05:42:30 INFO - '--xperf_path', 05:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:30 INFO - 'tests': ['tp5n']}}, 05:42:30 INFO - 'talos.zip': {'path': '', 05:42:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:42:30 INFO - Running post-action listener: _resource_record_post_action 05:42:30 INFO - ##### 05:42:30 INFO - ##### Running create-virtualenv step. 05:42:30 INFO - ##### 05:42:30 INFO - Running pre-action listener: _resource_record_pre_action 05:42:30 INFO - Running main action method: create_virtualenv 05:42:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:42:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:42:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:42:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:42:30 INFO - Using real prefix '/tools/python27' 05:42:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:42:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:42:33 INFO - Installing pip.................done. 05:42:33 INFO - Return code: 0 05:42:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:33 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:33 INFO - 'HOME': '/Users/cltbld', 05:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:33 INFO - 'LOGNAME': 'cltbld', 05:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:33 INFO - 'MOZ_NO_REMOTE': '1', 05:42:33 INFO - 'NO_EM_RESTART': '1', 05:42:33 INFO - 'PAGER': '/bin/cat', 05:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:33 INFO - 'PWD': '/builds/slave/test', 05:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:33 INFO - 'SHELL': '/bin/bash', 05:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:33 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:33 INFO - 'USER': 'cltbld', 05:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:33 INFO - 'XPC_FLAGS': '0x0', 05:42:33 INFO - 'XPC_SERVICE_NAME': '0', 05:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:42:34 INFO - Cleaning up... 05:42:34 INFO - Return code: 0 05:42:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:34 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:34 INFO - 'HOME': '/Users/cltbld', 05:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:34 INFO - 'LOGNAME': 'cltbld', 05:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:34 INFO - 'MOZ_NO_REMOTE': '1', 05:42:34 INFO - 'NO_EM_RESTART': '1', 05:42:34 INFO - 'PAGER': '/bin/cat', 05:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:34 INFO - 'PWD': '/builds/slave/test', 05:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:34 INFO - 'SHELL': '/bin/bash', 05:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:34 INFO - 'USER': 'cltbld', 05:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:34 INFO - 'XPC_FLAGS': '0x0', 05:42:34 INFO - 'XPC_SERVICE_NAME': '0', 05:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:34 INFO - Downloading/unpacking psutil>=0.7.1 05:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:42:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:37 INFO - Installing collected packages: psutil 05:42:37 INFO - Running setup.py install for psutil 05:42:37 INFO - building 'psutil._psutil_osx' extension 05:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:42:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:42:38 INFO - building 'psutil._psutil_posix' extension 05:42:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:42:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:42:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:42:38 INFO - ^ 05:42:38 INFO - 1 warning generated. 05:42:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:42:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:38 INFO - Successfully installed psutil 05:42:38 INFO - Cleaning up... 05:42:38 INFO - Return code: 0 05:42:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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', '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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:42: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 mozsystemmonitor==0.0.0 05:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:38 INFO - 'HOME': '/Users/cltbld', 05:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:38 INFO - 'LOGNAME': 'cltbld', 05:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:38 INFO - 'MOZ_NO_REMOTE': '1', 05:42:38 INFO - 'NO_EM_RESTART': '1', 05:42:38 INFO - 'PAGER': '/bin/cat', 05:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:38 INFO - 'PWD': '/builds/slave/test', 05:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:38 INFO - 'SHELL': '/bin/bash', 05:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:38 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:38 INFO - 'USER': 'cltbld', 05:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:38 INFO - 'XPC_FLAGS': '0x0', 05:42:38 INFO - 'XPC_SERVICE_NAME': '0', 05:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:42: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 05:42: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 05:42: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 05:42: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 05:42: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 05:42: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 05:42:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:42:41 INFO - Installing collected packages: mozsystemmonitor 05:42:41 INFO - Running setup.py install for mozsystemmonitor 05:42:41 INFO - Successfully installed mozsystemmonitor 05:42:41 INFO - Cleaning up... 05:42:41 INFO - Return code: 0 05:42:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:41 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:42:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:41 INFO - 'HOME': '/Users/cltbld', 05:42:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:41 INFO - 'LOGNAME': 'cltbld', 05:42:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:41 INFO - 'MOZ_NO_REMOTE': '1', 05:42:41 INFO - 'NO_EM_RESTART': '1', 05:42:41 INFO - 'PAGER': '/bin/cat', 05:42:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:41 INFO - 'PWD': '/builds/slave/test', 05:42:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:41 INFO - 'SHELL': '/bin/bash', 05:42:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:41 INFO - 'USER': 'cltbld', 05:42:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:41 INFO - 'XPC_FLAGS': '0x0', 05:42:41 INFO - 'XPC_SERVICE_NAME': '0', 05:42:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:42:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:42:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:45 INFO - Downloading docopt-0.6.1.tar.gz 05:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:42:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:42:45 INFO - Running setup.py install for blobuploader 05:42:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:42:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:42:45 INFO - Running setup.py install for requests 05:42:45 INFO - Running setup.py install for docopt 05:42:46 INFO - Successfully installed blobuploader requests docopt 05:42:46 INFO - Cleaning up... 05:42:46 INFO - Return code: 0 05:42:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:46 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:46 INFO - 'HOME': '/Users/cltbld', 05:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:46 INFO - 'LOGNAME': 'cltbld', 05:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:46 INFO - 'MOZ_NO_REMOTE': '1', 05:42:46 INFO - 'NO_EM_RESTART': '1', 05:42:46 INFO - 'PAGER': '/bin/cat', 05:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:46 INFO - 'PWD': '/builds/slave/test', 05:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:46 INFO - 'SHELL': '/bin/bash', 05:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:46 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:46 INFO - 'USER': 'cltbld', 05:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:46 INFO - 'XPC_FLAGS': '0x0', 05:42:46 INFO - 'XPC_SERVICE_NAME': '0', 05:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Iu3Vgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-AT80Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-VsvlQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_t7LrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-awuSz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:42:46 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rHT_pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-88S5DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kdB4Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-wbh27x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7TlUIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-9R9AJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Go2x9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xwpJZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2nfJjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_FQyue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qkYPAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-i66WVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:42:48 INFO - Running setup.py install for manifestparser 05:42:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:42:48 INFO - Running setup.py install for mozcrash 05:42:48 INFO - Running setup.py install for mozdebug 05:42:48 INFO - Running setup.py install for mozdevice 05:42:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:42:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:42:48 INFO - Running setup.py install for mozfile 05:42:48 INFO - Running setup.py install for mozhttpd 05:42:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Running setup.py install for mozinfo 05:42:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Running setup.py install for mozInstall 05:42:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Running setup.py install for mozleak 05:42:49 INFO - Running setup.py install for mozlog 05:42:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Running setup.py install for moznetwork 05:42:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:42:49 INFO - Running setup.py install for mozprocess 05:42:50 INFO - Running setup.py install for mozprofile 05:42:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:42:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:42:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:42:50 INFO - Running setup.py install for mozrunner 05:42:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:42:50 INFO - Running setup.py install for mozscreenshot 05:42:50 INFO - Running setup.py install for moztest 05:42:50 INFO - Running setup.py install for mozversion 05:42:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:42:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:42:50 INFO - Cleaning up... 05:42:50 INFO - Return code: 0 05:42:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:50 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:50 INFO - 'HOME': '/Users/cltbld', 05:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:50 INFO - 'LOGNAME': 'cltbld', 05:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:50 INFO - 'MOZ_NO_REMOTE': '1', 05:42:50 INFO - 'NO_EM_RESTART': '1', 05:42:50 INFO - 'PAGER': '/bin/cat', 05:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:50 INFO - 'PWD': '/builds/slave/test', 05:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:50 INFO - 'SHELL': '/bin/bash', 05:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:50 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:50 INFO - 'USER': 'cltbld', 05:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:50 INFO - 'XPC_FLAGS': '0x0', 05:42:50 INFO - 'XPC_SERVICE_NAME': '0', 05:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2Iw3OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-jVamCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-KE_UTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4IAh9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gjGaAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eP8lPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GHjvKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Hzf9_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:42:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GtGHmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-yam_Pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4VZvft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Yvpxyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-iBk351-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WhZp0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-fzUI_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-UaoiOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7OkVqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:55 INFO - Downloading blessings-1.6.tar.gz 05:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:42:55 INFO - Installing collected packages: blessings 05:42:55 INFO - Running setup.py install for blessings 05:42:55 INFO - Successfully installed blessings 05:42:55 INFO - Cleaning up... 05:42:55 INFO - Return code: 0 05:42:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:42:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:55 INFO - Reading from file tmpfile_stdout 05:42:55 INFO - Current package versions: 05:42:55 INFO - blessings == 1.6 05:42:55 INFO - blobuploader == 1.2.4 05:42:55 INFO - docopt == 0.6.1 05:42:55 INFO - manifestparser == 1.1 05:42:55 INFO - mozInstall == 1.12 05:42:55 INFO - mozcrash == 0.17 05:42:55 INFO - mozdebug == 0.1 05:42:55 INFO - mozdevice == 0.48 05:42:55 INFO - mozfile == 1.2 05:42:55 INFO - mozhttpd == 0.7 05:42:55 INFO - mozinfo == 0.9 05:42:55 INFO - mozleak == 0.1 05:42:55 INFO - mozlog == 3.1 05:42:55 INFO - moznetwork == 0.27 05:42:55 INFO - mozprocess == 0.22 05:42:55 INFO - mozprofile == 0.28 05:42:55 INFO - mozrunner == 6.11 05:42:55 INFO - mozscreenshot == 0.1 05:42:55 INFO - mozsystemmonitor == 0.0 05:42:55 INFO - moztest == 0.7 05:42:55 INFO - mozversion == 1.4 05:42:55 INFO - psutil == 3.1.1 05:42:55 INFO - requests == 1.2.3 05:42:55 INFO - wsgiref == 0.1.2 05:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:55 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:55 INFO - 'HOME': '/Users/cltbld', 05:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:55 INFO - 'LOGNAME': 'cltbld', 05:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:55 INFO - 'MOZ_NO_REMOTE': '1', 05:42:55 INFO - 'NO_EM_RESTART': '1', 05:42:55 INFO - 'PAGER': '/bin/cat', 05:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:55 INFO - 'PWD': '/builds/slave/test', 05:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:55 INFO - 'SHELL': '/bin/bash', 05:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:55 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:55 INFO - 'USER': 'cltbld', 05:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:55 INFO - 'XPC_FLAGS': '0x0', 05:42:55 INFO - 'XPC_SERVICE_NAME': '0', 05:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:42:56 INFO - Cleaning up... 05:42:56 INFO - Return code: 0 05:42:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:56 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:56 INFO - 'HOME': '/Users/cltbld', 05:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:56 INFO - 'LOGNAME': 'cltbld', 05:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:56 INFO - 'MOZ_NO_REMOTE': '1', 05:42:56 INFO - 'NO_EM_RESTART': '1', 05:42:56 INFO - 'PAGER': '/bin/cat', 05:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:56 INFO - 'PWD': '/builds/slave/test', 05:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:56 INFO - 'SHELL': '/bin/bash', 05:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:56 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:56 INFO - 'USER': 'cltbld', 05:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:56 INFO - 'XPC_FLAGS': '0x0', 05:42:56 INFO - 'XPC_SERVICE_NAME': '0', 05:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:56 INFO - Downloading/unpacking jsonschema 05:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:42:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:42:59 INFO - warning: no files found matching '*.txt' 05:42:59 INFO - no previously-included directories found matching 'build' 05:42:59 INFO - no previously-included directories found matching 'dist' 05:42:59 INFO - no previously-included directories found matching '.git*' 05:42:59 INFO - Installing collected packages: jsonschema, functools32 05:42:59 INFO - Running setup.py install for functools32 05:42:59 INFO - warning: no files found matching '*.txt' 05:42:59 INFO - no previously-included directories found matching 'build' 05:42:59 INFO - no previously-included directories found matching 'dist' 05:42:59 INFO - no previously-included directories found matching '.git*' 05:42:59 INFO - Successfully installed jsonschema functools32 05:42:59 INFO - Cleaning up... 05:42:59 INFO - Return code: 0 05:42:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:59 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 0x10d613a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d61a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb1047ad30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, '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.9VyyCoKax3/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.bfyUhdFaGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:42:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:42:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:42:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:59 INFO - 'HOME': '/Users/cltbld', 05:42:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:59 INFO - 'LOGNAME': 'cltbld', 05:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:59 INFO - 'MOZ_NO_REMOTE': '1', 05:42:59 INFO - 'NO_EM_RESTART': '1', 05:42:59 INFO - 'PAGER': '/bin/cat', 05:42:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:59 INFO - 'PWD': '/builds/slave/test', 05:42:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:59 INFO - 'SHELL': '/bin/bash', 05:42:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:42:59 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:42:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:59 INFO - 'USER': 'cltbld', 05:42:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:59 INFO - 'XPC_FLAGS': '0x0', 05:42:59 INFO - 'XPC_SERVICE_NAME': '0', 05:42:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:59 INFO - Downloading/unpacking flake8 05:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:43:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:43:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:43:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:43:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:43:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:43:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:43:03 INFO - no previously-included directories found matching 'docs/_build' 05:43:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:43:04 INFO - Running setup.py install for pep8 05:43:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:43:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:43:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:43:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:43:04 INFO - no previously-included directories found matching 'docs/_build' 05:43:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:43:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:43:04 INFO - Cleaning up... 05:43:04 INFO - Return code: 0 05:43:04 INFO - Running post-action listener: _resource_record_post_action 05:43:04 INFO - Running post-action listener: _start_resource_monitoring 05:43:04 INFO - Starting resource monitoring. 05:43:04 INFO - ##### 05:43:04 INFO - ##### Running install step. 05:43:04 INFO - ##### 05:43:04 INFO - Running pre-action listener: _resource_record_pre_action 05:43:04 INFO - Running main action method: install 05:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:04 INFO - Reading from file tmpfile_stdout 05:43:04 INFO - Detecting whether we're running mozinstall >=1.0... 05:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:43:04 INFO - Reading from file tmpfile_stdout 05:43:04 INFO - Output received: 05:43:04 INFO - Usage: mozinstall [options] installer 05:43:04 INFO - Options: 05:43:04 INFO - -h, --help show this help message and exit 05:43:04 INFO - -d DEST, --destination=DEST 05:43:04 INFO - Directory to install application into. [default: 05:43:04 INFO - "/builds/slave/test"] 05:43:04 INFO - --app=APP Application being installed. [default: firefox] 05:43:04 INFO - mkdir: /builds/slave/test/build/application 05:43:04 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'] 05:43:04 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 05:43:36 INFO - Reading from file tmpfile_stdout 05:43:36 INFO - Output received: 05:43:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:43:36 INFO - Running post-action listener: _resource_record_post_action 05:43:36 INFO - ##### 05:43:36 INFO - ##### Running run-tests step. 05:43:36 INFO - ##### 05:43:36 INFO - Running pre-action listener: _resource_record_pre_action 05:43:36 INFO - Running main action method: run_tests 05:43:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:36 INFO - 2016-04-25 05:43:36.714 screenresolution[1637:7476] starting screenresolution argv=screenresolution get 05:43:36 INFO - 2016-04-25 05:43:36.717 screenresolution[1637:7476] Display 0: 1600x1200x32@60 05:43:36 INFO - 2016-04-25 05:43:36.722 screenresolution[1638:7479] starting screenresolution argv=screenresolution list 05:43:36 INFO - Available Modes on Display 0 05:43:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:36 INFO - Intel Iris: 05:43:36 INFO - Chipset Model: Intel Iris 05:43:36 INFO - Type: GPU 05:43:36 INFO - Bus: Built-In 05:43:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:36 INFO - Vendor: Intel (0x8086) 05:43:36 INFO - Device ID: 0x0a2e 05:43:36 INFO - Revision ID: 0x0009 05:43:36 INFO - Displays: 05:43:36 INFO - Display: 05:43:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:36 INFO - Display Serial Number: 1600x1200 60 05:43:36 INFO - Main Display: Yes 05:43:36 INFO - Mirror: Off 05:43:36 INFO - Online: Yes 05:43:36 INFO - Rotation: Supported 05:43:36 INFO - Return code: 0 05:43:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:43:36 INFO - Python 2.7.3 05:43:36 INFO - Return code: 0 05:43:36 INFO - grabbing minidump binary from tooltool 05:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:36 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 0x10d4b7d30>, '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 0x10d31bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d4aeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:43:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:43:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:43:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:43:37 INFO - Return code: 0 05:43:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:43:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:43:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:43:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:43:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:43:38 INFO - Return code: 0 05:43:38 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-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:43:38 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-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:43:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9VyyCoKax3/Render', 05:43:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:38 INFO - 'HOME': '/Users/cltbld', 05:43:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:38 INFO - 'LOGNAME': 'cltbld', 05:43:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:43:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:38 INFO - 'MOZ_NO_REMOTE': '1', 05:43:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:43:38 INFO - 'NO_EM_RESTART': '1', 05:43:38 INFO - 'PAGER': '/bin/cat', 05:43:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:38 INFO - 'PWD': '/builds/slave/test', 05:43:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:43:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:38 INFO - 'SHELL': '/bin/bash', 05:43:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bfyUhdFaGP/Listeners', 05:43:38 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 05:43:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:38 INFO - 'USER': 'cltbld', 05:43:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:38 INFO - 'XPC_FLAGS': '0x0', 05:43:38 INFO - 'XPC_SERVICE_NAME': '0', 05:43:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:38 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-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:43:38 INFO - mozversion application_buildid: 20160425042155 05:43:38 INFO - mozversion application_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 05:43:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:43:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:43:38 INFO - mozversion application_name: Firefox 05:43:38 INFO - mozversion application_remotingname: firefox-dev 05:43:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:43:38 INFO - mozversion application_vendor: Mozilla 05:43:38 INFO - mozversion application_version: 47.0a2 05:43:38 INFO - mozversion platform_buildid: 20160425042155 05:43:38 INFO - mozversion platform_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 05:43:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:43:38 INFO - mozversion platform_version: 47.0a2 05:43:38 INFO - using testdate: 1461588218 05:43:38 INFO - actual date: 1461588218 05:43:38 INFO - starting webserver on 'localhost:49270' 05:43:38 INFO - SUITE-START | Running 2 tests 05:43:38 INFO - TEST-START | dromaeo_css 05:43:38 INFO - operating with platform_type : mac_ 05:43:38 INFO - Initialising browser for dromaeo_css test... 05:43:38 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpbaLhaW/profile http://localhost:49270/getInfo.html) 05:43:40 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 05:43:40 INFO - PROCESS | 1656 | colorDepth:24 05:43:40 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 05:43:40 INFO - PROCESS | 1656 | __metrics 05:43:40 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:43:40 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:41 INFO - TEST-INFO | 1656: exit 0 05:43:41 INFO - Browser initialized. 05:43:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:43:41 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpbaLhaW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:53 INFO - PROCESS | 1658 | RSS: Main: 208113664 05:43:53 INFO - PROCESS | 1658 | 05:44:44 INFO - PROCESS | 1658 | 1461588284280 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:46:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:46:55 INFO - PROCESS | 1658 | RSS: Main: 319307776 05:46:55 INFO - PROCESS | 1658 | 05:49:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:49:58 INFO - PROCESS | 1658 | RSS: Main: 339779584 05:49:58 INFO - PROCESS | 1658 | 05:52:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:53:00 INFO - PROCESS | 1658 | RSS: Main: 345255936 05:53:00 INFO - PROCESS | 1658 | 05:56:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:56:03 INFO - PROCESS | 1658 | RSS: Main: 304181248 05:56:03 INFO - PROCESS | 1658 | 05:59:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:59:06 INFO - PROCESS | 1658 | RSS: Main: 306556928 05:59:06 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 06:02:09 INFO - PROCESS | 1658 | RSS: Main: 351621120 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | __start_tp_report 06:02:09 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:02:09 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:02:09 INFO - PROCESS | 1658 | |i|pagename|runs| 06:02:09 INFO - PROCESS | 1658 | |0;dojo.html;3412.5051867479215;3483.4252348362506;3511.7541772075933;3514.1917359007434;3561.8219089045556;5707.172459993299;5712.428757124286;5713.828585142447;5729.5702822288395;5743.626664266825;5721.656700597957;5757.165211044396;5760.596758226926;5770.365259821417;5774.451427114417;5117.2068329409;5137.820176293831;5158.896822063556;5167.586593072564;5239.318888544484;5219.634625576211;5228;5230.450802665722;5233.73831308435;5235.842924712265;5180.1452760294505;5214.322138122039;5214.530692237698;5227.73861306935;5228.320318358627;5222.477752224766;5253.6322457427805;5261.921071183897;5269.710165940872;5292.814751483679;4987.102321582114;5163.380394352702;5178.482151784829;5186.273921650972;5187.533122019036;4698.882527936795;5666.178404131378;5692.316921969349;5709.257796486471;5715.257016587859;6086.134871692894;6288.245410550718;6290.622562646256;6292.811215663537;6300.9684951574945;6221.222347206599;6245.063240513914;6263.217097862767;6331.810045698637;6332.556721029529;5790.826275211751;5825.0388685866355;5826.446487583673;5830.459898795415;5871.148683440877;6189.473894718909;6252.374762523711;6312.715927783261;6313.747450102036;6322.083297921776;6159.599626024384;6194.5044396448175;6205.317415084337;6209.565330426917;6213.596116252429;6300.30696623371;6303.6532990685955;6317.557770956081;6322.968385158045;6328.367163283635;4676.415448068991;6046.606970546901;6166.167567378437;6249.937510623205;6263.937360626426;4496.3255511673515;6207.131001659771;6234.438900498977;6251.812445626639;6275.937240627535;4922.90154196914;4923.261510773413;4930.285108659296;4937.210046392632;4945.950540494621;4351.73889566627;4394.406755088086;4465.553444655573;4476.6418686505;4488.730676159441;5662.6036177468;5663.207150998864;5674.801381951612;5691.03252447085;5699.116636921284;6287.308396076428;6299.685015749286;6300;6310.179676642099;6320.778772742932;5504.311961004874;5659.43405659439;5662.546996240291;5671.2060311556415;5681.0910254359105;5365.517103460707;5398.595105367113;5402.8919421611345;5402.945970540323;5438.755256013489;6332.208473940757;6340.587861788969;6349.55553111287;6349.619022858643;6353.841153971188;6166.352532983972;6219.0982307566055;6294.3076261612105;6300.401461861115;6305.337939516285;6318.241810982711;6321.557490975588;6331.240251169889;6339.112524246517;6347;6225.408586184305;6227.657478838707;6231.22109736283;6237.812865614039;6247.187865577446;6131.866048757656;6235.501159907287;6300.117983482408;6302.936970630235;6303.873922521616;6003.729832157651;6129.632222066691;6162.506999440124;6162.845928851814;6164.445199932027;6030.356625398331;6132.509399248139;6180.690965451619;6181.660008699565;6182.319944806158;5881.823545293648;5914.2311499504785;5978.521718262616;5983.9401605983385;6034;6123.448889599779;6149.692515374124;6155.47678447337;6156.846078848064;6160.106784516131;5809.128630705428;6014.248218972629;6035.366286540026;6041.335453100243;6053.4551890330085 06:02:09 INFO - PROCESS | 1658 | |1;ext.html;502.803906476474;597.9028482734183;626.7399029402798;639.8560323927117;673.2157037051835;31764.20589485264;32262.516062259056;32290.67709322906;32318.838405807997;32327.838360808164;42076.15847683052;42258.788706056504;42459.93850153748;42475;42519;9662.56518456671;9842.458664773434;9844.852327215098;9848.852267215998;9865.964073772222;9741.561629726644;9908.455034973074;9909.702708918743;9920.503974801268;9936.205103591732;9848.852267215998;9923.751906202331;9928.404295742243;9929;9933.106020458157;9967.501624918761;10029.398236105857;10055.849162262572;10063.044010819034;10072.496375181212;9811.411315321104;9929.90070099297;9944.502774861228;9959.90040099597;9961;42703.57296427027;43084.35373469401;43153.78423107896;43159;43176;3307.0409581221415;3462.480627905809;3496.1434448560267;3501.7723847950056;3732.570754363229;29896.505174741174;30014.849925750448;30240;30255.697443025507;30399;2710.6747190337087;2766.6679998400323;2799.930001749952;2821.0690472144347;2840.5029119903934;470810;474228.2577174253;474524;475165;476423.9999999965;68000.6599966997;68296.65851670809;68307.65846170737;68406;68446.65776671085;73527;73819;77757.61121194357;78988.21011789923;79116;18497.352592659463;18542.60930430223;18714.81285187158;18764.81235187658;18765.7185142223;14130;14212;14219.573412797632;14231.359588818528;14238;20314.593708125754;21920.232791852526;23132.884335578216;23449.06203751831;23450.358474906592;9511.668366428705;9759.560819762986;9781.364211326241;9786.902130978742;9834;975.6877799104218;985.9852102218473;1021.2442792333619;1027.4143738069379;1161.9070474362031;31146.844265778527;32686.509702354484;33819.323613527595;33920.66079339224;34057;1098.5880294889416;1159.3449700918954;1192.9940350298193;1207.0524638159004;1234.4013153620488;11790.882091178979;13074.215547067206;16551.751723724155;16790.916045419937;16899.662006759794;673921.6303918449;676992;677588;679460;679537;61883.690581546805;63643.68178159079;63705;63786;63816.36183638197;1330.3680751643085;1431.51328548292;1456.3955958277197;1456.7159403916135;1529.5640742388366;27603;30102.096937091923;30750.231244219074;31180;31293.904713334923;45855.77072114618;55357;56271.43728562826;56317;56317.71841140768;410853;413706.8629313789;414031;414667;414753;32700.345993080482;33964.49053264154;34048.319033618696;34090.65909340875;35349.469757953135;45267.094658106;45279.54720452885;45538.08923821571;47109.52890471051;47308.05383892372;38073.4288985671;38159.42760858534;38262;38623.42064869085;38800.80599596984;37472.250554988204;37889.81055094707;37999.810000949816;38410.80794596121;38606.2278754429;32365.838170808995;37205.62794372022;37602.811985939894;38468.23063538769;38789.80605097069;28688.303515238495;32446.72608484821;32680.50979235361;32962.83518582392;32989;25397.492050159264;25434.23697289084;25438.872805635852;25686;25797.484050319264 06:02:09 INFO - PROCESS | 1658 | |2;jquery.html;383.4574077680083;384.0724649970321;387.55431254057845;393.6142580271334;396.2847061054795;19850.602987940234;21183.788162118373;21346.93265336735;21638;21650.3504894853;17714.379996700118;21334.51930620585;21704.674429883566;21953.012114454847;22078.11687532497;5457.727113644321;5853.531717462614;6051.576389652726;6105.206323178003;6113.296970848365;6060.2121724175795;6101.816945491621;6105.358937311585;6128.172696685935;6131.724072416752;6033.145117713248;6060.060690592965;6061.242344706911;6081.847953801146;6132.172156758847;5923.200367950315;6036.577439579231;6053.969730151365;6089.995150800112;6107.389261073879;4496.325551167319;5237.79048838046;5274.947250527523;5275.920861187085;5282.366116066097;26592.73407265922;26664.466710665678;26900.73099269002;26918.865405672845;26983.460330793274;2178.6296329624;2519.8992040318367;2525.722170561237;2608.243609353285;2977.7980776868617;15210.543683689508;15236.542903712907;15261.542153735405;15339.539813805604;15371;1742.6688929103557;1748.4317596781098;1762.7620271263343;1763.7795275590552;1840.7514985476923;102700;104840.47579762129;105464;106624;107186.92813071847;10725.08836748885;10839.783204335869;10851.728706782236;10935.125189984783;11027.117830573536;13453.461861525626;13537.45850166002;13557.728845423037;13567.457301708018;13588.728225435436;8749.518776467356;8913.73258802224;8920.420172688755;8946;9038.683646072355;9121.407108538056;9131.863022054675;9144.497052662167;9188.356815022884;9240.537973101318;6274.937250627435;6342.841428964312;6346.619202847844;6359.046143078483;6407.871842563122;1818.9363372281907;1832.7800663920416;1847.381127322362;1866.0576408913412;1868.588910439701;1033.2088643155007;1036.6838114375037;1051.5951358726945;1053.4679986606868;1120.6357933671588;17413.216405261795;17559;21218.893905530374;22161.66757498639;22218.00018999149;1020.6478764826271;1042.3797840285017;1084.511969613677;1087.7280679830042;1094.7974624694457;11630.70923226926;11770.94061534466;11840.27501788416;11964.40177991097;11986.400679965967;263196;263736.362636375;264521;264670.6766466194;264684;102359.95280094506;103513.48243258735;108128.45935770271;109620;112157.43921280341;1049.7480604654827;1082.9025387714985;1090.2640717515644;1100.2573263047411;1102.8014957307762;15382.538523844303;18166.818331816514;18777.812221878154;19103.140358683613;19120.13959371804;50277;50401.243981341046;51005.46983590498;52124.739376302874;52753.73623132013;65760.34239657542;69116;70197.29802701907;70271.9459208102;70334.64832675803;8296.377771667028;8412.831743365221;8425.115362886809;8521.957390213009;8763.036066032604;10888.67333979982;13240.80138797938;13713.108647937852;13955.232462214675;13975;5692.914606280992;8921.509316987627;11803.114766392726;11819.409029548317;11854.762904741685;9591.328606997444;11654.592089276835;11752.23610465317;11932.224405413968;12074.517019319128;11753.764924701629;11875.346855923008;12094.057136021995;12200.633980980585;12208.938955305166;8160.8775868360835;8292.175721341295;8578.571071446278;8600.827983440422;8634.827303454022;11612.88387116118;11954;12026.398680066137;12154.331511766937;12186 06:02:09 INFO - PROCESS | 1658 | |3;mootools.html;1508.8340282568922;1611.7340638794597;1639.8524132828043;1937.70934359846;2054.3118055451423;8353.582320883954;8908.8663670045;8928.776780580489;8939.642414303427;8980.550972451383;8798.340124490673;9076.273898088137;9095.499747513884;9095.681651142211;9098.090190980898;2251.4596496840804;2503.662005629235;2543.0844895837486;2549.7067837198774;2572.87135643218;2544.821862469628;2549.6430499730054;2549.7577730115704;2552.1067626330823;2554.6806649168852;2477.764612361832;2545.5418024755536;2551.2473820223076;2552.2981180175507;2552.540542702313;2494.4761600064007;2543.5675935090985;2545.9872700636565;2549.4518678484123;2550.2604244768995;2508.072013355052;2529.9241022769347;2536.467341858212;2559.9360015999564;2561.1292160665275;9057.954710226473;9124.133207345292;9124.498152601604;9135.771605709844;9156.404833685789;5572.080606699889;5671.06427439472;5677.659340439587;5730.627509211888;5731.541476681856;3612.24142929985;3699.093722038091;3708.9258214835822;3728;3736.065983504124;1065.8454524093963;1076.5478499030426;1081.5133190064423;1097.358045543358;1109.9056580190693;12552.81170782439;12577.559785407559;12578;12591.433385497674;12594.559190428383;4967.950320496749;4981.178105612606;5037.924431133536;5046.26829109777;5068.366454193226;10326.948365258277;10367.274290799722;10368.222383321281;10387.065164135263;10392.636257730943;5562.137868630369;5707.0583353747;5711.514521265739;5715.199872017921;5723.942760572341;3936.9803150984635;4620.075984803053;4755.0489902019735;5062.721550314768;5121.795128194905;3945.3095708251517;4040.5151381834366;4049.7367671101283;4058.411530328086;4097.385392191136;1207.6353720296186;1290.9096363254482;1344.764666183414;1347.2186132043391;1352.1616597709287;1237.0907383073434;1361.884239839605;1411.6400317918865;1421.530894804712;1444.2778610694652;8593.828123437495;8621.827563448696;8633;8645.092265312178;8651.95674021626;1295.501232025658;1351.8445378781369;1359.1437394441439;1366.3373263966969;1376.6165004170743;6327.050942358591;6376.553641245068;6394.104825324458;6396.136521569529;6410.423061924449;10692.625758098584;10786.94606526978;10788.40663763485;10816.026557609854;10949.178811589163;10378.636747713794;10530.789384212272;10659.467026648792;10674.573017079385;10675.519601617945;8322.334213262924;8458.281046111026;8470.745877623682;8482.236598706146;8489.36329775257;8190.344772418312;8240.052393974769;8319.417640765088;8367.832643346977;8427.578621069044;9072.410293330913;9785.315027947976;9852.556634951301;9903.653372132228;10036.749081272734;9884.654037108667;10189.84715229287;10301.536430860479;10348.172146228133;10397.168226541708;6654.50091243149;6839.62382068991;7619.047619047619;7619.504732192389;7757.689692412466;8385.83228335442;8396.916030839613;8458.830823383374;8480.618372173143;8493.745187644381;7229.457790665616;7565.4325925556905;7567.545947242963;7727.072751269883;7853.293203611703;7556.206598307099;7565.962170189114;7576.4317676173405;7612.1246056701975;7616.543007419573;7954.443188976716;7987;8038.959805200937;8043.1554686757045;8050.27547520726;7212.567245965259;7223.783286501413;7271.963640181765;7304.671289792079;7322.2677732228485;6218.19163508741;7344.889826652711;7382.446316526175;7426.554406735613;7432.2567743225245 06:02:09 INFO - PROCESS | 1658 | |4;prototype.html;337.25298463902453;342.21291030629544;344.85860797073207;347.0265904138891;349.9282647057352;6498.870022599551;6600.471962243021;6606.10817539632;6664.600123992564;6715.8656826863435;6548.607083574989;6555.41001309882;6567.244766851813;6582.572132811364;6595.1096601958725;4647.628189744829;4701.9059618807605;4735.550122738335;4833.7824797884;4898.363212782342;4235.004773878136;4753.405824271966;4783.928241076386;4784.712917224961;4810.25441056635;4768.785404656782;4800.735959522225;4816.686915350509;4829.7585120744;4840.830570930018;4613.723176609397;4698.882527936795;4790.688605240648;4814.614830813526;4825.855224343275;4146.875593732193;4164.8125834337525;4169.312063509517;4174.645155161815;4180.728252663567;6242.563020588561;6322;6325.367463253684;6340;6346.52401069917;5737.483626473638;5758.510526605243;5873.177755114288;5876;5882.3823498532465;5692.544596432275;6723.092382528345;6970.756023539203;6990.021397004423;6994.2656021117555;4710;4750.596199323062;4763.261694437368;4768.666193366465;4775.546323099335;6222.81331560054;6223.719932603044;7202.819929501752;7371.078615173157;7401.370883474911;4830.082284365959;4851.331767527846;4853.514648535189;4858.975705121452;4860.343853579721;4049.2913740095955;4915.139850526144;4940.1601727706375;4943;4943.629227807929;5771.278590176228;5773.971130144322;5776.191333213354;5789.536837053026;5792.4497172768515;6778.288279730656;7027.156741190987;7044.823879402953;7047.89428158578;7079.89380159298;1680.5210515003166;1697.4228762220905;1708.9743653845203;1709.9316027359016;1711.5721069732567;1033.9741506462399;1067.6156583629886;1107.8449017137607;1120.78705046041;1133.820826340613;6365.427111559982;6544.901826472606;6552.180977377828;6554.573952693059;6559.344065593403;7338.192798792129;7369.852602948018;7374.668139933716;7379.2251813558805;7397.741079062207;6531.183602049744;6531.771388001397;6538.313477084837;6550.6069635822005;6553;3737.4767532545466;3838.405047217686;3888.51393575803;3897.785621790772;3898.7270891037924;7420.888686669704;7561.7731468056045;7674.386049116058;7693.076830780342;7729.961350193213;6426.3573642635365;6879.208890977501;6883.760923033802;7556.357709594746;7557.584332861636;7621.657025433758;7636.3890888728765;7656.081270247606;7661.731839385595;7674.083848137672;6964.965175174092;7308.634568271459;7328.142024610513;7346.412287017132;7350.191478937206;7110.715571377087;7132.215456299907;7136;7173.820654483679;7231.204567497466;7636.80907977305;7645.464817462725;7652.61736913141;7652.885206721792;7656.272654097852;4570.314452831969;4603.263477843529;4605.93091103627;4608.792604332746;4630.027694184124;6981.895271570829;7193.100862392201;7193.136823581203;7225.385842203472;7239.131304243525;6976.965115174392;6993.0908981832035;6995.685194166376;7000.054992575937;7001.789946301619;6936.237013928565;6963.268856770169;6973.511854170362;6980.511364204457;6999.09011828459;6795.694193761194;6814.557053791487;6824.556403833735;6833.41915937131;6850.794476165723;5641.717914104361;6953.965230173817;6954.547954382949;6971.198312193959;6973.3375329343635;6582.76960306387;6714;6781;6827.48793840454;6928.1339832520935 06:02:09 INFO - PROCESS | 1658 | |5;yui.html;669.6752075243509;699.6257002503659;726.7311094894889;786.2844811221789;797.4736673795293;15331.15678637676;15497.53507394777;15526.223688815571;15531.145786981711;15533.068015919038;14403.063800852937;14408.639784005405;14480.927595362034;14486.49297274593;14492.420303187886;784.0591290451453;791.9287264146226;793.2067932067932;948.09456968595;970.2141265574871;784.7331907151568;788.597815114293;952.8999455057224;984.3798407003578;985.9753506162332;1017.6234793126516;1025.6102680981217;1025.9487025648725;1028.408665017614;1030.2376241581214;1023.5291765787747;1025.1542477456053;1025.3130402630181;1026.6252817721515;1026.6355443817413;613.2610204703367;621.2389822467486;621.9253689557237;747.4170147285072;773.3503856760292;15190.848091519054;15252.0086194397;15278.694426111526;15301.005434646713;15338.769918451233;13009;13045.02162337819;13072.888804451633;13229.933850330783;13575.660608484768;10416.739581510446;10522.947385263102;10724.249302548824;10807.513661885234;10952.233343665946;3596.021801872251;3617.4935509028755;3621.6016238213774;3675.2833197526493;3881.126746482047;67363;86544.56727716383;88548.22903541893;88618;88671.55664221702;9277.90722092784;9715.319927605;9814.705558833246;9961.601535938626;10255.641052563275;19636.25092487053;20892.895535522224;22187.223447179593;22604.773952260595;22696.205632802772;7539.0576177977755;7540.8491830164185;7655.770326890202;7671.769846904602;7693.4999225051315;4000.979995100064;4094.4267802507675;4101.712880098424;4105.261053010487;4107.54816970133;3674.706023518112;3706.8694048315524;3718;3719.367707489733;3732.8506859725308;684.5995092870627;689.4346635758629;689.648279377521;693.729445516249;704.2534912992245;502.7712390862149;518.3753576939779;581.9854503637443;629.1003864473755;631.157404864511;8350.206730360605;8500.192481714228;8705.869411958825;8722.825543489222;8855.468671879707;574.9741261643236;585.9179714839926;614.6619359352428;628.8899442597527;632.8481164520574;3417.3165366926714;4004.619561141687;4009.3384591542067;4019.4975628046495;4023.0344717267644;100105;101322;102827;102991;104294;84844;85229;85414.86462838479;86302.13697862941;87817;72627.63686181535;73998.89001664979;74229;75042;76391.61804191055;7012.333828286304;7858.135605083322;7994.440389172934;8136.06435259929;8253.83492330138;49535.25697114474;52118.95762084813;52408;52670.47329526656;52715.20927186172;71719.56560868649;75022.2497775037;75581.24418755963;75773;76496.2350376489;10201.846972295272;10214.336068155546;10371.429571373646;10485.790284194121;10537.315074520438;10909.887178568431;11169.106471482424;11298.49156787963;11408.828867566826;11906.464209110783;10548.789024219626;10611.257211995086;10668.466576671291;10832;10883.67348979532;9619.663311784054;11395.886041139482;11453.59912403062;11508.424578771195;11773.293602383885;11232.269902456314;11317.319697369167;11402.372869491926;11504.712382190512;11671.183017188716;9645.27660425485;9692;9905.877804707758;9934.013197360413;10252.43611601369;8659.567021649018;9743.074407931235;9964.750881228027;10017.849732253877;10167.542460589439 06:02:09 INFO - PROCESS | 1658 | __end_tp_report 06:02:09 INFO - PROCESS | 1658 | __start_cc_report 06:02:09 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1017 06:02:09 INFO - PROCESS | 1658 | __end_cc_report 06:02:09 INFO - PROCESS | 1658 | __startTimestamp1461589329563__endTimestamp 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:02:09 INFO - PROCESS | 1658 | Number of tests: 6 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5744.68 Median:6003.73 stddev:651.88 (10.9%) stddev-sans-first:627.68 06:02:09 INFO - PROCESS | 1658 | Values: 3412.5 3483.4 3511.8 3514.2 3561.8 5707.2 5712.4 5713.8 5729.6 5743.6 5721.7 5757.2 5760.6 5770.4 5774.5 5117.2 5137.8 5158.9 5167.6 5239.3 5219.6 5228.0 5230.5 5233.7 5235.8 5180.1 5214.3 5214.5 5227.7 5228.3 5222.5 5253.6 5261.9 5269.7 5292.8 4987.1 5163.4 5178.5 5186.3 5187.5 4698.9 5666.2 5692.3 5709.3 5715.3 6086.1 6288.2 6290.6 6292.8 6301.0 6221.2 6245.1 6263.2 6331.8 6332.6 5790.8 5825.0 5826.4 5830.5 5871.1 6189.5 6252.4 6312.7 6313.7 6322.1 6159.6 6194.5 6205.3 6209.6 6213.6 6300.3 6303.7 6317.6 6323.0 6328.4 4676.4 6046.6 6166.2 6249.9 6263.9 4496.3 6207.1 6234.4 6251.8 6275.9 4922.9 4923.3 4930.3 4937.2 4946.0 4351.7 4394.4 4465.6 4476.6 4488.7 5662.6 5663.2 5674.8 5691.0 5699.1 6287.3 6299.7 6300.0 6310.2 6320.8 5504.3 5659.4 5662.5 5671.2 5681.1 5365.5 5398.6 5402.9 5402.9 5438.8 6332.2 6340.6 6349.6 6349.6 6353.8 6166.4 6219.1 6294.3 6300.4 6305.3 6318.2 6321.6 6331.2 6339.1 6347.0 6225.4 6227.7 6231.2 6237.8 6247.2 6131.9 6235.5 6300.1 6302.9 6303.9 6003.7 6129.6 6162.5 6162.8 6164.4 6030.4 6132.5 6180.7 6181.7 6182.3 5881.8 5914.2 5978.5 5983.9 6034.0 6123.4 6149.7 6155.5 6156.8 6160.1 5809.1 6014.2 6035.4 6041.3 6053.5 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:67497.41 Median:38336.40 stddev:142524.42 (371.8%) stddev-sans-first:142835.48 06:02:09 INFO - PROCESS | 1658 | Values: 502.8 597.9 626.7 639.9 673.2 31764.2 32262.5 32290.7 32318.8 32327.8 42076.2 42258.8 42459.9 42475.0 42519.0 9662.6 9842.5 9844.9 9848.9 9866.0 9741.6 9908.5 9909.7 9920.5 9936.2 9848.9 9923.8 9928.4 9929.0 9933.1 9967.5 10029.4 10055.8 10063.0 10072.5 9811.4 9929.9 9944.5 9959.9 9961.0 42703.6 43084.4 43153.8 43159.0 43176.0 3307.0 3462.5 3496.1 3501.8 3732.6 29896.5 30014.8 30240.0 30255.7 30399.0 2710.7 2766.7 2799.9 2821.1 2840.5 470810.0 474228.3 474524.0 475165.0 476424.0 68000.7 68296.7 68307.7 68406.0 68446.7 73527.0 73819.0 77757.6 78988.2 79116.0 18497.4 18542.6 18714.8 18764.8 18765.7 14130.0 14212.0 14219.6 14231.4 14238.0 20314.6 21920.2 23132.9 23449.1 23450.4 9511.7 9759.6 9781.4 9786.9 9834.0 975.7 986.0 1021.2 1027.4 1161.9 31146.8 32686.5 33819.3 33920.7 34057.0 1098.6 1159.3 1193.0 1207.1 1234.4 11790.9 13074.2 16551.8 16790.9 16899.7 673921.6 676992.0 677588.0 679460.0 679537.0 61883.7 63643.7 63705.0 63786.0 63816.4 1330.4 1431.5 1456.4 1456.7 1529.6 27603.0 30102.1 30750.2 31180.0 31293.9 45855.8 55357.0 56271.4 56317.0 56317.7 410853.0 413706.9 414031.0 414667.0 414753.0 32700.3 33964.5 34048.3 34090.7 35349.5 45267.1 45279.5 45538.1 47109.5 47308.1 38073.4 38159.4 38262.0 38623.4 38800.8 37472.3 37889.8 37999.8 38410.8 38606.2 32365.8 37205.6 37602.8 38468.2 38789.8 28688.3 32446.7 32680.5 32962.8 32989.0 25397.5 25434.2 25438.9 25686.0 25797.5 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:24854.89 Median:21644.18 stddev:47805.84 (220.9%) stddev-sans-first:47904.64 06:02:09 INFO - PROCESS | 1658 | Values: 383.5 384.1 387.6 393.6 396.3 19850.6 21183.8 21346.9 21638.0 21650.4 17714.4 21334.5 21704.7 21953.0 22078.1 5457.7 5853.5 6051.6 6105.2 6113.3 6060.2 6101.8 6105.4 6128.2 6131.7 6033.1 6060.1 6061.2 6081.8 6132.2 5923.2 6036.6 6054.0 6090.0 6107.4 4496.3 5237.8 5274.9 5275.9 5282.4 26592.7 26664.5 26900.7 26918.9 26983.5 2178.6 2519.9 2525.7 2608.2 2977.8 15210.5 15236.5 15261.5 15339.5 15371.0 1742.7 1748.4 1762.8 1763.8 1840.8 102700.0 104840.5 105464.0 106624.0 107186.9 10725.1 10839.8 10851.7 10935.1 11027.1 13453.5 13537.5 13557.7 13567.5 13588.7 8749.5 8913.7 8920.4 8946.0 9038.7 9121.4 9131.9 9144.5 9188.4 9240.5 6274.9 6342.8 6346.6 6359.0 6407.9 1818.9 1832.8 1847.4 1866.1 1868.6 1033.2 1036.7 1051.6 1053.5 1120.6 17413.2 17559.0 21218.9 22161.7 22218.0 1020.6 1042.4 1084.5 1087.7 1094.8 11630.7 11770.9 11840.3 11964.4 11986.4 263196.0 263736.4 264521.0 264670.7 264684.0 102360.0 103513.5 108128.5 109620.0 112157.4 1049.7 1082.9 1090.3 1100.3 1102.8 15382.5 18166.8 18777.8 19103.1 19120.1 50277.0 50401.2 51005.5 52124.7 52753.7 65760.3 69116.0 70197.3 70271.9 70334.6 8296.4 8412.8 8425.1 8522.0 8763.0 10888.7 13240.8 13713.1 13955.2 13975.0 5692.9 8921.5 11803.1 11819.4 11854.8 9591.3 11654.6 11752.2 11932.2 12074.5 11753.8 11875.3 12094.1 12200.6 12208.9 8160.9 8292.2 8578.6 8600.8 8634.8 11612.9 11954.0 12026.4 12154.3 12186.0 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:6242.23 Median:5042.10 stddev:3264.65 (64.7%) stddev-sans-first:3254.42 06:02:09 INFO - PROCESS | 1658 | Values: 1508.8 1611.7 1639.9 1937.7 2054.3 8353.6 8908.9 8928.8 8939.6 8980.6 8798.3 9076.3 9095.5 9095.7 9098.1 2251.5 2503.7 2543.1 2549.7 2572.9 2544.8 2549.6 2549.8 2552.1 2554.7 2477.8 2545.5 2551.2 2552.3 2552.5 2494.5 2543.6 2546.0 2549.5 2550.3 2508.1 2529.9 2536.5 2559.9 2561.1 9058.0 9124.1 9124.5 9135.8 9156.4 5572.1 5671.1 5677.7 5730.6 5731.5 3612.2 3699.1 3708.9 3728.0 3736.1 1065.8 1076.5 1081.5 1097.4 1109.9 12552.8 12577.6 12578.0 12591.4 12594.6 4968.0 4981.2 5037.9 5046.3 5068.4 10326.9 10367.3 10368.2 10387.1 10392.6 5562.1 5707.1 5711.5 5715.2 5723.9 3937.0 4620.1 4755.0 5062.7 5121.8 3945.3 4040.5 4049.7 4058.4 4097.4 1207.6 1290.9 1344.8 1347.2 1352.2 1237.1 1361.9 1411.6 1421.5 1444.3 8593.8 8621.8 8633.0 8645.1 8652.0 1295.5 1351.8 1359.1 1366.3 1376.6 6327.1 6376.6 6394.1 6396.1 6410.4 10692.6 10786.9 10788.4 10816.0 10949.2 10378.6 10530.8 10659.5 10674.6 10675.5 8322.3 8458.3 8470.7 8482.2 8489.4 8190.3 8240.1 8319.4 8367.8 8427.6 9072.4 9785.3 9852.6 9903.7 10036.7 9884.7 10189.8 10301.5 10348.2 10397.2 6654.5 6839.6 7619.0 7619.5 7757.7 8385.8 8396.9 8458.8 8480.6 8493.7 7229.5 7565.4 7567.5 7727.1 7853.3 7556.2 7566.0 7576.4 7612.1 7616.5 7954.4 7987.0 8039.0 8043.2 8050.3 7212.6 7223.8 7272.0 7304.7 7322.3 6218.2 7344.9 7382.4 7426.6 7432.3 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:5750.67 Median:6546.75 stddev:1807.13 (27.6%) stddev-sans-first:1765.93 06:02:09 INFO - PROCESS | 1658 | Values: 337.3 342.2 344.9 347.0 349.9 6498.9 6600.5 6606.1 6664.6 6715.9 6548.6 6555.4 6567.2 6582.6 6595.1 4647.6 4701.9 4735.6 4833.8 4898.4 4235.0 4753.4 4783.9 4784.7 4810.3 4768.8 4800.7 4816.7 4829.8 4840.8 4613.7 4698.9 4790.7 4814.6 4825.9 4146.9 4164.8 4169.3 4174.6 4180.7 6242.6 6322.0 6325.4 6340.0 6346.5 5737.5 5758.5 5873.2 5876.0 5882.4 5692.5 6723.1 6970.8 6990.0 6994.3 4710.0 4750.6 4763.3 4768.7 4775.5 6222.8 6223.7 7202.8 7371.1 7401.4 4830.1 4851.3 4853.5 4859.0 4860.3 4049.3 4915.1 4940.2 4943.0 4943.6 5771.3 5774.0 5776.2 5789.5 5792.4 6778.3 7027.2 7044.8 7047.9 7079.9 1680.5 1697.4 1709.0 1709.9 1711.6 1034.0 1067.6 1107.8 1120.8 1133.8 6365.4 6544.9 6552.2 6554.6 6559.3 7338.2 7369.9 7374.7 7379.2 7397.7 6531.2 6531.8 6538.3 6550.6 6553.0 3737.5 3838.4 3888.5 3897.8 3898.7 7420.9 7561.8 7674.4 7693.1 7730.0 6426.4 6879.2 6883.8 7556.4 7557.6 7621.7 7636.4 7656.1 7661.7 7674.1 6965.0 7308.6 7328.1 7346.4 7350.2 7110.7 7132.2 7136.0 7173.8 7231.2 7636.8 7645.5 7652.6 7652.9 7656.3 4570.3 4603.3 4605.9 4608.8 4630.0 6981.9 7193.1 7193.1 7225.4 7239.1 6977.0 6993.1 6995.7 7000.1 7001.8 6936.2 6963.3 6973.5 6980.5 6999.1 6795.7 6814.6 6824.6 6833.4 6850.8 5641.7 6954.0 6954.5 6971.2 6973.3 6582.8 6714.0 6781.0 6827.5 6928.1 06:02:09 INFO - PROCESS | 1658 | 06:02:09 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:19307.53 Median:4103.49 stddev:27964.86 (681.5%) stddev-sans-first:28008.29 06:02:09 INFO - PROCESS | 1658 | Values: 669.7 699.6 726.7 786.3 797.5 15331.2 15497.5 15526.2 15531.1 15533.1 14403.1 14408.6 14480.9 14486.5 14492.4 784.1 791.9 793.2 948.1 970.2 784.7 788.6 952.9 984.4 986.0 1017.6 1025.6 1025.9 1028.4 1030.2 1023.5 1025.2 1025.3 1026.6 1026.6 613.3 621.2 621.9 747.4 773.4 15190.8 15252.0 15278.7 15301.0 15338.8 13009.0 13045.0 13072.9 13229.9 13575.7 10416.7 10522.9 10724.2 10807.5 10952.2 3596.0 3617.5 3621.6 3675.3 3881.1 67363.0 86544.6 88548.2 88618.0 88671.6 9277.9 9715.3 9814.7 9961.6 10255.6 19636.3 20892.9 22187.2 22604.8 22696.2 7539.1 7540.8 7655.8 7671.8 7693.5 4001.0 4094.4 4101.7 4105.3 4107.5 3674.7 3706.9 3718.0 3719.4 3732.9 684.6 689.4 689.6 693.7 704.3 502.8 518.4 582.0 629.1 631.2 8350.2 8500.2 8705.9 8722.8 8855.5 575.0 585.9 614.7 628.9 632.8 3417.3 4004.6 4009.3 4019.5 4023.0 100105.0 101322.0 102827.0 102991.0 104294.0 84844.0 85229.0 85414.9 86302.1 87817.0 72627.6 73998.9 74229.0 75042.0 76391.6 7012.3 7858.1 7994.4 8136.1 8253.8 49535.3 52119.0 52408.0 52670.5 52715.2 71719.6 75022.2 75581.2 75773.0 76496.2 10201.8 10214.3 10371.4 10485.8 10537.3 10909.9 11169.1 11298.5 11408.8 11906.5 10548.8 10611.3 10668.5 10832.0 10883.7 9619.7 11395.9 11453.6 11508.4 11773.3 11232.3 11317.3 11402.4 11504.7 11671.2 9645.3 9692.0 9905.9 9934.0 10252.4 8659.6 9743.1 9964.8 10017.8 10167.5 06:02:09 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:02:09 INFO - PROCESS | 1658 | 06:02:10 INFO - TEST-INFO | 1658: exit 0 06:02:10 INFO - TEST-OK | dromaeo_css | took 1111665ms 06:02:10 INFO - TEST-START | kraken 06:02:10 INFO - operating with platform_type : mac_ 06:02:10 INFO - Initialising browser for kraken test... 06:02:10 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpFm5ueG/profile http://localhost:49270/getInfo.html) 06:02:12 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 06:02:12 INFO - PROCESS | 1669 | colorDepth:24 06:02:12 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 06:02:12 INFO - PROCESS | 1669 | __metrics 06:02:12 INFO - PROCESS | 1669 | 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" 06:02:12 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:02:12 INFO - TEST-INFO | 1669: exit 0 06:02:12 INFO - Browser initialized. 06:02:12 INFO - Running cycle 1/1 for kraken test... 06:02:12 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpFm5ueG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:02:24 INFO - PROCESS | 1671 | RSS: Main: 207994880 06:02:24 INFO - PROCESS | 1671 | 06:02:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 06:02:27 INFO - PROCESS | 1671 | RSS: Main: 263213056 06:02:27 INFO - PROCESS | 1671 | 06:02:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 06:02:31 INFO - PROCESS | 1671 | RSS: Main: 273379328 06:02:31 INFO - PROCESS | 1671 | 06:02:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 06:02:34 INFO - PROCESS | 1671 | RSS: Main: 263815168 06:02:34 INFO - PROCESS | 1671 | 06:02:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 06:02:37 INFO - PROCESS | 1671 | RSS: Main: 265478144 06:02:37 INFO - PROCESS | 1671 | 06:02:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:40 INFO - PROCESS | 1671 | RSS: Main: 265048064 06:02:40 INFO - PROCESS | 1671 | 06:02:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 06:02:43 INFO - PROCESS | 1671 | RSS: Main: 265371648 06:02:43 INFO - PROCESS | 1671 | 06:02:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 06:02:46 INFO - PROCESS | 1671 | RSS: Main: 265007104 06:02:46 INFO - PROCESS | 1671 | 06:02:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 06:02:50 INFO - PROCESS | 1671 | RSS: Main: 265535488 06:02:50 INFO - PROCESS | 1671 | 06:02:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:52 INFO - PROCESS | 1671 | RSS: Main: 298266624 06:02:52 INFO - PROCESS | 1671 | 06:02:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:54 INFO - PROCESS | 1671 | RSS: Main: 300232704 06:02:54 INFO - PROCESS | 1671 | 06:02:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:57 INFO - PROCESS | 1671 | RSS: Main: 306393088 06:02:57 INFO - PROCESS | 1671 | 06:02:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:03:00 INFO - PROCESS | 1671 | RSS: Main: 307589120 06:03:00 INFO - PROCESS | 1671 | 06:03:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:03:03 INFO - PROCESS | 1671 | RSS: Main: 308027392 06:03:03 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 06:03:05 INFO - PROCESS | 1671 | RSS: Main: 370823168 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | __start_tp_report 06:03:05 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 06:03:05 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 06:03:05 INFO - PROCESS | 1671 | |i|pagename|runs| 06:03:05 INFO - PROCESS | 1671 | |0;ai-astar;111;109;109;108;109;109;120;112;110;108 06:03:05 INFO - PROCESS | 1671 | |1;audio-beat-detection;167;125;143;165;145;173;165;117;156;118 06:03:05 INFO - PROCESS | 1671 | |2;audio-dft;187;181;186;183;184;184;183;184;184;187 06:03:05 INFO - PROCESS | 1671 | |3;audio-fft;73;71;75;112;55;57;60;57;115;56 06:03:05 INFO - PROCESS | 1671 | |4;audio-oscillator;92;95;128;80;78;83;117;75;79;118 06:03:05 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;109;130;87;84;87;83;89;84;86;89 06:03:05 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;92;92;92;92;92;93;93;91;92 06:03:05 INFO - PROCESS | 1671 | |7;imaging-desaturate;87;85;85;84;85;85;87;84;96;132 06:03:05 INFO - PROCESS | 1671 | |8;json-parse-financial;55;57;58;54;57;56;56;66;54;52 06:03:05 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;61;72;68;76;72;67;64;67;66 06:03:05 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;79;81;80;81;79;79;80;80;80;85 06:03:05 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;115;123;134;128;129;118;117;116;131;138 06:03:05 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;123;134;135;132;134;132;159;171;172;131 06:03:05 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;65;65;65;65;63;64;64;65;64 06:03:05 INFO - PROCESS | 1671 | __end_tp_report 06:03:05 INFO - PROCESS | 1671 | __start_cc_report 06:03:05 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1279 06:03:05 INFO - PROCESS | 1671 | __end_cc_report 06:03:05 INFO - PROCESS | 1671 | __startTimestamp1461589385671__endTimestamp 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | ------- Summary: start ------- 06:03:05 INFO - PROCESS | 1671 | Number of tests: 14 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:110.50 Median:109.50 stddev:3.57 (3.3%) stddev-sans-first:3.78 06:03:05 INFO - PROCESS | 1671 | Values: 111.0 109.0 109.0 108.0 109.0 109.0 120.0 112.0 110.0 108.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:160.50 stddev:21.21 (13.2%) stddev-sans-first:21.28 06:03:05 INFO - PROCESS | 1671 | Values: 167.0 125.0 143.0 165.0 145.0 173.0 165.0 117.0 156.0 118.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:1.89 (1.0%) stddev-sans-first:1.73 06:03:05 INFO - PROCESS | 1671 | Values: 187.0 181.0 186.0 183.0 184.0 184.0 183.0 184.0 184.0 187.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.10 Median:58.50 stddev:22.58 (38.6%) stddev-sans-first:23.94 06:03:05 INFO - PROCESS | 1671 | Values: 73.0 71.0 75.0 112.0 55.0 57.0 60.0 57.0 115.0 56.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:79.50 stddev:19.50 (24.5%) stddev-sans-first:20.66 06:03:05 INFO - PROCESS | 1671 | Values: 92.0 95.0 128.0 80.0 78.0 83.0 117.0 75.0 79.0 118.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:86.50 stddev:15.05 (17.4%) stddev-sans-first:14.78 06:03:05 INFO - PROCESS | 1671 | Values: 109.0 130.0 87.0 84.0 87.0 83.0 89.0 84.0 86.0 89.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 06:03:05 INFO - PROCESS | 1671 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 93.0 93.0 91.0 92.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:85.00 stddev:14.83 (17.4%) stddev-sans-first:15.66 06:03:05 INFO - PROCESS | 1671 | Values: 87.0 85.0 85.0 84.0 85.0 85.0 87.0 84.0 96.0 132.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:3.78 (6.7%) stddev-sans-first:3.97 06:03:05 INFO - PROCESS | 1671 | Values: 55.0 57.0 58.0 54.0 57.0 56.0 56.0 66.0 54.0 52.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:67.50 stddev:4.50 (6.7%) stddev-sans-first:4.57 06:03:05 INFO - PROCESS | 1671 | Values: 64.0 61.0 72.0 68.0 76.0 72.0 67.0 64.0 67.0 66.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.00 stddev:1.78 (2.2%) stddev-sans-first:1.81 06:03:05 INFO - PROCESS | 1671 | Values: 79.0 81.0 80.0 81.0 79.0 79.0 80.0 80.0 80.0 85.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:128.50 stddev:8.23 (6.4%) stddev-sans-first:7.91 06:03:05 INFO - PROCESS | 1671 | Values: 115.0 123.0 134.0 128.0 129.0 118.0 117.0 116.0 131.0 138.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.30 Median:134.50 stddev:17.91 (13.3%) stddev-sans-first:17.59 06:03:05 INFO - PROCESS | 1671 | Values: 123.0 134.0 135.0 132.0 134.0 132.0 159.0 171.0 172.0 131.0 06:03:05 INFO - PROCESS | 1671 | 06:03:05 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.06 (1.6%) stddev-sans-first:0.73 06:03:05 INFO - PROCESS | 1671 | Values: 67.0 65.0 65.0 65.0 65.0 63.0 64.0 64.0 65.0 64.0 06:03:05 INFO - PROCESS | 1671 | -------- Summary: end -------- 06:03:05 INFO - PROCESS | 1671 | 06:03:06 INFO - TEST-INFO | 1671: exit 0 06:03:06 INFO - TEST-OK | kraken | took 56313ms 06:03:06 INFO - SUITE-END | took 1167s 06:03:06 INFO - Completed test suite (00:19:28) 06:03:06 INFO - javascript benchmark 06:03:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3412.5051867479215, 3483.4252348362506, 3511.7541772075933, 3514.1917359007434, 3561.8219089045556, 5707.172459993299, 5712.428757124286, 5713.828585142447, 5729.5702822288395, 5743.626664266825, 5721.656700597957, 5757.165211044396, 5760.596758226926, 5770.365259821417, 5774.451427114417, 5117.2068329409, 5137.820176293831, 5158.896822063556, 5167.586593072564, 5239.318888544484, 5219.634625576211, 5228.0, 5230.450802665722, 5233.73831308435, 5235.842924712265, 5180.1452760294505, 5214.322138122039, 5214.530692237698, 5227.73861306935, 5228.320318358627, 5222.477752224766, 5253.6322457427805, 5261.921071183897, 5269.710165940872, 5292.814751483679, 4987.102321582114, 5163.380394352702, 5178.482151784829, 5186.273921650972, 5187.533122019036, 4698.882527936795, 5666.178404131378, 5692.316921969349, 5709.257796486471, 5715.257016587859, 6086.134871692894, 6288.245410550718, 6290.622562646256, 6292.811215663537, 6300.9684951574945, 6221.222347206599, 6245.063240513914, 6263.217097862767, 6331.810045698637, 6332.556721029529, 5790.826275211751, 5825.0388685866355, 5826.446487583673, 5830.459898795415, 5871.148683440877, 6189.473894718909, 6252.374762523711, 6312.715927783261, 6313.747450102036, 6322.083297921776, 6159.599626024384, 6194.5044396448175, 6205.317415084337, 6209.565330426917, 6213.596116252429, 6300.30696623371, 6303.6532990685955, 6317.557770956081, 6322.968385158045, 6328.367163283635, 4676.415448068991, 6046.606970546901, 6166.167567378437, 6249.937510623205, 6263.937360626426, 4496.3255511673515, 6207.131001659771, 6234.438900498977, 6251.812445626639, 6275.937240627535, 4922.90154196914, 4923.261510773413, 4930.285108659296, 4937.210046392632, 4945.950540494621, 4351.73889566627, 4394.406755088086, 4465.553444655573, 4476.6418686505, 4488.730676159441, 5662.6036177468, 5663.207150998864, 5674.801381951612, 5691.03252447085, 5699.116636921284, 6287.308396076428, 6299.685015749286, 6300.0, 6310.179676642099, 6320.778772742932, 5504.311961004874, 5659.43405659439, 5662.546996240291, 5671.2060311556415, 5681.0910254359105, 5365.517103460707, 5398.595105367113, 5402.8919421611345, 5402.945970540323, 5438.755256013489, 6332.208473940757, 6340.587861788969, 6349.55553111287, 6349.619022858643, 6353.841153971188, 6166.352532983972, 6219.0982307566055, 6294.3076261612105, 6300.401461861115, 6305.337939516285, 6318.241810982711, 6321.557490975588, 6331.240251169889, 6339.112524246517, 6347.0, 6225.408586184305, 6227.657478838707, 6231.22109736283, 6237.812865614039, 6247.187865577446, 6131.866048757656, 6235.501159907287, 6300.117983482408, 6302.936970630235, 6303.873922521616, 6003.729832157651, 6129.632222066691, 6162.506999440124, 6162.845928851814, 6164.445199932027, 6030.356625398331, 6132.509399248139, 6180.690965451619, 6181.660008699565, 6182.319944806158, 5881.823545293648, 5914.2311499504785, 5978.521718262616, 5983.9401605983385, 6034.0, 6123.448889599779, 6149.692515374124, 6155.47678447337, 6156.846078848064, 6160.106784516131, 5809.128630705428, 6014.248218972629, 6035.366286540026, 6041.335453100243, 6053.4551890330085], "unit": "score", "name": "dojo.html", "value": 5705.16207715746}, {"lowerIsBetter": false, "replicates": [502.803906476474, 597.9028482734183, 626.7399029402798, 639.8560323927117, 673.2157037051835, 31764.20589485264, 32262.516062259056, 32290.67709322906, 32318.838405807997, 32327.838360808164, 42076.15847683052, 42258.788706056504, 42459.93850153748, 42475.0, 42519.0, 9662.56518456671, 9842.458664773434, 9844.852327215098, 9848.852267215998, 9865.964073772222, 9741.561629726644, 9908.455034973074, 9909.702708918743, 9920.503974801268, 9936.205103591732, 9848.852267215998, 9923.751906202331, 9928.404295742243, 9929.0, 9933.106020458157, 9967.501624918761, 10029.398236105857, 10055.849162262572, 10063.044010819034, 10072.496375181212, 9811.411315321104, 9929.90070099297, 9944.502774861228, 9959.90040099597, 9961.0, 42703.57296427027, 43084.35373469401, 43153.78423107896, 43159.0, 43176.0, 3307.0409581221415, 3462.480627905809, 3496.1434448560267, 3501.7723847950056, 3732.570754363229, 29896.505174741174, 30014.849925750448, 30240.0, 30255.697443025507, 30399.0, 2710.6747190337087, 2766.6679998400323, 2799.930001749952, 2821.0690472144347, 2840.5029119903934, 470810.0, 474228.2577174253, 474524.0, 475165.0, 476423.9999999965, 68000.6599966997, 68296.65851670809, 68307.65846170737, 68406.0, 68446.65776671085, 73527.0, 73819.0, 77757.61121194357, 78988.21011789923, 79116.0, 18497.352592659463, 18542.60930430223, 18714.81285187158, 18764.81235187658, 18765.7185142223, 14130.0, 14212.0, 14219.573412797632, 14231.359588818528, 14238.0, 20314.593708125754, 21920.232791852526, 23132.884335578216, 23449.06203751831, 23450.358474906592, 9511.668366428705, 9759.560819762986, 9781.364211326241, 9786.902130978742, 9834.0, 975.6877799104218, 985.9852102218473, 1021.2442792333619, 1027.4143738069379, 1161.9070474362031, 31146.844265778527, 32686.509702354484, 33819.323613527595, 33920.66079339224, 34057.0, 1098.5880294889416, 1159.3449700918954, 1192.9940350298193, 1207.0524638159004, 1234.4013153620488, 11790.882091178979, 13074.215547067206, 16551.751723724155, 16790.916045419937, 16899.662006759794, 673921.6303918449, 676992.0, 677588.0, 679460.0, 679537.0, 61883.690581546805, 63643.68178159079, 63705.0, 63786.0, 63816.36183638197, 1330.3680751643085, 1431.51328548292, 1456.3955958277197, 1456.7159403916135, 1529.5640742388366, 27603.0, 30102.096937091923, 30750.231244219074, 31180.0, 31293.904713334923, 45855.77072114618, 55357.0, 56271.43728562826, 56317.0, 56317.71841140768, 410853.0, 413706.8629313789, 414031.0, 414667.0, 414753.0, 32700.345993080482, 33964.49053264154, 34048.319033618696, 34090.65909340875, 35349.469757953135, 45267.094658106, 45279.54720452885, 45538.08923821571, 47109.52890471051, 47308.05383892372, 38073.4288985671, 38159.42760858534, 38262.0, 38623.42064869085, 38800.80599596984, 37472.250554988204, 37889.81055094707, 37999.810000949816, 38410.80794596121, 38606.2278754429, 32365.838170808995, 37205.62794372022, 37602.811985939894, 38468.23063538769, 38789.80605097069, 28688.303515238495, 32446.72608484821, 32680.50979235361, 32962.83518582392, 32989.0, 25397.492050159264, 25434.23697289084, 25438.872805635852, 25686.0, 25797.484050319264], "unit": "score", "name": "ext.html", "value": 20739.38427580274}, {"lowerIsBetter": false, "replicates": [383.4574077680083, 384.0724649970321, 387.55431254057845, 393.6142580271334, 396.2847061054795, 19850.602987940234, 21183.788162118373, 21346.93265336735, 21638.0, 21650.3504894853, 17714.379996700118, 21334.51930620585, 21704.674429883566, 21953.012114454847, 22078.11687532497, 5457.727113644321, 5853.531717462614, 6051.576389652726, 6105.206323178003, 6113.296970848365, 6060.2121724175795, 6101.816945491621, 6105.358937311585, 6128.172696685935, 6131.724072416752, 6033.145117713248, 6060.060690592965, 6061.242344706911, 6081.847953801146, 6132.172156758847, 5923.200367950315, 6036.577439579231, 6053.969730151365, 6089.995150800112, 6107.389261073879, 4496.325551167319, 5237.79048838046, 5274.947250527523, 5275.920861187085, 5282.366116066097, 26592.73407265922, 26664.466710665678, 26900.73099269002, 26918.865405672845, 26983.460330793274, 2178.6296329624, 2519.8992040318367, 2525.722170561237, 2608.243609353285, 2977.7980776868617, 15210.543683689508, 15236.542903712907, 15261.542153735405, 15339.539813805604, 15371.0, 1742.6688929103557, 1748.4317596781098, 1762.7620271263343, 1763.7795275590552, 1840.7514985476923, 102700.0, 104840.47579762129, 105464.0, 106624.0, 107186.92813071847, 10725.08836748885, 10839.783204335869, 10851.728706782236, 10935.125189984783, 11027.117830573536, 13453.461861525626, 13537.45850166002, 13557.728845423037, 13567.457301708018, 13588.728225435436, 8749.518776467356, 8913.73258802224, 8920.420172688755, 8946.0, 9038.683646072355, 9121.407108538056, 9131.863022054675, 9144.497052662167, 9188.356815022884, 9240.537973101318, 6274.937250627435, 6342.841428964312, 6346.619202847844, 6359.046143078483, 6407.871842563122, 1818.9363372281907, 1832.7800663920416, 1847.381127322362, 1866.0576408913412, 1868.588910439701, 1033.2088643155007, 1036.6838114375037, 1051.5951358726945, 1053.4679986606868, 1120.6357933671588, 17413.216405261795, 17559.0, 21218.893905530374, 22161.66757498639, 22218.00018999149, 1020.6478764826271, 1042.3797840285017, 1084.511969613677, 1087.7280679830042, 1094.7974624694457, 11630.70923226926, 11770.94061534466, 11840.27501788416, 11964.40177991097, 11986.400679965967, 263196.0, 263736.362636375, 264521.0, 264670.6766466194, 264684.0, 102359.95280094506, 103513.48243258735, 108128.45935770271, 109620.0, 112157.43921280341, 1049.7480604654827, 1082.9025387714985, 1090.2640717515644, 1100.2573263047411, 1102.8014957307762, 15382.538523844303, 18166.818331816514, 18777.812221878154, 19103.140358683613, 19120.13959371804, 50277.0, 50401.243981341046, 51005.46983590498, 52124.739376302874, 52753.73623132013, 65760.34239657542, 69116.0, 70197.29802701907, 70271.9459208102, 70334.64832675803, 8296.377771667028, 8412.831743365221, 8425.115362886809, 8521.957390213009, 8763.036066032604, 10888.67333979982, 13240.80138797938, 13713.108647937852, 13955.232462214675, 13975.0, 5692.914606280992, 8921.509316987627, 11803.114766392726, 11819.409029548317, 11854.762904741685, 9591.328606997444, 11654.592089276835, 11752.23610465317, 11932.224405413968, 12074.517019319128, 11753.764924701629, 11875.346855923008, 12094.057136021995, 12200.633980980585, 12208.938955305166, 8160.8775868360835, 8292.175721341295, 8578.571071446278, 8600.827983440422, 8634.827303454022, 11612.88387116118, 11954.0, 12026.398680066137, 12154.331511766937, 12186.0], "unit": "score", "name": "jquery.html", "value": 9640.346061937462}, {"lowerIsBetter": false, "replicates": [1508.8340282568922, 1611.7340638794597, 1639.8524132828043, 1937.70934359846, 2054.3118055451423, 8353.582320883954, 8908.8663670045, 8928.776780580489, 8939.642414303427, 8980.550972451383, 8798.340124490673, 9076.273898088137, 9095.499747513884, 9095.681651142211, 9098.090190980898, 2251.4596496840804, 2503.662005629235, 2543.0844895837486, 2549.7067837198774, 2572.87135643218, 2544.821862469628, 2549.6430499730054, 2549.7577730115704, 2552.1067626330823, 2554.6806649168852, 2477.764612361832, 2545.5418024755536, 2551.2473820223076, 2552.2981180175507, 2552.540542702313, 2494.4761600064007, 2543.5675935090985, 2545.9872700636565, 2549.4518678484123, 2550.2604244768995, 2508.072013355052, 2529.9241022769347, 2536.467341858212, 2559.9360015999564, 2561.1292160665275, 9057.954710226473, 9124.133207345292, 9124.498152601604, 9135.771605709844, 9156.404833685789, 5572.080606699889, 5671.06427439472, 5677.659340439587, 5730.627509211888, 5731.541476681856, 3612.24142929985, 3699.093722038091, 3708.9258214835822, 3728.0, 3736.065983504124, 1065.8454524093963, 1076.5478499030426, 1081.5133190064423, 1097.358045543358, 1109.9056580190693, 12552.81170782439, 12577.559785407559, 12578.0, 12591.433385497674, 12594.559190428383, 4967.950320496749, 4981.178105612606, 5037.924431133536, 5046.26829109777, 5068.366454193226, 10326.948365258277, 10367.274290799722, 10368.222383321281, 10387.065164135263, 10392.636257730943, 5562.137868630369, 5707.0583353747, 5711.514521265739, 5715.199872017921, 5723.942760572341, 3936.9803150984635, 4620.075984803053, 4755.0489902019735, 5062.721550314768, 5121.795128194905, 3945.3095708251517, 4040.5151381834366, 4049.7367671101283, 4058.411530328086, 4097.385392191136, 1207.6353720296186, 1290.9096363254482, 1344.764666183414, 1347.2186132043391, 1352.1616597709287, 1237.0907383073434, 1361.884239839605, 1411.6400317918865, 1421.530894804712, 1444.2778610694652, 8593.828123437495, 8621.827563448696, 8633.0, 8645.092265312178, 8651.95674021626, 1295.501232025658, 1351.8445378781369, 1359.1437394441439, 1366.3373263966969, 1376.6165004170743, 6327.050942358591, 6376.553641245068, 6394.104825324458, 6396.136521569529, 6410.423061924449, 10692.625758098584, 10786.94606526978, 10788.40663763485, 10816.026557609854, 10949.178811589163, 10378.636747713794, 10530.789384212272, 10659.467026648792, 10674.573017079385, 10675.519601617945, 8322.334213262924, 8458.281046111026, 8470.745877623682, 8482.236598706146, 8489.36329775257, 8190.344772418312, 8240.052393974769, 8319.417640765088, 8367.832643346977, 8427.578621069044, 9072.410293330913, 9785.315027947976, 9852.556634951301, 9903.653372132228, 10036.749081272734, 9884.654037108667, 10189.84715229287, 10301.536430860479, 10348.172146228133, 10397.168226541708, 6654.50091243149, 6839.62382068991, 7619.047619047619, 7619.504732192389, 7757.689692412466, 8385.83228335442, 8396.916030839613, 8458.830823383374, 8480.618372173143, 8493.745187644381, 7229.457790665616, 7565.4325925556905, 7567.545947242963, 7727.072751269883, 7853.293203611703, 7556.206598307099, 7565.962170189114, 7576.4317676173405, 7612.1246056701975, 7616.543007419573, 7954.443188976716, 7987.0, 8038.959805200937, 8043.1554686757045, 8050.27547520726, 7212.567245965259, 7223.783286501413, 7271.963640181765, 7304.671289792079, 7322.2677732228485, 6218.19163508741, 7344.889826652711, 7382.446316526175, 7426.554406735613, 7432.2567743225245], "unit": "score", "name": "mootools.html", "value": 5117.406186248636}, {"lowerIsBetter": false, "replicates": [337.25298463902453, 342.21291030629544, 344.85860797073207, 347.0265904138891, 349.9282647057352, 6498.870022599551, 6600.471962243021, 6606.10817539632, 6664.600123992564, 6715.8656826863435, 6548.607083574989, 6555.41001309882, 6567.244766851813, 6582.572132811364, 6595.1096601958725, 4647.628189744829, 4701.9059618807605, 4735.550122738335, 4833.7824797884, 4898.363212782342, 4235.004773878136, 4753.405824271966, 4783.928241076386, 4784.712917224961, 4810.25441056635, 4768.785404656782, 4800.735959522225, 4816.686915350509, 4829.7585120744, 4840.830570930018, 4613.723176609397, 4698.882527936795, 4790.688605240648, 4814.614830813526, 4825.855224343275, 4146.875593732193, 4164.8125834337525, 4169.312063509517, 4174.645155161815, 4180.728252663567, 6242.563020588561, 6322.0, 6325.367463253684, 6340.0, 6346.52401069917, 5737.483626473638, 5758.510526605243, 5873.177755114288, 5876.0, 5882.3823498532465, 5692.544596432275, 6723.092382528345, 6970.756023539203, 6990.021397004423, 6994.2656021117555, 4710.0, 4750.596199323062, 4763.261694437368, 4768.666193366465, 4775.546323099335, 6222.81331560054, 6223.719932603044, 7202.819929501752, 7371.078615173157, 7401.370883474911, 4830.082284365959, 4851.331767527846, 4853.514648535189, 4858.975705121452, 4860.343853579721, 4049.2913740095955, 4915.139850526144, 4940.1601727706375, 4943.0, 4943.629227807929, 5771.278590176228, 5773.971130144322, 5776.191333213354, 5789.536837053026, 5792.4497172768515, 6778.288279730656, 7027.156741190987, 7044.823879402953, 7047.89428158578, 7079.89380159298, 1680.5210515003166, 1697.4228762220905, 1708.9743653845203, 1709.9316027359016, 1711.5721069732567, 1033.9741506462399, 1067.6156583629886, 1107.8449017137607, 1120.78705046041, 1133.820826340613, 6365.427111559982, 6544.901826472606, 6552.180977377828, 6554.573952693059, 6559.344065593403, 7338.192798792129, 7369.852602948018, 7374.668139933716, 7379.2251813558805, 7397.741079062207, 6531.183602049744, 6531.771388001397, 6538.313477084837, 6550.6069635822005, 6553.0, 3737.4767532545466, 3838.405047217686, 3888.51393575803, 3897.785621790772, 3898.7270891037924, 7420.888686669704, 7561.7731468056045, 7674.386049116058, 7693.076830780342, 7729.961350193213, 6426.3573642635365, 6879.208890977501, 6883.760923033802, 7556.357709594746, 7557.584332861636, 7621.657025433758, 7636.3890888728765, 7656.081270247606, 7661.731839385595, 7674.083848137672, 6964.965175174092, 7308.634568271459, 7328.142024610513, 7346.412287017132, 7350.191478937206, 7110.715571377087, 7132.215456299907, 7136.0, 7173.820654483679, 7231.204567497466, 7636.80907977305, 7645.464817462725, 7652.61736913141, 7652.885206721792, 7656.272654097852, 4570.314452831969, 4603.263477843529, 4605.93091103627, 4608.792604332746, 4630.027694184124, 6981.895271570829, 7193.100862392201, 7193.136823581203, 7225.385842203472, 7239.131304243525, 6976.965115174392, 6993.0908981832035, 6995.685194166376, 7000.054992575937, 7001.789946301619, 6936.237013928565, 6963.268856770169, 6973.511854170362, 6980.511364204457, 6999.09011828459, 6795.694193761194, 6814.557053791487, 6824.556403833735, 6833.41915937131, 6850.794476165723, 5641.717914104361, 6953.965230173817, 6954.547954382949, 6971.198312193959, 6973.3375329343635, 6582.76960306387, 6714.0, 6781.0, 6827.48793840454, 6928.1339832520935], "unit": "score", "name": "prototype.html", "value": 5155.012118257666}, {"lowerIsBetter": false, "replicates": [669.6752075243509, 699.6257002503659, 726.7311094894889, 786.2844811221789, 797.4736673795293, 15331.15678637676, 15497.53507394777, 15526.223688815571, 15531.145786981711, 15533.068015919038, 14403.063800852937, 14408.639784005405, 14480.927595362034, 14486.49297274593, 14492.420303187886, 784.0591290451453, 791.9287264146226, 793.2067932067932, 948.09456968595, 970.2141265574871, 784.7331907151568, 788.597815114293, 952.8999455057224, 984.3798407003578, 985.9753506162332, 1017.6234793126516, 1025.6102680981217, 1025.9487025648725, 1028.408665017614, 1030.2376241581214, 1023.5291765787747, 1025.1542477456053, 1025.3130402630181, 1026.6252817721515, 1026.6355443817413, 613.2610204703367, 621.2389822467486, 621.9253689557237, 747.4170147285072, 773.3503856760292, 15190.848091519054, 15252.0086194397, 15278.694426111526, 15301.005434646713, 15338.769918451233, 13009.0, 13045.02162337819, 13072.888804451633, 13229.933850330783, 13575.660608484768, 10416.739581510446, 10522.947385263102, 10724.249302548824, 10807.513661885234, 10952.233343665946, 3596.021801872251, 3617.4935509028755, 3621.6016238213774, 3675.2833197526493, 3881.126746482047, 67363.0, 86544.56727716383, 88548.22903541893, 88618.0, 88671.55664221702, 9277.90722092784, 9715.319927605, 9814.705558833246, 9961.601535938626, 10255.641052563275, 19636.25092487053, 20892.895535522224, 22187.223447179593, 22604.773952260595, 22696.205632802772, 7539.0576177977755, 7540.8491830164185, 7655.770326890202, 7671.769846904602, 7693.4999225051315, 4000.979995100064, 4094.4267802507675, 4101.712880098424, 4105.261053010487, 4107.54816970133, 3674.706023518112, 3706.8694048315524, 3718.0, 3719.367707489733, 3732.8506859725308, 684.5995092870627, 689.4346635758629, 689.648279377521, 693.729445516249, 704.2534912992245, 502.7712390862149, 518.3753576939779, 581.9854503637443, 629.1003864473755, 631.157404864511, 8350.206730360605, 8500.192481714228, 8705.869411958825, 8722.825543489222, 8855.468671879707, 574.9741261643236, 585.9179714839926, 614.6619359352428, 628.8899442597527, 632.8481164520574, 3417.3165366926714, 4004.619561141687, 4009.3384591542067, 4019.4975628046495, 4023.0344717267644, 100105.0, 101322.0, 102827.0, 102991.0, 104294.0, 84844.0, 85229.0, 85414.86462838479, 86302.13697862941, 87817.0, 72627.63686181535, 73998.89001664979, 74229.0, 75042.0, 76391.61804191055, 7012.333828286304, 7858.135605083322, 7994.440389172934, 8136.06435259929, 8253.83492330138, 49535.25697114474, 52118.95762084813, 52408.0, 52670.47329526656, 52715.20927186172, 71719.56560868649, 75022.2497775037, 75581.24418755963, 75773.0, 76496.2350376489, 10201.846972295272, 10214.336068155546, 10371.429571373646, 10485.790284194121, 10537.315074520438, 10909.887178568431, 11169.106471482424, 11298.49156787963, 11408.828867566826, 11906.464209110783, 10548.789024219626, 10611.257211995086, 10668.466576671291, 10832.0, 10883.67348979532, 9619.663311784054, 11395.886041139482, 11453.59912403062, 11508.424578771195, 11773.293602383885, 11232.269902456314, 11317.319697369167, 11402.372869491926, 11504.712382190512, 11671.183017188716, 9645.27660425485, 9692.0, 9905.877804707758, 9934.013197360413, 10252.43611601369, 8659.567021649018, 9743.074407931235, 9964.750881228027, 10017.849732253877, 10167.542460589439], "unit": "score", "name": "yui.html", "value": 7064.03499633128}], "name": "dromaeo_css", "value": 7725.395372951376}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 109.0, 109.0, 108.0, 109.0, 109.0, 120.0, 112.0, 110.0, 108.0], "unit": "score", "name": "ai-astar", "value": 110.5}, {"lowerIsBetter": true, "replicates": [167.0, 125.0, 143.0, 165.0, 145.0, 173.0, 165.0, 117.0, 156.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 147.4}, {"lowerIsBetter": true, "replicates": [187.0, 181.0, 186.0, 183.0, 184.0, 184.0, 183.0, 184.0, 184.0, 187.0], "unit": "score", "name": "audio-dft", "value": 184.3}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 75.0, 112.0, 55.0, 57.0, 60.0, 57.0, 115.0, 56.0], "unit": "score", "name": "audio-fft", "value": 73.1}, {"lowerIsBetter": true, "replicates": [92.0, 95.0, 128.0, 80.0, 78.0, 83.0, 117.0, 75.0, 79.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 94.5}, {"lowerIsBetter": true, "replicates": [109.0, 130.0, 87.0, 84.0, 87.0, 83.0, 89.0, 84.0, 86.0, 89.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.8}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 91.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.1}, {"lowerIsBetter": true, "replicates": [87.0, 85.0, 85.0, 84.0, 85.0, 85.0, 87.0, 84.0, 96.0, 132.0], "unit": "score", "name": "imaging-desaturate", "value": 91.0}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 58.0, 54.0, 57.0, 56.0, 56.0, 66.0, 54.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [64.0, 61.0, 72.0, 68.0, 76.0, 72.0, 67.0, 64.0, 67.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [79.0, 81.0, 80.0, 81.0, 79.0, 79.0, 80.0, 80.0, 80.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.4}, {"lowerIsBetter": true, "replicates": [115.0, 123.0, 134.0, 128.0, 129.0, 118.0, 117.0, 116.0, 131.0, 138.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.9}, {"lowerIsBetter": true, "replicates": [123.0, 134.0, 135.0, 132.0, 134.0, 132.0, 159.0, 171.0, 172.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.3}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 65.0, 65.0, 65.0, 63.0, 64.0, 64.0, 65.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 64.7}], "name": "kraken", "value": 1422.2}]} 06:03:06 INFO - Return code: 0 06:03:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:03:06 INFO - # TBPL SUCCESS # 06:03:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:03:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:03:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:03:06 INFO - 2016-04-25 06:03:06.991 screenresolution[1675:9824] starting screenresolution argv=screenresolution get 06:03:06 INFO - 2016-04-25 06:03:06.993 screenresolution[1675:9824] Display 0: 1600x1200x32@60 06:03:07 INFO - 2016-04-25 06:03:06.999 screenresolution[1676:9827] starting screenresolution argv=screenresolution list 06:03:07 INFO - Available Modes on Display 0 06:03:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:03:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:03:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:03:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:03:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:03:07 INFO - Intel Iris: 06:03:07 INFO - Chipset Model: Intel Iris 06:03:07 INFO - Type: GPU 06:03:07 INFO - Bus: Built-In 06:03:07 INFO - VRAM (Dynamic, Max): 1536 MB 06:03:07 INFO - Vendor: Intel (0x8086) 06:03:07 INFO - Device ID: 0x0a2e 06:03:07 INFO - Revision ID: 0x0009 06:03:07 INFO - Displays: 06:03:07 INFO - Display: 06:03:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:03:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:03:07 INFO - Display Serial Number: 1600x1200 60 06:03:07 INFO - Main Display: Yes 06:03:07 INFO - Mirror: Off 06:03:07 INFO - Online: Yes 06:03:07 INFO - Rotation: Supported 06:03:07 INFO - Return code: 0 06:03:07 INFO - Running post-action listener: _resource_record_post_action 06:03:07 INFO - Running post-run listener: _resource_record_post_run 06:03:08 INFO - Total resource usage - Wall time: 1202s; CPU: 52.0%; Read bytes: 37344768; Write bytes: 665567744; Read time: 392; Write time: 2099 06:03:08 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 359293440; Write bytes: 354510336; Read time: 27756; Write time: 619 06:03:08 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 35530240; Write bytes: 299879424; Read time: 371; Write time: 1443 06:03:08 INFO - Running post-run listener: _upload_blobber_files 06:03:08 INFO - Blob upload gear active. 06:03:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:03:08 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 06:03:08 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'] 06:03:08 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 06:03:08 INFO - (blobuploader) - INFO - Open directory for files ... 06:03:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:03:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:03:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:09 INFO - (blobuploader) - INFO - Done attempting. 06:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:03:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:10 INFO - (blobuploader) - INFO - Done attempting. 06:03:10 INFO - (blobuploader) - INFO - Iteration through files over. 06:03:10 INFO - Return code: 0 06:03:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:03:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:03:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b8610881549d7fe9e16f595b6a29438d0c98a985018df86df4f3b1e4b0650665f36b570399f03086f5e432a528c84266cf98c24812cbe70e0763bbc58457095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16c19f5dcc06295a98eb063480e451f76666607def5835885f72f12833327383f107b4900e7385f396ddbcb77170a3a2fe0fa6eb70d15335b7207efe5ab1aaf8"} 06:03:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:03:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:03:10 INFO - Contents: 06:03:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b8610881549d7fe9e16f595b6a29438d0c98a985018df86df4f3b1e4b0650665f36b570399f03086f5e432a528c84266cf98c24812cbe70e0763bbc58457095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16c19f5dcc06295a98eb063480e451f76666607def5835885f72f12833327383f107b4900e7385f396ddbcb77170a3a2fe0fa6eb70d15335b7207efe5ab1aaf8"} 06:03:10 INFO - Running post-run listener: copy_logs_to_upload_dir 06:03:10 INFO - Copying logs to upload dir... 06:03:10 INFO - mkdir: /builds/slave/test/build/upload/logs 06:03:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.079876 ========= master_lag: 73.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-04-25 06:04:24.257909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 06:04:24.261232) ========= 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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/4b8610881549d7fe9e16f595b6a29438d0c98a985018df86df4f3b1e4b0650665f36b570399f03086f5e432a528c84266cf98c24812cbe70e0763bbc58457095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16c19f5dcc06295a98eb063480e451f76666607def5835885f72f12833327383f107b4900e7385f396ddbcb77170a3a2fe0fa6eb70d15335b7207efe5ab1aaf8"} 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.011002 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/4b8610881549d7fe9e16f595b6a29438d0c98a985018df86df4f3b1e4b0650665f36b570399f03086f5e432a528c84266cf98c24812cbe70e0763bbc58457095", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16c19f5dcc06295a98eb063480e451f76666607def5835885f72f12833327383f107b4900e7385f396ddbcb77170a3a2fe0fa6eb70d15335b7207efe5ab1aaf8"}' ========= master_lag: 1.66 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 06:04:25.931944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 06:04:25.932228) ========= 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.9VyyCoKax3/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.bfyUhdFaGP/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005093 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 06:04:26.025066) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 06:04:26.025367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 06:04:26.041768) ========= ========= Total master_lag: 75.14 =========