builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0055 starttime: 1460635793.63 results: success (0) buildid: 20160414032548 builduid: 8695f4ce90c94b97a0ac36cbd725f5c3 revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:53.630237) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:53.630739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-14 05:09:53.631068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-14 05:10:00.219551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:00.219842) ========= 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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-14 05:10:00-- 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.3M=0.001s 2016-04-14 05:10:00 (13.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163824 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:00.454376) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:00.454715) ========= 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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.033895 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:00.535713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 05:10:00.536132) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-14 05:10:00,627 truncating revision to first 12 chars 2016-04-14 05:10:00,627 Setting DEBUG logging. 2016-04-14 05:10:00,627 attempt 1/10 2016-04-14 05:10:00,627 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4a496faf1c5c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 05:10:01,615 unpacking tar archive at: fx-team-4a496faf1c5c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.258373 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 05:10:01.827355) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:01.827685) ========= script_repo_revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:01.828173) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:01.828501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:10:01.880948) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-14 05:10:01.881425) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:02 INFO - MultiFileLogger online at 20160414 05:10:02 in /builds/slave/test 05:10:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:10:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:02 INFO - {'append_to_log': False, 05:10:02 INFO - 'base_work_dir': '/builds/slave/test', 05:10:02 INFO - 'blob_upload_branch': 'Fx-Team', 05:10:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:02 INFO - 'branch': 'Fx-Team', 05:10:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:02 INFO - 'config_files': ('talos/mac_config.py',), 05:10:02 INFO - 'default_actions': ('clobber', 05:10:02 INFO - 'read-buildbot-config', 05:10:02 INFO - 'download-and-extract', 05:10:02 INFO - 'populate-webroot', 05:10:02 INFO - 'create-virtualenv', 05:10:02 INFO - 'install', 05:10:02 INFO - 'run-tests'), 05:10:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:02 INFO - 'download_minidump_stackwalk': True, 05:10:02 INFO - 'download_symbols': 'ondemand', 05:10:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:02 INFO - 'installer_path': 'installer.exe', 05:10:02 INFO - 'log_level': 'info', 05:10:02 INFO - 'log_name': 'talos', 05:10:02 INFO - 'log_to_console': True, 05:10:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:02 INFO - 'opt_config_files': (), 05:10:02 INFO - 'pip_index': False, 05:10:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:02 INFO - 'cmd': ('bash', 05:10:02 INFO - '-c', 05:10:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:02 INFO - 'enabled': True, 05:10:02 INFO - 'halt_on_failure': False, 05:10:02 INFO - 'name': 'check_screen_resolution'},), 05:10:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:02 INFO - 'cmd': ('bash', 05:10:02 INFO - '-c', 05:10:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:02 INFO - 'enabled': True, 05:10:02 INFO - 'halt_on_failure': False, 05:10:02 INFO - 'name': 'check_screen_resolution'},), 05:10:02 INFO - 'run_cmd_checks_enabled': True, 05:10:02 INFO - 'sps_profile': False, 05:10:02 INFO - 'sps_profile_interval': 0, 05:10:02 INFO - 'suite': 'dromaeojs', 05:10:02 INFO - 'system_bits': '32', 05:10:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:02 INFO - 'title': 't-yosemite-r7-0055', 05:10:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:02 INFO - 'use_talos_json': True, 05:10:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:02 INFO - 'work_dir': 'build'} 05:10:02 INFO - ##### 05:10:02 INFO - ##### Running clobber step. 05:10:02 INFO - ##### 05:10:02 INFO - Running pre-action listener: _resource_record_pre_action 05:10:02 INFO - Running main action method: clobber 05:10:02 INFO - rmtree: /builds/slave/test/build 05:10:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:04 INFO - Running post-action listener: _resource_record_post_action 05:10:04 INFO - ##### 05:10:04 INFO - ##### Running read-buildbot-config step. 05:10:04 INFO - ##### 05:10:04 INFO - Running pre-action listener: _resource_record_pre_action 05:10:04 INFO - Running main action method: read_buildbot_config 05:10:04 INFO - Using buildbot properties: 05:10:04 INFO - { 05:10:04 INFO - "project": "", 05:10:04 INFO - "product": "firefox", 05:10:04 INFO - "script_repo_revision": "production", 05:10:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:10:04 INFO - "repository": "", 05:10:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:10:04 INFO - "buildid": "20160414032548", 05:10:04 INFO - "pgo_build": "False", 05:10:04 INFO - "basedir": "/builds/slave/test", 05:10:04 INFO - "buildnumber": 658, 05:10:04 INFO - "slavename": "t-yosemite-r7-0055", 05:10:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:10:04 INFO - "platform": "yosemite_r7", 05:10:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:10:04 INFO - "branch": "fx-team", 05:10:04 INFO - "repo_path": "integration/fx-team", 05:10:04 INFO - "revision": "4a496faf1c5cac37ab6dac9db549cb65867fa7f6", 05:10:04 INFO - "stage_platform": "macosx64", 05:10:04 INFO - "builduid": "8695f4ce90c94b97a0ac36cbd725f5c3", 05:10:04 INFO - "slavebuilddir": "test" 05:10:04 INFO - } 05:10:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:10:04 INFO - Running post-action listener: _resource_record_post_action 05:10:04 INFO - ##### 05:10:04 INFO - ##### Running download-and-extract step. 05:10:04 INFO - ##### 05:10:04 INFO - Running pre-action listener: _resource_record_pre_action 05:10:04 INFO - Running main action method: download_and_extract 05:10:04 INFO - mkdir: /builds/slave/test/build/tests 05:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:04 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:10:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:10:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:10:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:10:08 INFO - Downloaded 1199 bytes. 05:10:08 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:10:08 INFO - Using the following test package requirements: 05:10:08 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:10:08 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:10:08 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:10:08 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'jsshell-mac.zip'], 05:10:08 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:10:08 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:10:08 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:10:08 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:10:08 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:10:08 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:10:08 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:10:08 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:08 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:10 INFO - Downloaded 18031141 bytes. 05:10:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:10:13 INFO - Return code: 0 05:10:13 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:10: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:10:13 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:16 INFO - Downloaded 18031141 bytes. 05:10:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:10:19 INFO - Return code: 0 05:10: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:10:19 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:10:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:10:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:10:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:10:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:10:20 INFO - Downloaded 10841076 bytes. 05:10:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:10:21 INFO - Return code: 0 05:10:21 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:10:21 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:10:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:10:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:10:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:10:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:10:26 INFO - Downloaded 109830898 bytes. 05:10:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:10:26 INFO - mkdir: /builds/slave/test/properties 05:10:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:10:26 INFO - Writing to file /builds/slave/test/properties/build_url 05:10:26 INFO - Contents: 05:10:26 INFO - build_url:https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:10:27 INFO - Running post-action listener: _resource_record_post_action 05:10:27 INFO - Running post-action listener: set_extra_try_arguments 05:10:27 INFO - ##### 05:10:27 INFO - ##### Running populate-webroot step. 05:10:27 INFO - ##### 05:10:27 INFO - Running pre-action listener: _resource_record_pre_action 05:10:27 INFO - Running main action method: populate_webroot 05:10:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:10:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:10:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:10:27 INFO - '--tppagecycles', 05:10:27 INFO - '7'], 05:10:27 INFO - 'tests': ['tsvgm']}}, 05:10:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:10:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:10:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:10:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:10:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['damp', 'tps']}, 05:10:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'tests': ['damp', 'tps']}, 05:10:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['dromaeo_dom']}, 05:10:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:10:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['a11yr', 05:10:27 INFO - 'ts_paint', 05:10:27 INFO - 'tpaint', 05:10:27 INFO - 'sessionrestore', 05:10:27 INFO - 'sessionrestore_no_auto_restore', 05:10:27 INFO - 'tabpaint']}, 05:10:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['tsvgx', 05:10:27 INFO - 'tsvgr_opacity', 05:10:27 INFO - 'tart', 05:10:27 INFO - 'tscrollx', 05:10:27 INFO - 'cart']}, 05:10:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:10:27 INFO - 'tsvgr_opacity', 05:10:27 INFO - 'tart', 05:10:27 INFO - 'tscrollx', 05:10:27 INFO - 'cart']}, 05:10:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'talos_options': ['--disable-e10s'], 05:10:27 INFO - 'tests': ['tp5o']}, 05:10:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'tests': ['tp5o']}, 05:10:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'talos_options': ['--disable-e10s', 05:10:27 INFO - '--xperf_path', 05:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:27 INFO - 'tests': ['tp5n']}, 05:10:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:27 INFO - 'talos_options': ['--xperf_path', 05:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:27 INFO - 'tests': ['tp5n']}}, 05:10:27 INFO - 'talos.zip': {'path': '', 05:10:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:10:27 INFO - Running post-action listener: _resource_record_post_action 05:10:27 INFO - ##### 05:10:27 INFO - ##### Running create-virtualenv step. 05:10:27 INFO - ##### 05:10:27 INFO - Running pre-action listener: _resource_record_pre_action 05:10:27 INFO - Running main action method: create_virtualenv 05:10:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:10:27 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:10:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:10:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:10:27 INFO - Using real prefix '/tools/python27' 05:10:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:10:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:10:31 INFO - Installing pip.................done. 05:10:31 INFO - Return code: 0 05:10:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:31 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:10:31 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:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:31 INFO - 'HOME': '/Users/cltbld', 05:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:31 INFO - 'LOGNAME': 'cltbld', 05:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:31 INFO - 'MOZ_NO_REMOTE': '1', 05:10:31 INFO - 'NO_EM_RESTART': '1', 05:10:31 INFO - 'PAGER': '/bin/cat', 05:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:31 INFO - 'PWD': '/builds/slave/test', 05:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:31 INFO - 'SHELL': '/bin/bash', 05:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:31 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:31 INFO - 'USER': 'cltbld', 05:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:31 INFO - 'XPC_FLAGS': '0x0', 05:10:31 INFO - 'XPC_SERVICE_NAME': '0', 05:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:31 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:10:31 INFO - Cleaning up... 05:10:31 INFO - Return code: 0 05:10:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:31 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:10:31 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:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:31 INFO - 'HOME': '/Users/cltbld', 05:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:31 INFO - 'LOGNAME': 'cltbld', 05:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:31 INFO - 'MOZ_NO_REMOTE': '1', 05:10:31 INFO - 'NO_EM_RESTART': '1', 05:10:31 INFO - 'PAGER': '/bin/cat', 05:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:31 INFO - 'PWD': '/builds/slave/test', 05:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:31 INFO - 'SHELL': '/bin/bash', 05:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:31 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:31 INFO - 'USER': 'cltbld', 05:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:31 INFO - 'XPC_FLAGS': '0x0', 05:10:31 INFO - 'XPC_SERVICE_NAME': '0', 05:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:31 INFO - Downloading/unpacking psutil>=0.7.1 05:10:31 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:10:31 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:10:31 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:10:31 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:10:31 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:10:31 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:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:10:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:34 INFO - Installing collected packages: psutil 05:10:34 INFO - Running setup.py install for psutil 05:10:34 INFO - building 'psutil._psutil_osx' extension 05:10:34 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:10:35 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:10:35 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:10:35 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:10:35 INFO - building 'psutil._psutil_posix' extension 05:10:35 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:10:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:10:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:10:35 INFO - ^ 05:10:35 INFO - 1 warning generated. 05:10:35 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:10:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:35 INFO - Successfully installed psutil 05:10:35 INFO - Cleaning up... 05:10:36 INFO - Return code: 0 05:10:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:10: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:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:36 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:36 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:10:36 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:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:36 INFO - 'HOME': '/Users/cltbld', 05:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:36 INFO - 'LOGNAME': 'cltbld', 05:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:36 INFO - 'MOZ_NO_REMOTE': '1', 05:10:36 INFO - 'NO_EM_RESTART': '1', 05:10:36 INFO - 'PAGER': '/bin/cat', 05:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:36 INFO - 'PWD': '/builds/slave/test', 05:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:36 INFO - 'SHELL': '/bin/bash', 05:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:36 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:36 INFO - 'USER': 'cltbld', 05:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:36 INFO - 'XPC_FLAGS': '0x0', 05:10:36 INFO - 'XPC_SERVICE_NAME': '0', 05:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:10:36 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:10:36 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:10:36 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:10:36 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:10:36 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:10:36 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:10:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:10:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:10:38 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:10:38 INFO - Installing collected packages: mozsystemmonitor 05:10:38 INFO - Running setup.py install for mozsystemmonitor 05:10:38 INFO - Successfully installed mozsystemmonitor 05:10:38 INFO - Cleaning up... 05:10:39 INFO - Return code: 0 05:10:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:39 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:39 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:10:39 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:10:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:39 INFO - 'HOME': '/Users/cltbld', 05:10:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:39 INFO - 'LOGNAME': 'cltbld', 05:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:39 INFO - 'MOZ_NO_REMOTE': '1', 05:10:39 INFO - 'NO_EM_RESTART': '1', 05:10:39 INFO - 'PAGER': '/bin/cat', 05:10:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:39 INFO - 'PWD': '/builds/slave/test', 05:10:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:39 INFO - 'SHELL': '/bin/bash', 05:10:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:39 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:39 INFO - 'USER': 'cltbld', 05:10:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:39 INFO - 'XPC_FLAGS': '0x0', 05:10:39 INFO - 'XPC_SERVICE_NAME': '0', 05:10:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:10:39 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:10:39 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:10:39 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:10:39 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:10:39 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:10:39 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:10:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10: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:10: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:10: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:10: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:10: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:10: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:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:42 INFO - Downloading docopt-0.6.1.tar.gz 05:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:42 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:42 INFO - Running setup.py install for blobuploader 05:10:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:43 INFO - Running setup.py install for requests 05:10:43 INFO - Running setup.py install for docopt 05:10:43 INFO - Successfully installed blobuploader requests docopt 05:10:43 INFO - Cleaning up... 05:10:43 INFO - Return code: 0 05:10:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:44 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:44 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:10:44 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:10:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:44 INFO - 'HOME': '/Users/cltbld', 05:10:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:44 INFO - 'LOGNAME': 'cltbld', 05:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:44 INFO - 'MOZ_NO_REMOTE': '1', 05:10:44 INFO - 'NO_EM_RESTART': '1', 05:10:44 INFO - 'PAGER': '/bin/cat', 05:10:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:44 INFO - 'PWD': '/builds/slave/test', 05:10:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:44 INFO - 'SHELL': '/bin/bash', 05:10:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:44 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:44 INFO - 'USER': 'cltbld', 05:10:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:44 INFO - 'XPC_FLAGS': '0x0', 05:10:44 INFO - 'XPC_SERVICE_NAME': '0', 05:10:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:44 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-9SBOci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:44 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-2YaMKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:44 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-l7PGzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:44 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-t_fuTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:44 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ycc1zE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-KydHK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-8RoavG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-yoaEQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ODLPl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-BSXbD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-kd9q2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Fwr_be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-XjDfMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-_fKDNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:45 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-vaPX9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:46 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-E6L6v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:46 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-stf3HN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:46 INFO - Running setup.py install for manifestparser 05:10:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:46 INFO - Running setup.py install for mozcrash 05:10:46 INFO - Running setup.py install for mozdebug 05:10:46 INFO - Running setup.py install for mozdevice 05:10:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:10:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:46 INFO - Running setup.py install for mozfile 05:10:47 INFO - Running setup.py install for mozhttpd 05:10:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Running setup.py install for mozinfo 05:10:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Running setup.py install for mozInstall 05:10:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Running setup.py install for mozleak 05:10:47 INFO - Running setup.py install for mozlog 05:10:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Running setup.py install for moznetwork 05:10:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:47 INFO - Running setup.py install for mozprocess 05:10:48 INFO - Running setup.py install for mozprofile 05:10:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:48 INFO - Running setup.py install for mozrunner 05:10:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:48 INFO - Running setup.py install for mozscreenshot 05:10:48 INFO - Running setup.py install for moztest 05:10:48 INFO - Running setup.py install for mozversion 05:10:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:48 INFO - Cleaning up... 05:10:48 INFO - Return code: 0 05:10:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:48 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:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:48 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:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:48 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:48 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:10:48 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:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:48 INFO - 'HOME': '/Users/cltbld', 05:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:48 INFO - 'LOGNAME': 'cltbld', 05:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:48 INFO - 'MOZ_NO_REMOTE': '1', 05:10:48 INFO - 'NO_EM_RESTART': '1', 05:10:48 INFO - 'PAGER': '/bin/cat', 05:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:48 INFO - 'PWD': '/builds/slave/test', 05:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:48 INFO - 'SHELL': '/bin/bash', 05:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:48 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:48 INFO - 'USER': 'cltbld', 05:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:48 INFO - 'XPC_FLAGS': '0x0', 05:10:48 INFO - 'XPC_SERVICE_NAME': '0', 05:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-pHGRWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-iu3C5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-lUJYX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-BUF4Op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-3mqnl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-yONZtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-VBOGeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-GXKeqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:49 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:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-43VeuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-bIHJVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-VDUk9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-eMrGcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-RMELm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-QR6hIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PRm5Vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-FJkxFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:50 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:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-o7VW6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:50 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:10:50 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:10:50 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:10:50 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:10:50 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:10:50 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:10:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:53 INFO - Downloading blessings-1.6.tar.gz 05:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:10:53 INFO - Installing collected packages: blessings 05:10:53 INFO - Running setup.py install for blessings 05:10:53 INFO - Successfully installed blessings 05:10:53 INFO - Cleaning up... 05:10:54 INFO - Return code: 0 05:10:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:54 INFO - Reading from file tmpfile_stdout 05:10:54 INFO - Current package versions: 05:10:54 INFO - blessings == 1.6 05:10:54 INFO - blobuploader == 1.2.4 05:10:54 INFO - docopt == 0.6.1 05:10:54 INFO - manifestparser == 1.1 05:10:54 INFO - mozInstall == 1.12 05:10:54 INFO - mozcrash == 0.17 05:10:54 INFO - mozdebug == 0.1 05:10:54 INFO - mozdevice == 0.48 05:10:54 INFO - mozfile == 1.2 05:10:54 INFO - mozhttpd == 0.7 05:10:54 INFO - mozinfo == 0.9 05:10:54 INFO - mozleak == 0.1 05:10:54 INFO - mozlog == 3.1 05:10:54 INFO - moznetwork == 0.27 05:10:54 INFO - mozprocess == 0.22 05:10:54 INFO - mozprofile == 0.28 05:10:54 INFO - mozrunner == 6.11 05:10:54 INFO - mozscreenshot == 0.1 05:10:54 INFO - mozsystemmonitor == 0.0 05:10:54 INFO - moztest == 0.7 05:10:54 INFO - mozversion == 1.4 05:10:54 INFO - psutil == 3.1.1 05:10:54 INFO - requests == 1.2.3 05:10:54 INFO - wsgiref == 0.1.2 05:10:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:54 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:54 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:10:54 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:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:54 INFO - 'HOME': '/Users/cltbld', 05:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:54 INFO - 'LOGNAME': 'cltbld', 05:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:54 INFO - 'MOZ_NO_REMOTE': '1', 05:10:54 INFO - 'NO_EM_RESTART': '1', 05:10:54 INFO - 'PAGER': '/bin/cat', 05:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:54 INFO - 'PWD': '/builds/slave/test', 05:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:54 INFO - 'SHELL': '/bin/bash', 05:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:54 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:54 INFO - 'USER': 'cltbld', 05:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:54 INFO - 'XPC_FLAGS': '0x0', 05:10:54 INFO - 'XPC_SERVICE_NAME': '0', 05:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 INFO - Cleaning up... 05:10:54 INFO - Return code: 0 05:10:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:54 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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10:54 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:10:54 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:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:54 INFO - 'HOME': '/Users/cltbld', 05:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:54 INFO - 'LOGNAME': 'cltbld', 05:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:54 INFO - 'MOZ_NO_REMOTE': '1', 05:10:54 INFO - 'NO_EM_RESTART': '1', 05:10:54 INFO - 'PAGER': '/bin/cat', 05:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:54 INFO - 'PWD': '/builds/slave/test', 05:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:54 INFO - 'SHELL': '/bin/bash', 05:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:54 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:54 INFO - 'USER': 'cltbld', 05:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:54 INFO - 'XPC_FLAGS': '0x0', 05:10:54 INFO - 'XPC_SERVICE_NAME': '0', 05:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:54 INFO - Downloading/unpacking jsonschema 05:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:54 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:10:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:10: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:10: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:10: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:10: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:10: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:10: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:10:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:58 INFO - warning: no files found matching '*.txt' 05:10:58 INFO - no previously-included directories found matching 'build' 05:10:58 INFO - no previously-included directories found matching 'dist' 05:10:58 INFO - no previously-included directories found matching '.git*' 05:10:58 INFO - Installing collected packages: jsonschema, functools32 05:10:58 INFO - Running setup.py install for functools32 05:10:58 INFO - warning: no files found matching '*.txt' 05:10:58 INFO - no previously-included directories found matching 'build' 05:10:58 INFO - no previously-included directories found matching 'dist' 05:10:58 INFO - no previously-included directories found matching '.git*' 05:10:58 INFO - Successfully installed jsonschema functools32 05:10:58 INFO - Cleaning up... 05:10:59 INFO - Return code: 0 05:10:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:10: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:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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 0x107f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f83420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6324dd870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, '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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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:10: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:10: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:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:59 INFO - 'HOME': '/Users/cltbld', 05:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:59 INFO - 'LOGNAME': 'cltbld', 05:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:59 INFO - 'MOZ_NO_REMOTE': '1', 05:10:59 INFO - 'NO_EM_RESTART': '1', 05:10:59 INFO - 'PAGER': '/bin/cat', 05:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:59 INFO - 'PWD': '/builds/slave/test', 05:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:59 INFO - 'SHELL': '/bin/bash', 05:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:10:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:59 INFO - 'USER': 'cltbld', 05:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:59 INFO - 'XPC_FLAGS': '0x0', 05:10:59 INFO - 'XPC_SERVICE_NAME': '0', 05:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:59 INFO - Downloading/unpacking flake8 05:10: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:10: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:10: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:10: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:10: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:10: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:11:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:11:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:11:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:11: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:11: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:11: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:11: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:11: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:11: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:11:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:11: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:11: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:11: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:11: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:11: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:11: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:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:02 INFO - no previously-included directories found matching 'docs/_build' 05:11:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:11:03 INFO - Running setup.py install for pep8 05:11:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:03 INFO - no previously-included directories found matching 'docs/_build' 05:11:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:11:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:11:03 INFO - Cleaning up... 05:11:03 INFO - Return code: 0 05:11:03 INFO - Running post-action listener: _resource_record_post_action 05:11:03 INFO - Running post-action listener: _start_resource_monitoring 05:11:03 INFO - Starting resource monitoring. 05:11:03 INFO - ##### 05:11:03 INFO - ##### Running install step. 05:11:03 INFO - ##### 05:11:03 INFO - Running pre-action listener: _resource_record_pre_action 05:11:03 INFO - Running main action method: install 05:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:03 INFO - Reading from file tmpfile_stdout 05:11:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:03 INFO - Reading from file tmpfile_stdout 05:11:03 INFO - Output received: 05:11:03 INFO - Usage: mozinstall [options] installer 05:11:03 INFO - Options: 05:11:03 INFO - -h, --help show this help message and exit 05:11:03 INFO - -d DEST, --destination=DEST 05:11:03 INFO - Directory to install application into. [default: 05:11:03 INFO - "/builds/slave/test"] 05:11:03 INFO - --app=APP Application being installed. [default: firefox] 05:11:03 INFO - mkdir: /builds/slave/test/build/application 05:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:11:37 INFO - Reading from file tmpfile_stdout 05:11:37 INFO - Output received: 05:11:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:11:37 INFO - Running post-action listener: _resource_record_post_action 05:11:37 INFO - ##### 05:11:37 INFO - ##### Running run-tests step. 05:11:37 INFO - ##### 05:11:37 INFO - Running pre-action listener: _resource_record_pre_action 05:11:37 INFO - Running main action method: run_tests 05:11:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:11:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:11:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:11:37 INFO - 2016-04-14 05:11:37.122 screenresolution[1647:7710] starting screenresolution argv=screenresolution get 05:11:37 INFO - 2016-04-14 05:11:37.124 screenresolution[1647:7710] Display 0: 1600x1200x32@60 05:11:37 INFO - 2016-04-14 05:11:37.130 screenresolution[1648:7712] starting screenresolution argv=screenresolution list 05:11:37 INFO - Available Modes on Display 0 05:11:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:11:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:11:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:11:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:11:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:11:37 INFO - Intel Iris: 05:11:37 INFO - Chipset Model: Intel Iris 05:11:37 INFO - Type: GPU 05:11:37 INFO - Bus: Built-In 05:11:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:11:37 INFO - Vendor: Intel (0x8086) 05:11:37 INFO - Device ID: 0x0a2e 05:11:37 INFO - Revision ID: 0x0009 05:11:37 INFO - Displays: 05:11:37 INFO - Display: 05:11:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:11:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:11:37 INFO - Display Serial Number: 1600x1200 60 05:11:37 INFO - Main Display: Yes 05:11:37 INFO - Mirror: Off 05:11:37 INFO - Online: Yes 05:11:37 INFO - Rotation: Supported 05:11:37 INFO - Return code: 0 05:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:37 INFO - Python 2.7.3 05:11:37 INFO - Return code: 0 05:11:37 INFO - grabbing minidump binary from tooltool 05:11:37 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:11:37 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 0x107e12e00>, '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 0x107c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e0db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:37 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:11:37 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:11:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:37 INFO - Return code: 0 05:11:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:11:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:11:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:11:38 INFO - Return code: 0 05:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --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:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0Mf78Wgz9P/Render', 05:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:38 INFO - 'HOME': '/Users/cltbld', 05:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:38 INFO - 'LOGNAME': 'cltbld', 05:11:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:38 INFO - 'MOZ_NO_REMOTE': '1', 05:11:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:38 INFO - 'NO_EM_RESTART': '1', 05:11:38 INFO - 'PAGER': '/bin/cat', 05:11: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:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:38 INFO - 'PWD': '/builds/slave/test', 05:11:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:38 INFO - 'SHELL': '/bin/bash', 05:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u6cZ9n6b2I/Listeners', 05:11:38 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 05:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:38 INFO - 'USER': 'cltbld', 05:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:38 INFO - 'XPC_FLAGS': '0x0', 05:11:38 INFO - 'XPC_SERVICE_NAME': '0', 05:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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:11:38 INFO - mozversion application_buildid: 20160414032548 05:11:38 INFO - mozversion application_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:11:38 INFO - mozversion application_display_name: Nightly 05:11:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:38 INFO - mozversion application_name: Firefox 05:11:38 INFO - mozversion application_remotingname: firefox 05:11:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:11:38 INFO - mozversion application_vendor: Mozilla 05:11:38 INFO - mozversion application_version: 48.0a1 05:11:38 INFO - mozversion platform_buildid: 20160414032548 05:11:38 INFO - mozversion platform_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:11:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:11:38 INFO - mozversion platform_version: 48.0a1 05:11:38 INFO - using testdate: 1460635898 05:11:38 INFO - actual date: 1460635898 05:11:38 INFO - starting webserver on 'localhost:49276' 05:11:38 INFO - SUITE-START | Running 2 tests 05:11:38 INFO - TEST-START | dromaeo_css 05:11:38 INFO - operating with platform_type : mac_ 05:11:38 INFO - Initialising browser for dromaeo_css test... 05:11:38 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpI4NEsS/profile http://localhost:49276/getInfo.html) 05:11:41 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:11:41 INFO - PROCESS | 1666 | colorDepth:24 05:11:41 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:11:41 INFO - PROCESS | 1666 | __metrics 05:11:41 INFO - PROCESS | 1666 | 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:11:41 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:11:41 INFO - TEST-INFO | 1666: exit 0 05:11:41 INFO - Browser initialized. 05:11:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:41 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpI4NEsS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:53 INFO - PROCESS | 1668 | RSS: Main: 237236224 05:11:53 INFO - PROCESS | 1668 | 05:12:45 INFO - PROCESS | 1668 | 1460635965384 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:14:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 05:14:57 INFO - PROCESS | 1668 | RSS: Main: 358785024 05:14:57 INFO - PROCESS | 1668 | 05:17:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 05:18:00 INFO - PROCESS | 1668 | RSS: Main: 380887040 05:18:00 INFO - PROCESS | 1668 | 05:21:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 05:21:03 INFO - PROCESS | 1668 | RSS: Main: 378195968 05:21:03 INFO - PROCESS | 1668 | 05:24:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 05:24:05 INFO - PROCESS | 1668 | RSS: Main: 379224064 05:24:05 INFO - PROCESS | 1668 | 05:27:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 05:27:09 INFO - PROCESS | 1668 | RSS: Main: 343048192 05:27:09 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 05:30:12 INFO - PROCESS | 1668 | RSS: Main: 384581632 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | __start_tp_report 05:30:12 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:30:12 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:30:12 INFO - PROCESS | 1668 | |i|pagename|runs| 05:30:12 INFO - PROCESS | 1668 | |0;dojo.html;3409.147713071732;3444.3627928833153;3472.7916325020515;3482.878099266525;3512.8419221135046;5550.888982220353;5625.606207565472;5652.915206271904;5673.5177509911655;5678.46054624811;5615.354234263061;5673;5675.460831221038;5679.176519404684;5686.516646085088;5123.282740416354;5133.640645154841;5162.406323272815;5171.5862730981435;5215.374155101393;4447.355133505656;5122.743862806863;5210.2445145453885;5215.426303106655;5234.816781412632;5171.793128274864;5223.973880130594;5225.320708307933;5232.869178270555;5242.6330156889035;5151.278820965068;5219.947800521984;5221.6083793715625;5233.555147812441;5281.36623605166;5075.467075957045;5093.363329583802;5118.846434606968;5124.8206312778875;5128.974355128201;5618.325800903876;5641.746121424505;5652.236948012007;5655.434456554352;5683.602147849652;6178.752849885999;6229.034499652561;6238.189035425411;6241.157443745082;6241.531885108635;6098.780243951228;6174.969125154345;6240.31356550779;6264.123022776815;6283.120363149163;5752.194692743019;5810.389909059572;5826.563007774434;5826.76692932278;5843.707814609295;6247.750089996349;6254.74981000764;6258.15514905491;6262.467690246289;6262.874742505124;5099.745012749348;6016.578839481194;6100.762843515373;6118.694065296718;6130.816075517741;6222.004479283293;6246.656433896179;6256.0303153011355;6259.405356491126;6267.62394256346;6140.986737188314;6180.103884936659;6181.505479561714;6197.566170368031;6209.441150296495;6096.69516524172;6138.51101655979;6150.892839288881;6167.444929956236;6186.1648677427975;4871.95128048715;4912;4921.827736029221;4924.63065270099;4928.359313289249;4421.40311058003;4432.667549933768;4466.062126953311;4486.102779444124;4539.16025535277;5669.461401166883;5679.971600142056;5686.658800471985;5696.51579615729;5700.173474846124;6163.476104531165;6294.055891616203;6299.433051025429;6315.052742088724;6319.273283572448;4695.5539223773685;5654.293213348332;5656.575756818255;5707.0583353747;5707.857303567361;5371.489708477767;5374.838754837361;5386.48828361305;5388.272583201296;5421.322334708161;6185.226846644169;6289.874202516015;6332.525060620472;6337.588056776295;6352.841178970562;5171.534561889448;6284.402981716729;6284.905726414199;6306.653134077486;6310.716017779303;6220.377962203744;6327.810165695037;6336.20797400325;6339.207599050119;6347.841303967438;6093.847653808513;6113.233070740737;6135.171422439487;6195.690215489118;6203.193584833943;5323.520883120537;5444.292242008513;6121.0262130506535;6234.75060997555;6270.404311590391;5767.711614419179;6117.204763380732;6123.8162855114415;6128.142060111499;6134.478569321657;5957.463828255304;6013.278406591237;6067.87744267312;6126.479249263863;6147.72335244924;5939.376365481735;5997.880042399215;6006.639601623917;6045.335013148462;6071.635701857903;6088.4824789893355;6107.206063211754;6111.419415155553;6118.942928706938;6154.230721159855;5822.650640961556;5903.173555702291;5964.522838172849;5980.671063091571;6009.45914867664 05:30:12 INFO - PROCESS | 1668 | |1;ext.html;495.7669895149279;502.1288065206868;530.482779290192;535.799075346745;539.0001547130073;25331.11341103062;36613;37778.055548611264;37794.8110259449;37801.05497362561;40887;41265.58734412655;41348.79325603375;41528.37707434391;41551.792241038755;8287.212714792115;9309.16217540423;9562.474063926482;9562.521873906311;9590.376625519353;8462.576871156449;8621.827563448727;8625.827483450326;11182.720431989184;11555.826662600068;11217.943910280437;11331.603393881216;11341.489632966537;11342.886571134306;11349.829752553676;9977.15194208493;11424.543018279259;11435.371054592035;11436.942815285953;11456.541738330458;8350.665973361058;11220.551177952953;11522.76954460906;11542.711432214177;11546.53813847445;33463.66536334654;36004.239321708665;38379.23241535181;38520;38572;3170.5878235829427;3176.237702951275;3226.516022596606;3236.6925142111463;3395.5246265522846;28129.57805632917;28655.57016644752;28697;28962.855185724147;28991.56512652291;2254.7407048189502;2674.211107723226;2682.369643133862;2711.498372801038;2733.1253998720417;471347;471513;472652;472759;473304;59837;60040;60440;60519.39480605226;60546;63660;65833.67083164553;67725;67738.5487596364;67854.66072669704;16285.674286514204;16519;16544.917275413787;16550.006999579822;16569.25438355277;11682.532698692126;12706.174098683741;12792.360381980863;12794.552190673281;12803.359832008362;16591.17044147812;19266.903665481583;19779.802201978084;20026.399208023784;20221.988900554912;6948.27043160471;8192;8268.173182681683;8280.958595206985;8294.336453083739;953.2135987810084;1061.6761887624348;1068.5725709716176;1069.5240617924949;1103.2883789955433;22653.093876245097;25549.59477228733;26881.596776048376;26915.461690766075;27034.91860325565;919.6643225222746;1082.8159212933663;1110.900018998294;1123.5898896902572;1179.3926128044063;12594.559190428292;15075.623109422353;15086.396544138332;15476;15487.457938972082;602264;611220;611344;612120.8787912152;617491;58182.70908645514;59363.40636593665;59478.70260648669;59573.40426595765;59576;1315.4869600855675;1442.163545143814;1464.5240296903548;1488.8138982627172;1500.7073620644087;24770.752292477566;26757.331066723487;28003.579946300415;28654.57018144771;28965.565516516846;33614.49578256377;45640.54359456363;45707.54292457166;45848;45898.85252868705;344498;345350;346194;346411.53588464804;348339;25542.669791091765;26146.86926565355;26590;26603.33491662724;26699.599506007813;45497.31754023626;45908.770456147504;46288.53711462842;46738;47177;36356.454653179695;37409.43885841769;38211.42682859815;38343;38800.223995519365;36704.449433257985;37108.94929963299;37323.81338093401;38183.427248590735;38575.6142438572;36418.8179059103;36530.452043219906;36670;36806.63193368032;37548.616735154545;30366.39267214599;32534.83732581322;32649.34701306008;32697.673023269464;32725.672743272262;23850.64224036606;23979.76020239845;24271.029158833448;24963.750362496143;25622.61566076473 05:30:12 INFO - PROCESS | 1668 | |2;jquery.html;396.7262588813719;396.97618142911426;399.7241903086872;401.68869126427035;410.58530883807344;18233.452996410128;18318.633627327446;19119.71320430195;19251;19262.90368548157;18381.264749410042;18434;19086.61826763464;19112.610923634547;19651;4820.879478013043;5487.588430867681;5684.886302273952;5832;5852.795152169675;4962.801487940478;5786.669066114796;5790.449907258803;5804.158397032428;5852.531797456193;5657.434256574351;5687.943120568803;5697.6866272355;5703.486686198241;5798.594098413092;5612.158176273571;5708.086706127041;5745.856353591152;5772.913406298909;5797.188393624917;5503.862403439906;5669.716514174274;5712.6286791358425;5714.71426428681;5717.228174196472;12886.420111095023;13314.667133321647;16555.834441655672;24079.638805417933;24092.397690057714;2030.857839951204;2045.9386218413472;2147.742270927483;2235.6870038194666;2235.7876001779805;7586;9416.576254068583;9659.613615455373;9961.65134220306;9970.601175952954;1699.7195462748632;1736.722124460093;1779.830916062972;1787.356551641408;1804.296324433472;105459;107834.92165078328;112615.43692281569;115716.97557223719;116949;10635.308704934308;11270.887291126985;11317.773644527228;11411.372374519315;11436.311112443858;14230.501932432522;14298;14311.425743168244;14343.067700599428;14359.641008974859;8218.835623287501;8287.875681864776;8316.501009939422;8331.8333633327;8410.537420441935;8279.172082791792;8284.875726864102;8391;8397.454165479225;8401.453905496122;4431.578999994996;4524.990881064055;4528.437688997291;4537.115262523842;4582.198115329804;1783.8126996665424;1883.1282294500995;1941.9223231070598;1946.688529835248;1987.8807271563753;904.6291020681489;941.7551436626527;953.2088366655797;956.4404823178379;1007.2646967713611;18498.35255766042;19496.025198740008;19587.51031224231;19710.802891970896;19796.406107816787;882.2677177942293;973.2603221551531;981.6417007792104;990.00999000999;1024.3085917005992;9981.650642227632;10085.546150423248;10162.644307449204;10166.440845753554;10258.384496930208;592980;599415;606370;607934;620129;121622;124256;125870;126091.3695431517;126409.36795316149;996.5864166370875;1007.5365331947386;1037.7716902281486;1041.8697662792151;1042.8957104289661;16945.576360591083;17439.912800436425;17555;17591.82408175902;18180.909145450787;35300.82349588236;40242;41530.58469415267;46697.766511168586;51470.48529514657;69706;71434.2141446468;72365.27634723796;73238;74198.62900685462;6058.939410605837;7729.961350193213;7843.568603726621;7948;8136.674533018613;11314.264572802742;11584.94207528957;11882.643520694393;12172.634820955385;12244.75510489803;9551.522423878918;10535.63125290611;10610.416427096276;10634.042936135476;10793.29843560166;10264.846027309446;10332.793344133226;10348.637797677045;10485.21360897951;10629.681109566725;5848.853778655397;8979.653052042246;10504.36973781545;10551.94724026375;10634.361938283728;7663.770086897402;7916;8062.838743224986;8082.919170808452;8086.1509541499645;8160.26557609818;9948.900510995087;10519.474026298807;10857.077148442155;10867.402292873969 05:30:12 INFO - PROCESS | 1668 | |3;mootools.html;1421.6872288096615;1594.9553042756995;1621.7324141516651;1878.8496920246373;2014.1842553765712;7232.8553428931455;7914.010748656418;8856.247218986378;8856.734297971072;8872.556372181398;8453.28147107495;8697.391182617219;8698.478091314526;8719.694810681627;8737.126287371275;2137.8289736821093;2209.812165965895;2214.82281417487;2393.7845593896504;2428.6842710447618;2213.1921848525253;2375.4773949731116;2455.26342097371;2458.3485376375274;2460.409501719583;2392.306231192952;2415.8912848921755;2421.5520128776143;2422.4307287787356;2424.903003879843;2112.8732276063333;2394.197943688858;2412.6863507744056;2414.9637755433682;2417.2023232333286;2357;2437.8049756019655;2438.0491608272787;2441.2554170977846;2446.5963116085823;8586.613602387908;8659.696910608098;8676.479411235347;8735.388522803407;8885.644574217024;5084.161113416282;5250.6324557281005;5264.842054738364;5314;5328.49379308965;3329.783564068352;3444.6555344465355;3511.20997775501;3562.6259242779392;3569.839357228905;964.5804075227298;1015.8628585140985;1019.3323373190572;1025.876665051767;1028.5217373921162;11708.12189085822;11721.472533736003;11724.88275117255;11727.061835053175;11746.64760057191;4623.398958135488;4691.601213896857;4847.75761211938;4858.222684370484;4863.781129849165;9969.80060398788;9972.850407243897;9984.80030399388;10001.249906256915;10018.649347272956;5350.250964864922;5372.382176049726;5374.3013408256675;5389.461053894579;5390;4829.710217386898;4889.657723959289;4895.681780684244;4905.656604037683;4912.238603016538;3612.4039533477207;3701.0192299040777;3736.551613806361;3743.887683369503;3754.7934863582764;1313.5008696695195;1314.3691028306275;1315.5527120778981;1319.1755153029358;1348.1169833758904;781.2148790465545;879.2262808728406;898.3666515106834;898.4384759525296;951.5860600638745;5982.46157845796;5986.102084687332;6038.516918646497;6140.140380346755;6231.813045608548;875.1685898396548;886.5522910930068;919.6827094652334;932.6455946740195;970.0590427285522;3787.981060094737;3885.1646895917515;3981.402789581528;4015.13674559971;4023.8591649292134;9907.207423406111;10917.39954302505;10917.672469825919;10944.23390362683;10959;10121.595136194617;10716.624918127829;10871.83692244617;10885.401302928414;10902.781944361068;8125.553094579855;8298.419110662197;8303.501789892505;8313.875291870627;8315.542645154575;4987.401511818605;5757.222774925499;5939.881202376015;5966.791162309472;6107.633541987495;9833.85249221248;9971.950140249251;10023.749406264902;10080.848787268344;10142.340747851365;7807.687692492236;9167.083291671046;9698.36960597559;10358.378497290187;10371.58513659445;7312.41500679955;7363.963180184065;7446.218147094477;7452.292032256927;7526.209747976383;8070.2333278338465;8096.676132954616;8106.675732970615;8167.673293068211;8174.7956301092945;7185.425165986813;7235.204127546071;7275.890861636974;7299.562026278228;7368.742094026683;7042.366187043191;7147.2852714728115;7158.642067896689;7290.3438690518105;7393.186749457663;7000.50996430245;7053.4004609608755;7127;7128.073350464407;7144.4641651877355;4706.62347012245;4729.077829823152;4830.347903033045;4885.291632713308;4914.606831453547;4863.076015557032;4923.803047878045;4929.827456039022;4981.4769449207315;5117.8720531987 05:30:12 INFO - PROCESS | 1668 | |4;prototype.html;316.27257308191156;320.43923134514597;324.90740139060364;328.74193757900053;334.5650654149605;5049.671771334861;5743.741531631076;5816.592838501307;5817.389174136718;5823.796167134151;5731.770729170838;5743.167240750089;5763.711814409284;5769.682667453289;5777.855553611162;4142.544320124784;4232.238197124517;4286.121345124242;4352.107817897339;4438.644908407319;3905.3360928642055;4254.425652536899;4350.673699472536;4396;4410.71330363527;3780.1305699689087;4212.557681443435;4381.123775244948;4384.539623339551;4395.9889225478155;4103.671706263507;4156.896077598054;4389.2318844202455;4391.165678521103;4397.824087036547;3364.798112113281;3781.5840257571645;3797.9240415191816;3799.4490798834013;3809.6380843819793;5530.5299049580835;5534.149912794914;5546.611737178358;5549.002359150702;5554.111342185231;4221.416968636761;4877.975610121962;5061.342025536656;5088.618353623456;5123.974380128113;5259.526642602184;6009.639421634716;6049.879002419971;6066.484348830355;6109.755609775603;3800.885973420774;4036.6367026967714;4188.832446702158;4219.578042195786;4222.472190976128;4543.229119830566;6404.231492220963;6410.935890641081;6442.742290308382;6457.773977910798;3792.3363411402893;4270.466191726035;4299.462567179103;4309.353596960481;4318.481782186158;4378.759168245713;4385.978070109629;4398.186335527937;4399.5820397062225;4402.625776808943;4362.847300344472;4801.231802911518;4805.821135459828;5047.116754567936;5069.087564238473;5354.678719276856;5842.2405087338375;6134.141220229171;6143.447089761943;6170.10533472644;1449.4854326714076;1491.8955673102778;1512.8880272999336;1523.223156190351;1529.3118096856238;752.9924700752922;844.2486187293314;849.2526576612665;929.707142250189;962.167724917955;5169.940162266802;5759.94240057594;5787.045137552257;5793.420657934257;5797.826065218051;5337.733113344318;5495.422980587061;6286.779962701284;6383.71273292703;6442.8389290268115;4892.19278818998;5118.464460661787;5381.892362152735;5657.915131273035;5662.546996240291;3331.06730115568;3349.43059679855;3362.6637336266667;3380.9492857607156;3409.3863104641405;6369.777057802955;6593.274739778621;6620.139381880324;6684.866302674017;6709.630970296583;5410.323709536308;5463.754131064112;5473.863153421116;6522.086907832907;6577.539572229994;5538.196961440649;6519.348065193538;6580.243272023683;6641.468682505388;6654.467642588581;5745.569082318759;6359.650219237987;6365.427111559982;6370.426661600478;6411.51913606472;5762.645778242194;6246.470744029609;6254.968725156527;6297.897867873012;6312.096435639473;5331;6073.726682299219;6340.302566717565;6618.631703076139;6669.833254168685;4275.187714334391;4313.590208930146;4320.9135817284105;4353.891152721208;4360.585744354281;5582.469665381782;6293.402126797947;6301.936980630318;6336.239651241881;6369.172007638977;5800.795697634871;6090.421409966046;6097.2683278006925;6109.84725381869;6120.847763401941;4989.351384320015;6044.546659000504;6079.422454866781;6103.359147289649;6111.816645500642;4698.553637404441;5757.251557297524;5928.67392293428;6000.369961154016;6025.307089684742;5341.599380046434;6170.167027451416;6180.81457556274;6192.151610259151;6197.442230199304;5315.468453154654;6037.154798328325;6069.150318955438;6071.848203794941;6082.90875636857 05:30:12 INFO - PROCESS | 1668 | |5;yui.html;665.8701553197127;750.6171852355304;750.9812254693632;753.6985205917629;773.2422226218308;14844.109353438802;14850.92574537127;15190.54428367148;15207.84792152078;15229.4669686561;14379.06536075157;14458.060226085297;14458.349374278156;14461.987660863744;14474.059186152892;721.2354903801987;743.7062360367679;907.5054095517926;927.2026057590467;929.0848514213494;896.0457113174448;909.1363204955286;909.6770646420491;910.3809409601469;910.8269428808505;950.2967803825154;951.4053716427233;952.1430712358863;952.8475443928972;958.8513780364054;744.0178963767829;942.0626476655772;944.5749412764284;955.2119501411363;967.5307475874196;720.783764870542;724.4711360706714;724.5616402076773;725.9310365515267;729.5987207036162;14777.630559236106;14850.628734281729;14850.925745371203;14883.032602880778;14883.479078232318;12521.56174533896;12612.369381530887;12733.045021623322;12804.871951280555;12830.55093071738;9954.5022748863;9955.701328960144;10121.34211276272;10499.68500944973;10572.894271057268;3363.7981721096567;3369.309291595217;3429.9142521436916;3455.7580969332157;3479.9652003479896;82055;82239.17760822375;82504.9999999994;82557.17442825616;83307.58346208291;8672.609732561923;9272.35093543459;9503;9514.858217014003;9759.804803903882;20386.59226815485;20641.587168256552;20655.070521826252;20677.379678609665;20703.896480517502;6607.57050791697;7425.480216384801;7593.962030189924;7845.803854903559;7948.960255198687;4098.446709694213;4147.315692910696;4164.187983343219;4172.436721042681;4228.921624985609;3438.157651375429;3551.2009797795804;3558.1282585766653;3559.110222444389;3813.2373525294497;678.8065401360589;687.5977553131374;689.7344522358928;690.9689063992132;693.3343989769777;543.4945500684322;584.6579750845718;595.8361450601033;600.2047287344199;600.3906035374099;6218.412002193128;6276.278228003838;8051.63767630458;8132.75601731949;8206.794830129294;485.995140048595;552.1772558887229;562.8002059268982;605.5821483176594;608.8843119807311;3872.9419058714143;3877.689784817208;3932.9213415731524;3957.9010524737114;4038.676905847525;82625.58687206525;82967.75548366779;83911.5804420974;85794;85825;60806.47983800352;64673;70997.2900271001;72820.63589682123;73580.63209683917;64540.67729661416;65801;65923;65963;66792.3320766786;7434.479586428898;7477.775666730006;7586.3930885527925;7614.961925190339;7689.8462030757955;29717.257068573457;40282;41032.76901692954;41494.75515734533;41818.58181418269;50932.74533627308;51678.741606291725;52230.738846305525;53017.93964120773;60293.69853150882;10734.355938643706;10943.179261555257;10963.232573719763;10990.120790336594;11119.666410007712;8873.511956842436;10549;11916.642500724993;12194;12268.570600028956;11390.544378224778;11565.595204167814;11580.826287605862;11730.36066884944;11834.112441566745;11200.887991119984;11255;11267.661970140909;11405.372704501333;11531.250468719507;10955.56177752881;11110.277831940897;11136.147712632795;11183.049440797298;11494.827577586175;7665.88501172494;9235.722928312161;9343.392679475812;9372.484513351832;9836.557354919189;9235.445873247627;9287.953560232427;9301.674441394789;9485.33602647808;9500.524973751422 05:30:12 INFO - PROCESS | 1668 | __end_tp_report 05:30:12 INFO - PROCESS | 1668 | __start_cc_report 05:30:12 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1117 05:30:12 INFO - PROCESS | 1668 | __end_cc_report 05:30:12 INFO - PROCESS | 1668 | __startTimestamp1460637012830__endTimestamp 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:30:12 INFO - PROCESS | 1668 | Number of tests: 6 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5705.04 Median:5939.38 stddev:637.07 (10.7%) stddev-sans-first:613.03 05:30:12 INFO - PROCESS | 1668 | Values: 3409.1 3444.4 3472.8 3482.9 3512.8 5550.9 5625.6 5652.9 5673.5 5678.5 5615.4 5673.0 5675.5 5679.2 5686.5 5123.3 5133.6 5162.4 5171.6 5215.4 4447.4 5122.7 5210.2 5215.4 5234.8 5171.8 5224.0 5225.3 5232.9 5242.6 5151.3 5219.9 5221.6 5233.6 5281.4 5075.5 5093.4 5118.8 5124.8 5129.0 5618.3 5641.7 5652.2 5655.4 5683.6 6178.8 6229.0 6238.2 6241.2 6241.5 6098.8 6175.0 6240.3 6264.1 6283.1 5752.2 5810.4 5826.6 5826.8 5843.7 6247.8 6254.7 6258.2 6262.5 6262.9 5099.7 6016.6 6100.8 6118.7 6130.8 6222.0 6246.7 6256.0 6259.4 6267.6 6141.0 6180.1 6181.5 6197.6 6209.4 6096.7 6138.5 6150.9 6167.4 6186.2 4872.0 4912.0 4921.8 4924.6 4928.4 4421.4 4432.7 4466.1 4486.1 4539.2 5669.5 5680.0 5686.7 5696.5 5700.2 6163.5 6294.1 6299.4 6315.1 6319.3 4695.6 5654.3 5656.6 5707.1 5707.9 5371.5 5374.8 5386.5 5388.3 5421.3 6185.2 6289.9 6332.5 6337.6 6352.8 5171.5 6284.4 6284.9 6306.7 6310.7 6220.4 6327.8 6336.2 6339.2 6347.8 6093.8 6113.2 6135.2 6195.7 6203.2 5323.5 5444.3 6121.0 6234.8 6270.4 5767.7 6117.2 6123.8 6128.1 6134.5 5957.5 6013.3 6067.9 6126.5 6147.7 5939.4 5997.9 6006.6 6045.3 6071.6 6088.5 6107.2 6111.4 6118.9 6154.2 5822.7 5903.2 5964.5 5980.7 6009.5 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:61911.32 Median:173946.76 stddev:130375.54 (75.0%) stddev-sans-first:130659.73 05:30:12 INFO - PROCESS | 1668 | Values: 495.8 502.1 530.5 535.8 539.0 25331.1 36613.0 37778.1 37794.8 37801.1 40887.0 41265.6 41348.8 41528.4 41551.8 8287.2 9309.2 9562.5 9562.5 9590.4 8462.6 8621.8 8625.8 11182.7 11555.8 11217.9 11331.6 11341.5 11342.9 11349.8 9977.2 11424.5 11435.4 11436.9 11456.5 8350.7 11220.6 11522.8 11542.7 11546.5 33463.7 36004.2 38379.2 38520.0 38572.0 3170.6 3176.2 3226.5 3236.7 3395.5 28129.6 28655.6 28697.0 28962.9 28991.6 2254.7 2674.2 2682.4 2711.5 2733.1 471347.0 471513.0 472652.0 472759.0 473304.0 59837.0 60040.0 60440.0 60519.4 60546.0 63660.0 65833.7 67725.0 67738.5 67854.7 16285.7 16519.0 16544.9 16550.0 16569.3 11682.5 12706.2 12792.4 12794.6 12803.4 16591.2 19266.9 19779.8 20026.4 20222.0 6948.3 8192.0 8268.2 8281.0 8294.3 953.2 1061.7 1068.6 1069.5 1103.3 22653.1 25549.6 26881.6 26915.5 27034.9 919.7 1082.8 1110.9 1123.6 1179.4 12594.6 15075.6 15086.4 15476.0 15487.5 602264.0 611220.0 611344.0 612120.9 617491.0 58182.7 59363.4 59478.7 59573.4 59576.0 1315.5 1442.2 1464.5 1488.8 1500.7 24770.8 26757.3 28003.6 28654.6 28965.6 33614.5 45640.5 45707.5 45848.0 45898.9 344498.0 345350.0 346194.0 346411.5 348339.0 25542.7 26146.9 26590.0 26603.3 26699.6 45497.3 45908.8 46288.5 46738.0 47177.0 36356.5 37409.4 38211.4 38343.0 38800.2 36704.4 37108.9 37323.8 38183.4 38575.6 36418.8 36530.5 36670.0 36806.6 37548.6 30366.4 32534.8 32649.3 32697.7 32725.7 23850.6 23979.8 24271.0 24963.8 25622.6 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33923.12 Median:17848.77 stddev:100777.79 (564.6%) stddev-sans-first:101029.05 05:30:12 INFO - PROCESS | 1668 | Values: 396.7 397.0 399.7 401.7 410.6 18233.5 18318.6 19119.7 19251.0 19262.9 18381.3 18434.0 19086.6 19112.6 19651.0 4820.9 5487.6 5684.9 5832.0 5852.8 4962.8 5786.7 5790.4 5804.2 5852.5 5657.4 5687.9 5697.7 5703.5 5798.6 5612.2 5708.1 5745.9 5772.9 5797.2 5503.9 5669.7 5712.6 5714.7 5717.2 12886.4 13314.7 16555.8 24079.6 24092.4 2030.9 2045.9 2147.7 2235.7 2235.8 7586.0 9416.6 9659.6 9961.7 9970.6 1699.7 1736.7 1779.8 1787.4 1804.3 105459.0 107834.9 112615.4 115717.0 116949.0 10635.3 11270.9 11317.8 11411.4 11436.3 14230.5 14298.0 14311.4 14343.1 14359.6 8218.8 8287.9 8316.5 8331.8 8410.5 8279.2 8284.9 8391.0 8397.5 8401.5 4431.6 4525.0 4528.4 4537.1 4582.2 1783.8 1883.1 1941.9 1946.7 1987.9 904.6 941.8 953.2 956.4 1007.3 18498.4 19496.0 19587.5 19710.8 19796.4 882.3 973.3 981.6 990.0 1024.3 9981.7 10085.5 10162.6 10166.4 10258.4 592980.0 599415.0 606370.0 607934.0 620129.0 121622.0 124256.0 125870.0 126091.4 126409.4 996.6 1007.5 1037.8 1041.9 1042.9 16945.6 17439.9 17555.0 17591.8 18180.9 35300.8 40242.0 41530.6 46697.8 51470.5 69706.0 71434.2 72365.3 73238.0 74198.6 6058.9 7730.0 7843.6 7948.0 8136.7 11314.3 11584.9 11882.6 12172.6 12244.8 9551.5 10535.6 10610.4 10634.0 10793.3 10264.8 10332.8 10348.6 10485.2 10629.7 5848.9 8979.7 10504.4 10551.9 10634.4 7663.8 7916.0 8062.8 8082.9 8086.2 8160.3 9948.9 10519.5 10857.1 10867.4 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5679.36 Median:4919.20 stddev:3173.93 (64.5%) stddev-sans-first:3166.71 05:30:12 INFO - PROCESS | 1668 | Values: 1421.7 1595.0 1621.7 1878.8 2014.2 7232.9 7914.0 8856.2 8856.7 8872.6 8453.3 8697.4 8698.5 8719.7 8737.1 2137.8 2209.8 2214.8 2393.8 2428.7 2213.2 2375.5 2455.3 2458.3 2460.4 2392.3 2415.9 2421.6 2422.4 2424.9 2112.9 2394.2 2412.7 2415.0 2417.2 2357.0 2437.8 2438.0 2441.3 2446.6 8586.6 8659.7 8676.5 8735.4 8885.6 5084.2 5250.6 5264.8 5314.0 5328.5 3329.8 3444.7 3511.2 3562.6 3569.8 964.6 1015.9 1019.3 1025.9 1028.5 11708.1 11721.5 11724.9 11727.1 11746.6 4623.4 4691.6 4847.8 4858.2 4863.8 9969.8 9972.9 9984.8 10001.2 10018.6 5350.3 5372.4 5374.3 5389.5 5390.0 4829.7 4889.7 4895.7 4905.7 4912.2 3612.4 3701.0 3736.6 3743.9 3754.8 1313.5 1314.4 1315.6 1319.2 1348.1 781.2 879.2 898.4 898.4 951.6 5982.5 5986.1 6038.5 6140.1 6231.8 875.2 886.6 919.7 932.6 970.1 3788.0 3885.2 3981.4 4015.1 4023.9 9907.2 10917.4 10917.7 10944.2 10959.0 10121.6 10716.6 10871.8 10885.4 10902.8 8125.6 8298.4 8303.5 8313.9 8315.5 4987.4 5757.2 5939.9 5966.8 6107.6 9833.9 9972.0 10023.7 10080.8 10142.3 7807.7 9167.1 9698.4 10358.4 10371.6 7312.4 7364.0 7446.2 7452.3 7526.2 8070.2 8096.7 8106.7 8167.7 8174.8 7185.4 7235.2 7275.9 7299.6 7368.7 7042.4 7147.3 7158.6 7290.3 7393.2 7000.5 7053.4 7127.0 7128.1 7144.5 4706.6 4729.1 4830.3 4885.3 4914.6 4863.1 4923.8 4929.8 4981.5 5117.9 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4960.35 Median:5542.40 stddev:1531.53 (27.6%) stddev-sans-first:1495.64 05:30:12 INFO - PROCESS | 1668 | Values: 316.3 320.4 324.9 328.7 334.6 5049.7 5743.7 5816.6 5817.4 5823.8 5731.8 5743.2 5763.7 5769.7 5777.9 4142.5 4232.2 4286.1 4352.1 4438.6 3905.3 4254.4 4350.7 4396.0 4410.7 3780.1 4212.6 4381.1 4384.5 4396.0 4103.7 4156.9 4389.2 4391.2 4397.8 3364.8 3781.6 3797.9 3799.4 3809.6 5530.5 5534.1 5546.6 5549.0 5554.1 4221.4 4878.0 5061.3 5088.6 5124.0 5259.5 6009.6 6049.9 6066.5 6109.8 3800.9 4036.6 4188.8 4219.6 4222.5 4543.2 6404.2 6410.9 6442.7 6457.8 3792.3 4270.5 4299.5 4309.4 4318.5 4378.8 4386.0 4398.2 4399.6 4402.6 4362.8 4801.2 4805.8 5047.1 5069.1 5354.7 5842.2 6134.1 6143.4 6170.1 1449.5 1491.9 1512.9 1523.2 1529.3 753.0 844.2 849.3 929.7 962.2 5169.9 5759.9 5787.0 5793.4 5797.8 5337.7 5495.4 6286.8 6383.7 6442.8 4892.2 5118.5 5381.9 5657.9 5662.5 3331.1 3349.4 3362.7 3380.9 3409.4 6369.8 6593.3 6620.1 6684.9 6709.6 5410.3 5463.8 5473.9 6522.1 6577.5 5538.2 6519.3 6580.2 6641.5 6654.5 5745.6 6359.7 6365.4 6370.4 6411.5 5762.6 6246.5 6255.0 6297.9 6312.1 5331.0 6073.7 6340.3 6618.6 6669.8 4275.2 4313.6 4320.9 4353.9 4360.6 5582.5 6293.4 6301.9 6336.2 6369.2 5800.8 6090.4 6097.3 6109.8 6120.8 4989.4 6044.5 6079.4 6103.4 6111.8 4698.6 5757.3 5928.7 6000.4 6025.3 5341.6 6170.2 6180.8 6192.2 6197.4 5315.5 6037.2 6069.2 6071.8 6082.9 05:30:12 INFO - PROCESS | 1668 | 05:30:12 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:16944.68 Median:607.23 stddev:23350.94 (3845.5%) stddev-sans-first:23384.46 05:30:12 INFO - PROCESS | 1668 | Values: 665.9 750.6 751.0 753.7 773.2 14844.1 14850.9 15190.5 15207.8 15229.5 14379.1 14458.1 14458.3 14462.0 14474.1 721.2 743.7 907.5 927.2 929.1 896.0 909.1 909.7 910.4 910.8 950.3 951.4 952.1 952.8 958.9 744.0 942.1 944.6 955.2 967.5 720.8 724.5 724.6 725.9 729.6 14777.6 14850.6 14850.9 14883.0 14883.5 12521.6 12612.4 12733.0 12804.9 12830.6 9954.5 9955.7 10121.3 10499.7 10572.9 3363.8 3369.3 3429.9 3455.8 3480.0 82055.0 82239.2 82505.0 82557.2 83307.6 8672.6 9272.4 9503.0 9514.9 9759.8 20386.6 20641.6 20655.1 20677.4 20703.9 6607.6 7425.5 7594.0 7845.8 7949.0 4098.4 4147.3 4164.2 4172.4 4228.9 3438.2 3551.2 3558.1 3559.1 3813.2 678.8 687.6 689.7 691.0 693.3 543.5 584.7 595.8 600.2 600.4 6218.4 6276.3 8051.6 8132.8 8206.8 486.0 552.2 562.8 605.6 608.9 3872.9 3877.7 3932.9 3957.9 4038.7 82625.6 82967.8 83911.6 85794.0 85825.0 60806.5 64673.0 70997.3 72820.6 73580.6 64540.7 65801.0 65923.0 65963.0 66792.3 7434.5 7477.8 7586.4 7615.0 7689.8 29717.3 40282.0 41032.8 41494.8 41818.6 50932.7 51678.7 52230.7 53017.9 60293.7 10734.4 10943.2 10963.2 10990.1 11119.7 8873.5 10549.0 11916.6 12194.0 12268.6 11390.5 11565.6 11580.8 11730.4 11834.1 11200.9 11255.0 11267.7 11405.4 11531.3 10955.6 11110.3 11136.1 11183.0 11494.8 7665.9 9235.7 9343.4 9372.5 9836.6 9235.4 9288.0 9301.7 9485.3 9500.5 05:30:12 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:30:12 INFO - PROCESS | 1668 | 05:30:13 INFO - TEST-INFO | 1668: exit 0 05:30:13 INFO - TEST-OK | dromaeo_css | took 1114527ms 05:30:13 INFO - TEST-START | kraken 05:30:13 INFO - operating with platform_type : mac_ 05:30:13 INFO - Initialising browser for kraken test... 05:30:13 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpbbDIsV/profile http://localhost:49276/getInfo.html) 05:30:15 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 05:30:15 INFO - PROCESS | 1676 | colorDepth:24 05:30:15 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 05:30:15 INFO - PROCESS | 1676 | __metrics 05:30:15 INFO - PROCESS | 1676 | 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:30:15 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:30:16 INFO - TEST-INFO | 1676: exit 0 05:30:16 INFO - Browser initialized. 05:30:16 INFO - Running cycle 1/1 for kraken test... 05:30:16 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpbbDIsV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:28 INFO - PROCESS | 1678 | RSS: Main: 237588480 05:30:28 INFO - PROCESS | 1678 | 05:30:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:31 INFO - PROCESS | 1678 | RSS: Main: 285917184 05:30:31 INFO - PROCESS | 1678 | 05:30:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 05:30:35 INFO - PROCESS | 1678 | RSS: Main: 299237376 05:30:35 INFO - PROCESS | 1678 | 05:30:38 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 05:30:39 INFO - PROCESS | 1678 | RSS: Main: 385445888 05:30:39 INFO - PROCESS | 1678 | 05:30:41 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 05:30:41 INFO - PROCESS | 1678 | RSS: Main: 286593024 05:30:41 INFO - PROCESS | 1678 | 05:30:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:44 INFO - PROCESS | 1678 | RSS: Main: 289554432 05:30:44 INFO - PROCESS | 1678 | 05:30:47 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:48 INFO - PROCESS | 1678 | RSS: Main: 329977856 05:30:48 INFO - PROCESS | 1678 | 05:30:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:51 INFO - PROCESS | 1678 | RSS: Main: 330182656 05:30:51 INFO - PROCESS | 1678 | 05:30:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 05:30:54 INFO - PROCESS | 1678 | RSS: Main: 326963200 05:30:54 INFO - PROCESS | 1678 | 05:30:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:57 INFO - PROCESS | 1678 | RSS: Main: 319893504 05:30:57 INFO - PROCESS | 1678 | 05:30:58 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:59 INFO - PROCESS | 1678 | RSS: Main: 327413760 05:30:59 INFO - PROCESS | 1678 | 05:31:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:02 INFO - PROCESS | 1678 | RSS: Main: 330350592 05:31:02 INFO - PROCESS | 1678 | 05:31:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:06 INFO - PROCESS | 1678 | RSS: Main: 334462976 05:31:06 INFO - PROCESS | 1678 | 05:31:08 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:09 INFO - PROCESS | 1678 | RSS: Main: 332226560 05:31:09 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 05:31:11 INFO - PROCESS | 1678 | RSS: Main: 397152256 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | __start_tp_report 05:31:11 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 05:31:11 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 05:31:11 INFO - PROCESS | 1678 | |i|pagename|runs| 05:31:11 INFO - PROCESS | 1678 | |0;ai-astar;104;109;111;113;112;113;119;113;114;113 05:31:11 INFO - PROCESS | 1678 | |1;audio-beat-detection;155;122;173;203;119;142;141;127;215;174 05:31:11 INFO - PROCESS | 1678 | |2;audio-dft;186;186;187;185;188;183;187;185;185;185 05:31:11 INFO - PROCESS | 1678 | |3;audio-fft;77;72;87;91;56;61;58;78;85;55 05:31:11 INFO - PROCESS | 1678 | |4;audio-oscillator;91;96;132;81;78;80;122;79;77;125 05:31:11 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;86;84;87;84;86;83;88;85;86;88 05:31:11 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;93;93;94;93;93;94;100;92;93 05:31:11 INFO - PROCESS | 1678 | |7;imaging-desaturate;86;86;84;86;87;87;86;86;87;85 05:31:11 INFO - PROCESS | 1678 | |8;json-parse-financial;60;61;60;58;58;59;57;74;59;57 05:31:11 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;65;64;78;72;74;71;67;67;70;68 05:31:11 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;86;84;85;86;87;86;84;85;86;87 05:31:11 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;162;158;143;140;143;150;154;163;184;148 05:31:11 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;168;170;168;173;172;175;169;168;169;167 05:31:11 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;70;67;68;70;70;68;71;70;68;67 05:31:11 INFO - PROCESS | 1678 | __end_tp_report 05:31:11 INFO - PROCESS | 1678 | __start_cc_report 05:31:11 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1949 05:31:11 INFO - PROCESS | 1678 | __end_cc_report 05:31:11 INFO - PROCESS | 1678 | __startTimestamp1460637071436__endTimestamp 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | ------- Summary: start ------- 05:31:11 INFO - PROCESS | 1678 | Number of tests: 14 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.81 (3.4%) stddev-sans-first:2.69 05:31:11 INFO - PROCESS | 1678 | Values: 104.0 109.0 111.0 113.0 112.0 113.0 119.0 113.0 114.0 113.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:157.10 Median:164.00 stddev:33.46 (20.4%) stddev-sans-first:35.49 05:31:11 INFO - PROCESS | 1678 | Values: 155.0 122.0 173.0 203.0 119.0 142.0 141.0 127.0 215.0 174.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:1.42 (0.8%) stddev-sans-first:1.50 05:31:11 INFO - PROCESS | 1678 | Values: 186.0 186.0 187.0 185.0 188.0 183.0 187.0 185.0 185.0 185.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:72.00 Median:77.50 stddev:13.65 (17.6%) stddev-sans-first:14.36 05:31:11 INFO - PROCESS | 1678 | Values: 77.0 72.0 87.0 91.0 56.0 61.0 58.0 78.0 85.0 55.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:79.50 stddev:21.84 (27.5%) stddev-sans-first:23.09 05:31:11 INFO - PROCESS | 1678 | Values: 91.0 96.0 132.0 81.0 78.0 80.0 122.0 79.0 77.0 125.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.80 05:31:11 INFO - PROCESS | 1678 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 88.0 85.0 86.0 88.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:93.00 stddev:2.25 (2.4%) stddev-sans-first:2.37 05:31:11 INFO - PROCESS | 1678 | Values: 93.0 93.0 93.0 94.0 93.0 93.0 94.0 100.0 92.0 93.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 05:31:11 INFO - PROCESS | 1678 | Values: 86.0 86.0 84.0 86.0 87.0 87.0 86.0 86.0 87.0 85.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:59.50 stddev:4.99 (8.4%) stddev-sans-first:5.29 05:31:11 INFO - PROCESS | 1678 | Values: 60.0 61.0 60.0 58.0 58.0 59.0 57.0 74.0 59.0 57.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:4.30 (6.1%) stddev-sans-first:4.23 05:31:11 INFO - PROCESS | 1678 | Values: 65.0 64.0 78.0 72.0 74.0 71.0 67.0 67.0 70.0 68.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:1.07 (1.2%) stddev-sans-first:1.13 05:31:11 INFO - PROCESS | 1678 | Values: 86.0 84.0 85.0 86.0 87.0 86.0 84.0 85.0 86.0 87.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:156.00 stddev:13.12 (8.4%) stddev-sans-first:13.63 05:31:11 INFO - PROCESS | 1678 | Values: 162.0 158.0 143.0 140.0 143.0 150.0 154.0 163.0 184.0 148.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:169.50 stddev:2.60 (1.5%) stddev-sans-first:2.67 05:31:11 INFO - PROCESS | 1678 | Values: 168.0 170.0 168.0 173.0 172.0 175.0 169.0 168.0 169.0 167.0 05:31:11 INFO - PROCESS | 1678 | 05:31:11 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:70.00 stddev:1.45 (2.1%) stddev-sans-first:1.48 05:31:11 INFO - PROCESS | 1678 | Values: 70.0 67.0 68.0 70.0 70.0 68.0 71.0 70.0 68.0 67.0 05:31:11 INFO - PROCESS | 1678 | -------- Summary: end -------- 05:31:11 INFO - PROCESS | 1678 | 05:31:12 INFO - TEST-INFO | 1678: exit 0 05:31:12 INFO - TEST-OK | kraken | took 58836ms 05:31:12 INFO - SUITE-END | took 1173s 05:31:12 INFO - Completed test suite (00:19:33) 05:31:12 INFO - javascript benchmark 05:31:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3409.147713071732, 3444.3627928833153, 3472.7916325020515, 3482.878099266525, 3512.8419221135046, 5550.888982220353, 5625.606207565472, 5652.915206271904, 5673.5177509911655, 5678.46054624811, 5615.354234263061, 5673.0, 5675.460831221038, 5679.176519404684, 5686.516646085088, 5123.282740416354, 5133.640645154841, 5162.406323272815, 5171.5862730981435, 5215.374155101393, 4447.355133505656, 5122.743862806863, 5210.2445145453885, 5215.426303106655, 5234.816781412632, 5171.793128274864, 5223.973880130594, 5225.320708307933, 5232.869178270555, 5242.6330156889035, 5151.278820965068, 5219.947800521984, 5221.6083793715625, 5233.555147812441, 5281.36623605166, 5075.467075957045, 5093.363329583802, 5118.846434606968, 5124.8206312778875, 5128.974355128201, 5618.325800903876, 5641.746121424505, 5652.236948012007, 5655.434456554352, 5683.602147849652, 6178.752849885999, 6229.034499652561, 6238.189035425411, 6241.157443745082, 6241.531885108635, 6098.780243951228, 6174.969125154345, 6240.31356550779, 6264.123022776815, 6283.120363149163, 5752.194692743019, 5810.389909059572, 5826.563007774434, 5826.76692932278, 5843.707814609295, 6247.750089996349, 6254.74981000764, 6258.15514905491, 6262.467690246289, 6262.874742505124, 5099.745012749348, 6016.578839481194, 6100.762843515373, 6118.694065296718, 6130.816075517741, 6222.004479283293, 6246.656433896179, 6256.0303153011355, 6259.405356491126, 6267.62394256346, 6140.986737188314, 6180.103884936659, 6181.505479561714, 6197.566170368031, 6209.441150296495, 6096.69516524172, 6138.51101655979, 6150.892839288881, 6167.444929956236, 6186.1648677427975, 4871.95128048715, 4912.0, 4921.827736029221, 4924.63065270099, 4928.359313289249, 4421.40311058003, 4432.667549933768, 4466.062126953311, 4486.102779444124, 4539.16025535277, 5669.461401166883, 5679.971600142056, 5686.658800471985, 5696.51579615729, 5700.173474846124, 6163.476104531165, 6294.055891616203, 6299.433051025429, 6315.052742088724, 6319.273283572448, 4695.5539223773685, 5654.293213348332, 5656.575756818255, 5707.0583353747, 5707.857303567361, 5371.489708477767, 5374.838754837361, 5386.48828361305, 5388.272583201296, 5421.322334708161, 6185.226846644169, 6289.874202516015, 6332.525060620472, 6337.588056776295, 6352.841178970562, 5171.534561889448, 6284.402981716729, 6284.905726414199, 6306.653134077486, 6310.716017779303, 6220.377962203744, 6327.810165695037, 6336.20797400325, 6339.207599050119, 6347.841303967438, 6093.847653808513, 6113.233070740737, 6135.171422439487, 6195.690215489118, 6203.193584833943, 5323.520883120537, 5444.292242008513, 6121.0262130506535, 6234.75060997555, 6270.404311590391, 5767.711614419179, 6117.204763380732, 6123.8162855114415, 6128.142060111499, 6134.478569321657, 5957.463828255304, 6013.278406591237, 6067.87744267312, 6126.479249263863, 6147.72335244924, 5939.376365481735, 5997.880042399215, 6006.639601623917, 6045.335013148462, 6071.635701857903, 6088.4824789893355, 6107.206063211754, 6111.419415155553, 6118.942928706938, 6154.230721159855, 5822.650640961556, 5903.173555702291, 5964.522838172849, 5980.671063091571, 6009.45914867664], "value": 5666.373815167888, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.7669895149279, 502.1288065206868, 530.482779290192, 535.799075346745, 539.0001547130073, 25331.11341103062, 36613.0, 37778.055548611264, 37794.8110259449, 37801.05497362561, 40887.0, 41265.58734412655, 41348.79325603375, 41528.37707434391, 41551.792241038755, 8287.212714792115, 9309.16217540423, 9562.474063926482, 9562.521873906311, 9590.376625519353, 8462.576871156449, 8621.827563448727, 8625.827483450326, 11182.720431989184, 11555.826662600068, 11217.943910280437, 11331.603393881216, 11341.489632966537, 11342.886571134306, 11349.829752553676, 9977.15194208493, 11424.543018279259, 11435.371054592035, 11436.942815285953, 11456.541738330458, 8350.665973361058, 11220.551177952953, 11522.76954460906, 11542.711432214177, 11546.53813847445, 33463.66536334654, 36004.239321708665, 38379.23241535181, 38520.0, 38572.0, 3170.5878235829427, 3176.237702951275, 3226.516022596606, 3236.6925142111463, 3395.5246265522846, 28129.57805632917, 28655.57016644752, 28697.0, 28962.855185724147, 28991.56512652291, 2254.7407048189502, 2674.211107723226, 2682.369643133862, 2711.498372801038, 2733.1253998720417, 471347.0, 471513.0, 472652.0, 472759.0, 473304.0, 59837.0, 60040.0, 60440.0, 60519.39480605226, 60546.0, 63660.0, 65833.67083164553, 67725.0, 67738.5487596364, 67854.66072669704, 16285.674286514204, 16519.0, 16544.917275413787, 16550.006999579822, 16569.25438355277, 11682.532698692126, 12706.174098683741, 12792.360381980863, 12794.552190673281, 12803.359832008362, 16591.17044147812, 19266.903665481583, 19779.802201978084, 20026.399208023784, 20221.988900554912, 6948.27043160471, 8192.0, 8268.173182681683, 8280.958595206985, 8294.336453083739, 953.2135987810084, 1061.6761887624348, 1068.5725709716176, 1069.5240617924949, 1103.2883789955433, 22653.093876245097, 25549.59477228733, 26881.596776048376, 26915.461690766075, 27034.91860325565, 919.6643225222746, 1082.8159212933663, 1110.900018998294, 1123.5898896902572, 1179.3926128044063, 12594.559190428292, 15075.623109422353, 15086.396544138332, 15476.0, 15487.457938972082, 602264.0, 611220.0, 611344.0, 612120.8787912152, 617491.0, 58182.70908645514, 59363.40636593665, 59478.70260648669, 59573.40426595765, 59576.0, 1315.4869600855675, 1442.163545143814, 1464.5240296903548, 1488.8138982627172, 1500.7073620644087, 24770.752292477566, 26757.331066723487, 28003.579946300415, 28654.57018144771, 28965.565516516846, 33614.49578256377, 45640.54359456363, 45707.54292457166, 45848.0, 45898.85252868705, 344498.0, 345350.0, 346194.0, 346411.53588464804, 348339.0, 25542.669791091765, 26146.86926565355, 26590.0, 26603.33491662724, 26699.599506007813, 45497.31754023626, 45908.770456147504, 46288.53711462842, 46738.0, 47177.0, 36356.454653179695, 37409.43885841769, 38211.42682859815, 38343.0, 38800.223995519365, 36704.449433257985, 37108.94929963299, 37323.81338093401, 38183.427248590735, 38575.6142438572, 36418.8179059103, 36530.452043219906, 36670.0, 36806.63193368032, 37548.616735154545, 30366.39267214599, 32534.83732581322, 32649.34701306008, 32697.673023269464, 32725.672743272262, 23850.64224036606, 23979.76020239845, 24271.029158833448, 24963.750362496143, 25622.61566076473], "value": 19413.851619574176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.7262588813719, 396.97618142911426, 399.7241903086872, 401.68869126427035, 410.58530883807344, 18233.452996410128, 18318.633627327446, 19119.71320430195, 19251.0, 19262.90368548157, 18381.264749410042, 18434.0, 19086.61826763464, 19112.610923634547, 19651.0, 4820.879478013043, 5487.588430867681, 5684.886302273952, 5832.0, 5852.795152169675, 4962.801487940478, 5786.669066114796, 5790.449907258803, 5804.158397032428, 5852.531797456193, 5657.434256574351, 5687.943120568803, 5697.6866272355, 5703.486686198241, 5798.594098413092, 5612.158176273571, 5708.086706127041, 5745.856353591152, 5772.913406298909, 5797.188393624917, 5503.862403439906, 5669.716514174274, 5712.6286791358425, 5714.71426428681, 5717.228174196472, 12886.420111095023, 13314.667133321647, 16555.834441655672, 24079.638805417933, 24092.397690057714, 2030.857839951204, 2045.9386218413472, 2147.742270927483, 2235.6870038194666, 2235.7876001779805, 7586.0, 9416.576254068583, 9659.613615455373, 9961.65134220306, 9970.601175952954, 1699.7195462748632, 1736.722124460093, 1779.830916062972, 1787.356551641408, 1804.296324433472, 105459.0, 107834.92165078328, 112615.43692281569, 115716.97557223719, 116949.0, 10635.308704934308, 11270.887291126985, 11317.773644527228, 11411.372374519315, 11436.311112443858, 14230.501932432522, 14298.0, 14311.425743168244, 14343.067700599428, 14359.641008974859, 8218.835623287501, 8287.875681864776, 8316.501009939422, 8331.8333633327, 8410.537420441935, 8279.172082791792, 8284.875726864102, 8391.0, 8397.454165479225, 8401.453905496122, 4431.578999994996, 4524.990881064055, 4528.437688997291, 4537.115262523842, 4582.198115329804, 1783.8126996665424, 1883.1282294500995, 1941.9223231070598, 1946.688529835248, 1987.8807271563753, 904.6291020681489, 941.7551436626527, 953.2088366655797, 956.4404823178379, 1007.2646967713611, 18498.35255766042, 19496.025198740008, 19587.51031224231, 19710.802891970896, 19796.406107816787, 882.2677177942293, 973.2603221551531, 981.6417007792104, 990.00999000999, 1024.3085917005992, 9981.650642227632, 10085.546150423248, 10162.644307449204, 10166.440845753554, 10258.384496930208, 592980.0, 599415.0, 606370.0, 607934.0, 620129.0, 121622.0, 124256.0, 125870.0, 126091.3695431517, 126409.36795316149, 996.5864166370875, 1007.5365331947386, 1037.7716902281486, 1041.8697662792151, 1042.8957104289661, 16945.576360591083, 17439.912800436425, 17555.0, 17591.82408175902, 18180.909145450787, 35300.82349588236, 40242.0, 41530.58469415267, 46697.766511168586, 51470.48529514657, 69706.0, 71434.2141446468, 72365.27634723796, 73238.0, 74198.62900685462, 6058.939410605837, 7729.961350193213, 7843.568603726621, 7948.0, 8136.674533018613, 11314.264572802742, 11584.94207528957, 11882.643520694393, 12172.634820955385, 12244.75510489803, 9551.522423878918, 10535.63125290611, 10610.416427096276, 10634.042936135476, 10793.29843560166, 10264.846027309446, 10332.793344133226, 10348.637797677045, 10485.21360897951, 10629.681109566725, 5848.853778655397, 8979.653052042246, 10504.36973781545, 10551.94724026375, 10634.361938283728, 7663.770086897402, 7916.0, 8062.838743224986, 8082.919170808452, 8086.1509541499645, 8160.26557609818, 9948.900510995087, 10519.474026298807, 10857.077148442155, 10867.402292873969], "value": 9085.787870078571, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.6872288096615, 1594.9553042756995, 1621.7324141516651, 1878.8496920246373, 2014.1842553765712, 7232.8553428931455, 7914.010748656418, 8856.247218986378, 8856.734297971072, 8872.556372181398, 8453.28147107495, 8697.391182617219, 8698.478091314526, 8719.694810681627, 8737.126287371275, 2137.8289736821093, 2209.812165965895, 2214.82281417487, 2393.7845593896504, 2428.6842710447618, 2213.1921848525253, 2375.4773949731116, 2455.26342097371, 2458.3485376375274, 2460.409501719583, 2392.306231192952, 2415.8912848921755, 2421.5520128776143, 2422.4307287787356, 2424.903003879843, 2112.8732276063333, 2394.197943688858, 2412.6863507744056, 2414.9637755433682, 2417.2023232333286, 2357.0, 2437.8049756019655, 2438.0491608272787, 2441.2554170977846, 2446.5963116085823, 8586.613602387908, 8659.696910608098, 8676.479411235347, 8735.388522803407, 8885.644574217024, 5084.161113416282, 5250.6324557281005, 5264.842054738364, 5314.0, 5328.49379308965, 3329.783564068352, 3444.6555344465355, 3511.20997775501, 3562.6259242779392, 3569.839357228905, 964.5804075227298, 1015.8628585140985, 1019.3323373190572, 1025.876665051767, 1028.5217373921162, 11708.12189085822, 11721.472533736003, 11724.88275117255, 11727.061835053175, 11746.64760057191, 4623.398958135488, 4691.601213896857, 4847.75761211938, 4858.222684370484, 4863.781129849165, 9969.80060398788, 9972.850407243897, 9984.80030399388, 10001.249906256915, 10018.649347272956, 5350.250964864922, 5372.382176049726, 5374.3013408256675, 5389.461053894579, 5390.0, 4829.710217386898, 4889.657723959289, 4895.681780684244, 4905.656604037683, 4912.238603016538, 3612.4039533477207, 3701.0192299040777, 3736.551613806361, 3743.887683369503, 3754.7934863582764, 1313.5008696695195, 1314.3691028306275, 1315.5527120778981, 1319.1755153029358, 1348.1169833758904, 781.2148790465545, 879.2262808728406, 898.3666515106834, 898.4384759525296, 951.5860600638745, 5982.46157845796, 5986.102084687332, 6038.516918646497, 6140.140380346755, 6231.813045608548, 875.1685898396548, 886.5522910930068, 919.6827094652334, 932.6455946740195, 970.0590427285522, 3787.981060094737, 3885.1646895917515, 3981.402789581528, 4015.13674559971, 4023.8591649292134, 9907.207423406111, 10917.39954302505, 10917.672469825919, 10944.23390362683, 10959.0, 10121.595136194617, 10716.624918127829, 10871.83692244617, 10885.401302928414, 10902.781944361068, 8125.553094579855, 8298.419110662197, 8303.501789892505, 8313.875291870627, 8315.542645154575, 4987.401511818605, 5757.222774925499, 5939.881202376015, 5966.791162309472, 6107.633541987495, 9833.85249221248, 9971.950140249251, 10023.749406264902, 10080.848787268344, 10142.340747851365, 7807.687692492236, 9167.083291671046, 9698.36960597559, 10358.378497290187, 10371.58513659445, 7312.41500679955, 7363.963180184065, 7446.218147094477, 7452.292032256927, 7526.209747976383, 8070.2333278338465, 8096.676132954616, 8106.675732970615, 8167.673293068211, 8174.7956301092945, 7185.425165986813, 7235.204127546071, 7275.890861636974, 7299.562026278228, 7368.742094026683, 7042.366187043191, 7147.2852714728115, 7158.642067896689, 7290.3438690518105, 7393.186749457663, 7000.50996430245, 7053.4004609608755, 7127.0, 7128.073350464407, 7144.4641651877355, 4706.62347012245, 4729.077829823152, 4830.347903033045, 4885.291632713308, 4914.606831453547, 4863.076015557032, 4923.803047878045, 4929.827456039022, 4981.4769449207315, 5117.8720531987], "value": 4571.319830453487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.27257308191156, 320.43923134514597, 324.90740139060364, 328.74193757900053, 334.5650654149605, 5049.671771334861, 5743.741531631076, 5816.592838501307, 5817.389174136718, 5823.796167134151, 5731.770729170838, 5743.167240750089, 5763.711814409284, 5769.682667453289, 5777.855553611162, 4142.544320124784, 4232.238197124517, 4286.121345124242, 4352.107817897339, 4438.644908407319, 3905.3360928642055, 4254.425652536899, 4350.673699472536, 4396.0, 4410.71330363527, 3780.1305699689087, 4212.557681443435, 4381.123775244948, 4384.539623339551, 4395.9889225478155, 4103.671706263507, 4156.896077598054, 4389.2318844202455, 4391.165678521103, 4397.824087036547, 3364.798112113281, 3781.5840257571645, 3797.9240415191816, 3799.4490798834013, 3809.6380843819793, 5530.5299049580835, 5534.149912794914, 5546.611737178358, 5549.002359150702, 5554.111342185231, 4221.416968636761, 4877.975610121962, 5061.342025536656, 5088.618353623456, 5123.974380128113, 5259.526642602184, 6009.639421634716, 6049.879002419971, 6066.484348830355, 6109.755609775603, 3800.885973420774, 4036.6367026967714, 4188.832446702158, 4219.578042195786, 4222.472190976128, 4543.229119830566, 6404.231492220963, 6410.935890641081, 6442.742290308382, 6457.773977910798, 3792.3363411402893, 4270.466191726035, 4299.462567179103, 4309.353596960481, 4318.481782186158, 4378.759168245713, 4385.978070109629, 4398.186335527937, 4399.5820397062225, 4402.625776808943, 4362.847300344472, 4801.231802911518, 4805.821135459828, 5047.116754567936, 5069.087564238473, 5354.678719276856, 5842.2405087338375, 6134.141220229171, 6143.447089761943, 6170.10533472644, 1449.4854326714076, 1491.8955673102778, 1512.8880272999336, 1523.223156190351, 1529.3118096856238, 752.9924700752922, 844.2486187293314, 849.2526576612665, 929.707142250189, 962.167724917955, 5169.940162266802, 5759.94240057594, 5787.045137552257, 5793.420657934257, 5797.826065218051, 5337.733113344318, 5495.422980587061, 6286.779962701284, 6383.71273292703, 6442.8389290268115, 4892.19278818998, 5118.464460661787, 5381.892362152735, 5657.915131273035, 5662.546996240291, 3331.06730115568, 3349.43059679855, 3362.6637336266667, 3380.9492857607156, 3409.3863104641405, 6369.777057802955, 6593.274739778621, 6620.139381880324, 6684.866302674017, 6709.630970296583, 5410.323709536308, 5463.754131064112, 5473.863153421116, 6522.086907832907, 6577.539572229994, 5538.196961440649, 6519.348065193538, 6580.243272023683, 6641.468682505388, 6654.467642588581, 5745.569082318759, 6359.650219237987, 6365.427111559982, 6370.426661600478, 6411.51913606472, 5762.645778242194, 6246.470744029609, 6254.968725156527, 6297.897867873012, 6312.096435639473, 5331.0, 6073.726682299219, 6340.302566717565, 6618.631703076139, 6669.833254168685, 4275.187714334391, 4313.590208930146, 4320.9135817284105, 4353.891152721208, 4360.585744354281, 5582.469665381782, 6293.402126797947, 6301.936980630318, 6336.239651241881, 6369.172007638977, 5800.795697634871, 6090.421409966046, 6097.2683278006925, 6109.84725381869, 6120.847763401941, 4989.351384320015, 6044.546659000504, 6079.422454866781, 6103.359147289649, 6111.816645500642, 4698.553637404441, 5757.251557297524, 5928.67392293428, 6000.369961154016, 6025.307089684742, 5341.599380046434, 6170.167027451416, 6180.81457556274, 6192.151610259151, 6197.442230199304, 5315.468453154654, 6037.154798328325, 6069.150318955438, 6071.848203794941, 6082.90875636857], "value": 4464.0504760997455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8701553197127, 750.6171852355304, 750.9812254693632, 753.6985205917629, 773.2422226218308, 14844.109353438802, 14850.92574537127, 15190.54428367148, 15207.84792152078, 15229.4669686561, 14379.06536075157, 14458.060226085297, 14458.349374278156, 14461.987660863744, 14474.059186152892, 721.2354903801987, 743.7062360367679, 907.5054095517926, 927.2026057590467, 929.0848514213494, 896.0457113174448, 909.1363204955286, 909.6770646420491, 910.3809409601469, 910.8269428808505, 950.2967803825154, 951.4053716427233, 952.1430712358863, 952.8475443928972, 958.8513780364054, 744.0178963767829, 942.0626476655772, 944.5749412764284, 955.2119501411363, 967.5307475874196, 720.783764870542, 724.4711360706714, 724.5616402076773, 725.9310365515267, 729.5987207036162, 14777.630559236106, 14850.628734281729, 14850.925745371203, 14883.032602880778, 14883.479078232318, 12521.56174533896, 12612.369381530887, 12733.045021623322, 12804.871951280555, 12830.55093071738, 9954.5022748863, 9955.701328960144, 10121.34211276272, 10499.68500944973, 10572.894271057268, 3363.7981721096567, 3369.309291595217, 3429.9142521436916, 3455.7580969332157, 3479.9652003479896, 82055.0, 82239.17760822375, 82504.9999999994, 82557.17442825616, 83307.58346208291, 8672.609732561923, 9272.35093543459, 9503.0, 9514.858217014003, 9759.804803903882, 20386.59226815485, 20641.587168256552, 20655.070521826252, 20677.379678609665, 20703.896480517502, 6607.57050791697, 7425.480216384801, 7593.962030189924, 7845.803854903559, 7948.960255198687, 4098.446709694213, 4147.315692910696, 4164.187983343219, 4172.436721042681, 4228.921624985609, 3438.157651375429, 3551.2009797795804, 3558.1282585766653, 3559.110222444389, 3813.2373525294497, 678.8065401360589, 687.5977553131374, 689.7344522358928, 690.9689063992132, 693.3343989769777, 543.4945500684322, 584.6579750845718, 595.8361450601033, 600.2047287344199, 600.3906035374099, 6218.412002193128, 6276.278228003838, 8051.63767630458, 8132.75601731949, 8206.794830129294, 485.995140048595, 552.1772558887229, 562.8002059268982, 605.5821483176594, 608.8843119807311, 3872.9419058714143, 3877.689784817208, 3932.9213415731524, 3957.9010524737114, 4038.676905847525, 82625.58687206525, 82967.75548366779, 83911.5804420974, 85794.0, 85825.0, 60806.47983800352, 64673.0, 70997.2900271001, 72820.63589682123, 73580.63209683917, 64540.67729661416, 65801.0, 65923.0, 65963.0, 66792.3320766786, 7434.479586428898, 7477.775666730006, 7586.3930885527925, 7614.961925190339, 7689.8462030757955, 29717.257068573457, 40282.0, 41032.76901692954, 41494.75515734533, 41818.58181418269, 50932.74533627308, 51678.741606291725, 52230.738846305525, 53017.93964120773, 60293.69853150882, 10734.355938643706, 10943.179261555257, 10963.232573719763, 10990.120790336594, 11119.666410007712, 8873.511956842436, 10549.0, 11916.642500724993, 12194.0, 12268.570600028956, 11390.544378224778, 11565.595204167814, 11580.826287605862, 11730.36066884944, 11834.112441566745, 11200.887991119984, 11255.0, 11267.661970140909, 11405.372704501333, 11531.250468719507, 10955.56177752881, 11110.277831940897, 11136.147712632795, 11183.049440797298, 11494.827577586175, 7665.88501172494, 9235.722928312161, 9343.392679475812, 9372.484513351832, 9836.557354919189, 9235.445873247627, 9287.953560232427, 9301.674441394789, 9485.33602647808, 9500.524973751422], "value": 6701.849558087643, "unit": "score"}], "name": "dromaeo_css", "value": 7177.352734119139, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 109.0, 111.0, 113.0, 112.0, 113.0, 119.0, 113.0, 114.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 173.0, 203.0, 119.0, 142.0, 141.0, 127.0, 215.0, 174.0], "value": 157.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 187.0, 185.0, 188.0, 183.0, 187.0, 185.0, 185.0, 185.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 87.0, 91.0, 56.0, 61.0, 58.0, 78.0, 85.0, 55.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 132.0, 81.0, 78.0, 80.0, 122.0, 79.0, 77.0, 125.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 88.0, 85.0, 86.0, 88.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 100.0, 92.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 86.0, 87.0, 87.0, 86.0, 86.0, 87.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 58.0, 58.0, 59.0, 57.0, 74.0, 59.0, 57.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 78.0, 72.0, 74.0, 71.0, 67.0, 67.0, 70.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 86.0, 87.0, 86.0, 84.0, 85.0, 86.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 158.0, 143.0, 140.0, 143.0, 150.0, 154.0, 163.0, 184.0, 148.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 173.0, 172.0, 175.0, 169.0, 168.0, 169.0, 167.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 70.0, 70.0, 68.0, 71.0, 70.0, 68.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1497.3000000000002, "alertThreshold": 2.0}]} 05:31:12 INFO - Return code: 0 05:31:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:31:12 ERROR - # TBPL SUCCESS # 05:31:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:12 INFO - 2016-04-14 05:31:12.778 screenresolution[1682:9911] starting screenresolution argv=screenresolution get 05:31:12 INFO - 2016-04-14 05:31:12.780 screenresolution[1682:9911] Display 0: 1600x1200x32@60 05:31:12 INFO - 2016-04-14 05:31:12.786 screenresolution[1683:9914] starting screenresolution argv=screenresolution list 05:31:12 INFO - Available Modes on Display 0 05:31:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:13 INFO - Intel Iris: 05:31:13 INFO - Chipset Model: Intel Iris 05:31:13 INFO - Type: GPU 05:31:13 INFO - Bus: Built-In 05:31:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:13 INFO - Vendor: Intel (0x8086) 05:31:13 INFO - Device ID: 0x0a2e 05:31:13 INFO - Revision ID: 0x0009 05:31:13 INFO - Displays: 05:31:13 INFO - Display: 05:31:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:13 INFO - Display Serial Number: 1600x1200 60 05:31:13 INFO - Main Display: Yes 05:31:13 INFO - Mirror: Off 05:31:13 INFO - Online: Yes 05:31:13 INFO - Rotation: Supported 05:31:13 INFO - Return code: 0 05:31:13 INFO - Running post-action listener: _resource_record_post_action 05:31:13 INFO - Running post-run listener: _resource_record_post_run 05:31:14 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37639680; Write bytes: 692531712; Read time: 387; Write time: 1934 05:31:14 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 382579200; Write bytes: 371594752; Read time: 28054; Write time: 661 05:31:14 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 31505920; Write bytes: 295775232; Read time: 307; Write time: 1224 05:31:14 INFO - Running post-run listener: _upload_blobber_files 05:31:14 INFO - Blob upload gear active. 05:31:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:31:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:31:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:31:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:15 INFO - (blobuploader) - INFO - Done attempting. 05:31:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:31:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:31:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:16 INFO - (blobuploader) - INFO - Done attempting. 05:31:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:16 INFO - Return code: 0 05:31:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77d497a45910e352b45ea9811fd1f8c65055ecca759fa220abed24088f052da26f3a97eb51fce741c2680346023b447d6fb4f6f025c77b2419877bf6539d8603", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c15ab07b9e672ec08aa8345a26054834abee5a183f8db5ae965c6d56c9fe9fabb24f0d4bbbf2261d08ede3ee09f4a594e3f178f7395c07b1a7e4757ee098b7d"} 05:31:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:16 INFO - Contents: 05:31:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77d497a45910e352b45ea9811fd1f8c65055ecca759fa220abed24088f052da26f3a97eb51fce741c2680346023b447d6fb4f6f025c77b2419877bf6539d8603", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c15ab07b9e672ec08aa8345a26054834abee5a183f8db5ae965c6d56c9fe9fabb24f0d4bbbf2261d08ede3ee09f4a594e3f178f7395c07b1a7e4757ee098b7d"} 05:31:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:16 INFO - Copying logs to upload dir... 05:31:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.302265 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-14 05:31:16.243627) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:31:16.247613) ========= 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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77d497a45910e352b45ea9811fd1f8c65055ecca759fa220abed24088f052da26f3a97eb51fce741c2680346023b447d6fb4f6f025c77b2419877bf6539d8603", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c15ab07b9e672ec08aa8345a26054834abee5a183f8db5ae965c6d56c9fe9fabb24f0d4bbbf2261d08ede3ee09f4a594e3f178f7395c07b1a7e4757ee098b7d"} build_url:https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010967 build_url: 'https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77d497a45910e352b45ea9811fd1f8c65055ecca759fa220abed24088f052da26f3a97eb51fce741c2680346023b447d6fb4f6f025c77b2419877bf6539d8603", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c15ab07b9e672ec08aa8345a26054834abee5a183f8db5ae965c6d56c9fe9fabb24f0d4bbbf2261d08ede3ee09f4a594e3f178f7395c07b1a7e4757ee098b7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:31:16.280147) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:31:16.280520) ========= 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.0Mf78Wgz9P/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.u6cZ9n6b2I/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005143 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:31:16.329816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:31:16.330181) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:31:16.330543) ========= ========= Total master_lag: 0.28 =========