builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0033 starttime: 1460635767.67 results: success (0) buildid: 20160414032548 builduid: 8695f4ce90c94b97a0ac36cbd725f5c3 revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:27.666928) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:27.667467) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:27.667780) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.041717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.042094) ========= 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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.24M=0.001s 2016-04-14 05:09:28 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.395190 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.454292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.454640) ========= 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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.019806 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.503294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:28.503681) ========= 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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:28,581 truncating revision to first 12 chars 2016-04-14 05:09:28,581 Setting DEBUG logging. 2016-04-14 05:09:28,581 attempt 1/10 2016-04-14 05:09:28,581 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:09:29,212 unpacking tar archive at: fx-team-4a496faf1c5c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.884058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:29.405373) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:29.405731) ========= script_repo_revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:29.406206) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:29.406583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:29.420723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-14 05:09:29.421061) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:29 INFO - MultiFileLogger online at 20160414 05:09:29 in /builds/slave/test 05:09:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:09:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:29 INFO - {'append_to_log': False, 05:09:29 INFO - 'base_work_dir': '/builds/slave/test', 05:09:29 INFO - 'blob_upload_branch': 'Fx-Team', 05:09:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:29 INFO - 'branch': 'Fx-Team', 05:09:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:29 INFO - 'config_files': ('talos/mac_config.py',), 05:09:29 INFO - 'default_actions': ('clobber', 05:09:29 INFO - 'read-buildbot-config', 05:09:29 INFO - 'download-and-extract', 05:09:29 INFO - 'populate-webroot', 05:09:29 INFO - 'create-virtualenv', 05:09:29 INFO - 'install', 05:09:29 INFO - 'run-tests'), 05:09:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:29 INFO - 'download_minidump_stackwalk': True, 05:09:29 INFO - 'download_symbols': 'ondemand', 05:09:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:29 INFO - 'installer_path': 'installer.exe', 05:09:29 INFO - 'log_level': 'info', 05:09:29 INFO - 'log_name': 'talos', 05:09:29 INFO - 'log_to_console': True, 05:09:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:09:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:09:29 INFO - 'opt_config_files': (), 05:09:29 INFO - 'pip_index': False, 05:09:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:29 INFO - 'cmd': ('bash', 05:09:29 INFO - '-c', 05:09:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:29 INFO - 'enabled': True, 05:09:29 INFO - 'halt_on_failure': False, 05:09:29 INFO - 'name': 'check_screen_resolution'},), 05:09:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:29 INFO - 'cmd': ('bash', 05:09:29 INFO - '-c', 05:09:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:29 INFO - 'enabled': True, 05:09:29 INFO - 'halt_on_failure': False, 05:09:29 INFO - 'name': 'check_screen_resolution'},), 05:09:29 INFO - 'run_cmd_checks_enabled': True, 05:09:29 INFO - 'sps_profile': False, 05:09:29 INFO - 'sps_profile_interval': 0, 05:09:29 INFO - 'suite': 'dromaeojs-e10s', 05:09:29 INFO - 'system_bits': '32', 05:09:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:29 INFO - 'title': 't-yosemite-r7-0033', 05:09:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:29 INFO - 'use_talos_json': True, 05:09:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:29 INFO - 'work_dir': 'build'} 05:09:29 INFO - ##### 05:09:29 INFO - ##### Running clobber step. 05:09:29 INFO - ##### 05:09:29 INFO - Running pre-action listener: _resource_record_pre_action 05:09:29 INFO - Running main action method: clobber 05:09:29 INFO - rmtree: /builds/slave/test/build 05:09:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - ##### 05:09:30 INFO - ##### Running read-buildbot-config step. 05:09:30 INFO - ##### 05:09:30 INFO - Running pre-action listener: _resource_record_pre_action 05:09:30 INFO - Running main action method: read_buildbot_config 05:09:30 INFO - Using buildbot properties: 05:09:30 INFO - { 05:09:30 INFO - "project": "", 05:09:30 INFO - "product": "firefox", 05:09:30 INFO - "script_repo_revision": "production", 05:09:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:09:30 INFO - "repository": "", 05:09:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:09:30 INFO - "buildid": "20160414032548", 05:09:30 INFO - "pgo_build": "False", 05:09:30 INFO - "basedir": "/builds/slave/test", 05:09:30 INFO - "buildnumber": 602, 05:09:30 INFO - "slavename": "t-yosemite-r7-0033", 05:09:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:09:30 INFO - "platform": "yosemite_r7", 05:09:30 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:09:30 INFO - "branch": "fx-team", 05:09:30 INFO - "repo_path": "integration/fx-team", 05:09:30 INFO - "revision": "4a496faf1c5cac37ab6dac9db549cb65867fa7f6", 05:09:30 INFO - "stage_platform": "macosx64", 05:09:30 INFO - "builduid": "8695f4ce90c94b97a0ac36cbd725f5c3", 05:09:30 INFO - "slavebuilddir": "test" 05:09:30 INFO - } 05:09:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - ##### 05:09:30 INFO - ##### Running download-and-extract step. 05:09:30 INFO - ##### 05:09:30 INFO - Running pre-action listener: _resource_record_pre_action 05:09:30 INFO - Running main action method: download_and_extract 05:09:30 INFO - mkdir: /builds/slave/test/build/tests 05:09:30 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:09:30 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:09:30 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:09:30 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:09:30 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:09:30 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:09:32 INFO - Downloaded 1199 bytes. 05:09:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:09:32 INFO - Using the following test package requirements: 05:09:32 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:09:32 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:09:32 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:09:32 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'jsshell-mac.zip'], 05:09:32 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:09:32 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:09:32 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:09:32 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:09:32 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:09:32 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:09:32 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:09:32 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:09:32 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:09:32 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:09:32 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:09:32 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:09:32 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:09:32 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:09:34 INFO - Downloaded 18031141 bytes. 05:09:34 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:09:34 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:09:37 INFO - Return code: 0 05:09:37 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:09: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:09:37 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:09:37 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:09:37 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:09:37 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:09:37 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:09:38 INFO - Downloaded 18031141 bytes. 05:09:38 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:09:38 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:09:41 INFO - Return code: 0 05:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:41 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:09:41 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:09:41 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:09:41 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:09:41 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:09:42 INFO - Downloaded 10841076 bytes. 05:09:42 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:09:42 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:09:42 INFO - Return code: 0 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:42 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:09:42 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:09:42 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:09:42 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:09:42 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:09:45 INFO - Downloaded 109830898 bytes. 05:09:45 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:09:45 INFO - mkdir: /builds/slave/test/properties 05:09:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:45 INFO - Contents: 05:09:45 INFO - build_url:https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:09:47 INFO - Running post-action listener: _resource_record_post_action 05:09:47 INFO - Running post-action listener: set_extra_try_arguments 05:09:47 INFO - ##### 05:09:47 INFO - ##### Running populate-webroot step. 05:09:47 INFO - ##### 05:09:47 INFO - Running pre-action listener: _resource_record_pre_action 05:09:47 INFO - Running main action method: populate_webroot 05:09:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:47 INFO - '--tppagecycles', 05:09:47 INFO - '7'], 05:09:47 INFO - 'tests': ['tsvgm']}}, 05:09:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['damp', 'tps']}, 05:09:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'tests': ['damp', 'tps']}, 05:09:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['dromaeo_dom']}, 05:09:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['a11yr', 05:09:47 INFO - 'ts_paint', 05:09:47 INFO - 'tpaint', 05:09:47 INFO - 'sessionrestore', 05:09:47 INFO - 'sessionrestore_no_auto_restore', 05:09:47 INFO - 'tabpaint']}, 05:09:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['tsvgx', 05:09:47 INFO - 'tsvgr_opacity', 05:09:47 INFO - 'tart', 05:09:47 INFO - 'tscrollx', 05:09:47 INFO - 'cart']}, 05:09:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:47 INFO - 'tsvgr_opacity', 05:09:47 INFO - 'tart', 05:09:47 INFO - 'tscrollx', 05:09:47 INFO - 'cart']}, 05:09:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'talos_options': ['--disable-e10s'], 05:09:47 INFO - 'tests': ['tp5o']}, 05:09:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'tests': ['tp5o']}, 05:09:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'talos_options': ['--disable-e10s', 05:09:47 INFO - '--xperf_path', 05:09:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:47 INFO - 'tests': ['tp5n']}, 05:09:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:47 INFO - 'talos_options': ['--xperf_path', 05:09:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:47 INFO - 'tests': ['tp5n']}}, 05:09:47 INFO - 'talos.zip': {'path': '', 05:09:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:47 INFO - Running post-action listener: _resource_record_post_action 05:09:47 INFO - ##### 05:09:47 INFO - ##### Running create-virtualenv step. 05:09:47 INFO - ##### 05:09:47 INFO - Running pre-action listener: _resource_record_pre_action 05:09:47 INFO - Running main action method: create_virtualenv 05:09:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:47 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:09:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:47 INFO - Using real prefix '/tools/python27' 05:09:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:50 INFO - Installing pip.................done. 05:09:50 INFO - Return code: 0 05:09:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:50 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:50 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:09:50 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:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:50 INFO - 'HOME': '/Users/cltbld', 05:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:50 INFO - 'LOGNAME': 'cltbld', 05:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:50 INFO - 'MOZ_NO_REMOTE': '1', 05:09:50 INFO - 'NO_EM_RESTART': '1', 05:09:50 INFO - 'PAGER': '/bin/cat', 05:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:50 INFO - 'PWD': '/builds/slave/test', 05:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:50 INFO - 'SHELL': '/bin/bash', 05:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:09:50 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:50 INFO - 'USER': 'cltbld', 05:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:50 INFO - 'XPC_FLAGS': '0x0', 05:09:50 INFO - 'XPC_SERVICE_NAME': '0', 05:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:50 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:09:50 INFO - Cleaning up... 05:09:51 INFO - Return code: 0 05:09:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:09:51 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:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 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:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:51 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:09:51 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:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:51 INFO - 'HOME': '/Users/cltbld', 05:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:51 INFO - 'LOGNAME': 'cltbld', 05:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:51 INFO - 'MOZ_NO_REMOTE': '1', 05:09:51 INFO - 'NO_EM_RESTART': '1', 05:09:51 INFO - 'PAGER': '/bin/cat', 05:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:51 INFO - 'PWD': '/builds/slave/test', 05:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:51 INFO - 'SHELL': '/bin/bash', 05:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:09:51 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:51 INFO - 'USER': 'cltbld', 05:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:51 INFO - 'XPC_FLAGS': '0x0', 05:09:51 INFO - 'XPC_SERVICE_NAME': '0', 05:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:51 INFO - Downloading/unpacking psutil>=0.7.1 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:53 INFO - Installing collected packages: psutil 05:09:53 INFO - Running setup.py install for psutil 05:09:53 INFO - building 'psutil._psutil_osx' extension 05:09:53 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:09:54 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:09:54 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:09:54 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:09:54 INFO - building 'psutil._psutil_posix' extension 05:09:54 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:09:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:09:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:09:54 INFO - ^ 05:09:54 INFO - 1 warning generated. 05:09:54 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:09:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:54 INFO - Successfully installed psutil 05:09:54 INFO - Cleaning up... 05:09:54 INFO - Return code: 0 05:09:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:09: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:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:09: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 mozsystemmonitor==0.0.0 05:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:54 INFO - 'HOME': '/Users/cltbld', 05:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:54 INFO - 'LOGNAME': 'cltbld', 05:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:54 INFO - 'MOZ_NO_REMOTE': '1', 05:09:54 INFO - 'NO_EM_RESTART': '1', 05:09:54 INFO - 'PAGER': '/bin/cat', 05:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:54 INFO - 'PWD': '/builds/slave/test', 05:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:54 INFO - 'SHELL': '/bin/bash', 05:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:09:54 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:54 INFO - 'USER': 'cltbld', 05:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:54 INFO - 'XPC_FLAGS': '0x0', 05:09:54 INFO - 'XPC_SERVICE_NAME': '0', 05:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09: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:09: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:09: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:09: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:09: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:09: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:09:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:57 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:09:57 INFO - Installing collected packages: mozsystemmonitor 05:09:57 INFO - Running setup.py install for mozsystemmonitor 05:09:57 INFO - Successfully installed mozsystemmonitor 05:09:57 INFO - Cleaning up... 05:09:57 INFO - Return code: 0 05:09:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:57 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:09:57 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:09:57 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:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:57 INFO - 'HOME': '/Users/cltbld', 05:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:57 INFO - 'LOGNAME': 'cltbld', 05:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:57 INFO - 'MOZ_NO_REMOTE': '1', 05:09:57 INFO - 'NO_EM_RESTART': '1', 05:09:57 INFO - 'PAGER': '/bin/cat', 05:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:57 INFO - 'PWD': '/builds/slave/test', 05:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:57 INFO - 'SHELL': '/bin/bash', 05:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:09:57 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:57 INFO - 'USER': 'cltbld', 05:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:57 INFO - 'XPC_FLAGS': '0x0', 05:09:57 INFO - 'XPC_SERVICE_NAME': '0', 05:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:00 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10: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:10: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:10: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:10: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:10: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:10: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:10:01 INFO - Downloading docopt-0.6.1.tar.gz 05:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:01 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:01 INFO - Running setup.py install for blobuploader 05:10:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:01 INFO - Running setup.py install for requests 05:10:02 INFO - Running setup.py install for docopt 05:10:02 INFO - Successfully installed blobuploader requests docopt 05:10:02 INFO - Cleaning up... 05:10:02 INFO - Return code: 0 05:10:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:02 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:02 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:02 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:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:02 INFO - 'HOME': '/Users/cltbld', 05:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:02 INFO - 'LOGNAME': 'cltbld', 05:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:02 INFO - 'MOZ_NO_REMOTE': '1', 05:10:02 INFO - 'NO_EM_RESTART': '1', 05:10:02 INFO - 'PAGER': '/bin/cat', 05:10:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:02 INFO - 'PWD': '/builds/slave/test', 05:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:02 INFO - 'SHELL': '/bin/bash', 05:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:02 INFO - 'USER': 'cltbld', 05:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:02 INFO - 'XPC_FLAGS': '0x0', 05:10:02 INFO - 'XPC_SERVICE_NAME': '0', 05:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jheeaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Zlqx2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:02 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JRYWR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fqTIgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-4V0XcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lnofEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-aMz_96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TMaO83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6S1qkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-osy_ES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IrU5a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-XoWUWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:03 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dpBvyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:04 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DlJNP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:04 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ZaU9aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:04 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-X1VgtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:04 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-PS5WPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:04 INFO - Running setup.py install for manifestparser 05:10:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:04 INFO - Running setup.py install for mozcrash 05:10:04 INFO - Running setup.py install for mozdebug 05:10:04 INFO - Running setup.py install for mozdevice 05:10:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Running setup.py install for mozfile 05:10:05 INFO - Running setup.py install for mozhttpd 05:10:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Running setup.py install for mozinfo 05:10:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Running setup.py install for mozInstall 05:10:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Running setup.py install for mozleak 05:10:05 INFO - Running setup.py install for mozlog 05:10:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:05 INFO - Running setup.py install for moznetwork 05:10:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:06 INFO - Running setup.py install for mozprocess 05:10:06 INFO - Running setup.py install for mozprofile 05:10:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:06 INFO - Running setup.py install for mozrunner 05:10:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:06 INFO - Running setup.py install for mozscreenshot 05:10:06 INFO - Running setup.py install for moztest 05:10:06 INFO - Running setup.py install for mozversion 05:10:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:07 INFO - Cleaning up... 05:10:07 INFO - Return code: 0 05:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:07 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:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:07 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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:07 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:07 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:07 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:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:07 INFO - 'HOME': '/Users/cltbld', 05:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:07 INFO - 'LOGNAME': 'cltbld', 05:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:07 INFO - 'MOZ_NO_REMOTE': '1', 05:10:07 INFO - 'NO_EM_RESTART': '1', 05:10:07 INFO - 'PAGER': '/bin/cat', 05:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:07 INFO - 'PWD': '/builds/slave/test', 05:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:07 INFO - 'SHELL': '/bin/bash', 05:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:07 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:07 INFO - 'USER': 'cltbld', 05:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:07 INFO - 'XPC_FLAGS': '0x0', 05:10:07 INFO - 'XPC_SERVICE_NAME': '0', 05:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-w5TEHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Z7F7N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jR3jIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-xRmqFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-C0J2XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GGQxs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-BCnI_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OBsSI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-AY3NMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-HFhjAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-v59JEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-aRAqRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-a45PBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IL8jvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-0f4g1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-U5J1rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kaVxMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:09 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:09 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:09 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:09 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:09 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:09 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:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:11 INFO - Downloading blessings-1.6.tar.gz 05:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:10:11 INFO - Installing collected packages: blessings 05:10:11 INFO - Running setup.py install for blessings 05:10:11 INFO - Successfully installed blessings 05:10:11 INFO - Cleaning up... 05:10:11 INFO - Return code: 0 05:10:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:10:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:12 INFO - Reading from file tmpfile_stdout 05:10:12 INFO - Current package versions: 05:10:12 INFO - blessings == 1.6 05:10:12 INFO - blobuploader == 1.2.4 05:10:12 INFO - docopt == 0.6.1 05:10:12 INFO - manifestparser == 1.1 05:10:12 INFO - mozInstall == 1.12 05:10:12 INFO - mozcrash == 0.17 05:10:12 INFO - mozdebug == 0.1 05:10:12 INFO - mozdevice == 0.48 05:10:12 INFO - mozfile == 1.2 05:10:12 INFO - mozhttpd == 0.7 05:10:12 INFO - mozinfo == 0.9 05:10:12 INFO - mozleak == 0.1 05:10:12 INFO - mozlog == 3.1 05:10:12 INFO - moznetwork == 0.27 05:10:12 INFO - mozprocess == 0.22 05:10:12 INFO - mozprofile == 0.28 05:10:12 INFO - mozrunner == 6.11 05:10:12 INFO - mozscreenshot == 0.1 05:10:12 INFO - mozsystemmonitor == 0.0 05:10:12 INFO - moztest == 0.7 05:10:12 INFO - mozversion == 1.4 05:10:12 INFO - psutil == 3.1.1 05:10:12 INFO - requests == 1.2.3 05:10:12 INFO - wsgiref == 0.1.2 05:10:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:12 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:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:12 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:12 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:12 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:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:12 INFO - 'HOME': '/Users/cltbld', 05:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:12 INFO - 'LOGNAME': 'cltbld', 05:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:12 INFO - 'MOZ_NO_REMOTE': '1', 05:10:12 INFO - 'NO_EM_RESTART': '1', 05:10:12 INFO - 'PAGER': '/bin/cat', 05:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:12 INFO - 'PWD': '/builds/slave/test', 05:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:12 INFO - 'SHELL': '/bin/bash', 05:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:12 INFO - 'USER': 'cltbld', 05:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:12 INFO - 'XPC_FLAGS': '0x0', 05:10:12 INFO - 'XPC_SERVICE_NAME': '0', 05:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:12 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:12 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:12 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:12 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:12 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:12 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:12 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:12 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:12 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:12 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:12 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:12 INFO - Cleaning up... 05:10:12 INFO - Return code: 0 05:10:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:10:12 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:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:12 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:12 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:12 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:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:12 INFO - 'HOME': '/Users/cltbld', 05:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:12 INFO - 'LOGNAME': 'cltbld', 05:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:12 INFO - 'MOZ_NO_REMOTE': '1', 05:10:12 INFO - 'NO_EM_RESTART': '1', 05:10:12 INFO - 'PAGER': '/bin/cat', 05:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:12 INFO - 'PWD': '/builds/slave/test', 05:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:12 INFO - 'SHELL': '/bin/bash', 05:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:12 INFO - 'USER': 'cltbld', 05:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:12 INFO - 'XPC_FLAGS': '0x0', 05:10:12 INFO - 'XPC_SERVICE_NAME': '0', 05:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:12 INFO - Downloading/unpacking jsonschema 05:10:12 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:12 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:12 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:12 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:12 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:12 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:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:14 INFO - Downloading/unpacking functools32 (from jsonschema) 05:10:14 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:14 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:14 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:14 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:14 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:14 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:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:15 INFO - warning: no files found matching '*.txt' 05:10:15 INFO - no previously-included directories found matching 'build' 05:10:15 INFO - no previously-included directories found matching 'dist' 05:10:15 INFO - no previously-included directories found matching '.git*' 05:10:15 INFO - Installing collected packages: jsonschema, functools32 05:10:15 INFO - Running setup.py install for functools32 05:10:15 INFO - warning: no files found matching '*.txt' 05:10:15 INFO - no previously-included directories found matching 'build' 05:10:15 INFO - no previously-included directories found matching 'dist' 05:10:15 INFO - no previously-included directories found matching '.git*' 05:10:15 INFO - Successfully installed jsonschema functools32 05:10:15 INFO - Cleaning up... 05:10:15 INFO - Return code: 0 05:10:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:15 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 0x108eeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108efd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89db45a970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, '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.v3FzUVdADg/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.OijRET9TWq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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:15 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:15 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:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:15 INFO - 'HOME': '/Users/cltbld', 05:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:15 INFO - 'LOGNAME': 'cltbld', 05:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:15 INFO - 'MOZ_NO_REMOTE': '1', 05:10:15 INFO - 'NO_EM_RESTART': '1', 05:10:15 INFO - 'PAGER': '/bin/cat', 05:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:15 INFO - 'PWD': '/builds/slave/test', 05:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:15 INFO - 'SHELL': '/bin/bash', 05:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:15 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:15 INFO - 'USER': 'cltbld', 05:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:15 INFO - 'XPC_FLAGS': '0x0', 05:10:15 INFO - 'XPC_SERVICE_NAME': '0', 05:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:16 INFO - Downloading/unpacking flake8 05:10:16 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:16 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:16 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:16 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:16 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:16 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:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:10:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:10:18 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:18 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:18 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:18 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:18 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:18 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:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:10:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:10:18 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:18 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:18 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:18 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:18 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:18 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:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:10:19 INFO - no previously-included directories found matching 'docs/_build' 05:10:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:10:19 INFO - Running setup.py install for pep8 05:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:10:19 INFO - no previously-included directories found matching 'docs/_build' 05:10:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:10:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:10:19 INFO - Cleaning up... 05:10:20 INFO - Return code: 0 05:10:20 INFO - Running post-action listener: _resource_record_post_action 05:10:20 INFO - Running post-action listener: _start_resource_monitoring 05:10:20 INFO - Starting resource monitoring. 05:10:20 INFO - ##### 05:10:20 INFO - ##### Running install step. 05:10:20 INFO - ##### 05:10:20 INFO - Running pre-action listener: _resource_record_pre_action 05:10:20 INFO - Running main action method: install 05:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:20 INFO - Reading from file tmpfile_stdout 05:10:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:10:20 INFO - Reading from file tmpfile_stdout 05:10:20 INFO - Output received: 05:10:20 INFO - Usage: mozinstall [options] installer 05:10:20 INFO - Options: 05:10:20 INFO - -h, --help show this help message and exit 05:10:20 INFO - -d DEST, --destination=DEST 05:10:20 INFO - Directory to install application into. [default: 05:10:20 INFO - "/builds/slave/test"] 05:10:20 INFO - --app=APP Application being installed. [default: firefox] 05:10:20 INFO - mkdir: /builds/slave/test/build/application 05:10:20 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:10:20 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:10:54 INFO - Reading from file tmpfile_stdout 05:10:54 INFO - Output received: 05:10:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:10:54 INFO - Running post-action listener: _resource_record_post_action 05:10:54 INFO - ##### 05:10:54 INFO - ##### Running run-tests step. 05:10:54 INFO - ##### 05:10:54 INFO - Running pre-action listener: _resource_record_pre_action 05:10:54 INFO - Running main action method: run_tests 05:10:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:54 INFO - 2016-04-14 05:10:54.033 screenresolution[1914:14110] starting screenresolution argv=screenresolution get 05:10:54 INFO - 2016-04-14 05:10:54.036 screenresolution[1914:14110] Display 0: 1600x1200x32@60 05:10:54 INFO - 2016-04-14 05:10:54.042 screenresolution[1915:14112] starting screenresolution argv=screenresolution list 05:10:54 INFO - Available Modes on Display 0 05:10:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:54 INFO - Intel Iris: 05:10:54 INFO - Chipset Model: Intel Iris 05:10:54 INFO - Type: GPU 05:10:54 INFO - Bus: Built-In 05:10:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:54 INFO - Vendor: Intel (0x8086) 05:10:54 INFO - Device ID: 0x0a2e 05:10:54 INFO - Revision ID: 0x0009 05:10:54 INFO - Displays: 05:10:54 INFO - Display: 05:10:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:54 INFO - Display Serial Number: 1600x1200 60 05:10:54 INFO - Main Display: Yes 05:10:54 INFO - Mirror: Off 05:10:54 INFO - Online: Yes 05:10:54 INFO - Rotation: Supported 05:10:54 INFO - Return code: 0 05:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:54 INFO - Python 2.7.3 05:10:54 INFO - Return code: 0 05:10:54 INFO - grabbing minidump binary from tooltool 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 - 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 0x108d8ce00>, '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 0x108bf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d87b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:54 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:10:54 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:10:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:54 INFO - Return code: 0 05:10:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:10:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:10:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:10:55 INFO - Return code: 0 05:10:55 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-e10s', '--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-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:10:55 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-e10s --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-0033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v3FzUVdADg/Render', 05:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:55 INFO - 'HOME': '/Users/cltbld', 05:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:55 INFO - 'LOGNAME': 'cltbld', 05:10:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:55 INFO - 'MOZ_NO_REMOTE': '1', 05:10:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:55 INFO - 'NO_EM_RESTART': '1', 05:10:55 INFO - 'PAGER': '/bin/cat', 05:10:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:55 INFO - 'PWD': '/builds/slave/test', 05:10:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:55 INFO - 'SHELL': '/bin/bash', 05:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OijRET9TWq/Listeners', 05:10:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 05:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:55 INFO - 'USER': 'cltbld', 05:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:55 INFO - 'XPC_FLAGS': '0x0', 05:10:55 INFO - 'XPC_SERVICE_NAME': '0', 05:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--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-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:10:55 INFO - mozversion application_buildid: 20160414032548 05:10:55 INFO - mozversion application_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:10:55 INFO - mozversion application_display_name: Nightly 05:10:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:55 INFO - mozversion application_name: Firefox 05:10:55 INFO - mozversion application_remotingname: firefox 05:10:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:10:55 INFO - mozversion application_vendor: Mozilla 05:10:55 INFO - mozversion application_version: 48.0a1 05:10:55 INFO - mozversion platform_buildid: 20160414032548 05:10:55 INFO - mozversion platform_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:10:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:10:55 INFO - mozversion platform_version: 48.0a1 05:10:55 INFO - using testdate: 1460635855 05:10:55 INFO - actual date: 1460635855 05:10:55 INFO - starting webserver on 'localhost:52304' 05:10:55 INFO - SUITE-START | Running 2 tests 05:10:55 INFO - TEST-START | dromaeo_css 05:10:55 INFO - operating with platform_type : mac_ 05:10:55 INFO - Initialising browser for dromaeo_css test... 05:10:55 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpSTfvAM/profile http://localhost:52304/getInfo.html) 05:10:58 INFO - PROCESS | 1933 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:10:58 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 05:10:58 INFO - PROCESS | 1933 | colorDepth:24 05:10:58 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 05:10:58 INFO - PROCESS | 1933 | __metrics 05:10:58 ERROR - PROCESS | 1933 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:10:59 INFO - TEST-INFO | 1933: exit 0 05:10:59 INFO - Browser initialized. 05:10:59 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:59 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpSTfvAM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:11 INFO - PROCESS | 1936 | RSS: Main: 248139776 05:11:11 INFO - PROCESS | 1936 | 05:12:00 INFO - PROCESS | 1936 | 1460635920993 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:12 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52304/tests/dromaeo/cssquery-ext.html) 05:14:13 INFO - PROCESS | 1936 | RSS: Main: 263540736 05:14:13 INFO - PROCESS | 1936 | 05:17:15 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-ext.html (next: http://localhost:52304/tests/dromaeo/cssquery-jquery.html) 05:17:16 INFO - PROCESS | 1936 | RSS: Main: 275406848 05:17:16 INFO - PROCESS | 1936 | 05:20:18 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52304/tests/dromaeo/cssquery-mootools.html) 05:20:18 INFO - PROCESS | 1936 | RSS: Main: 277434368 05:20:18 INFO - PROCESS | 1936 | 05:23:20 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52304/tests/dromaeo/cssquery-prototype.html) 05:23:21 INFO - PROCESS | 1936 | RSS: Main: 278966272 05:23:21 INFO - PROCESS | 1936 | 05:26:23 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52304/tests/dromaeo/cssquery-yui.html) 05:26:24 INFO - PROCESS | 1936 | RSS: Main: 281849856 05:26:24 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52304/tests/dromaeo/cssquery-yui.html (next: http://localhost:52304/tests/dromaeo/cssquery-dojo.html) 05:29:26 INFO - PROCESS | 1936 | RSS: Main: 283111424 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | __start_tp_report 05:29:26 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 05:29:26 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 05:29:26 INFO - PROCESS | 1936 | |i|pagename|runs| 05:29:26 INFO - PROCESS | 1936 | |0;dojo.html;3475.913102172445;3499.1077275294806;3504.386732321843;3504.474328850672;3567.7680950738186;5828.941710582898;5874.148248503964;5875.794347197849;5901.970490147554;5908.793192238272;5773.509251713599;5828.766849326022;5906.084556893688;5969.522438204944;5990.730417131229;5262.973685131569;5279.41926388097;5317.627766056358;5329.866753331178;5403.459654034585;5331.173668081434;5347.465253474641;5374.140137577989;5378.838634840961;5393.379761327439;4254.510731265882;5029.84910452687;5178.922316165261;5220.321358223426;5234.057869583474;5272.657277277003;5313.867153321159;5316.415194328621;5318.825330494228;5334.03987282289;5151.150060240056;5195.5324020837925;5250.894982100336;5258.6318957672975;5292.576593872481;5592.692401917893;5720.799772008001;5757.568182386338;5760.452756988079;5763.971180144114;6189.6905154742535;6240.4071613196675;6247.437730604267;6259.530535209832;6290.3709629037185;6172.105044768528;6198.132261483396;6269.373062693694;6280.811575652738;6282.591631543935;6000.489958353545;6031.517478601701;6035;6053.636781793107;6094.878102437927;6377.872442551123;6426.485881129498;6461.57999730016;6466.773662921775;6468.741250350027;6048.697565121726;6156.5998210116195;6231.283402408781;6246;6268.216472940882;6157.9384206158265;6239;6261.592996455216;6284.874302513924;6320.494360451153;6230.563860529719;6257.124002639634;6259.280182778948;6279.905801412982;6308.716107775161;6141.723622437001;6154.3845615438095;6254;6262.436380725757;6307.463865571478;4160.375943608483;4310.181065597527;4997.90004199914;5028.396592408862;5057.317262169634;3713;4479.216137175981;4499.797509112098;4515.909681806345;4568.743595511261;5043.949560504421;5856.912146317809;5899.675517846474;5927.199828023248;5927.496162826122;6243.094751261133;6245.344238854855;6275.6862156892885;6303.905441418382;6308.400701933309;5911.142884281841;5913.556483263773;5919.9936010878255;5958.225430694069;5998.610090344114;5372;5422.0511410503;5461.590380721462;5489.862753431196;5565.610407271533;6452.161219041588;6476.870462590816;6510.414062734377;6517.348265173445;6520.967395162994;6266;6282.43458088756;6286.905696414649;6292.087647291209;6296.590721603081;6334.588251763805;6428.935710643021;6493.935060649522;6545.050967609765;6553.311902250195;6207.255129384503;6213.9067913982235;6217.347178546188;6226.40849119333;6235.844103897439;6122.816315510364;6266.154069200599;6267.2479302484;6269.937300627118;6273.153124328157;6100.672006988121;6125.264968203665;6127.281156068254;6185.2886918003205;6197.667501487202;6052.515798736003;6179.351168127282;6217.440430361289;6219.595726277777;6221.813345599639;5276.788928442819;6025.307089684742;6030.939690603038;6098.969505152446;6103.84027034862;6102.095423428566;6173.444390004742;6202.906956395748;6212.627242365473;6225.844353891189;6083.239595050619;6147.415995480422;6149.2313460817395;6168.660723660242;6173.382661733791 05:29:26 INFO - PROCESS | 1936 | |1;ext.html;499.13649386561247;521.3509181069569;565.9122835960425;583.6614763437204;626.2266101364817;36446.81776591117;37210.81394593027;37217.88346349607;37272.62727372725;37446.62553374466;39302.21395572094;39783.801080994555;39865.40201896967;39959.80020099903;40032.199356012934;9281.535923203846;9464.1482266596;9496.097870702306;9524.571394287239;9534;9411.62353505859;9541.141297283242;9541.379810312343;9555.23558115349;9561.856572151388;9451.527423628826;9467.857982130274;9549.379290346105;9552.713418597454;9560.187384072327;8858.9369275687;8866.246369058637;9434.669786557472;9550.044995500464;9556.808863822685;9377.156055954996;9406.9529652352;9420.764480887965;9474.147326740558;9534.141927226583;31122.533162002586;37012;37014.07464813374;37307.25385492275;37526;2989.1032690192856;3019.909402717922;3226.2257058306072;3272.590926134233;3767.773933563995;25514.36214094644;25756;26274.474510509874;26353.20940371792;27001.32496687579;2438.5244877248942;2540.2252313044514;2645.8412495250345;2677.357434215774;2741.5606806426586;321396;350254;350706;354027;355302;34411.311773764886;35355;46572.53427465682;56037;58213;28726.85636571804;34221.631134754825;34522.136946576036;34572;34836.477452838226;12685;14474.565763026916;14527.201003944885;14573.854261457249;14668.706625867637;12523.248605083723;12684.365781711062;12724.681882953;12823.807642885364;12855.678608034874;7851.018622672166;7934.325582325451;8047.396445266511;8061;8078.434509584273;3434.587849458081;3445.1214940190093;3464.7921124732597;3467.8092704901474;3485.4249048906645;973.5181085362791;993.7614972406715;1014.9900848655635;1033.1270076785104;1102.327580176092;28248;28255.43489130206;28422.85788571044;28533.857330713214;28611;992.7567745902301;1055.6199768083484;1124.8481455003634;1189.41718557906;1193.985112654237;12306.446209920576;14613.415463381558;14617.85382146165;14723.558293251219;15006.324715387835;617456;617957;623483;633487.8279260204;633602.8319858464;48805.26792098121;57831.42168578345;58669.82660346769;58891;58939;1453.25157543865;1464.8022516960075;1477.793108964746;1497.5282785922618;1500.1374209829173;22255.332340029825;27726;27814.3046423841;28073.71926280711;28739;47519.09923603017;47719.76140119277;48304;48557.27164092471;48659.75670121627;442814;457770;459210;459457;460252;26031.088911887993;26175.214743557724;26385.472290554466;26394;26421;44500.33249501196;44768.328475072245;44985.550144498135;45540.08919821651;46749.065018700116;37324;37329;37653.62346376611;37831.62168378281;38479.61520384761;30867;33343;37451.43822842605;37903.62096379111;38126.23747525089;36778.448323275705;37062.07344816293;37068.6293137076;37339.43990840194;37584.43623345707;25631.743682562937;31991.040268791974;32255.677443225268;32934.34131317408;33174;23977.400564986016;24001.519969600162;24311.63532546978;24326.148584799448;24529 05:29:26 INFO - PROCESS | 1936 | |2;jquery.html;317.8728508596561;341.3803945838303;389.495603193864;396.827380089661;404.20573572929226;19274.229030838767;19863.10616022279;19965;20659.69010464844;20712.68930966035;19453.416397508063;19995.200191992302;20406.387808365773;20473.18107275711;20502;4077.4903137107863;4815.903681926359;5164.535191832734;5284.630075894689;5480.342358916916;4491.483479399878;5375.543078838304;5386.838394848141;5424.674519528841;5463.890722185554;5235.973820130894;5300.25796388504;5301.1783173607955;5409.080456322435;5410.48600382963;5086.491350864921;5999.250093738283;6017.398260173992;6032.879342413149;6047.5766696331275;5092.388913330424;5277.47225277473;5298.152295632719;5305.15117581189;5309.495597918191;10278.845817312746;12960.48158073676;13238.53665121716;13473.663158421019;13481.05632605718;1841.7292068472711;2075.3151460018157;2112.197365001305;2143.5498545305504;2192.5943700415314;9442.831052962498;9750.707478775577;9984.201263898944;10042;10122.924800415896;1706.3089448773267;1735.687576236277;1740.7040803063383;1795;1800.0729624243525;118804.40597797043;122701;122752;125410.74589254081;126663;11458.828117578243;11500.309981400975;11745.823812642817;11796.410179490991;11851.111166662535;14960.775588366183;14998.550043498712;15131.621709457351;15207.695846083237;15219;6987.930120698829;8441.269539744339;8772.78068048304;8834.690785822466;8852.291816654651;8501.19238672325;8560.700375486827;8571.954013847522;8649.221570058598;8774.824503510023;4533.274676051816;4609.631229501699;4652.092841895866;4667.8599642010795;4692.202325604587;1700.2518891687619;1921.28912302449;1989.5125694205012;1996.1316827180224;2027.1485975890162;735.6321839080459;881.3433991676114;945.2957546627669;961.3272905145137;1016.9338992965434;20322.695159572617;20408.18367265293;20453.795462045488;20769.68845467289;20819.167233310498;947.7914858731068;960.9471479068718;969.9321047526605;981.7776867799636;1000.8899021107673;10764.461776911123;10932.180086493545;10975;10977.890221097847;10981.560737570568;499273;606958;616407;619160;628431;77299.99999999888;113614;125808;128110;134595;870.190722627962;931.5807886451124;1018.6791160784329;1032.8450732390052;1051.558345494894;15024.023438476464;15481.612959676098;16319.347226110822;17455;17590.648187036444;39832;47430.76284618555;48082;48145;48981.02037959149;55881;69956;70250.64874675593;70521.29478705148;72959;7647.961760191163;7827.647755851114;7900.68397264103;8032.437729359122;8246.463979841532;8503.914960850312;8988.595513201792;9429.717108486757;10679.679609611723;12234.449449774604;10599.788004240027;10939;10974.670759877217;10999.34003959765;11083.224174307721;10377.221708371751;10426.426546540013;10714.19643526723;10781.353118812582;10965.61620343284;10904.400257985886;10926.890731092906;11030.889691102986;11242.213045086766;11275.267107637977;7759.418043646636;7854.293113619801;8103.716369927024;8103.918960810316;8147.877781833396;8011.319037881845;9701;10972.286801358205;11079.446027698421;11092.001719845253 05:29:26 INFO - PROCESS | 1936 | |3;mootools.html;1628.519586721917;1631.3963833381647;1638.9098599577026;1667.9325231851617;1989.2242025610005;8070.475419097755;8601.397902146837;8656.047834738174;8941.569348904168;8969.506677132764;6765.966170169155;8678.435901666388;8705.95647021764;8749.431286966343;9022.684206052789;1999.2702663527816;2000.6498862699043;2429.708434987804;2434.2453839309783;2435.427674496492;2398.796102331304;2408.7350391456926;2440.3777036855577;2448.338948483917;2456.4718585504115;2395.4490467192554;2401.2916189723987;2404.471016376403;2446.0704932125855;2457.950840983179;2137.177186783092;2362.7400985891536;2415.6014257647553;2422.2612103308484;2422.8061755059553;2402.807775377983;2403.291029146406;2411.3248290478696;2417.9274621761374;2430.6475561043726;8738.126187381275;8800.031996480382;8853.11468853116;8907.643694252221;8910.688125915562;5258.789648414059;5324.600654950894;5389.865253368658;5444.6188766785945;5464.0711079116645;3584.731145164123;3596.226811235571;3619.8009109498967;3679.908002299937;3688.2807852468777;993.2054356514759;1005.6882366466418;1013.9340942838766;1036.834106542957;1048.6067724603274;11536.711582210428;11556.653300401002;11649.534018639244;11852.873976972174;11859;4155.397467367215;4238.512571054368;4750.714957102516;4843.903121937612;4969.329140566049;9810.65662701802;9890.752731181634;9891.455969921579;9901.75245618851;9935.552900119512;5319.973400133052;5390.676559406448;5426.375966763794;5447.972760136174;5487.286652735199;4332.393464914914;4901.289313049588;4926.507349265116;4933.50664933511;5036.924446133311;3973.0861901762178;4004.4994375703036;4054.614811592894;4090.8977275567754;4133.111381053088;1150.6950658075616;1352.9120607160503;1382.5437605590132;1388.2780953904116;1397.0918902713115;898.2006014646969;920.4661296448044;927.9443233406017;932.6269492203173;943.7782121201512;5758.193852860602;5818.5636077294375;5858.209141765807;5858.736356863951;5911.231539899785;408.15511890387205;431.8985038515946;442.84859365649055;454.4569239758457;463.6777439679458;3780.3951367781024;3863.61363863617;3915.9021024474614;3940.9359472942147;3942.113024569449;10456.424896630759;10904.672859814218;10907.727306817234;10976.341419514854;10978.396188209725;10633.149348052137;10738.94630526858;10888.836667449994;10933.835992460119;11002.779944401067;8093.8381232376205;8178.814071959532;8179.345652347798;8323.375746819012;8389.161083891684;5630.296212973379;5652.745626446902;5717.771289148387;5725.026745453366;5835.94164058354;9702.320837541587;9771.169450596495;9964.202863770735;9982.401055936667;10126.392416455037;10188.490575471345;10339.379637221791;10340.017698318648;10387.896121038693;10392.168626510012;6727.764528241488;7090;7340.926590734024;7414.66634001482;7424.220456852169;8114.472559283628;8168.7957801055445;8206.712765053195;8210.507369557845;8217.79455513617;7220.458465614995;7294.1247050354295;7385.261473852786;7513.098428188652;7533.096028476618;7274.96362518184;7298.160711518243;7365.447591430771;7397.963010184914;7402.963585097983;6914.757983470555;6922.619255940972;6944.201416836926;7014.473914456538;7041.471889608402;4787.664863459664;4828.855134345975;4864.173090574682;4975.601951843916;5026.572741317029;4864.1043614867995;4880.389951256093;4915.311856340044;4978.352814134139;5131.307273518028 05:29:26 INFO - PROCESS | 1936 | |4;prototype.html;278.9609454676346;311.48293832238477;327.4809427058113;328.9950650740238;330.3113009375452;4887.802488390349;5095.719735414551;5827.912581311284;5988.311344195419;6014.789482368118;6025.548083893704;6136.23297087864;6226.501879849612;6231.50147988161;6235.189425374706;4400.691951563392;4408.537103604123;4575.885602859922;4654.511276315989;4660.231061874803;3507.771994820341;4498.392716983214;4630.819141119026;4633.235516139833;4658.394408726877;4119.2997190477545;4478.6417086633155;4480.955190448102;4492.258777301741;4499.61753250974;4512.075024619945;4526.615237704799;4530.161920044802;4530.569595888385;4545.863624091283;3440.2603440260214;3566.661167189113;3694.4273637586216;3859.517560304962;3868.6324799144036;4911.828086017008;5564.666120032811;5892.941070589282;5907.172995780594;5935.732892019826;4578.542145785428;5067.493250674903;5215.739213039371;5294.258803767476;5296.629235953485;5108.883581015578;5357.4106848246665;6130.908036379458;6620.6358650274215;6826.761063362758;4242.9787851060855;4268.338407546804;4288.571142885718;4294.806733696991;4295.806688699016;5473.534749546293;5777.508911742507;6168.537359698041;6580.93419065808;6848.14398200225;4323.524412314643;4336.457942757155;4359.411479450297;4363;4369.80335884886;3692.372296709566;4412.558744125626;4413.322937283811;4437.955620443819;4477.216487114807;5102.464241254689;5205.375354957429;5248.134057880483;5253.947460525423;5291.788328466895;5470.425605311465;6593.241777195684;6619;6625.238097618739;6656.633885136364;1230.9290916902173;1315.467235769515;1511.7430036893754;1523.3830298729033;1527.8548537888882;950.7879214719627;960.6061514778908;983.7688143286322;1002.04304888831;1002.8846682631445;6030.969845150834;6075.27096748393;6127.5404344674325;6153.661608588708;6161.168242287324;6802.557833740791;6806.55757375769;6818.28408017161;6835.418989385858;6843.315668433117;5128.897422051538;5800.767969281266;6069.332373438918;6111.938880611226;6123.377305014174;3543.8936831895085;3582.964170358263;3585.6414358563934;3601.513795637555;3605.2428989911887;6523.336549179929;6863.759768408082;7049.964750176319;7157.856842863218;7164.641767911688;5551.306086739158;6197.1324014637985;6648.335166483313;6785.728570857111;6856.177258728984;6749.257581666013;6807.256009194923;7087;7096.680649370791;7180.676869540883;5493.890122197454;6103.450689437972;6682.398584127257;6831.077804496329;6866.588004719732;6470.450011748866;6644.800655980328;6750.6624668767345;6781.830454238683;6786.830329241809;6205.22434695663;6798;7088.964555177191;7135.678894449658;7281.890771638324;4363.018320877777;4373.956260437355;4396.4064851244675;4403.647708183402;4411.272140096977;5554.389017208023;6465.741370345134;6610;6865.450763938773;6913.2049814272;5971.761129554943;6606.669666516559;6611.504137189634;6619.933800661932;6660.435732243218;6436.903446448214;6611.966940165268;6630.7679231226675;6645.169353830771;6661.16735408074;5767.885341915318;6194.287656919512;6428.935710643021;6566.179227596551;6586.473082153512;6387.808365749035;6466.191726034246;6648.933510665025;6662.6335551545135;6673.799786006428;5187.688738675692;6445.935540644721;6534.183227096613;6544.607323560602;6611.537192396486 05:29:26 INFO - PROCESS | 1936 | |5;yui.html;665.7436886798582;730.5142080516457;742.1279996004695;750.8460765543065;761.9238076192378;14845.628859278524;15162.014469059503;15312.923435382836;15323.92338038311;15371.923140384297;13957;14049;14055;14086.859131408683;14104.576862694135;909.7680091576639;919.0625561926864;926.5321012888481;929.7907970706605;930.9441433514011;727.2109760909419;892.7991201979568;911.3529394130143;928.9071092890724;931.4783721116197;963.7446076789656;966.0918736387808;966.3766870368609;966.4346357380905;970.3838062830083;955.8088382323563;958.1855422890487;959.9904000960006;960.5437417226831;963.0802583532709;692.6432887063166;701.2461603775961;719.3094629156017;720.4452571519953;723.9058922340115;14663.046901951338;14708.926455367762;14770.187639679812;14778.630534236623;15054;12000.69998250042;12077.758444831143;12104.757904841854;12341.69145771354;12416;9026.593803278869;10062.345947513388;10192.184625229964;10272.435016074112;10557.683269501927;3466;3488.180277634754;3518.652346183563;3524.7356448266482;3550.378683730337;57516.562085948186;71773;84018.57990710069;87228;87733;9386.90613093874;9526.285528585384;9580;9660.227181825438;9888.851667224997;20455.590888182152;21013.894930525556;21071.367858964255;21077.367678969655;21154.57690846205;7322.670479828421;7469.402447804163;7554.43341749371;7645.044369453818;7823.139454659983;4092.7748973806724;4124.216398884262;4124.340105583093;4147.066909945299;4153.584641535822;3539.557555305587;3626.5285512883156;3631.473436351714;3655.250673611903;3664.523611930432;572.3360901354409;652.0447544347553;667.4460198035619;668.8996650502366;674.919009718827;527.8996990571779;552.8148070396381;575.7869588252373;580.3268208877681;583.9708014599253;7815.374770018385;7831.451798374173;7918.604069796487;8091.216974497415;8148.755537333771;559.9780400768614;573.4781348972415;588.8586739182565;589.8820235952827;591.7721677154327;3748.8125593720206;3764.378877485184;3765.6610905018133;3768.378217594121;3861.3821788514265;67726.322736772;82491.58754206191;84117;84467;85112.14887851167;53678;70571.2942870575;72043;72926.63536682283;73693.631531842;62836.68581657154;63353;64304.35695643077;64377;65514.67242663661;7339.6330183489545;7347.742829000959;7447.590382529013;7599.65801538921;7757.495762775401;32734.87223330476;41245.38131928084;42575.7871210642;42663.36004959866;42834.78582607067;51894.481055190474;55106.72446637741;60273.79452411015;60459.69770151121;60607.39392606018;9590.376625519319;9664.71005869825;9722.9027709722;9839.311248212556;10281.485925703535;10506.579736810441;10517.737056573647;10545.420001899969;10637.893621064;10726.839097413389;9530.952345238507;9531.904680953101;9661.275404344562;9685.41887486753;9831.754206144904;10215.284930054824;10267.794644107225;10489.093726409375;10738.19463540222;11181.049610783175;9636.614535418505;10109;10137.23970702215;10691.839622405825;10879;8986.68546600866;9060.909390906007;9225.261979041794;9265.768355791159;9584.520773961414;8979.494349874845;9171.495567743837;9266.536673166449;9324.927633322255;9402.153806157205 05:29:26 INFO - PROCESS | 1936 | __end_tp_report 05:29:26 INFO - PROCESS | 1936 | __start_cc_report 05:29:26 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,379 05:29:26 INFO - PROCESS | 1936 | __end_cc_report 05:29:26 INFO - PROCESS | 1936 | __startTimestamp1460636966865__endTimestamp 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | ------- Summary: start ------- 05:29:26 INFO - PROCESS | 1936 | Number of tests: 6 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5813.93 Median:6100.67 stddev:674.50 (11.1%) stddev-sans-first:651.15 05:29:26 INFO - PROCESS | 1936 | Values: 3475.9 3499.1 3504.4 3504.5 3567.8 5828.9 5874.1 5875.8 5902.0 5908.8 5773.5 5828.8 5906.1 5969.5 5990.7 5263.0 5279.4 5317.6 5329.9 5403.5 5331.2 5347.5 5374.1 5378.8 5393.4 4254.5 5029.8 5178.9 5220.3 5234.1 5272.7 5313.9 5316.4 5318.8 5334.0 5151.2 5195.5 5250.9 5258.6 5292.6 5592.7 5720.8 5757.6 5760.5 5764.0 6189.7 6240.4 6247.4 6259.5 6290.4 6172.1 6198.1 6269.4 6280.8 6282.6 6000.5 6031.5 6035.0 6053.6 6094.9 6377.9 6426.5 6461.6 6466.8 6468.7 6048.7 6156.6 6231.3 6246.0 6268.2 6157.9 6239.0 6261.6 6284.9 6320.5 6230.6 6257.1 6259.3 6279.9 6308.7 6141.7 6154.4 6254.0 6262.4 6307.5 4160.4 4310.2 4997.9 5028.4 5057.3 3713.0 4479.2 4499.8 4515.9 4568.7 5043.9 5856.9 5899.7 5927.2 5927.5 6243.1 6245.3 6275.7 6303.9 6308.4 5911.1 5913.6 5920.0 5958.2 5998.6 5372.0 5422.1 5461.6 5489.9 5565.6 6452.2 6476.9 6510.4 6517.3 6521.0 6266.0 6282.4 6286.9 6292.1 6296.6 6334.6 6428.9 6493.9 6545.1 6553.3 6207.3 6213.9 6217.3 6226.4 6235.8 6122.8 6266.2 6267.2 6269.9 6273.2 6100.7 6125.3 6127.3 6185.3 6197.7 6052.5 6179.4 6217.4 6219.6 6221.8 5276.8 6025.3 6030.9 6099.0 6103.8 6102.1 6173.4 6202.9 6212.6 6225.8 6083.2 6147.4 6149.2 6168.7 6173.4 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:59817.21 Median:37065.35 stddev:131065.86 (353.6%) stddev-sans-first:131357.87 05:29:26 INFO - PROCESS | 1936 | Values: 499.1 521.4 565.9 583.7 626.2 36446.8 37210.8 37217.9 37272.6 37446.6 39302.2 39783.8 39865.4 39959.8 40032.2 9281.5 9464.1 9496.1 9524.6 9534.0 9411.6 9541.1 9541.4 9555.2 9561.9 9451.5 9467.9 9549.4 9552.7 9560.2 8858.9 8866.2 9434.7 9550.0 9556.8 9377.2 9407.0 9420.8 9474.1 9534.1 31122.5 37012.0 37014.1 37307.3 37526.0 2989.1 3019.9 3226.2 3272.6 3767.8 25514.4 25756.0 26274.5 26353.2 27001.3 2438.5 2540.2 2645.8 2677.4 2741.6 321396.0 350254.0 350706.0 354027.0 355302.0 34411.3 35355.0 46572.5 56037.0 58213.0 28726.9 34221.6 34522.1 34572.0 34836.5 12685.0 14474.6 14527.2 14573.9 14668.7 12523.2 12684.4 12724.7 12823.8 12855.7 7851.0 7934.3 8047.4 8061.0 8078.4 3434.6 3445.1 3464.8 3467.8 3485.4 973.5 993.8 1015.0 1033.1 1102.3 28248.0 28255.4 28422.9 28533.9 28611.0 992.8 1055.6 1124.8 1189.4 1194.0 12306.4 14613.4 14617.9 14723.6 15006.3 617456.0 617957.0 623483.0 633487.8 633602.8 48805.3 57831.4 58669.8 58891.0 58939.0 1453.3 1464.8 1477.8 1497.5 1500.1 22255.3 27726.0 27814.3 28073.7 28739.0 47519.1 47719.8 48304.0 48557.3 48659.8 442814.0 457770.0 459210.0 459457.0 460252.0 26031.1 26175.2 26385.5 26394.0 26421.0 44500.3 44768.3 44985.6 45540.1 46749.1 37324.0 37329.0 37653.6 37831.6 38479.6 30867.0 33343.0 37451.4 37903.6 38126.2 36778.4 37062.1 37068.6 37339.4 37584.4 25631.7 31991.0 32255.7 32934.3 33174.0 23977.4 24001.5 24311.6 24326.1 24529.0 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:33638.52 Median:1255.23 stddev:99380.33 (7917.3%) stddev-sans-first:99627.62 05:29:26 INFO - PROCESS | 1936 | Values: 317.9 341.4 389.5 396.8 404.2 19274.2 19863.1 19965.0 20659.7 20712.7 19453.4 19995.2 20406.4 20473.2 20502.0 4077.5 4815.9 5164.5 5284.6 5480.3 4491.5 5375.5 5386.8 5424.7 5463.9 5236.0 5300.3 5301.2 5409.1 5410.5 5086.5 5999.3 6017.4 6032.9 6047.6 5092.4 5277.5 5298.2 5305.2 5309.5 10278.8 12960.5 13238.5 13473.7 13481.1 1841.7 2075.3 2112.2 2143.5 2192.6 9442.8 9750.7 9984.2 10042.0 10122.9 1706.3 1735.7 1740.7 1795.0 1800.1 118804.4 122701.0 122752.0 125410.7 126663.0 11458.8 11500.3 11745.8 11796.4 11851.1 14960.8 14998.6 15131.6 15207.7 15219.0 6987.9 8441.3 8772.8 8834.7 8852.3 8501.2 8560.7 8572.0 8649.2 8774.8 4533.3 4609.6 4652.1 4667.9 4692.2 1700.3 1921.3 1989.5 1996.1 2027.1 735.6 881.3 945.3 961.3 1016.9 20322.7 20408.2 20453.8 20769.7 20819.2 947.8 960.9 969.9 981.8 1000.9 10764.5 10932.2 10975.0 10977.9 10981.6 499273.0 606958.0 616407.0 619160.0 628431.0 77300.0 113614.0 125808.0 128110.0 134595.0 870.2 931.6 1018.7 1032.8 1051.6 15024.0 15481.6 16319.3 17455.0 17590.6 39832.0 47430.8 48082.0 48145.0 48981.0 55881.0 69956.0 70250.6 70521.3 72959.0 7648.0 7827.6 7900.7 8032.4 8246.5 8503.9 8988.6 9429.7 10679.7 12234.4 10599.8 10939.0 10974.7 10999.3 11083.2 10377.2 10426.4 10714.2 10781.4 10965.6 10904.4 10926.9 11030.9 11242.2 11275.3 7759.4 7854.3 8103.7 8103.9 8147.9 8011.3 9701.0 10972.3 11079.4 11092.0 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:5699.66 Median:4908.30 stddev:3218.40 (65.6%) stddev-sans-first:3212.88 05:29:26 INFO - PROCESS | 1936 | Values: 1628.5 1631.4 1638.9 1667.9 1989.2 8070.5 8601.4 8656.0 8941.6 8969.5 6766.0 8678.4 8706.0 8749.4 9022.7 1999.3 2000.6 2429.7 2434.2 2435.4 2398.8 2408.7 2440.4 2448.3 2456.5 2395.4 2401.3 2404.5 2446.1 2458.0 2137.2 2362.7 2415.6 2422.3 2422.8 2402.8 2403.3 2411.3 2417.9 2430.6 8738.1 8800.0 8853.1 8907.6 8910.7 5258.8 5324.6 5389.9 5444.6 5464.1 3584.7 3596.2 3619.8 3679.9 3688.3 993.2 1005.7 1013.9 1036.8 1048.6 11536.7 11556.7 11649.5 11852.9 11859.0 4155.4 4238.5 4750.7 4843.9 4969.3 9810.7 9890.8 9891.5 9901.8 9935.6 5320.0 5390.7 5426.4 5448.0 5487.3 4332.4 4901.3 4926.5 4933.5 5036.9 3973.1 4004.5 4054.6 4090.9 4133.1 1150.7 1352.9 1382.5 1388.3 1397.1 898.2 920.5 927.9 932.6 943.8 5758.2 5818.6 5858.2 5858.7 5911.2 408.2 431.9 442.8 454.5 463.7 3780.4 3863.6 3915.9 3940.9 3942.1 10456.4 10904.7 10907.7 10976.3 10978.4 10633.1 10738.9 10888.8 10933.8 11002.8 8093.8 8178.8 8179.3 8323.4 8389.2 5630.3 5652.7 5717.8 5725.0 5835.9 9702.3 9771.2 9964.2 9982.4 10126.4 10188.5 10339.4 10340.0 10387.9 10392.2 6727.8 7090.0 7340.9 7414.7 7424.2 8114.5 8168.8 8206.7 8210.5 8217.8 7220.5 7294.1 7385.3 7513.1 7533.1 7275.0 7298.2 7365.4 7398.0 7403.0 6914.8 6922.6 6944.2 7014.5 7041.5 4787.7 4828.9 4864.2 4975.6 5026.6 4864.1 4880.4 4915.3 4978.4 5131.3 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:5256.67 Median:5921.45 stddev:1681.82 (28.4%) stddev-sans-first:1644.52 05:29:26 INFO - PROCESS | 1936 | Values: 279.0 311.5 327.5 329.0 330.3 4887.8 5095.7 5827.9 5988.3 6014.8 6025.5 6136.2 6226.5 6231.5 6235.2 4400.7 4408.5 4575.9 4654.5 4660.2 3507.8 4498.4 4630.8 4633.2 4658.4 4119.3 4478.6 4481.0 4492.3 4499.6 4512.1 4526.6 4530.2 4530.6 4545.9 3440.3 3566.7 3694.4 3859.5 3868.6 4911.8 5564.7 5892.9 5907.2 5935.7 4578.5 5067.5 5215.7 5294.3 5296.6 5108.9 5357.4 6130.9 6620.6 6826.8 4243.0 4268.3 4288.6 4294.8 4295.8 5473.5 5777.5 6168.5 6580.9 6848.1 4323.5 4336.5 4359.4 4363.0 4369.8 3692.4 4412.6 4413.3 4438.0 4477.2 5102.5 5205.4 5248.1 5253.9 5291.8 5470.4 6593.2 6619.0 6625.2 6656.6 1230.9 1315.5 1511.7 1523.4 1527.9 950.8 960.6 983.8 1002.0 1002.9 6031.0 6075.3 6127.5 6153.7 6161.2 6802.6 6806.6 6818.3 6835.4 6843.3 5128.9 5800.8 6069.3 6111.9 6123.4 3543.9 3583.0 3585.6 3601.5 3605.2 6523.3 6863.8 7050.0 7157.9 7164.6 5551.3 6197.1 6648.3 6785.7 6856.2 6749.3 6807.3 7087.0 7096.7 7180.7 5493.9 6103.5 6682.4 6831.1 6866.6 6470.5 6644.8 6750.7 6781.8 6786.8 6205.2 6798.0 7089.0 7135.7 7281.9 4363.0 4374.0 4396.4 4403.6 4411.3 5554.4 6465.7 6610.0 6865.5 6913.2 5971.8 6606.7 6611.5 6619.9 6660.4 6436.9 6612.0 6630.8 6645.2 6661.2 5767.9 6194.3 6428.9 6566.2 6586.5 6387.8 6466.2 6648.9 6662.6 6673.8 5187.7 6445.9 6534.2 6544.6 6611.5 05:29:26 INFO - PROCESS | 1936 | 05:29:26 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:16677.84 Median:32514.52 stddev:22996.04 (70.7%) stddev-sans-first:23029.12 05:29:26 INFO - PROCESS | 1936 | Values: 665.7 730.5 742.1 750.8 761.9 14845.6 15162.0 15312.9 15323.9 15371.9 13957.0 14049.0 14055.0 14086.9 14104.6 909.8 919.1 926.5 929.8 930.9 727.2 892.8 911.4 928.9 931.5 963.7 966.1 966.4 966.4 970.4 955.8 958.2 960.0 960.5 963.1 692.6 701.2 719.3 720.4 723.9 14663.0 14708.9 14770.2 14778.6 15054.0 12000.7 12077.8 12104.8 12341.7 12416.0 9026.6 10062.3 10192.2 10272.4 10557.7 3466.0 3488.2 3518.7 3524.7 3550.4 57516.6 71773.0 84018.6 87228.0 87733.0 9386.9 9526.3 9580.0 9660.2 9888.9 20455.6 21013.9 21071.4 21077.4 21154.6 7322.7 7469.4 7554.4 7645.0 7823.1 4092.8 4124.2 4124.3 4147.1 4153.6 3539.6 3626.5 3631.5 3655.3 3664.5 572.3 652.0 667.4 668.9 674.9 527.9 552.8 575.8 580.3 584.0 7815.4 7831.5 7918.6 8091.2 8148.8 560.0 573.5 588.9 589.9 591.8 3748.8 3764.4 3765.7 3768.4 3861.4 67726.3 82491.6 84117.0 84467.0 85112.1 53678.0 70571.3 72043.0 72926.6 73693.6 62836.7 63353.0 64304.4 64377.0 65514.7 7339.6 7347.7 7447.6 7599.7 7757.5 32734.9 41245.4 42575.8 42663.4 42834.8 51894.5 55106.7 60273.8 60459.7 60607.4 9590.4 9664.7 9722.9 9839.3 10281.5 10506.6 10517.7 10545.4 10637.9 10726.8 9531.0 9531.9 9661.3 9685.4 9831.8 10215.3 10267.8 10489.1 10738.2 11181.0 9636.6 10109.0 10137.2 10691.8 10879.0 8986.7 9060.9 9225.3 9265.8 9584.5 8979.5 9171.5 9266.5 9324.9 9402.2 05:29:26 INFO - PROCESS | 1936 | -------- Summary: end -------- 05:29:26 INFO - PROCESS | 1936 | 05:29:27 INFO - TEST-INFO | 1936: exit 0 05:29:27 INFO - TEST-OK | dromaeo_css | took 1111630ms 05:29:27 INFO - TEST-START | kraken 05:29:27 INFO - operating with platform_type : mac_ 05:29:27 INFO - Initialising browser for kraken test... 05:29:27 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpK4guiE/profile http://localhost:52304/getInfo.html) 05:29:29 INFO - PROCESS | 1941 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:29:30 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 05:29:30 INFO - PROCESS | 1941 | colorDepth:24 05:29:30 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 05:29:30 INFO - PROCESS | 1941 | __metrics 05:29:30 ERROR - PROCESS | 1941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:29:31 INFO - TEST-INFO | 1941: exit 0 05:29:31 INFO - Browser initialized. 05:29:31 INFO - Running cycle 1/1 for kraken test... 05:29:31 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpK4guiE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:43 INFO - PROCESS | 1944 | RSS: Main: 247730176 05:29:43 INFO - PROCESS | 1944 | 05:29:46 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52304/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:47 INFO - PROCESS | 1944 | RSS: Main: 259989504 05:29:47 INFO - PROCESS | 1944 | 05:29:49 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52304/tests/kraken/driver.html?testName=audio-dft) 05:29:50 INFO - PROCESS | 1944 | RSS: Main: 269004800 05:29:50 INFO - PROCESS | 1944 | 05:29:53 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52304/tests/kraken/driver.html?testName=audio-fft) 05:29:54 INFO - PROCESS | 1944 | RSS: Main: 272166912 05:29:54 INFO - PROCESS | 1944 | 05:29:56 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52304/tests/kraken/driver.html?testName=audio-oscillator) 05:29:57 INFO - PROCESS | 1944 | RSS: Main: 273059840 05:29:57 INFO - PROCESS | 1944 | 05:29:59 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52304/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:59 INFO - PROCESS | 1944 | RSS: Main: 274710528 05:29:59 INFO - PROCESS | 1944 | 05:30:02 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52304/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:03 INFO - PROCESS | 1944 | RSS: Main: 274894848 05:30:03 INFO - PROCESS | 1944 | 05:30:06 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52304/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:07 INFO - PROCESS | 1944 | RSS: Main: 274894848 05:30:07 INFO - PROCESS | 1944 | 05:30:09 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52304/tests/kraken/driver.html?testName=json-parse-financial) 05:30:10 INFO - PROCESS | 1944 | RSS: Main: 274890752 05:30:10 INFO - PROCESS | 1944 | 05:30:12 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52304/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:12 INFO - PROCESS | 1944 | RSS: Main: 275353600 05:30:12 INFO - PROCESS | 1944 | 05:30:14 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:15 INFO - PROCESS | 1944 | RSS: Main: 274952192 05:30:15 INFO - PROCESS | 1944 | 05:30:17 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:30:18 INFO - PROCESS | 1944 | RSS: Main: 275349504 05:30:18 INFO - PROCESS | 1944 | 05:30:21 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:30:21 INFO - PROCESS | 1944 | RSS: Main: 275636224 05:30:21 INFO - PROCESS | 1944 | 05:30:24 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:30:25 INFO - PROCESS | 1944 | RSS: Main: 275472384 05:30:25 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52304/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52304/tests/kraken/driver.html?testName=ai-astar) 05:30:27 INFO - PROCESS | 1944 | RSS: Main: 277262336 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | __start_tp_report 05:30:27 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 05:30:27 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 05:30:27 INFO - PROCESS | 1944 | |i|pagename|runs| 05:30:27 INFO - PROCESS | 1944 | |0;ai-astar;169;152;95;169;164;190;117;116;115;117 05:30:27 INFO - PROCESS | 1944 | |1;audio-beat-detection;153;125;166;172;140;176;162;137;167;151 05:30:27 INFO - PROCESS | 1944 | |2;audio-dft;204;215;254;276;197;248;278;200;199;193 05:30:27 INFO - PROCESS | 1944 | |3;audio-fft;68;79;79;132;46;46;91;91;109;84 05:30:27 INFO - PROCESS | 1944 | |4;audio-oscillator;81;84;130;77;83;82;124;79;80;117 05:30:27 INFO - PROCESS | 1944 | |5;imaging-gaussian-blur;90;86;90;87;90;76;136;123;118;108 05:30:27 INFO - PROCESS | 1944 | |6;imaging-darkroom;132;107;95;151;149;95;96;95;94;95 05:30:27 INFO - PROCESS | 1944 | |7;imaging-desaturate;91;95;93;92;95;91;90;92;93;90 05:30:27 INFO - PROCESS | 1944 | |8;json-parse-financial;61;63;61;62;61;61;61;70;61;61 05:30:27 INFO - PROCESS | 1944 | |9;json-stringify-tinderbox;65;67;73;69;77;73;70;68;63;81 05:30:27 INFO - PROCESS | 1944 | |10;stanford-crypto-aes;90;90;90;100;111;88;88;89;91;89 05:30:27 INFO - PROCESS | 1944 | |11;stanford-crypto-ccm;146;193;178;172;166;175;143;153;212;184 05:30:27 INFO - PROCESS | 1944 | |12;stanford-crypto-pbkdf2;171;172;171;174;179;173;180;220;174;174 05:30:27 INFO - PROCESS | 1944 | |13;stanford-crypto-sha256-iterative;100;104;98;67;72;72;70;81;104;60 05:30:27 INFO - PROCESS | 1944 | __end_tp_report 05:30:27 INFO - PROCESS | 1944 | __start_cc_report 05:30:27 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,989 05:30:27 INFO - PROCESS | 1944 | __end_cc_report 05:30:27 INFO - PROCESS | 1944 | __startTimestamp1460637027648__endTimestamp 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | ------- Summary: start ------- 05:30:27 INFO - PROCESS | 1944 | Number of tests: 14 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#0] ai-astar Cycles:10 Average:140.40 Median:166.50 stddev:31.95 (19.2%) stddev-sans-first:32.16 05:30:27 INFO - PROCESS | 1944 | Values: 169.0 152.0 95.0 169.0 164.0 190.0 117.0 116.0 115.0 117.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#1] audio-beat-detection Cycles:10 Average:154.90 Median:164.00 stddev:16.71 (10.2%) stddev-sans-first:17.71 05:30:27 INFO - PROCESS | 1944 | Values: 153.0 125.0 166.0 172.0 140.0 176.0 162.0 137.0 167.0 151.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#2] audio-dft Cycles:10 Average:226.40 Median:231.50 stddev:34.01 (14.7%) stddev-sans-first:35.09 05:30:27 INFO - PROCESS | 1944 | Values: 204.0 215.0 254.0 276.0 197.0 248.0 278.0 200.0 199.0 193.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#3] audio-fft Cycles:10 Average:82.50 Median:79.00 stddev:26.24 (33.2%) stddev-sans-first:27.31 05:30:27 INFO - PROCESS | 1944 | Values: 68.0 79.0 79.0 132.0 46.0 46.0 91.0 91.0 109.0 84.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:80.50 stddev:21.00 (26.1%) stddev-sans-first:21.76 05:30:27 INFO - PROCESS | 1944 | Values: 81.0 84.0 130.0 77.0 83.0 82.0 124.0 79.0 80.0 117.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:86.50 stddev:19.59 (22.6%) stddev-sans-first:20.41 05:30:27 INFO - PROCESS | 1944 | Values: 90.0 86.0 90.0 87.0 90.0 76.0 136.0 123.0 118.0 108.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:95.00 stddev:23.66 (24.9%) stddev-sans-first:23.83 05:30:27 INFO - PROCESS | 1944 | Values: 132.0 107.0 95.0 151.0 149.0 95.0 96.0 95.0 94.0 95.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:92.50 stddev:1.81 (2.0%) stddev-sans-first:1.87 05:30:27 INFO - PROCESS | 1944 | Values: 91.0 95.0 93.0 92.0 95.0 91.0 90.0 92.0 93.0 90.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:61.00 stddev:2.82 (4.6%) stddev-sans-first:2.96 05:30:27 INFO - PROCESS | 1944 | Values: 61.0 63.0 61.0 62.0 61.0 61.0 61.0 70.0 61.0 61.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.50 stddev:5.50 (7.7%) stddev-sans-first:5.45 05:30:27 INFO - PROCESS | 1944 | Values: 65.0 67.0 73.0 69.0 77.0 73.0 70.0 68.0 63.0 81.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:89.50 stddev:7.34 (8.2%) stddev-sans-first:7.72 05:30:27 INFO - PROCESS | 1944 | Values: 90.0 90.0 90.0 100.0 111.0 88.0 88.0 89.0 91.0 89.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#11] stanford-crypto-ccm Cycles:10 Average:172.20 Median:176.50 stddev:21.46 (12.2%) stddev-sans-first:20.56 05:30:27 INFO - PROCESS | 1944 | Values: 146.0 193.0 178.0 172.0 166.0 175.0 143.0 153.0 212.0 184.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:174.00 stddev:14.79 (8.5%) stddev-sans-first:15.42 05:30:27 INFO - PROCESS | 1944 | Values: 171.0 172.0 171.0 174.0 179.0 173.0 180.0 220.0 174.0 174.0 05:30:27 INFO - PROCESS | 1944 | 05:30:27 INFO - PROCESS | 1944 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.80 Median:71.00 stddev:16.98 (23.9%) stddev-sans-first:16.83 05:30:27 INFO - PROCESS | 1944 | Values: 100.0 104.0 98.0 67.0 72.0 72.0 70.0 81.0 104.0 60.0 05:30:27 INFO - PROCESS | 1944 | -------- Summary: end -------- 05:30:27 INFO - PROCESS | 1944 | 05:30:28 INFO - TEST-INFO | 1944: exit 0 05:30:28 INFO - TEST-OK | kraken | took 61011ms 05:30:28 INFO - SUITE-END | took 1172s 05:30:28 INFO - Completed test suite (00:19:33) 05:30:28 INFO - javascript benchmark 05:30:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3475.913102172445, 3499.1077275294806, 3504.386732321843, 3504.474328850672, 3567.7680950738186, 5828.941710582898, 5874.148248503964, 5875.794347197849, 5901.970490147554, 5908.793192238272, 5773.509251713599, 5828.766849326022, 5906.084556893688, 5969.522438204944, 5990.730417131229, 5262.973685131569, 5279.41926388097, 5317.627766056358, 5329.866753331178, 5403.459654034585, 5331.173668081434, 5347.465253474641, 5374.140137577989, 5378.838634840961, 5393.379761327439, 4254.510731265882, 5029.84910452687, 5178.922316165261, 5220.321358223426, 5234.057869583474, 5272.657277277003, 5313.867153321159, 5316.415194328621, 5318.825330494228, 5334.03987282289, 5151.150060240056, 5195.5324020837925, 5250.894982100336, 5258.6318957672975, 5292.576593872481, 5592.692401917893, 5720.799772008001, 5757.568182386338, 5760.452756988079, 5763.971180144114, 6189.6905154742535, 6240.4071613196675, 6247.437730604267, 6259.530535209832, 6290.3709629037185, 6172.105044768528, 6198.132261483396, 6269.373062693694, 6280.811575652738, 6282.591631543935, 6000.489958353545, 6031.517478601701, 6035.0, 6053.636781793107, 6094.878102437927, 6377.872442551123, 6426.485881129498, 6461.57999730016, 6466.773662921775, 6468.741250350027, 6048.697565121726, 6156.5998210116195, 6231.283402408781, 6246.0, 6268.216472940882, 6157.9384206158265, 6239.0, 6261.592996455216, 6284.874302513924, 6320.494360451153, 6230.563860529719, 6257.124002639634, 6259.280182778948, 6279.905801412982, 6308.716107775161, 6141.723622437001, 6154.3845615438095, 6254.0, 6262.436380725757, 6307.463865571478, 4160.375943608483, 4310.181065597527, 4997.90004199914, 5028.396592408862, 5057.317262169634, 3713.0, 4479.216137175981, 4499.797509112098, 4515.909681806345, 4568.743595511261, 5043.949560504421, 5856.912146317809, 5899.675517846474, 5927.199828023248, 5927.496162826122, 6243.094751261133, 6245.344238854855, 6275.6862156892885, 6303.905441418382, 6308.400701933309, 5911.142884281841, 5913.556483263773, 5919.9936010878255, 5958.225430694069, 5998.610090344114, 5372.0, 5422.0511410503, 5461.590380721462, 5489.862753431196, 5565.610407271533, 6452.161219041588, 6476.870462590816, 6510.414062734377, 6517.348265173445, 6520.967395162994, 6266.0, 6282.43458088756, 6286.905696414649, 6292.087647291209, 6296.590721603081, 6334.588251763805, 6428.935710643021, 6493.935060649522, 6545.050967609765, 6553.311902250195, 6207.255129384503, 6213.9067913982235, 6217.347178546188, 6226.40849119333, 6235.844103897439, 6122.816315510364, 6266.154069200599, 6267.2479302484, 6269.937300627118, 6273.153124328157, 6100.672006988121, 6125.264968203665, 6127.281156068254, 6185.2886918003205, 6197.667501487202, 6052.515798736003, 6179.351168127282, 6217.440430361289, 6219.595726277777, 6221.813345599639, 5276.788928442819, 6025.307089684742, 6030.939690603038, 6098.969505152446, 6103.84027034862, 6102.095423428566, 6173.444390004742, 6202.906956395748, 6212.627242365473, 6225.844353891189, 6083.239595050619, 6147.415995480422, 6149.2313460817395, 6168.660723660242, 6173.382661733791], "value": 5770.247677252395, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.13649386561247, 521.3509181069569, 565.9122835960425, 583.6614763437204, 626.2266101364817, 36446.81776591117, 37210.81394593027, 37217.88346349607, 37272.62727372725, 37446.62553374466, 39302.21395572094, 39783.801080994555, 39865.40201896967, 39959.80020099903, 40032.199356012934, 9281.535923203846, 9464.1482266596, 9496.097870702306, 9524.571394287239, 9534.0, 9411.62353505859, 9541.141297283242, 9541.379810312343, 9555.23558115349, 9561.856572151388, 9451.527423628826, 9467.857982130274, 9549.379290346105, 9552.713418597454, 9560.187384072327, 8858.9369275687, 8866.246369058637, 9434.669786557472, 9550.044995500464, 9556.808863822685, 9377.156055954996, 9406.9529652352, 9420.764480887965, 9474.147326740558, 9534.141927226583, 31122.533162002586, 37012.0, 37014.07464813374, 37307.25385492275, 37526.0, 2989.1032690192856, 3019.909402717922, 3226.2257058306072, 3272.590926134233, 3767.773933563995, 25514.36214094644, 25756.0, 26274.474510509874, 26353.20940371792, 27001.32496687579, 2438.5244877248942, 2540.2252313044514, 2645.8412495250345, 2677.357434215774, 2741.5606806426586, 321396.0, 350254.0, 350706.0, 354027.0, 355302.0, 34411.311773764886, 35355.0, 46572.53427465682, 56037.0, 58213.0, 28726.85636571804, 34221.631134754825, 34522.136946576036, 34572.0, 34836.477452838226, 12685.0, 14474.565763026916, 14527.201003944885, 14573.854261457249, 14668.706625867637, 12523.248605083723, 12684.365781711062, 12724.681882953, 12823.807642885364, 12855.678608034874, 7851.018622672166, 7934.325582325451, 8047.396445266511, 8061.0, 8078.434509584273, 3434.587849458081, 3445.1214940190093, 3464.7921124732597, 3467.8092704901474, 3485.4249048906645, 973.5181085362791, 993.7614972406715, 1014.9900848655635, 1033.1270076785104, 1102.327580176092, 28248.0, 28255.43489130206, 28422.85788571044, 28533.857330713214, 28611.0, 992.7567745902301, 1055.6199768083484, 1124.8481455003634, 1189.41718557906, 1193.985112654237, 12306.446209920576, 14613.415463381558, 14617.85382146165, 14723.558293251219, 15006.324715387835, 617456.0, 617957.0, 623483.0, 633487.8279260204, 633602.8319858464, 48805.26792098121, 57831.42168578345, 58669.82660346769, 58891.0, 58939.0, 1453.25157543865, 1464.8022516960075, 1477.793108964746, 1497.5282785922618, 1500.1374209829173, 22255.332340029825, 27726.0, 27814.3046423841, 28073.71926280711, 28739.0, 47519.09923603017, 47719.76140119277, 48304.0, 48557.27164092471, 48659.75670121627, 442814.0, 457770.0, 459210.0, 459457.0, 460252.0, 26031.088911887993, 26175.214743557724, 26385.472290554466, 26394.0, 26421.0, 44500.33249501196, 44768.328475072245, 44985.550144498135, 45540.08919821651, 46749.065018700116, 37324.0, 37329.0, 37653.62346376611, 37831.62168378281, 38479.61520384761, 30867.0, 33343.0, 37451.43822842605, 37903.62096379111, 38126.23747525089, 36778.448323275705, 37062.07344816293, 37068.6293137076, 37339.43990840194, 37584.43623345707, 25631.743682562937, 31991.040268791974, 32255.677443225268, 32934.34131317408, 33174.0, 23977.400564986016, 24001.519969600162, 24311.63532546978, 24326.148584799448, 24529.0], "value": 17700.40659065262, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.8728508596561, 341.3803945838303, 389.495603193864, 396.827380089661, 404.20573572929226, 19274.229030838767, 19863.10616022279, 19965.0, 20659.69010464844, 20712.68930966035, 19453.416397508063, 19995.200191992302, 20406.387808365773, 20473.18107275711, 20502.0, 4077.4903137107863, 4815.903681926359, 5164.535191832734, 5284.630075894689, 5480.342358916916, 4491.483479399878, 5375.543078838304, 5386.838394848141, 5424.674519528841, 5463.890722185554, 5235.973820130894, 5300.25796388504, 5301.1783173607955, 5409.080456322435, 5410.48600382963, 5086.491350864921, 5999.250093738283, 6017.398260173992, 6032.879342413149, 6047.5766696331275, 5092.388913330424, 5277.47225277473, 5298.152295632719, 5305.15117581189, 5309.495597918191, 10278.845817312746, 12960.48158073676, 13238.53665121716, 13473.663158421019, 13481.05632605718, 1841.7292068472711, 2075.3151460018157, 2112.197365001305, 2143.5498545305504, 2192.5943700415314, 9442.831052962498, 9750.707478775577, 9984.201263898944, 10042.0, 10122.924800415896, 1706.3089448773267, 1735.687576236277, 1740.7040803063383, 1795.0, 1800.0729624243525, 118804.40597797043, 122701.0, 122752.0, 125410.74589254081, 126663.0, 11458.828117578243, 11500.309981400975, 11745.823812642817, 11796.410179490991, 11851.111166662535, 14960.775588366183, 14998.550043498712, 15131.621709457351, 15207.695846083237, 15219.0, 6987.930120698829, 8441.269539744339, 8772.78068048304, 8834.690785822466, 8852.291816654651, 8501.19238672325, 8560.700375486827, 8571.954013847522, 8649.221570058598, 8774.824503510023, 4533.274676051816, 4609.631229501699, 4652.092841895866, 4667.8599642010795, 4692.202325604587, 1700.2518891687619, 1921.28912302449, 1989.5125694205012, 1996.1316827180224, 2027.1485975890162, 735.6321839080459, 881.3433991676114, 945.2957546627669, 961.3272905145137, 1016.9338992965434, 20322.695159572617, 20408.18367265293, 20453.795462045488, 20769.68845467289, 20819.167233310498, 947.7914858731068, 960.9471479068718, 969.9321047526605, 981.7776867799636, 1000.8899021107673, 10764.461776911123, 10932.180086493545, 10975.0, 10977.890221097847, 10981.560737570568, 499273.0, 606958.0, 616407.0, 619160.0, 628431.0, 77299.99999999888, 113614.0, 125808.0, 128110.0, 134595.0, 870.190722627962, 931.5807886451124, 1018.6791160784329, 1032.8450732390052, 1051.558345494894, 15024.023438476464, 15481.612959676098, 16319.347226110822, 17455.0, 17590.648187036444, 39832.0, 47430.76284618555, 48082.0, 48145.0, 48981.02037959149, 55881.0, 69956.0, 70250.64874675593, 70521.29478705148, 72959.0, 7647.961760191163, 7827.647755851114, 7900.68397264103, 8032.437729359122, 8246.463979841532, 8503.914960850312, 8988.595513201792, 9429.717108486757, 10679.679609611723, 12234.449449774604, 10599.788004240027, 10939.0, 10974.670759877217, 10999.34003959765, 11083.224174307721, 10377.221708371751, 10426.426546540013, 10714.19643526723, 10781.353118812582, 10965.61620343284, 10904.400257985886, 10926.890731092906, 11030.889691102986, 11242.213045086766, 11275.267107637977, 7759.418043646636, 7854.293113619801, 8103.716369927024, 8103.918960810316, 8147.877781833396, 8011.319037881845, 9701.0, 10972.286801358205, 11079.446027698421, 11092.001719845253], "value": 9009.54614543406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1628.519586721917, 1631.3963833381647, 1638.9098599577026, 1667.9325231851617, 1989.2242025610005, 8070.475419097755, 8601.397902146837, 8656.047834738174, 8941.569348904168, 8969.506677132764, 6765.966170169155, 8678.435901666388, 8705.95647021764, 8749.431286966343, 9022.684206052789, 1999.2702663527816, 2000.6498862699043, 2429.708434987804, 2434.2453839309783, 2435.427674496492, 2398.796102331304, 2408.7350391456926, 2440.3777036855577, 2448.338948483917, 2456.4718585504115, 2395.4490467192554, 2401.2916189723987, 2404.471016376403, 2446.0704932125855, 2457.950840983179, 2137.177186783092, 2362.7400985891536, 2415.6014257647553, 2422.2612103308484, 2422.8061755059553, 2402.807775377983, 2403.291029146406, 2411.3248290478696, 2417.9274621761374, 2430.6475561043726, 8738.126187381275, 8800.031996480382, 8853.11468853116, 8907.643694252221, 8910.688125915562, 5258.789648414059, 5324.600654950894, 5389.865253368658, 5444.6188766785945, 5464.0711079116645, 3584.731145164123, 3596.226811235571, 3619.8009109498967, 3679.908002299937, 3688.2807852468777, 993.2054356514759, 1005.6882366466418, 1013.9340942838766, 1036.834106542957, 1048.6067724603274, 11536.711582210428, 11556.653300401002, 11649.534018639244, 11852.873976972174, 11859.0, 4155.397467367215, 4238.512571054368, 4750.714957102516, 4843.903121937612, 4969.329140566049, 9810.65662701802, 9890.752731181634, 9891.455969921579, 9901.75245618851, 9935.552900119512, 5319.973400133052, 5390.676559406448, 5426.375966763794, 5447.972760136174, 5487.286652735199, 4332.393464914914, 4901.289313049588, 4926.507349265116, 4933.50664933511, 5036.924446133311, 3973.0861901762178, 4004.4994375703036, 4054.614811592894, 4090.8977275567754, 4133.111381053088, 1150.6950658075616, 1352.9120607160503, 1382.5437605590132, 1388.2780953904116, 1397.0918902713115, 898.2006014646969, 920.4661296448044, 927.9443233406017, 932.6269492203173, 943.7782121201512, 5758.193852860602, 5818.5636077294375, 5858.209141765807, 5858.736356863951, 5911.231539899785, 408.15511890387205, 431.8985038515946, 442.84859365649055, 454.4569239758457, 463.6777439679458, 3780.3951367781024, 3863.61363863617, 3915.9021024474614, 3940.9359472942147, 3942.113024569449, 10456.424896630759, 10904.672859814218, 10907.727306817234, 10976.341419514854, 10978.396188209725, 10633.149348052137, 10738.94630526858, 10888.836667449994, 10933.835992460119, 11002.779944401067, 8093.8381232376205, 8178.814071959532, 8179.345652347798, 8323.375746819012, 8389.161083891684, 5630.296212973379, 5652.745626446902, 5717.771289148387, 5725.026745453366, 5835.94164058354, 9702.320837541587, 9771.169450596495, 9964.202863770735, 9982.401055936667, 10126.392416455037, 10188.490575471345, 10339.379637221791, 10340.017698318648, 10387.896121038693, 10392.168626510012, 6727.764528241488, 7090.0, 7340.926590734024, 7414.66634001482, 7424.220456852169, 8114.472559283628, 8168.7957801055445, 8206.712765053195, 8210.507369557845, 8217.79455513617, 7220.458465614995, 7294.1247050354295, 7385.261473852786, 7513.098428188652, 7533.096028476618, 7274.96362518184, 7298.160711518243, 7365.447591430771, 7397.963010184914, 7402.963585097983, 6914.757983470555, 6922.619255940972, 6944.201416836926, 7014.473914456538, 7041.471889608402, 4787.664863459664, 4828.855134345975, 4864.173090574682, 4975.601951843916, 5026.572741317029, 4864.1043614867995, 4880.389951256093, 4915.311856340044, 4978.352814134139, 5131.307273518028], "value": 4508.572494971149, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.9609454676346, 311.48293832238477, 327.4809427058113, 328.9950650740238, 330.3113009375452, 4887.802488390349, 5095.719735414551, 5827.912581311284, 5988.311344195419, 6014.789482368118, 6025.548083893704, 6136.23297087864, 6226.501879849612, 6231.50147988161, 6235.189425374706, 4400.691951563392, 4408.537103604123, 4575.885602859922, 4654.511276315989, 4660.231061874803, 3507.771994820341, 4498.392716983214, 4630.819141119026, 4633.235516139833, 4658.394408726877, 4119.2997190477545, 4478.6417086633155, 4480.955190448102, 4492.258777301741, 4499.61753250974, 4512.075024619945, 4526.615237704799, 4530.161920044802, 4530.569595888385, 4545.863624091283, 3440.2603440260214, 3566.661167189113, 3694.4273637586216, 3859.517560304962, 3868.6324799144036, 4911.828086017008, 5564.666120032811, 5892.941070589282, 5907.172995780594, 5935.732892019826, 4578.542145785428, 5067.493250674903, 5215.739213039371, 5294.258803767476, 5296.629235953485, 5108.883581015578, 5357.4106848246665, 6130.908036379458, 6620.6358650274215, 6826.761063362758, 4242.9787851060855, 4268.338407546804, 4288.571142885718, 4294.806733696991, 4295.806688699016, 5473.534749546293, 5777.508911742507, 6168.537359698041, 6580.93419065808, 6848.14398200225, 4323.524412314643, 4336.457942757155, 4359.411479450297, 4363.0, 4369.80335884886, 3692.372296709566, 4412.558744125626, 4413.322937283811, 4437.955620443819, 4477.216487114807, 5102.464241254689, 5205.375354957429, 5248.134057880483, 5253.947460525423, 5291.788328466895, 5470.425605311465, 6593.241777195684, 6619.0, 6625.238097618739, 6656.633885136364, 1230.9290916902173, 1315.467235769515, 1511.7430036893754, 1523.3830298729033, 1527.8548537888882, 950.7879214719627, 960.6061514778908, 983.7688143286322, 1002.04304888831, 1002.8846682631445, 6030.969845150834, 6075.27096748393, 6127.5404344674325, 6153.661608588708, 6161.168242287324, 6802.557833740791, 6806.55757375769, 6818.28408017161, 6835.418989385858, 6843.315668433117, 5128.897422051538, 5800.767969281266, 6069.332373438918, 6111.938880611226, 6123.377305014174, 3543.8936831895085, 3582.964170358263, 3585.6414358563934, 3601.513795637555, 3605.2428989911887, 6523.336549179929, 6863.759768408082, 7049.964750176319, 7157.856842863218, 7164.641767911688, 5551.306086739158, 6197.1324014637985, 6648.335166483313, 6785.728570857111, 6856.177258728984, 6749.257581666013, 6807.256009194923, 7087.0, 7096.680649370791, 7180.676869540883, 5493.890122197454, 6103.450689437972, 6682.398584127257, 6831.077804496329, 6866.588004719732, 6470.450011748866, 6644.800655980328, 6750.6624668767345, 6781.830454238683, 6786.830329241809, 6205.22434695663, 6798.0, 7088.964555177191, 7135.678894449658, 7281.890771638324, 4363.018320877777, 4373.956260437355, 4396.4064851244675, 4403.647708183402, 4411.272140096977, 5554.389017208023, 6465.741370345134, 6610.0, 6865.450763938773, 6913.2049814272, 5971.761129554943, 6606.669666516559, 6611.504137189634, 6619.933800661932, 6660.435732243218, 6436.903446448214, 6611.966940165268, 6630.7679231226675, 6645.169353830771, 6661.16735408074, 5767.885341915318, 6194.287656919512, 6428.935710643021, 6566.179227596551, 6586.473082153512, 6387.808365749035, 6466.191726034246, 6648.933510665025, 6662.6335551545135, 6673.799786006428, 5187.688738675692, 6445.935540644721, 6534.183227096613, 6544.607323560602, 6611.537192396486], "value": 4702.437315303397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.7436886798582, 730.5142080516457, 742.1279996004695, 750.8460765543065, 761.9238076192378, 14845.628859278524, 15162.014469059503, 15312.923435382836, 15323.92338038311, 15371.923140384297, 13957.0, 14049.0, 14055.0, 14086.859131408683, 14104.576862694135, 909.7680091576639, 919.0625561926864, 926.5321012888481, 929.7907970706605, 930.9441433514011, 727.2109760909419, 892.7991201979568, 911.3529394130143, 928.9071092890724, 931.4783721116197, 963.7446076789656, 966.0918736387808, 966.3766870368609, 966.4346357380905, 970.3838062830083, 955.8088382323563, 958.1855422890487, 959.9904000960006, 960.5437417226831, 963.0802583532709, 692.6432887063166, 701.2461603775961, 719.3094629156017, 720.4452571519953, 723.9058922340115, 14663.046901951338, 14708.926455367762, 14770.187639679812, 14778.630534236623, 15054.0, 12000.69998250042, 12077.758444831143, 12104.757904841854, 12341.69145771354, 12416.0, 9026.593803278869, 10062.345947513388, 10192.184625229964, 10272.435016074112, 10557.683269501927, 3466.0, 3488.180277634754, 3518.652346183563, 3524.7356448266482, 3550.378683730337, 57516.562085948186, 71773.0, 84018.57990710069, 87228.0, 87733.0, 9386.90613093874, 9526.285528585384, 9580.0, 9660.227181825438, 9888.851667224997, 20455.590888182152, 21013.894930525556, 21071.367858964255, 21077.367678969655, 21154.57690846205, 7322.670479828421, 7469.402447804163, 7554.43341749371, 7645.044369453818, 7823.139454659983, 4092.7748973806724, 4124.216398884262, 4124.340105583093, 4147.066909945299, 4153.584641535822, 3539.557555305587, 3626.5285512883156, 3631.473436351714, 3655.250673611903, 3664.523611930432, 572.3360901354409, 652.0447544347553, 667.4460198035619, 668.8996650502366, 674.919009718827, 527.8996990571779, 552.8148070396381, 575.7869588252373, 580.3268208877681, 583.9708014599253, 7815.374770018385, 7831.451798374173, 7918.604069796487, 8091.216974497415, 8148.755537333771, 559.9780400768614, 573.4781348972415, 588.8586739182565, 589.8820235952827, 591.7721677154327, 3748.8125593720206, 3764.378877485184, 3765.6610905018133, 3768.378217594121, 3861.3821788514265, 67726.322736772, 82491.58754206191, 84117.0, 84467.0, 85112.14887851167, 53678.0, 70571.2942870575, 72043.0, 72926.63536682283, 73693.631531842, 62836.68581657154, 63353.0, 64304.35695643077, 64377.0, 65514.67242663661, 7339.6330183489545, 7347.742829000959, 7447.590382529013, 7599.65801538921, 7757.495762775401, 32734.87223330476, 41245.38131928084, 42575.7871210642, 42663.36004959866, 42834.78582607067, 51894.481055190474, 55106.72446637741, 60273.79452411015, 60459.69770151121, 60607.39392606018, 9590.376625519319, 9664.71005869825, 9722.9027709722, 9839.311248212556, 10281.485925703535, 10506.579736810441, 10517.737056573647, 10545.420001899969, 10637.893621064, 10726.839097413389, 9530.952345238507, 9531.904680953101, 9661.275404344562, 9685.41887486753, 9831.754206144904, 10215.284930054824, 10267.794644107225, 10489.093726409375, 10738.19463540222, 11181.049610783175, 9636.614535418505, 10109.0, 10137.23970702215, 10691.839622405825, 10879.0, 8986.68546600866, 9060.909390906007, 9225.261979041794, 9265.768355791159, 9584.520773961414, 8979.494349874845, 9171.495567743837, 9266.536673166449, 9324.927633322255, 9402.153806157205], "value": 6616.077729920978, "unit": "score"}], "name": "dromaeo_css", "value": 7109.0763205347075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 152.0, 95.0, 169.0, 164.0, 190.0, 117.0, 116.0, 115.0, 117.0], "value": 140.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 166.0, 172.0, 140.0, 176.0, 162.0, 137.0, 167.0, 151.0], "value": 154.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 215.0, 254.0, 276.0, 197.0, 248.0, 278.0, 200.0, 199.0, 193.0], "value": 226.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 79.0, 79.0, 132.0, 46.0, 46.0, 91.0, 91.0, 109.0, 84.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 130.0, 77.0, 83.0, 82.0, 124.0, 79.0, 80.0, 117.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 90.0, 76.0, 136.0, 123.0, 118.0, 108.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 107.0, 95.0, 151.0, 149.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 93.0, 92.0, 95.0, 91.0, 90.0, 92.0, 93.0, 90.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 62.0, 61.0, 61.0, 61.0, 70.0, 61.0, 61.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 73.0, 69.0, 77.0, 73.0, 70.0, 68.0, 63.0, 81.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 100.0, 111.0, 88.0, 88.0, 89.0, 91.0, 89.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 193.0, 178.0, 172.0, 166.0, 175.0, 143.0, 153.0, 212.0, 184.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 171.0, 174.0, 179.0, 173.0, 180.0, 220.0, 174.0, 174.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 104.0, 98.0, 67.0, 72.0, 72.0, 70.0, 81.0, 104.0, 60.0], "value": 82.8, "unit": "score"}], "name": "kraken", "value": 1660.6, "alertThreshold": 2.0}]} 05:30:28 INFO - Return code: 0 05:30:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:30:28 ERROR - # TBPL SUCCESS # 05:30:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:28 INFO - 2016-04-14 05:30:28.983 screenresolution[1950:16627] starting screenresolution argv=screenresolution get 05:30:28 INFO - 2016-04-14 05:30:28.985 screenresolution[1950:16627] Display 0: 1600x1200x32@60 05:30:28 INFO - 2016-04-14 05:30:28.991 screenresolution[1951:16630] starting screenresolution argv=screenresolution list 05:30:28 INFO - Available Modes on Display 0 05:30:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:29 INFO - Intel Iris: 05:30:29 INFO - Chipset Model: Intel Iris 05:30:29 INFO - Type: GPU 05:30:29 INFO - Bus: Built-In 05:30:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:29 INFO - Vendor: Intel (0x8086) 05:30:29 INFO - Device ID: 0x0a2e 05:30:29 INFO - Revision ID: 0x0009 05:30:29 INFO - Displays: 05:30:29 INFO - Display: 05:30:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:29 INFO - Display Serial Number: 1600x1200 60 05:30:29 INFO - Main Display: Yes 05:30:29 INFO - Mirror: Off 05:30:29 INFO - Online: Yes 05:30:29 INFO - Rotation: Supported 05:30:29 INFO - Return code: 0 05:30:29 INFO - Running post-action listener: _resource_record_post_action 05:30:29 INFO - Running post-run listener: _resource_record_post_run 05:30:30 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 1765376; Write bytes: 691741184; Read time: 15; Write time: 1874 05:30:30 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 372947456; Write bytes: 367355392; Read time: 27962; Write time: 711 05:30:30 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 1765376; Write bytes: 295128064; Read time: 15; Write time: 1111 05:30:30 INFO - Running post-run listener: _upload_blobber_files 05:30:30 INFO - Blob upload gear active. 05:30:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:30:30 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:30:30 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:30:30 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:30:30 INFO - (blobuploader) - INFO - Open directory for files ... 05:30:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:30:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:30:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:31 INFO - (blobuploader) - INFO - Done attempting. 05:30:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:30:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:30:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:32 INFO - (blobuploader) - INFO - Done attempting. 05:30:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:30:32 INFO - Return code: 0 05:30:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:30:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:30:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f90fceee1c98cc892761460b2b0b555187fe956e55fdb4c0ad40c6b10c3c71fcde00948c5688a9fddd6ba56660fb75607b19df54a3f8a28e8f7f9490dd8b6a85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91e890ab3d74ceeecbc01a35e386bcc68fcfe71cf6cbf4a97d1a7e84f891255b95a02d1327a2e03516a35cfcda82848fb88644360215b593d9eef9f1642be4cd"} 05:30:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:30:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:30:32 INFO - Contents: 05:30:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f90fceee1c98cc892761460b2b0b555187fe956e55fdb4c0ad40c6b10c3c71fcde00948c5688a9fddd6ba56660fb75607b19df54a3f8a28e8f7f9490dd8b6a85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91e890ab3d74ceeecbc01a35e386bcc68fcfe71cf6cbf4a97d1a7e84f891255b95a02d1327a2e03516a35cfcda82848fb88644360215b593d9eef9f1642be4cd"} 05:30:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:30:32 INFO - Copying logs to upload dir... 05:30:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:30:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.329224 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-14 05:30:32.784619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:30:32.788508) ========= 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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f90fceee1c98cc892761460b2b0b555187fe956e55fdb4c0ad40c6b10c3c71fcde00948c5688a9fddd6ba56660fb75607b19df54a3f8a28e8f7f9490dd8b6a85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91e890ab3d74ceeecbc01a35e386bcc68fcfe71cf6cbf4a97d1a7e84f891255b95a02d1327a2e03516a35cfcda82848fb88644360215b593d9eef9f1642be4cd"} 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.010800 build_url: 'https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f90fceee1c98cc892761460b2b0b555187fe956e55fdb4c0ad40c6b10c3c71fcde00948c5688a9fddd6ba56660fb75607b19df54a3f8a28e8f7f9490dd8b6a85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91e890ab3d74ceeecbc01a35e386bcc68fcfe71cf6cbf4a97d1a7e84f891255b95a02d1327a2e03516a35cfcda82848fb88644360215b593d9eef9f1642be4cd"}' ========= master_lag: 0.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:30:33.246416) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:30:33.246731) ========= 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.v3FzUVdADg/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.OijRET9TWq/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005166 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:30:33.267897) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:30:33.268188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:30:33.268535) ========= ========= Total master_lag: 0.56 =========