builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0152 starttime: 1460636354.63 results: success (0) buildid: 20160414032548 builduid: 8695f4ce90c94b97a0ac36cbd725f5c3 revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:14.628287) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:14.628797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2016-04-14 05:19:14.629128) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2016-04-14 05:19:30.872764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 05:19:30.873029) ========= 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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:19:31-- 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% 17.9M=0.001s 2016-04-14 05:19:32 (17.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.543180 ========= master_lag: 1.95 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 05:19:33.368944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:33.369388) ========= 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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.031412 ========= master_lag: 0.61 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:34.006621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-14 05:19:34.006991) ========= 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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:19:34,089 truncating revision to first 12 chars 2016-04-14 05:19:34,089 Setting DEBUG logging. 2016-04-14 05:19:34,089 attempt 1/10 2016-04-14 05:19:34,089 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:19:34,374 unpacking tar archive at: fx-team-4a496faf1c5c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.529732 ========= master_lag: 3.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-14 05:19:37.945554) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:37.945821) ========= script_repo_revision: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:37.946195) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:37.946455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:19:38.196259) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 05:19:38.196698) ========= /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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:19:38 INFO - MultiFileLogger online at 20160414 05:19:38 in /builds/slave/test 05:19:38 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:19:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:38 INFO - {'append_to_log': False, 05:19:38 INFO - 'base_work_dir': '/builds/slave/test', 05:19:38 INFO - 'blob_upload_branch': 'Fx-Team', 05:19:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:38 INFO - 'branch': 'Fx-Team', 05:19:38 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:38 INFO - 'config_files': ('talos/mac_config.py',), 05:19:38 INFO - 'default_actions': ('clobber', 05:19:38 INFO - 'read-buildbot-config', 05:19:38 INFO - 'download-and-extract', 05:19:38 INFO - 'populate-webroot', 05:19:38 INFO - 'create-virtualenv', 05:19:38 INFO - 'install', 05:19:38 INFO - 'run-tests'), 05:19:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:38 INFO - 'download_minidump_stackwalk': True, 05:19:38 INFO - 'download_symbols': 'ondemand', 05:19:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:19:38 INFO - 'tooltool.py': '/tools/tooltool.py', 05:19:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:19:38 INFO - '/tools/misc-python/virtualenv.py')}, 05:19:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:38 INFO - 'installer_path': 'installer.exe', 05:19:38 INFO - 'log_level': 'info', 05:19:38 INFO - 'log_name': 'talos', 05:19:38 INFO - 'log_to_console': True, 05:19:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:19:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:19:38 INFO - 'opt_config_files': (), 05:19:38 INFO - 'pip_index': False, 05:19:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:38 INFO - 'cmd': ('bash', 05:19:38 INFO - '-c', 05:19:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:38 INFO - 'enabled': True, 05:19:38 INFO - 'halt_on_failure': False, 05:19:38 INFO - 'name': 'check_screen_resolution'},), 05:19:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:38 INFO - 'cmd': ('bash', 05:19:38 INFO - '-c', 05:19:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:38 INFO - 'enabled': True, 05:19:38 INFO - 'halt_on_failure': False, 05:19:38 INFO - 'name': 'check_screen_resolution'},), 05:19:38 INFO - 'run_cmd_checks_enabled': True, 05:19:38 INFO - 'sps_profile': False, 05:19:38 INFO - 'sps_profile_interval': 0, 05:19:38 INFO - 'suite': 'dromaeojs-e10s', 05:19:38 INFO - 'system_bits': '32', 05:19:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:38 INFO - 'title': 't-yosemite-r7-0152', 05:19:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:38 INFO - 'use_talos_json': True, 05:19:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:38 INFO - 'work_dir': 'build'} 05:19:38 INFO - ##### 05:19:38 INFO - ##### Running clobber step. 05:19:38 INFO - ##### 05:19:38 INFO - Running pre-action listener: _resource_record_pre_action 05:19:38 INFO - Running main action method: clobber 05:19:38 INFO - rmtree: /builds/slave/test/build 05:19:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:39 INFO - Running post-action listener: _resource_record_post_action 05:19:39 INFO - ##### 05:19:39 INFO - ##### Running read-buildbot-config step. 05:19:39 INFO - ##### 05:19:39 INFO - Running pre-action listener: _resource_record_pre_action 05:19:39 INFO - Running main action method: read_buildbot_config 05:19:39 INFO - Using buildbot properties: 05:19:39 INFO - { 05:19:39 INFO - "project": "", 05:19:39 INFO - "product": "firefox", 05:19:39 INFO - "script_repo_revision": "production", 05:19:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:19:39 INFO - "repository": "", 05:19:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:19:39 INFO - "buildid": "20160414032548", 05:19:39 INFO - "pgo_build": "False", 05:19:39 INFO - "basedir": "/builds/slave/test", 05:19:39 INFO - "buildnumber": 610, 05:19:39 INFO - "slavename": "t-yosemite-r7-0152", 05:19:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:19:39 INFO - "platform": "yosemite_r7", 05:19:39 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:19:39 INFO - "branch": "fx-team", 05:19:39 INFO - "repo_path": "integration/fx-team", 05:19:39 INFO - "revision": "4a496faf1c5cac37ab6dac9db549cb65867fa7f6", 05:19:39 INFO - "stage_platform": "macosx64", 05:19:39 INFO - "builduid": "8695f4ce90c94b97a0ac36cbd725f5c3", 05:19:39 INFO - "slavebuilddir": "test" 05:19:39 INFO - } 05:19:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:19:39 INFO - Running post-action listener: _resource_record_post_action 05:19:39 INFO - ##### 05:19:39 INFO - ##### Running download-and-extract step. 05:19:39 INFO - ##### 05:19:39 INFO - Running pre-action listener: _resource_record_pre_action 05:19:39 INFO - Running main action method: download_and_extract 05:19:39 INFO - mkdir: /builds/slave/test/build/tests 05:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:39 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:19:39 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:19:39 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:19:39 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:19:39 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:19:41 INFO - Downloaded 1199 bytes. 05:19:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:19:41 INFO - Using the following test package requirements: 05:19:41 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:19:41 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:19:41 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:19:41 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'jsshell-mac.zip'], 05:19:41 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:19:41 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:19:41 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:19:41 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:19:41 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:19:41 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:41 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:19:41 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:19: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:19:41 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:19: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.common.tests.zip 05:19: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.common.tests.zip 05:19: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.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19: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.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:19:43 INFO - Downloaded 18031141 bytes. 05:19:43 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:19:43 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:19:46 INFO - Return code: 0 05:19:46 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:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:46 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:19:46 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:19:46 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:19:46 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:19:46 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:19:47 INFO - Downloaded 18031141 bytes. 05:19:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:19:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:19:50 INFO - Return code: 0 05:19: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:19:50 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:19:50 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:19:50 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:19:50 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:19:50 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:19:51 INFO - Downloaded 10841076 bytes. 05:19:51 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:19:51 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:19:52 INFO - Return code: 0 05:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:52 INFO - https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:19:52 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:19:52 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:19:52 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:19:52 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:19:55 INFO - Downloaded 109830898 bytes. 05:19:55 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:19:55 INFO - mkdir: /builds/slave/test/properties 05:19:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:55 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:55 INFO - Contents: 05:19:55 INFO - build_url:https://queue.taskcluster.net/v1/task/URwvq2oORFG065ACTMNv9Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:19:56 INFO - Running post-action listener: _resource_record_post_action 05:19:56 INFO - Running post-action listener: set_extra_try_arguments 05:19:56 INFO - ##### 05:19:56 INFO - ##### Running populate-webroot step. 05:19:56 INFO - ##### 05:19:56 INFO - Running pre-action listener: _resource_record_pre_action 05:19:56 INFO - Running main action method: populate_webroot 05:19:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:56 INFO - '--tppagecycles', 05:19:56 INFO - '7'], 05:19:56 INFO - 'tests': ['tsvgm']}}, 05:19:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['damp', 'tps']}, 05:19:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'tests': ['damp', 'tps']}, 05:19:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['dromaeo_dom']}, 05:19:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['a11yr', 05:19:56 INFO - 'ts_paint', 05:19:56 INFO - 'tpaint', 05:19:56 INFO - 'sessionrestore', 05:19:56 INFO - 'sessionrestore_no_auto_restore', 05:19:56 INFO - 'tabpaint']}, 05:19:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['tsvgx', 05:19:56 INFO - 'tsvgr_opacity', 05:19:56 INFO - 'tart', 05:19:56 INFO - 'tscrollx', 05:19:56 INFO - 'cart']}, 05:19:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:56 INFO - 'tsvgr_opacity', 05:19:56 INFO - 'tart', 05:19:56 INFO - 'tscrollx', 05:19:56 INFO - 'cart']}, 05:19:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'talos_options': ['--disable-e10s'], 05:19:56 INFO - 'tests': ['tp5o']}, 05:19:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'tests': ['tp5o']}, 05:19:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'talos_options': ['--disable-e10s', 05:19:56 INFO - '--xperf_path', 05:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:56 INFO - 'tests': ['tp5n']}, 05:19:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:56 INFO - 'talos_options': ['--xperf_path', 05:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:56 INFO - 'tests': ['tp5n']}}, 05:19:56 INFO - 'talos.zip': {'path': '', 05:19:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:56 INFO - Running post-action listener: _resource_record_post_action 05:19:56 INFO - ##### 05:19:56 INFO - ##### Running create-virtualenv step. 05:19:56 INFO - ##### 05:19:56 INFO - Running pre-action listener: _resource_record_pre_action 05:19:56 INFO - Running main action method: create_virtualenv 05:19:56 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:56 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:19:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:56 INFO - Using real prefix '/tools/python27' 05:19:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:00 INFO - Installing pip.................done. 05:20:00 INFO - Return code: 0 05:20:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:00 INFO - 'HOME': '/Users/cltbld', 05:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:00 INFO - 'LOGNAME': 'cltbld', 05:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:00 INFO - 'MOZ_NO_REMOTE': '1', 05:20:00 INFO - 'NO_EM_RESTART': '1', 05:20:00 INFO - 'PAGER': '/bin/cat', 05:20:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:00 INFO - 'PWD': '/builds/slave/test', 05:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:00 INFO - 'SHELL': '/bin/bash', 05:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:00 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:00 INFO - 'USER': 'cltbld', 05:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:00 INFO - 'XPC_FLAGS': '0x0', 05:20:00 INFO - 'XPC_SERVICE_NAME': '0', 05:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:20:00 INFO - Cleaning up... 05:20:00 INFO - Return code: 0 05:20:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:00 INFO - 'HOME': '/Users/cltbld', 05:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:00 INFO - 'LOGNAME': 'cltbld', 05:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:00 INFO - 'MOZ_NO_REMOTE': '1', 05:20:00 INFO - 'NO_EM_RESTART': '1', 05:20:00 INFO - 'PAGER': '/bin/cat', 05:20:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:00 INFO - 'PWD': '/builds/slave/test', 05:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:00 INFO - 'SHELL': '/bin/bash', 05:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:00 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:00 INFO - 'USER': 'cltbld', 05:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:00 INFO - 'XPC_FLAGS': '0x0', 05:20:00 INFO - 'XPC_SERVICE_NAME': '0', 05:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:00 INFO - Downloading/unpacking psutil>=0.7.1 05:20: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:20: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:20: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:20: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:20: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:20: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:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:04 INFO - Installing collected packages: psutil 05:20:04 INFO - Running setup.py install for psutil 05:20:04 INFO - building 'psutil._psutil_osx' extension 05:20:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:20:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:20:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:20:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:20:05 INFO - building 'psutil._psutil_posix' extension 05:20:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:20:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:20:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:20:05 INFO - ^ 05:20:05 INFO - 1 warning generated. 05:20:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:20:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:05 INFO - Successfully installed psutil 05:20:05 INFO - Cleaning up... 05:20:05 INFO - Return code: 0 05:20:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:20:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:05 INFO - 'HOME': '/Users/cltbld', 05:20:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:05 INFO - 'LOGNAME': 'cltbld', 05:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:05 INFO - 'MOZ_NO_REMOTE': '1', 05:20:05 INFO - 'NO_EM_RESTART': '1', 05:20:05 INFO - 'PAGER': '/bin/cat', 05:20:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:05 INFO - 'PWD': '/builds/slave/test', 05:20:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:05 INFO - 'SHELL': '/bin/bash', 05:20:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:05 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:05 INFO - 'USER': 'cltbld', 05:20:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:05 INFO - 'XPC_FLAGS': '0x0', 05:20:05 INFO - 'XPC_SERVICE_NAME': '0', 05:20:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:20:08 INFO - Installing collected packages: mozsystemmonitor 05:20:08 INFO - Running setup.py install for mozsystemmonitor 05:20:08 INFO - Successfully installed mozsystemmonitor 05:20:08 INFO - Cleaning up... 05:20:08 INFO - Return code: 0 05:20:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:08 INFO - 'HOME': '/Users/cltbld', 05:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:08 INFO - 'LOGNAME': 'cltbld', 05:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:08 INFO - 'MOZ_NO_REMOTE': '1', 05:20:08 INFO - 'NO_EM_RESTART': '1', 05:20:08 INFO - 'PAGER': '/bin/cat', 05:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:08 INFO - 'PWD': '/builds/slave/test', 05:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:08 INFO - 'SHELL': '/bin/bash', 05:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:08 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:08 INFO - 'USER': 'cltbld', 05:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:08 INFO - 'XPC_FLAGS': '0x0', 05:20:08 INFO - 'XPC_SERVICE_NAME': '0', 05:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:12 INFO - Downloading docopt-0.6.1.tar.gz 05:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:12 INFO - Running setup.py install for blobuploader 05:20:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:12 INFO - Running setup.py install for requests 05:20:13 INFO - Running setup.py install for docopt 05:20:13 INFO - Successfully installed blobuploader requests docopt 05:20:13 INFO - Cleaning up... 05:20:13 INFO - Return code: 0 05:20:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:13 INFO - 'HOME': '/Users/cltbld', 05:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:13 INFO - 'LOGNAME': 'cltbld', 05:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:13 INFO - 'MOZ_NO_REMOTE': '1', 05:20:13 INFO - 'NO_EM_RESTART': '1', 05:20:13 INFO - 'PAGER': '/bin/cat', 05:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:13 INFO - 'PWD': '/builds/slave/test', 05:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:13 INFO - 'SHELL': '/bin/bash', 05:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:13 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:13 INFO - 'USER': 'cltbld', 05:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:13 INFO - 'XPC_FLAGS': '0x0', 05:20:13 INFO - 'XPC_SERVICE_NAME': '0', 05:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:13 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-C1rYh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:13 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-NpY4JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:13 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-iUMh8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:13 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ioFlqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-7B_PiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Hw8VRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-pzEpLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Hc2l3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-g_yPGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-PHQLgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-DsWRGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-luymec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-axNFFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:14 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-iCiez0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:15 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-qS_cL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:15 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-bty8hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:15 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-gnDDAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:15 INFO - Running setup.py install for manifestparser 05:20:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:15 INFO - Running setup.py install for mozcrash 05:20:15 INFO - Running setup.py install for mozdebug 05:20:15 INFO - Running setup.py install for mozdevice 05:20:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:15 INFO - Running setup.py install for mozfile 05:20:16 INFO - Running setup.py install for mozhttpd 05:20:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Running setup.py install for mozinfo 05:20:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Running setup.py install for mozInstall 05:20:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Running setup.py install for mozleak 05:20:16 INFO - Running setup.py install for mozlog 05:20:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:16 INFO - Running setup.py install for moznetwork 05:20:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Running setup.py install for mozprocess 05:20:17 INFO - Running setup.py install for mozprofile 05:20:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Running setup.py install for mozrunner 05:20:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Running setup.py install for mozscreenshot 05:20:17 INFO - Running setup.py install for moztest 05:20:17 INFO - Running setup.py install for mozversion 05:20:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:17 INFO - Cleaning up... 05:20:17 INFO - Return code: 0 05:20:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:17 INFO - 'HOME': '/Users/cltbld', 05:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:17 INFO - 'LOGNAME': 'cltbld', 05:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:17 INFO - 'MOZ_NO_REMOTE': '1', 05:20:17 INFO - 'NO_EM_RESTART': '1', 05:20:17 INFO - 'PAGER': '/bin/cat', 05:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:17 INFO - 'PWD': '/builds/slave/test', 05:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:17 INFO - 'SHELL': '/bin/bash', 05:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:17 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:17 INFO - 'USER': 'cltbld', 05:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:17 INFO - 'XPC_FLAGS': '0x0', 05:20:17 INFO - 'XPC_SERVICE_NAME': '0', 05:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-nGCWzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-EwIfc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-rcCMOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-eH0UqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-6akb_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-k71Lo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-SoXZGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:18 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Bp7FQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:19 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:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-4TqnsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:19 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:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-lW5kY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IIghT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-8YUh0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yBSoIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ocZXdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-bJe64W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-0ZiPdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:19 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-eZwEhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:22 INFO - Downloading blessings-1.6.tar.gz 05:20:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:22 INFO - Installing collected packages: blessings 05:20:22 INFO - Running setup.py install for blessings 05:20:22 INFO - Successfully installed blessings 05:20:22 INFO - Cleaning up... 05:20:22 INFO - Return code: 0 05:20:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:23 INFO - Reading from file tmpfile_stdout 05:20:23 INFO - Current package versions: 05:20:23 INFO - blessings == 1.6 05:20:23 INFO - blobuploader == 1.2.4 05:20:23 INFO - docopt == 0.6.1 05:20:23 INFO - manifestparser == 1.1 05:20:23 INFO - mozInstall == 1.12 05:20:23 INFO - mozcrash == 0.17 05:20:23 INFO - mozdebug == 0.1 05:20:23 INFO - mozdevice == 0.48 05:20:23 INFO - mozfile == 1.2 05:20:23 INFO - mozhttpd == 0.7 05:20:23 INFO - mozinfo == 0.9 05:20:23 INFO - mozleak == 0.1 05:20:23 INFO - mozlog == 3.1 05:20:23 INFO - moznetwork == 0.27 05:20:23 INFO - mozprocess == 0.22 05:20:23 INFO - mozprofile == 0.28 05:20:23 INFO - mozrunner == 6.11 05:20:23 INFO - mozscreenshot == 0.1 05:20:23 INFO - mozsystemmonitor == 0.0 05:20:23 INFO - moztest == 0.7 05:20:23 INFO - mozversion == 1.4 05:20:23 INFO - psutil == 3.1.1 05:20:23 INFO - requests == 1.2.3 05:20:23 INFO - wsgiref == 0.1.2 05:20:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 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 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:23 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:20:23 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:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:23 INFO - 'HOME': '/Users/cltbld', 05:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:23 INFO - 'LOGNAME': 'cltbld', 05:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:23 INFO - 'MOZ_NO_REMOTE': '1', 05:20:23 INFO - 'NO_EM_RESTART': '1', 05:20:23 INFO - 'PAGER': '/bin/cat', 05:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:23 INFO - 'PWD': '/builds/slave/test', 05:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:23 INFO - 'SHELL': '/bin/bash', 05:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:23 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:23 INFO - 'USER': 'cltbld', 05:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:23 INFO - 'XPC_FLAGS': '0x0', 05:20:23 INFO - 'XPC_SERVICE_NAME': '0', 05:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:20:23 INFO - Cleaning up... 05:20:23 INFO - Return code: 0 05:20:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:23 INFO - 'HOME': '/Users/cltbld', 05:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:23 INFO - 'LOGNAME': 'cltbld', 05:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:23 INFO - 'MOZ_NO_REMOTE': '1', 05:20:23 INFO - 'NO_EM_RESTART': '1', 05:20:23 INFO - 'PAGER': '/bin/cat', 05:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:23 INFO - 'PWD': '/builds/slave/test', 05:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:23 INFO - 'SHELL': '/bin/bash', 05:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:23 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:23 INFO - 'USER': 'cltbld', 05:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:23 INFO - 'XPC_FLAGS': '0x0', 05:20:23 INFO - 'XPC_SERVICE_NAME': '0', 05:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:23 INFO - Downloading/unpacking jsonschema 05:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:26 INFO - warning: no files found matching '*.txt' 05:20:26 INFO - no previously-included directories found matching 'build' 05:20:26 INFO - no previously-included directories found matching 'dist' 05:20:26 INFO - no previously-included directories found matching '.git*' 05:20:26 INFO - Installing collected packages: jsonschema, functools32 05:20:26 INFO - Running setup.py install for functools32 05:20:26 INFO - warning: no files found matching '*.txt' 05:20:26 INFO - no previously-included directories found matching 'build' 05:20:26 INFO - no previously-included directories found matching 'dist' 05:20:26 INFO - no previously-included directories found matching '.git*' 05:20:26 INFO - Successfully installed jsonschema functools32 05:20:26 INFO - Cleaning up... 05:20:26 INFO - Return code: 0 05:20:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10520aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9619d990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, '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.CLdV7l3FBg/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.11pJExnKKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:26 INFO - 'HOME': '/Users/cltbld', 05:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:26 INFO - 'LOGNAME': 'cltbld', 05:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:26 INFO - 'MOZ_NO_REMOTE': '1', 05:20:26 INFO - 'NO_EM_RESTART': '1', 05:20:26 INFO - 'PAGER': '/bin/cat', 05:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:26 INFO - 'PWD': '/builds/slave/test', 05:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:26 INFO - 'SHELL': '/bin/bash', 05:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:20:26 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:26 INFO - 'USER': 'cltbld', 05:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:26 INFO - 'XPC_FLAGS': '0x0', 05:20:26 INFO - 'XPC_SERVICE_NAME': '0', 05:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:26 INFO - Downloading/unpacking flake8 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:20:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:20:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:20:30 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:20:30 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:20:30 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:20:30 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:20:30 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:20:30 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:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:20:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:20:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:20:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:20:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:20:30 INFO - no previously-included directories found matching 'docs/_build' 05:20:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:20:30 INFO - Running setup.py install for pep8 05:20:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:20:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:20:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:20:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:20:31 INFO - no previously-included directories found matching 'docs/_build' 05:20:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:20:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:20:31 INFO - Cleaning up... 05:20:31 INFO - Return code: 0 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - Running post-action listener: _start_resource_monitoring 05:20:31 INFO - Starting resource monitoring. 05:20:31 INFO - ##### 05:20:31 INFO - ##### Running install step. 05:20:31 INFO - ##### 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running main action method: install 05:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:31 INFO - Reading from file tmpfile_stdout 05:20:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:31 INFO - Reading from file tmpfile_stdout 05:20:31 INFO - Output received: 05:20:31 INFO - Usage: mozinstall [options] installer 05:20:31 INFO - Options: 05:20:31 INFO - -h, --help show this help message and exit 05:20:31 INFO - -d DEST, --destination=DEST 05:20:31 INFO - Directory to install application into. [default: 05:20:31 INFO - "/builds/slave/test"] 05:20:31 INFO - --app=APP Application being installed. [default: firefox] 05:20:31 INFO - mkdir: /builds/slave/test/build/application 05:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:21:05 INFO - Reading from file tmpfile_stdout 05:21:05 INFO - Output received: 05:21:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:21:05 INFO - Running post-action listener: _resource_record_post_action 05:21:05 INFO - ##### 05:21:05 INFO - ##### Running run-tests step. 05:21:05 INFO - ##### 05:21:05 INFO - Running pre-action listener: _resource_record_pre_action 05:21:05 INFO - Running main action method: run_tests 05:21:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:05 INFO - 2016-04-14 05:21:05.463 screenresolution[1643:7775] starting screenresolution argv=screenresolution get 05:21:05 INFO - 2016-04-14 05:21:05.466 screenresolution[1643:7775] Display 0: 1600x1200x32@60 05:21:05 INFO - 2016-04-14 05:21:05.471 screenresolution[1644:7777] starting screenresolution argv=screenresolution list 05:21:05 INFO - Available Modes on Display 0 05:21:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:05 INFO - Intel Iris: 05:21:05 INFO - Chipset Model: Intel Iris 05:21:05 INFO - Type: GPU 05:21:05 INFO - Bus: Built-In 05:21:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:05 INFO - Vendor: Intel (0x8086) 05:21:05 INFO - Device ID: 0x0a2e 05:21:05 INFO - Revision ID: 0x0009 05:21:05 INFO - Displays: 05:21:05 INFO - Display: 05:21:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:05 INFO - Display Serial Number: 1600x1200 60 05:21:05 INFO - Main Display: Yes 05:21:05 INFO - Mirror: Off 05:21:05 INFO - Online: Yes 05:21:05 INFO - Rotation: Supported 05:21:05 INFO - Return code: 0 05:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:05 INFO - Python 2.7.3 05:21:05 INFO - Return code: 0 05:21:05 INFO - grabbing minidump binary from tooltool 05:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:05 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 0x1050a9e00>, '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 0x104f12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:05 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:21:05 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:21:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:05 INFO - Return code: 0 05:21:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:21:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:21:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:21:07 INFO - Return code: 0 05:21:07 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-0152', '--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:21:07 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-0152 --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:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CLdV7l3FBg/Render', 05:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:07 INFO - 'HOME': '/Users/cltbld', 05:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:07 INFO - 'LOGNAME': 'cltbld', 05:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:07 INFO - 'MOZ_NO_REMOTE': '1', 05:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'NO_EM_RESTART': '1', 05:21:07 INFO - 'PAGER': '/bin/cat', 05:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:07 INFO - 'PWD': '/builds/slave/test', 05:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:07 INFO - 'SHELL': '/bin/bash', 05:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.11pJExnKKj/Listeners', 05:21:07 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 05:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:07 INFO - 'USER': 'cltbld', 05:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:07 INFO - 'XPC_FLAGS': '0x0', 05:21:07 INFO - 'XPC_SERVICE_NAME': '0', 05:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:07 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-0152', '--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:21:07 INFO - mozversion application_buildid: 20160414032548 05:21:07 INFO - mozversion application_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:21:07 INFO - mozversion application_display_name: Nightly 05:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:07 INFO - mozversion application_name: Firefox 05:21:07 INFO - mozversion application_remotingname: firefox 05:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:21:07 INFO - mozversion application_vendor: Mozilla 05:21:07 INFO - mozversion application_version: 48.0a1 05:21:07 INFO - mozversion platform_buildid: 20160414032548 05:21:07 INFO - mozversion platform_changeset: 4a496faf1c5cac37ab6dac9db549cb65867fa7f6 05:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:21:07 INFO - mozversion platform_version: 48.0a1 05:21:07 INFO - using testdate: 1460636467 05:21:07 INFO - actual date: 1460636467 05:21:07 INFO - starting webserver on 'localhost:49275' 05:21:07 INFO - SUITE-START | Running 2 tests 05:21:07 INFO - TEST-START | dromaeo_css 05:21:07 INFO - operating with platform_type : mac_ 05:21:07 INFO - Initialising browser for dromaeo_css test... 05:21:07 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpNbf0Dv/profile http://localhost:49275/getInfo.html) 05:21:09 INFO - PROCESS | 1662 | 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:21:10 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:21:10 INFO - PROCESS | 1662 | colorDepth:24 05:21:10 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:21:10 INFO - PROCESS | 1662 | __metrics 05:21:10 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:21:11 INFO - TEST-INFO | 1662: exit 0 05:21:11 INFO - Browser initialized. 05:21:11 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:11 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpNbf0Dv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:23 INFO - PROCESS | 1665 | RSS: Main: 247926784 05:21:23 INFO - PROCESS | 1665 | 05:22:12 INFO - PROCESS | 1665 | 1460636532643 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:24:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 05:24:24 INFO - PROCESS | 1665 | RSS: Main: 260407296 05:24:24 INFO - PROCESS | 1665 | 05:27:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 05:27:27 INFO - PROCESS | 1665 | RSS: Main: 271872000 05:27:27 INFO - PROCESS | 1665 | 05:30:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:30:29 INFO - PROCESS | 1665 | RSS: Main: 274079744 05:30:29 INFO - PROCESS | 1665 | 05:33:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 05:33:31 INFO - PROCESS | 1665 | RSS: Main: 275628032 05:33:31 INFO - PROCESS | 1665 | 05:36:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 05:36:35 INFO - PROCESS | 1665 | RSS: Main: 278482944 05:36:35 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 05:39:37 INFO - PROCESS | 1665 | RSS: Main: 279719936 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | __start_tp_report 05:39:37 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:39:37 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:39:37 INFO - PROCESS | 1665 | |i|pagename|runs| 05:39:37 INFO - PROCESS | 1665 | |0;dojo.html;3312.50312453132;3463.4458486642143;3485.3377858206923;3492.668196521331;3520.0671821967167;5653.971730141348;5693.629914055584;5694.174344720013;5714.657120572763;5720.313562372516;5614.9719251403785;5684.147377893308;5740.454656807607;5745.138229265602;5839.9124013139835;5158.509941555546;5190.143626301675;5194.844154675366;5231.973840130813;5256.159014557672;293.29813755682636;4243.787810609457;5219.843404697846;5222.738863056851;5282;5168.508991645806;5178.482151784829;5187.974060129694;5197.818076367309;5252.816151434681;5134.435212126663;5256.448072952323;5272;5299.993001329735;5309.734513274359;5118.232265160255;5133.281340612317;5161.845144645667;5216.243644671539;5239.16173412256;5025.84922452327;5516.7517461713915;5635.5773317001385;5663.8584035399035;5671.8582035449035;5009.223570346602;6135.140800782589;6295.213098362705;6299.118123462673;6311.779087731953;6187.969060154715;6262.436380725757;6268.15379923709;6276.372362763733;6297.2128483939505;5782.074868021138;5814.825555233349;5838.649681019152;5857.7949771757785;5875.618084824516;6156.107364432132;6226;6235.719392627343;6253.749850005949;6259.71831267594;6154.630722156685;6180.567360284737;6200.348963358872;6204.534659900525;6206.875862482725;6203.534734894901;6217.067439884054;6218.813435596849;6222.968885155636;6272.560920735505;6024.548158888101;6116.0010150213275;6116.2048933639235;6123.710312515612;6218.471429928416;6154.261488621304;6185.319734757812;6215.471684906834;6218.720157592919;6263.404976527314;4910.9932463844825;4944.283078953532;4948.480409556944;4953.628477864175;4972.825951091767;4312.633426158812;4371;4429.202743506135;4433.623142032899;4434.490033646108;5696.487316141567;5804.88390232193;5806.593538452268;5820.330661973927;5845.88308233833;6218.81343559694;6228.065790131426;6232.189815323979;6237.719302631393;6242.469390101802;4785.253382515426;4826.7827947742435;5798.188253644493;5834.416558344216;5894.823155305347;5339.252504649352;5390.649607775482;5395.676259424447;5418.945810541844;5426.2674538936735;6368.331325210786;6370.299267080617;6379.96810015947;6385.744570217233;6388;6159.4456498915315;6166.691665416622;6195.2255968004;6247.843803904939;6259.467945224706;6365.490760739222;6369.267534233622;6372.299047104722;6386.265579458422;6493.870122597427;4657.883552911204;6204.968975155276;6237.625742455467;6240.656763878031;6244.65654389013;6168.414000669929;6198.132261483486;6204.162438070984;6218.657973811484;6227.221597300338;6184.845378865564;6185.84535386619;6200.906986395117;6213.5339849512375;6225.408586184305;6053.334133245251;6146.969265153645;6184.165137706352;6202.689865506797;6203.658798766111;5839.299284085767;5929.110633405024;5999.130126131774;6041.6677082759115;6064.666443345658;6150.323464418821;6187.195664563758;6194.318624951342;6197.721102550306;6247.5939063960695;5899.203607513103;6016.398360164124;6057.242844644419;6069.4840938518955;6071.848203794941 05:39:37 INFO - PROCESS | 1665 | |1;ext.html;506.0940915760788;512.6078549909317;525.2934802690382;527.5489456514679;530.6683322923172;35595.466068008966;37901.620983790155;37908.67319643813;37926.241475170515;38065.809670951676;41418.79290603543;41446.58553414465;41687;41733.58266417335;41787;9424.151826335645;9639.566219520104;9672.081152290557;9712.757181070494;9723.076307750787;9451.905480945206;9542.141207291343;9546.665866694668;9587.23302135831;9612.663556775515;9025.458472491671;9123.08769123089;9522.85715714265;9565.234781217487;9634.98832622575;9541.904580954171;9610.471424071673;9619.278554108434;9627.374220675634;9629.807403851953;9514.28642851789;9545.427274363561;9595.328327017112;9646.903530964672;9658.468784216866;38016.61983380186;38218.80890595557;38354.80822595869;38384.80807595972;38470.61529384698;2790.9302267443272;2802.5936239245398;3130.1392117167734;3253.089135042192;3527.700145487611;26656.60015099775;26946.326341841414;27185.86407067972;27337;27391.904323827024;2721.115637417847;2728.358835673615;2760.6687197536226;2772.487089888377;2863.8138520996067;291420.5428972863;342010.28994854866;344721;346885;347209;59051;59611.70194149001;59743;59831;59855.70072149611;62221.688891555255;66231.66884165548;68241;68378;68509;16365.672686546202;16448.095354755365;16621.6675666486;16676.416325428552;16735.748963765553;12448.253104813559;12467.314297713532;12664.683382915317;12793.808092878615;12867.613971580868;16285.348586056425;19562.70655940162;19599.706004409945;19807.504812379517;19931.402057938285;7001.544899581511;8182.918170818335;8322.750317490485;8362.12197719243;8365.163483651708;942.6512190489562;960.8846938367301;1030.9329893556894;1034.8913364096811;1079.6491140379408;28319.71680283212;30001.849990750343;30067.849660751555;30484.695153048186;30652.84673576618;1078.962236321725;1124.8818874018275;1166.1428849795393;1191.6305945157028;1223.6023292429995;15459.690806183813;15583.06501609907;15628.374865005273;15700;15782.842171578366;619353;623014;628896;629005;629349.7065029291;47811.521884780705;55060;55928.72035639796;60355.69822150949;60680.69659651673;1454.178389210093;1472.3668822406469;1473.5358362115899;1489.0321291160833;1496.6632507685902;25253.368665783502;27138.050168244015;29180.27049323784;29340.413191735617;30115.698843012167;51887.70280743012;52355.738221310174;52874.471255288496;52908.47091529035;53393.733031334596;542538;543038;547632.2618386883;549506;549521.0191592452;26632.60051099274;26991.325216869736;41369.758907232834;42808;43683.78158109189;44778.77610611926;44864.55135448735;48291.79270518265;49652.00695985988;50186;37598.43602345912;37694.86915392543;37846.43230351492;38552.80723596476;38685;33237.667623323454;34252.48621270633;37419.62580374161;37925.431118533794;38100.237995240495;31938.36123277568;36160.63839361678;37238.8138059308;37933.24133517369;38803.417948730224;27833.443331132858;31840.68159318377;32246.677533224367;32612.858549950637;32629;21566.245181418573;23350.416239593465;24599.262022139363;25150.496990060463;25292.620610690487 05:39:37 INFO - PROCESS | 1665 | |2;jquery.html;398.1698159337781;398.92221016901715;401.23364374045576;408.17955908623674;421.886090755496;16278.26747796349;19480.707789383134;19607.215711371548;20436;20453.693194602074;19936.40190794275;20054.298099566517;20278.391648250577;20462.386128416136;20599.897000515015;5525.4474552544825;6073.423024812636;6104.328523862371;6108.572399931984;6114;5306.416294207634;5906.114082887559;5912.733926973297;5979.581429299929;6067.059605761114;4532.5240849710635;4814.9759251203695;5477.014137455257;5661.094224924013;5663.2637757091525;5285.2600635911;5670.3479099903625;5730.7707691692285;5739;5928.288605367384;5521.4478552144865;5554.19464177696;5684.351538053959;5684.744186511575;5714.942850571482;12856.614301570968;13142.474301027947;13273.269970151638;13316.600501984956;13371.26458044807;1815.7821061472707;2065.5042789730505;2106.2101711858054;2152.881591512466;2466.4450498637843;9570.425774453555;9713.417194968324;9755.951220243924;9812.116909478182;9955.850662240073;1700.3538655310904;1752.5443384720065;1775.4496106207116;1785.526835388623;1825.863060270485;106641;112031.43984280108;112501;113460.4326978368;113587.2961905572;11274.436278186222;11381.772364552662;11475.77048459026;11481.770364592661;11543.36511491876;13558.389872455764;13757.449702011807;14476.855231447551;14849.406023759144;14857.70284594302;7709.036370453693;8390.412671112963;8479.70321038773;8536.317094632415;8568.657253709907;7824.452288339761;8441.451305665112;8450.323974082181;8508.914910850935;8556.486610803247;4483.641308695296;4568.360429539867;4593.3339665748945;4637.373954516164;4663.300504924288;1938.4766113149371;1985.622731680976;1994.8204661580528;2018.8082132197417;2019.9293024744063;928.8838895138108;949.1789601994225;965.1506674126723;967.7241986034087;977.5014742481386;15467.458638947808;16688.91655541739;19163.137658805386;19739.50651233702;19797.30709425163;755.0637209859734;828.3083625172927;970.854371844229;987.2102318145455;1007.7833265847878;10056.798864022825;10079.24405669563;10130.341527800816;10208.795824083476;10209.74475638115;471967;561309;571409;571410;573232;112635.31047034301;115185;117550.41224793992;120112;122983;1000.9340052843698;1010.2271762101998;1012.0790081026231;1021.3718563083798;1022.2741853284103;17329;17405.12974351252;17452.82547174512;17542.561435963693;17750.378736744668;33725.83137084299;39205.41191882067;47227;49389.50610493849;49886.26307974629;59443;62923.37076629175;64293.0356044669;65004;65280.36799080061;6729;7688.615569221628;7934.920650793418;7939.364850811806;7963.362930965393;7230.794607644509;10799.56801727922;11272.887271127183;12109.455074521491;12181.451834667285;5487.533559647474;7876.921230787619;10615.203859710708;10764.40795756241;10791.838122428013;10389.324693894872;10519.474026298807;10531.57873685044;10660.46697665129;10679.09227715653;10509;10560.102391296827;10906.890931090587;11001.944990274997;11206.10351171888;7875.606219689107;7963.203679631991;7966;8062.39532035088;8170.918290817016;8208.015038195455;10409.895901040893;10613.310134841211;10714.73213169677;10930.781384372109 05:39:37 INFO - PROCESS | 1665 | |3;mootools.html;1587.801524809399;1638.9838300253843;1785.3394244129665;1814.2198854492563;1859.92560297588;7116;8078.838423231547;8632.39573229874;8939.061398553147;8961;8596.441231319975;8601.096884827097;8678.47929124253;8692.652293908237;8819.603117859695;2403.591389463791;2419.4556224849443;2447.779699827015;2451.1788550835404;2457.6190690442922;2421.842580232288;2429.4290841652196;2431.0397393029743;2437.390652336916;2465.050955382173;2451.742567030463;2470.530599186158;2472.0482614193575;2474.8638824864624;2495.7129930058004;2422.2369953464604;2429.7448767879473;2429.8056155507516;2438.317271164068;2445.8165637577254;2007.2874129684044;2390.4621460171325;2395.077895010416;2397.1490121006964;2405.410674384769;7731.768046958544;8628.266597339234;8828.779280518038;8838;8877.334199934967;5206.427292997767;5350.839474815762;5372.838814835562;5424.484673955968;5461.890762184734;2904.7530959868227;3519.560054993126;3557.2529768748336;3606.711463082947;3625.6374362563533;810.6392655268407;921.9124183202586;1040.7459011890653;1046.3042077018774;1051.621416290135;11523.19337646365;11633.767324653545;11656.825147622878;11738.765224695459;11771.352575608338;4762.261849413347;4806.639502037361;4870.9269360959615;4934.950650493521;4956.8760780980765;8599.5700214989;9245.583948722324;9451.76370590727;9907.207423406111;10092;4638.930416043762;5315.936812637488;5386.730663466811;5429.592780541475;5453.509184173444;3858.6527212551005;4752.429708434941;4786.521347865255;4907.1903135982875;4915.508449155056;4064.7357921734992;4080.163566468867;4080.404488839561;4087.5299340576216;4097;1143.2911594811271;1369.3906211735682;1370.3079944628098;1376.8623137686352;1389.916605003683;830.66773290683;903.9367244292837;920.1994264989369;935.1350001248953;949.6723630347519;5622.409646987007;5899.292084949833;5961.850953726105;5992.460678538952;6040.577159598786;446.1523106098439;451.88928712465656;454.3979227523478;455.8518481493528;457.7779776808245;3870.6709929656295;3878.049877779962;3933.645971862546;3968.464257325282;3971.3447281198855;10449.529771160316;10753.623623173151;10925.344479331265;10952.233343665866;10961.671149865517;10686.35881847076;10749.677509674722;10766.515506802212;10768.838467422995;10786.191035672198;6258.812235632939;8201.974753155855;8236.711715090063;8257.504549727035;8277.751667449986;4768.618510519081;5733.9965506037115;5828.009238429392;5905.055191169392;5909.556783241188;9768.755781105245;9776.871401151564;9824.852627210455;9990.250731195045;10016.549255283675;9814.822221333485;10104.84842727345;10125.139363154214;10220.284580079324;10254.436006019741;6939.16729992384;7327.340539351484;7588.27911348421;7589.6584653689615;7649.617519124133;8279.875801863098;8366.205210504992;8416.705415310435;8454.02778680435;8469.941257342833;7422.14645315774;7559.508631938907;7585.582792946441;7617.619119044137;7655.311022008046;7325.230850760807;7370.77887663371;7427.7771666850085;7466.066741657293;7523.210062943313;7138.214796372498;7157.425366419379;7176.102987126609;7199.6760145794615;7199.928000719926;4724.1732696777235;4814.109389762905;4853.587445067209;4876.390451193601;5014.24786282046;4883.340748998839;4898.902021959612;4974.12952733263;5019.472955339783;5159.535641792257 05:39:37 INFO - PROCESS | 1665 | |4;prototype.html;268.2569283085852;318.78322740536436;325.98207098609583;326.6521154969955;327.78038714061574;5861.384554621767;6095.116208149816;6191.659458729774;6204.379562043793;6219.875602487948;6157.661328626924;6163.428325776932;6169.876602467948;6188.884391879395;6193.628382297065;3848.8075596220215;4600.125976064537;4622.976885115578;4628.26932110055;4629.444466664005;3414.8804791832167;4009.8797036088963;4013.8595149169787;4408.2807704995075;4653.581177694007;3589.85640574378;4065.6747460203114;4474.932876006863;4515.4129963104915;4532;4388.473383194005;4546.1589605922845;4547.408836851221;4555.24838401665;4570.491737726542;3210.486322188462;3595.227026189382;3860.768353898775;3883.86406475772;3900.2004589059175;5602.60781745278;5902.852428689317;5913.733881975321;5927.229460170193;5956.315023772278;5244.39689435716;5265.394479634852;5267.86740850719;5281.683099014033;5287.629865909388;4918.458969513351;5705.857353566153;6208.565400421972;6256.092866534373;6538.509611779088;3877.6122387760997;3973.205358928197;4238.4490016298;4278.935815962763;4291.871243862689;6267.2165979252595;6670.966645166792;6723.93364928908;6865.009147347263;6905.516613837033;4227.556106608823;4324.978375108167;4338.327559228324;4339.7396156230725;4350.521442641307;3522.982385088058;3745.325841348529;4406.228909940747;4414.492333381638;4426.73439593619;5195.376554813447;5198.116320225495;5200.5059519345605;5213.504717051874;5216.947830521722;6367.840803979845;6485.319041500575;6561.3438656134;6578.76974305897;6584.50616203777;1497.3486533358025;1512.3496896334682;1515.6514001779653;1525.4203402707121;1525.557588299403;907.3904564810746;980.5146452506056;987.6888780034266;993.9850902236473;998.4159266828987;5529.585281103933;6103.267607887082;6125.509959203253;6138.1099740537375;6164.4143806338325;5731.455511726463;5813.186153938452;6241.313455519889;6786;6832.627118644072;5850.572012614717;6124.785632502841;6177.135201071854;6219.782307619212;6274.341194174637;3576.445650924111;3593.982030089885;3613.2412193439145;3642.2351306225987;3643.0145570664054;5718.68547229898;6305.833420817164;6497.772577959749;6720.697568609425;7206.135263768381;6689.862723337044;6983.511154219257;7016.508844380946;7064.646767661597;7169.5698258104685;6535.803925882231;6788;6883.965580172067;6959.512834101665;7128.429725622042;5742.16738572913;5851.946649603113;5872.970635146797;6776.254611992776;6813.525102198014;5547.7780888764;6463.256725476443;6630.3038180992235;6655.800325990228;6727.865442691216;5898.4101589840675;6902.654867256718;7084.64576771149;7141.535800172972;7158.355747982707;4288.678349123766;4309.892252693708;4319.8488052918;4329.090890912944;4336.371226172124;6538.496145886568;6795.490338224752;6818.693158807965;6822.11312529368;6855.828604284933;5417.918731219113;6567.343265673395;6603.966980165068;6612.206535215805;6618.7352505899225;6328.905066423915;6554.901676474952;6579.440747536513;6608.306127856506;6642.867142657216;6224.408681175281;6382.170317858649;6506.674666266573;6511.38141876521;6575.868482630416;4948.911239527299;6200.844978875564;6544.9672751635935;6607.579370435379;6620.470362371095;5973.820785376446;5980.102984552352;6438.4527315176865;6448.709808058742;6469.805905822833 05:39:37 INFO - PROCESS | 1665 | |5;yui.html;658.2200092889926;668.3583759590792;672.2302963107244;681.4003676764447;727.4834867244256;14208.579142085811;14559;14954.47659331925;14986.550403487885;15025;12288.262704237752;13054.673633159176;15820.446284380048;16011.519654410356;16174.75737863933;704.278114932196;735.1802739944976;890.3856339125982;896.4173287363201;898.5148020069155;865.0830120072711;873.6461732998146;893.0533634347612;894.8926128864546;896.995515022424;921.5392303848076;929.930255230857;933.9953300233524;935.723961431376;936.5925822267302;895.3329769321897;923.3997901364099;924.9445033298024;925.0749250749251;934.9158575728183;671.0370618162902;674.0495900779877;674.2482132422363;696.7979286007052;697.3967518096861;15296.694066118615;15527.068375897483;15622.218889055503;15690.372385104582;15693;12665;12708.364581770968;12746.171498852638;12761.170523915916;12957.676058098528;10125.544350504246;10181.33821301613;10413.427261500614;10439.259502683732;10640.148788096934;3467.098554375855;3486.703630191437;3513.1744040150543;3513.4027215373194;3588.102974256436;67756.98364524536;77416.22583774147;84243;85150.72273915895;85460.57269713611;9299.628014879328;9356.578953946953;9376.8593471098;9413.576389062371;9519.143277105093;19656.11547480338;19693.901530492254;19767.011649417473;20277.69583456249;20370.28703995353;7566.1298950620285;7600.125985511626;7635.656395462218;7642.5032372895585;7962.800929976681;3271.3457308538386;3512.613612502623;3589.964100359015;3964.266610677034;4314.892127696833;3552.769070010441;3586.1572530455323;3598.982005089958;3687.575928768226;3697.722670799647;534.6070638081007;647.2847503508649;650.06066234291;661.9271880093186;662.9138212032501;543.6004536665548;572.3086094689271;578.8494991302208;587.3832475900288;603.5594016368076;6911.550749201387;7201.171865235565;7594.544327340267;7723.691052357843;8124.146964568753;430.7932192547595;500.66705640748495;582.9533637309005;589.7344713134231;604.7218279591349;3489.4765785131926;3545.468179773003;3757.323681737259;3799.9430008549894;3830.71269654777;76883.61558192174;77114.45771084547;77283;77712.22287777162;77782.2221777775;60561.69719151376;67090.99363509551;69839.3016069843;70321.59356812834;70412.2958770416;54335.45664543383;57764;63377.68311158415;63803;64150;7457.888131677921;7466.813329666802;7475.962620186864;7532.698692052257;7560.7955792286975;41325.38011929884;42070.57929420667;42493.787531062146;42916.78541607272;43581.564184357754;45751;50962.490375095775;53910.46089539054;59511.809763805344;59520.51198720067;9492.477913714536;9603.855942160733;9673.613055477703;9808.509574521102;10053.597856085675;10435.426051567236;10481;10520.947395262974;10549.683509494727;10576.947115264375;8469.5341756204;9898.10917017472;10106.848397274194;10432.321899076536;10507.369557826554;10179.694609161737;10219.386836789816;10465.319754215952;10479.161667066464;10495.73760655959;9937.304388692723;10059.798804023732;10060.647877324258;10124.69625911224;10231;7356;8846.336524760798;9023.774405639911;9157;9323.067693230623;8278.958605206935;8649.481031138152;8717.52053637056;8775.597566924336;8926.419782714103 05:39:37 INFO - PROCESS | 1665 | __end_tp_report 05:39:37 INFO - PROCESS | 1665 | __start_cc_report 05:39:37 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,375 05:39:37 INFO - PROCESS | 1665 | __end_cc_report 05:39:37 INFO - PROCESS | 1665 | __startTimestamp1460637577942__endTimestamp 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:39:37 INFO - PROCESS | 1665 | Number of tests: 6 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5702.44 Median:6024.55 stddev:785.68 (13.0%) stddev-sans-first:765.39 05:39:37 INFO - PROCESS | 1665 | Values: 3312.5 3463.4 3485.3 3492.7 3520.1 5654.0 5693.6 5694.2 5714.7 5720.3 5615.0 5684.1 5740.5 5745.1 5839.9 5158.5 5190.1 5194.8 5232.0 5256.2 293.3 4243.8 5219.8 5222.7 5282.0 5168.5 5178.5 5188.0 5197.8 5252.8 5134.4 5256.4 5272.0 5300.0 5309.7 5118.2 5133.3 5161.8 5216.2 5239.2 5025.8 5516.8 5635.6 5663.9 5671.9 5009.2 6135.1 6295.2 6299.1 6311.8 6188.0 6262.4 6268.2 6276.4 6297.2 5782.1 5814.8 5838.6 5857.8 5875.6 6156.1 6226.0 6235.7 6253.7 6259.7 6154.6 6180.6 6200.3 6204.5 6206.9 6203.5 6217.1 6218.8 6223.0 6272.6 6024.5 6116.0 6116.2 6123.7 6218.5 6154.3 6185.3 6215.5 6218.7 6263.4 4911.0 4944.3 4948.5 4953.6 4972.8 4312.6 4371.0 4429.2 4433.6 4434.5 5696.5 5804.9 5806.6 5820.3 5845.9 6218.8 6228.1 6232.2 6237.7 6242.5 4785.3 4826.8 5798.2 5834.4 5894.8 5339.3 5390.6 5395.7 5418.9 5426.3 6368.3 6370.3 6380.0 6385.7 6388.0 6159.4 6166.7 6195.2 6247.8 6259.5 6365.5 6369.3 6372.3 6386.3 6493.9 4657.9 6205.0 6237.6 6240.7 6244.7 6168.4 6198.1 6204.2 6218.7 6227.2 6184.8 6185.8 6200.9 6213.5 6225.4 6053.3 6147.0 6184.2 6202.7 6203.7 5839.3 5929.1 5999.1 6041.7 6064.7 6150.3 6187.2 6194.3 6197.7 6247.6 5899.2 6016.4 6057.2 6069.5 6071.8 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:64557.76 Median:38041.21 stddev:138282.01 (363.5%) stddev-sans-first:138586.30 05:39:37 INFO - PROCESS | 1665 | Values: 506.1 512.6 525.3 527.5 530.7 35595.5 37901.6 37908.7 37926.2 38065.8 41418.8 41446.6 41687.0 41733.6 41787.0 9424.2 9639.6 9672.1 9712.8 9723.1 9451.9 9542.1 9546.7 9587.2 9612.7 9025.5 9123.1 9522.9 9565.2 9635.0 9541.9 9610.5 9619.3 9627.4 9629.8 9514.3 9545.4 9595.3 9646.9 9658.5 38016.6 38218.8 38354.8 38384.8 38470.6 2790.9 2802.6 3130.1 3253.1 3527.7 26656.6 26946.3 27185.9 27337.0 27391.9 2721.1 2728.4 2760.7 2772.5 2863.8 291420.5 342010.3 344721.0 346885.0 347209.0 59051.0 59611.7 59743.0 59831.0 59855.7 62221.7 66231.7 68241.0 68378.0 68509.0 16365.7 16448.1 16621.7 16676.4 16735.7 12448.3 12467.3 12664.7 12793.8 12867.6 16285.3 19562.7 19599.7 19807.5 19931.4 7001.5 8182.9 8322.8 8362.1 8365.2 942.7 960.9 1030.9 1034.9 1079.6 28319.7 30001.8 30067.8 30484.7 30652.8 1079.0 1124.9 1166.1 1191.6 1223.6 15459.7 15583.1 15628.4 15700.0 15782.8 619353.0 623014.0 628896.0 629005.0 629349.7 47811.5 55060.0 55928.7 60355.7 60680.7 1454.2 1472.4 1473.5 1489.0 1496.7 25253.4 27138.1 29180.3 29340.4 30115.7 51887.7 52355.7 52874.5 52908.5 53393.7 542538.0 543038.0 547632.3 549506.0 549521.0 26632.6 26991.3 41369.8 42808.0 43683.8 44778.8 44864.6 48291.8 49652.0 50186.0 37598.4 37694.9 37846.4 38552.8 38685.0 33237.7 34252.5 37419.6 37925.4 38100.2 31938.4 36160.6 37238.8 37933.2 38803.4 27833.4 31840.7 32246.7 32612.9 32629.0 21566.2 23350.4 24599.3 25150.5 25292.6 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:31842.77 Median:2309.66 stddev:91973.79 (3982.1%) stddev-sans-first:92201.50 05:39:37 INFO - PROCESS | 1665 | Values: 398.2 398.9 401.2 408.2 421.9 16278.3 19480.7 19607.2 20436.0 20453.7 19936.4 20054.3 20278.4 20462.4 20599.9 5525.4 6073.4 6104.3 6108.6 6114.0 5306.4 5906.1 5912.7 5979.6 6067.1 4532.5 4815.0 5477.0 5661.1 5663.3 5285.3 5670.3 5730.8 5739.0 5928.3 5521.4 5554.2 5684.4 5684.7 5714.9 12856.6 13142.5 13273.3 13316.6 13371.3 1815.8 2065.5 2106.2 2152.9 2466.4 9570.4 9713.4 9756.0 9812.1 9955.9 1700.4 1752.5 1775.4 1785.5 1825.9 106641.0 112031.4 112501.0 113460.4 113587.3 11274.4 11381.8 11475.8 11481.8 11543.4 13558.4 13757.4 14476.9 14849.4 14857.7 7709.0 8390.4 8479.7 8536.3 8568.7 7824.5 8441.5 8450.3 8508.9 8556.5 4483.6 4568.4 4593.3 4637.4 4663.3 1938.5 1985.6 1994.8 2018.8 2019.9 928.9 949.2 965.2 967.7 977.5 15467.5 16688.9 19163.1 19739.5 19797.3 755.1 828.3 970.9 987.2 1007.8 10056.8 10079.2 10130.3 10208.8 10209.7 471967.0 561309.0 571409.0 571410.0 573232.0 112635.3 115185.0 117550.4 120112.0 122983.0 1000.9 1010.2 1012.1 1021.4 1022.3 17329.0 17405.1 17452.8 17542.6 17750.4 33725.8 39205.4 47227.0 49389.5 49886.3 59443.0 62923.4 64293.0 65004.0 65280.4 6729.0 7688.6 7934.9 7939.4 7963.4 7230.8 10799.6 11272.9 12109.5 12181.5 5487.5 7876.9 10615.2 10764.4 10791.8 10389.3 10519.5 10531.6 10660.5 10679.1 10509.0 10560.1 10906.9 11001.9 11206.1 7875.6 7963.2 7966.0 8062.4 8170.9 8208.0 10409.9 10613.3 10714.7 10930.8 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5681.65 Median:4879.87 stddev:3188.04 (65.3%) stddev-sans-first:3182.14 05:39:37 INFO - PROCESS | 1665 | Values: 1587.8 1639.0 1785.3 1814.2 1859.9 7116.0 8078.8 8632.4 8939.1 8961.0 8596.4 8601.1 8678.5 8692.7 8819.6 2403.6 2419.5 2447.8 2451.2 2457.6 2421.8 2429.4 2431.0 2437.4 2465.1 2451.7 2470.5 2472.0 2474.9 2495.7 2422.2 2429.7 2429.8 2438.3 2445.8 2007.3 2390.5 2395.1 2397.1 2405.4 7731.8 8628.3 8828.8 8838.0 8877.3 5206.4 5350.8 5372.8 5424.5 5461.9 2904.8 3519.6 3557.3 3606.7 3625.6 810.6 921.9 1040.7 1046.3 1051.6 11523.2 11633.8 11656.8 11738.8 11771.4 4762.3 4806.6 4870.9 4935.0 4956.9 8599.6 9245.6 9451.8 9907.2 10092.0 4638.9 5315.9 5386.7 5429.6 5453.5 3858.7 4752.4 4786.5 4907.2 4915.5 4064.7 4080.2 4080.4 4087.5 4097.0 1143.3 1369.4 1370.3 1376.9 1389.9 830.7 903.9 920.2 935.1 949.7 5622.4 5899.3 5961.9 5992.5 6040.6 446.2 451.9 454.4 455.9 457.8 3870.7 3878.0 3933.6 3968.5 3971.3 10449.5 10753.6 10925.3 10952.2 10961.7 10686.4 10749.7 10766.5 10768.8 10786.2 6258.8 8202.0 8236.7 8257.5 8277.8 4768.6 5734.0 5828.0 5905.1 5909.6 9768.8 9776.9 9824.9 9990.3 10016.5 9814.8 10104.8 10125.1 10220.3 10254.4 6939.2 7327.3 7588.3 7589.7 7649.6 8279.9 8366.2 8416.7 8454.0 8469.9 7422.1 7559.5 7585.6 7617.6 7655.3 7325.2 7370.8 7427.8 7466.1 7523.2 7138.2 7157.4 7176.1 7199.7 7199.9 4724.2 4814.1 4853.6 4876.4 5014.2 4883.3 4898.9 4974.1 5019.5 5159.5 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5244.96 Median:5976.96 stddev:1684.09 (28.2%) stddev-sans-first:1646.86 05:39:37 INFO - PROCESS | 1665 | Values: 268.3 318.8 326.0 326.7 327.8 5861.4 6095.1 6191.7 6204.4 6219.9 6157.7 6163.4 6169.9 6188.9 6193.6 3848.8 4600.1 4623.0 4628.3 4629.4 3414.9 4009.9 4013.9 4408.3 4653.6 3589.9 4065.7 4474.9 4515.4 4532.0 4388.5 4546.2 4547.4 4555.2 4570.5 3210.5 3595.2 3860.8 3883.9 3900.2 5602.6 5902.9 5913.7 5927.2 5956.3 5244.4 5265.4 5267.9 5281.7 5287.6 4918.5 5705.9 6208.6 6256.1 6538.5 3877.6 3973.2 4238.4 4278.9 4291.9 6267.2 6671.0 6723.9 6865.0 6905.5 4227.6 4325.0 4338.3 4339.7 4350.5 3523.0 3745.3 4406.2 4414.5 4426.7 5195.4 5198.1 5200.5 5213.5 5216.9 6367.8 6485.3 6561.3 6578.8 6584.5 1497.3 1512.3 1515.7 1525.4 1525.6 907.4 980.5 987.7 994.0 998.4 5529.6 6103.3 6125.5 6138.1 6164.4 5731.5 5813.2 6241.3 6786.0 6832.6 5850.6 6124.8 6177.1 6219.8 6274.3 3576.4 3594.0 3613.2 3642.2 3643.0 5718.7 6305.8 6497.8 6720.7 7206.1 6689.9 6983.5 7016.5 7064.6 7169.6 6535.8 6788.0 6884.0 6959.5 7128.4 5742.2 5851.9 5873.0 6776.3 6813.5 5547.8 6463.3 6630.3 6655.8 6727.9 5898.4 6902.7 7084.6 7141.5 7158.4 4288.7 4309.9 4319.8 4329.1 4336.4 6538.5 6795.5 6818.7 6822.1 6855.8 5417.9 6567.3 6604.0 6612.2 6618.7 6328.9 6554.9 6579.4 6608.3 6642.9 6224.4 6382.2 6506.7 6511.4 6575.9 4948.9 6200.8 6545.0 6607.6 6620.5 5973.8 5980.1 6438.5 6448.7 6469.8 05:39:37 INFO - PROCESS | 1665 | 05:39:37 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:16429.49 Median:30583.21 stddev:22468.13 (73.5%) stddev-sans-first:22499.95 05:39:37 INFO - PROCESS | 1665 | Values: 658.2 668.4 672.2 681.4 727.5 14208.6 14559.0 14954.5 14986.6 15025.0 12288.3 13054.7 15820.4 16011.5 16174.8 704.3 735.2 890.4 896.4 898.5 865.1 873.6 893.1 894.9 897.0 921.5 929.9 934.0 935.7 936.6 895.3 923.4 924.9 925.1 934.9 671.0 674.0 674.2 696.8 697.4 15296.7 15527.1 15622.2 15690.4 15693.0 12665.0 12708.4 12746.2 12761.2 12957.7 10125.5 10181.3 10413.4 10439.3 10640.1 3467.1 3486.7 3513.2 3513.4 3588.1 67757.0 77416.2 84243.0 85150.7 85460.6 9299.6 9356.6 9376.9 9413.6 9519.1 19656.1 19693.9 19767.0 20277.7 20370.3 7566.1 7600.1 7635.7 7642.5 7962.8 3271.3 3512.6 3590.0 3964.3 4314.9 3552.8 3586.2 3599.0 3687.6 3697.7 534.6 647.3 650.1 661.9 662.9 543.6 572.3 578.8 587.4 603.6 6911.6 7201.2 7594.5 7723.7 8124.1 430.8 500.7 583.0 589.7 604.7 3489.5 3545.5 3757.3 3799.9 3830.7 76883.6 77114.5 77283.0 77712.2 77782.2 60561.7 67091.0 69839.3 70321.6 70412.3 54335.5 57764.0 63377.7 63803.0 64150.0 7457.9 7466.8 7476.0 7532.7 7560.8 41325.4 42070.6 42493.8 42916.8 43581.6 45751.0 50962.5 53910.5 59511.8 59520.5 9492.5 9603.9 9673.6 9808.5 10053.6 10435.4 10481.0 10520.9 10549.7 10576.9 8469.5 9898.1 10106.8 10432.3 10507.4 10179.7 10219.4 10465.3 10479.2 10495.7 9937.3 10059.8 10060.6 10124.7 10231.0 7356.0 8846.3 9023.8 9157.0 9323.1 8279.0 8649.5 8717.5 8775.6 8926.4 05:39:37 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:39:37 INFO - PROCESS | 1665 | 05:39:38 INFO - TEST-INFO | 1665: exit 0 05:39:38 INFO - TEST-OK | dromaeo_css | took 1111241ms 05:39:38 INFO - TEST-START | kraken 05:39:38 INFO - operating with platform_type : mac_ 05:39:38 INFO - Initialising browser for kraken test... 05:39:38 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpiZI2pj/profile http://localhost:49275/getInfo.html) 05:39:40 INFO - PROCESS | 1674 | 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:39:41 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:39:41 INFO - PROCESS | 1674 | colorDepth:24 05:39:41 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:39:41 INFO - PROCESS | 1674 | __metrics 05:39:41 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:39:42 INFO - TEST-INFO | 1674: exit 0 05:39:42 INFO - Browser initialized. 05:39:42 INFO - Running cycle 1/1 for kraken test... 05:39:42 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpiZI2pj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:54 INFO - PROCESS | 1677 | RSS: Main: 247558144 05:39:54 INFO - PROCESS | 1677 | 05:39:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:57 INFO - PROCESS | 1677 | RSS: Main: 259780608 05:39:57 INFO - PROCESS | 1677 | 05:40:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 05:40:01 INFO - PROCESS | 1677 | RSS: Main: 268738560 05:40:01 INFO - PROCESS | 1677 | 05:40:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 05:40:05 INFO - PROCESS | 1677 | RSS: Main: 271712256 05:40:05 INFO - PROCESS | 1677 | 05:40:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 05:40:07 INFO - PROCESS | 1677 | RSS: Main: 273588224 05:40:07 INFO - PROCESS | 1677 | 05:40:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:10 INFO - PROCESS | 1677 | RSS: Main: 274321408 05:40:10 INFO - PROCESS | 1677 | 05:40:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:14 INFO - PROCESS | 1677 | RSS: Main: 274522112 05:40:14 INFO - PROCESS | 1677 | 05:40:16 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:17 INFO - PROCESS | 1677 | RSS: Main: 274739200 05:40:17 INFO - PROCESS | 1677 | 05:40:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 05:40:21 INFO - PROCESS | 1677 | RSS: Main: 274841600 05:40:21 INFO - PROCESS | 1677 | 05:40:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:23 INFO - PROCESS | 1677 | RSS: Main: 274632704 05:40:23 INFO - PROCESS | 1677 | 05:40:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:25 INFO - PROCESS | 1677 | RSS: Main: 274804736 05:40:25 INFO - PROCESS | 1677 | 05:40:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:28 INFO - PROCESS | 1677 | RSS: Main: 274968576 05:40:28 INFO - PROCESS | 1677 | 05:40:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:32 INFO - PROCESS | 1677 | RSS: Main: 275304448 05:40:32 INFO - PROCESS | 1677 | 05:40:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:35 INFO - PROCESS | 1677 | RSS: Main: 275087360 05:40:35 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 05:40:37 INFO - PROCESS | 1677 | RSS: Main: 276942848 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | __start_tp_report 05:40:37 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:40:37 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:40:37 INFO - PROCESS | 1677 | |i|pagename|runs| 05:40:37 INFO - PROCESS | 1677 | |0;ai-astar;118;108;117;116;116;127;116;115;119;119 05:40:37 INFO - PROCESS | 1677 | |1;audio-beat-detection;150;126;145;163;122;142;142;142;215;147 05:40:37 INFO - PROCESS | 1677 | |2;audio-dft;195;203;214;205;194;197;197;196;201;196 05:40:37 INFO - PROCESS | 1677 | |3;audio-fft;69;72;77;143;57;54;64;64;75;59 05:40:37 INFO - PROCESS | 1677 | |4;audio-oscillator;80;82;135;65;79;111;102;115;67;134 05:40:37 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;110;133;96;87;92;85;113;90;138;77 05:40:37 INFO - PROCESS | 1677 | |6;imaging-darkroom;94;100;154;95;95;96;96;96;96;95 05:40:37 INFO - PROCESS | 1677 | |7;imaging-desaturate;93;93;96;92;93;95;150;145;90;93 05:40:37 INFO - PROCESS | 1677 | |8;json-parse-financial;62;61;61;61;62;61;61;79;63;60 05:40:37 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;62;68;71;66;70;76;73;66;65;60 05:40:37 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;86;88;89;88;86;85;87;94;108;113 05:40:37 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;150;151;165;164;161;156;141;146;219;168 05:40:37 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;177;171;231;209;173;168;171;169;173;172 05:40:37 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;73;69;69;82;95;99;71;71;67;67 05:40:37 INFO - PROCESS | 1677 | __end_tp_report 05:40:37 INFO - PROCESS | 1677 | __start_cc_report 05:40:37 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,992 05:40:37 INFO - PROCESS | 1677 | __end_cc_report 05:40:37 INFO - PROCESS | 1677 | __startTimestamp1460637637912__endTimestamp 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:40:37 INFO - PROCESS | 1677 | Number of tests: 14 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:117.10 Median:117.50 stddev:4.68 (4.0%) stddev-sans-first:4.95 05:40:37 INFO - PROCESS | 1677 | Values: 118.0 108.0 117.0 116.0 116.0 127.0 116.0 115.0 119.0 119.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:146.00 stddev:25.77 (17.7%) stddev-sans-first:27.33 05:40:37 INFO - PROCESS | 1677 | Values: 150.0 126.0 145.0 163.0 122.0 142.0 142.0 142.0 215.0 147.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:199.80 Median:199.00 stddev:6.16 (3.1%) stddev-sans-first:6.28 05:40:37 INFO - PROCESS | 1677 | Values: 195.0 203.0 214.0 205.0 194.0 197.0 197.0 196.0 201.0 196.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:73.40 Median:66.50 stddev:25.63 (38.5%) stddev-sans-first:27.13 05:40:37 INFO - PROCESS | 1677 | Values: 69.0 72.0 77.0 143.0 57.0 54.0 64.0 64.0 75.0 59.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:66.00 stddev:26.08 (39.5%) stddev-sans-first:26.92 05:40:37 INFO - PROCESS | 1677 | Values: 80.0 82.0 135.0 65.0 79.0 111.0 102.0 115.0 67.0 134.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:102.10 Median:86.00 stddev:20.71 (24.1%) stddev-sans-first:21.77 05:40:37 INFO - PROCESS | 1677 | Values: 110.0 133.0 96.0 87.0 92.0 85.0 113.0 90.0 138.0 77.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:95.50 stddev:18.45 (19.3%) stddev-sans-first:19.35 05:40:37 INFO - PROCESS | 1677 | Values: 94.0 100.0 154.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:104.00 Median:93.00 stddev:23.01 (24.7%) stddev-sans-first:24.06 05:40:37 INFO - PROCESS | 1677 | Values: 93.0 93.0 96.0 92.0 93.0 95.0 150.0 145.0 90.0 93.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:61.50 stddev:5.65 (9.2%) stddev-sans-first:5.97 05:40:37 INFO - PROCESS | 1677 | Values: 62.0 61.0 61.0 61.0 62.0 61.0 61.0 79.0 63.0 60.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:69.00 stddev:4.92 (7.1%) stddev-sans-first:4.77 05:40:37 INFO - PROCESS | 1677 | Values: 62.0 68.0 71.0 66.0 70.0 76.0 73.0 66.0 65.0 60.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:87.50 stddev:9.92 (11.3%) stddev-sans-first:10.25 05:40:37 INFO - PROCESS | 1677 | Values: 86.0 88.0 89.0 88.0 86.0 85.0 87.0 94.0 108.0 113.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:162.10 Median:162.50 stddev:21.85 (13.4%) stddev-sans-first:22.73 05:40:37 INFO - PROCESS | 1677 | Values: 150.0 151.0 165.0 164.0 161.0 156.0 141.0 146.0 219.0 168.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:173.00 stddev:21.14 (12.2%) stddev-sans-first:22.36 05:40:37 INFO - PROCESS | 1677 | Values: 177.0 171.0 231.0 209.0 173.0 168.0 171.0 169.0 173.0 172.0 05:40:37 INFO - PROCESS | 1677 | 05:40:37 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:72.00 stddev:11.76 (16.3%) stddev-sans-first:12.41 05:40:37 INFO - PROCESS | 1677 | Values: 73.0 69.0 69.0 82.0 95.0 99.0 71.0 71.0 67.0 67.0 05:40:37 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:40:37 INFO - PROCESS | 1677 | 05:40:38 INFO - TEST-INFO | 1677: exit 0 05:40:38 INFO - TEST-OK | kraken | took 60292ms 05:40:38 INFO - SUITE-END | took 1171s 05:40:39 INFO - Completed test suite (00:19:31) 05:40:39 INFO - javascript benchmark 05:40:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.50312453132, 3463.4458486642143, 3485.3377858206923, 3492.668196521331, 3520.0671821967167, 5653.971730141348, 5693.629914055584, 5694.174344720013, 5714.657120572763, 5720.313562372516, 5614.9719251403785, 5684.147377893308, 5740.454656807607, 5745.138229265602, 5839.9124013139835, 5158.509941555546, 5190.143626301675, 5194.844154675366, 5231.973840130813, 5256.159014557672, 293.29813755682636, 4243.787810609457, 5219.843404697846, 5222.738863056851, 5282.0, 5168.508991645806, 5178.482151784829, 5187.974060129694, 5197.818076367309, 5252.816151434681, 5134.435212126663, 5256.448072952323, 5272.0, 5299.993001329735, 5309.734513274359, 5118.232265160255, 5133.281340612317, 5161.845144645667, 5216.243644671539, 5239.16173412256, 5025.84922452327, 5516.7517461713915, 5635.5773317001385, 5663.8584035399035, 5671.8582035449035, 5009.223570346602, 6135.140800782589, 6295.213098362705, 6299.118123462673, 6311.779087731953, 6187.969060154715, 6262.436380725757, 6268.15379923709, 6276.372362763733, 6297.2128483939505, 5782.074868021138, 5814.825555233349, 5838.649681019152, 5857.7949771757785, 5875.618084824516, 6156.107364432132, 6226.0, 6235.719392627343, 6253.749850005949, 6259.71831267594, 6154.630722156685, 6180.567360284737, 6200.348963358872, 6204.534659900525, 6206.875862482725, 6203.534734894901, 6217.067439884054, 6218.813435596849, 6222.968885155636, 6272.560920735505, 6024.548158888101, 6116.0010150213275, 6116.2048933639235, 6123.710312515612, 6218.471429928416, 6154.261488621304, 6185.319734757812, 6215.471684906834, 6218.720157592919, 6263.404976527314, 4910.9932463844825, 4944.283078953532, 4948.480409556944, 4953.628477864175, 4972.825951091767, 4312.633426158812, 4371.0, 4429.202743506135, 4433.623142032899, 4434.490033646108, 5696.487316141567, 5804.88390232193, 5806.593538452268, 5820.330661973927, 5845.88308233833, 6218.81343559694, 6228.065790131426, 6232.189815323979, 6237.719302631393, 6242.469390101802, 4785.253382515426, 4826.7827947742435, 5798.188253644493, 5834.416558344216, 5894.823155305347, 5339.252504649352, 5390.649607775482, 5395.676259424447, 5418.945810541844, 5426.2674538936735, 6368.331325210786, 6370.299267080617, 6379.96810015947, 6385.744570217233, 6388.0, 6159.4456498915315, 6166.691665416622, 6195.2255968004, 6247.843803904939, 6259.467945224706, 6365.490760739222, 6369.267534233622, 6372.299047104722, 6386.265579458422, 6493.870122597427, 4657.883552911204, 6204.968975155276, 6237.625742455467, 6240.656763878031, 6244.65654389013, 6168.414000669929, 6198.132261483486, 6204.162438070984, 6218.657973811484, 6227.221597300338, 6184.845378865564, 6185.84535386619, 6200.906986395117, 6213.5339849512375, 6225.408586184305, 6053.334133245251, 6146.969265153645, 6184.165137706352, 6202.689865506797, 6203.658798766111, 5839.299284085767, 5929.110633405024, 5999.130126131774, 6041.6677082759115, 6064.666443345658, 6150.323464418821, 6187.195664563758, 6194.318624951342, 6197.721102550306, 6247.5939063960695, 5899.203607513103, 6016.398360164124, 6057.242844644419, 6069.4840938518955, 6071.848203794941], "value": 5654.001075066202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.0940915760788, 512.6078549909317, 525.2934802690382, 527.5489456514679, 530.6683322923172, 35595.466068008966, 37901.620983790155, 37908.67319643813, 37926.241475170515, 38065.809670951676, 41418.79290603543, 41446.58553414465, 41687.0, 41733.58266417335, 41787.0, 9424.151826335645, 9639.566219520104, 9672.081152290557, 9712.757181070494, 9723.076307750787, 9451.905480945206, 9542.141207291343, 9546.665866694668, 9587.23302135831, 9612.663556775515, 9025.458472491671, 9123.08769123089, 9522.85715714265, 9565.234781217487, 9634.98832622575, 9541.904580954171, 9610.471424071673, 9619.278554108434, 9627.374220675634, 9629.807403851953, 9514.28642851789, 9545.427274363561, 9595.328327017112, 9646.903530964672, 9658.468784216866, 38016.61983380186, 38218.80890595557, 38354.80822595869, 38384.80807595972, 38470.61529384698, 2790.9302267443272, 2802.5936239245398, 3130.1392117167734, 3253.089135042192, 3527.700145487611, 26656.60015099775, 26946.326341841414, 27185.86407067972, 27337.0, 27391.904323827024, 2721.115637417847, 2728.358835673615, 2760.6687197536226, 2772.487089888377, 2863.8138520996067, 291420.5428972863, 342010.28994854866, 344721.0, 346885.0, 347209.0, 59051.0, 59611.70194149001, 59743.0, 59831.0, 59855.70072149611, 62221.688891555255, 66231.66884165548, 68241.0, 68378.0, 68509.0, 16365.672686546202, 16448.095354755365, 16621.6675666486, 16676.416325428552, 16735.748963765553, 12448.253104813559, 12467.314297713532, 12664.683382915317, 12793.808092878615, 12867.613971580868, 16285.348586056425, 19562.70655940162, 19599.706004409945, 19807.504812379517, 19931.402057938285, 7001.544899581511, 8182.918170818335, 8322.750317490485, 8362.12197719243, 8365.163483651708, 942.6512190489562, 960.8846938367301, 1030.9329893556894, 1034.8913364096811, 1079.6491140379408, 28319.71680283212, 30001.849990750343, 30067.849660751555, 30484.695153048186, 30652.84673576618, 1078.962236321725, 1124.8818874018275, 1166.1428849795393, 1191.6305945157028, 1223.6023292429995, 15459.690806183813, 15583.06501609907, 15628.374865005273, 15700.0, 15782.842171578366, 619353.0, 623014.0, 628896.0, 629005.0, 629349.7065029291, 47811.521884780705, 55060.0, 55928.72035639796, 60355.69822150949, 60680.69659651673, 1454.178389210093, 1472.3668822406469, 1473.5358362115899, 1489.0321291160833, 1496.6632507685902, 25253.368665783502, 27138.050168244015, 29180.27049323784, 29340.413191735617, 30115.698843012167, 51887.70280743012, 52355.738221310174, 52874.471255288496, 52908.47091529035, 53393.733031334596, 542538.0, 543038.0, 547632.2618386883, 549506.0, 549521.0191592452, 26632.60051099274, 26991.325216869736, 41369.758907232834, 42808.0, 43683.78158109189, 44778.77610611926, 44864.55135448735, 48291.79270518265, 49652.00695985988, 50186.0, 37598.43602345912, 37694.86915392543, 37846.43230351492, 38552.80723596476, 38685.0, 33237.667623323454, 34252.48621270633, 37419.62580374161, 37925.431118533794, 38100.237995240495, 31938.36123277568, 36160.63839361678, 37238.8138059308, 37933.24133517369, 38803.417948730224, 27833.443331132858, 31840.68159318377, 32246.677533224367, 32612.858549950637, 32629.0, 21566.245181418573, 23350.416239593465, 24599.262022139363, 25150.496990060463, 25292.620610690487], "value": 19658.466433601523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.1698159337781, 398.92221016901715, 401.23364374045576, 408.17955908623674, 421.886090755496, 16278.26747796349, 19480.707789383134, 19607.215711371548, 20436.0, 20453.693194602074, 19936.40190794275, 20054.298099566517, 20278.391648250577, 20462.386128416136, 20599.897000515015, 5525.4474552544825, 6073.423024812636, 6104.328523862371, 6108.572399931984, 6114.0, 5306.416294207634, 5906.114082887559, 5912.733926973297, 5979.581429299929, 6067.059605761114, 4532.5240849710635, 4814.9759251203695, 5477.014137455257, 5661.094224924013, 5663.2637757091525, 5285.2600635911, 5670.3479099903625, 5730.7707691692285, 5739.0, 5928.288605367384, 5521.4478552144865, 5554.19464177696, 5684.351538053959, 5684.744186511575, 5714.942850571482, 12856.614301570968, 13142.474301027947, 13273.269970151638, 13316.600501984956, 13371.26458044807, 1815.7821061472707, 2065.5042789730505, 2106.2101711858054, 2152.881591512466, 2466.4450498637843, 9570.425774453555, 9713.417194968324, 9755.951220243924, 9812.116909478182, 9955.850662240073, 1700.3538655310904, 1752.5443384720065, 1775.4496106207116, 1785.526835388623, 1825.863060270485, 106641.0, 112031.43984280108, 112501.0, 113460.4326978368, 113587.2961905572, 11274.436278186222, 11381.772364552662, 11475.77048459026, 11481.770364592661, 11543.36511491876, 13558.389872455764, 13757.449702011807, 14476.855231447551, 14849.406023759144, 14857.70284594302, 7709.036370453693, 8390.412671112963, 8479.70321038773, 8536.317094632415, 8568.657253709907, 7824.452288339761, 8441.451305665112, 8450.323974082181, 8508.914910850935, 8556.486610803247, 4483.641308695296, 4568.360429539867, 4593.3339665748945, 4637.373954516164, 4663.300504924288, 1938.4766113149371, 1985.622731680976, 1994.8204661580528, 2018.8082132197417, 2019.9293024744063, 928.8838895138108, 949.1789601994225, 965.1506674126723, 967.7241986034087, 977.5014742481386, 15467.458638947808, 16688.91655541739, 19163.137658805386, 19739.50651233702, 19797.30709425163, 755.0637209859734, 828.3083625172927, 970.854371844229, 987.2102318145455, 1007.7833265847878, 10056.798864022825, 10079.24405669563, 10130.341527800816, 10208.795824083476, 10209.74475638115, 471967.0, 561309.0, 571409.0, 571410.0, 573232.0, 112635.31047034301, 115185.0, 117550.41224793992, 120112.0, 122983.0, 1000.9340052843698, 1010.2271762101998, 1012.0790081026231, 1021.3718563083798, 1022.2741853284103, 17329.0, 17405.12974351252, 17452.82547174512, 17542.561435963693, 17750.378736744668, 33725.83137084299, 39205.41191882067, 47227.0, 49389.50610493849, 49886.26307974629, 59443.0, 62923.37076629175, 64293.0356044669, 65004.0, 65280.36799080061, 6729.0, 7688.615569221628, 7934.920650793418, 7939.364850811806, 7963.362930965393, 7230.794607644509, 10799.56801727922, 11272.887271127183, 12109.455074521491, 12181.451834667285, 5487.533559647474, 7876.921230787619, 10615.203859710708, 10764.40795756241, 10791.838122428013, 10389.324693894872, 10519.474026298807, 10531.57873685044, 10660.46697665129, 10679.09227715653, 10509.0, 10560.102391296827, 10906.890931090587, 11001.944990274997, 11206.10351171888, 7875.606219689107, 7963.203679631991, 7966.0, 8062.39532035088, 8170.918290817016, 8208.015038195455, 10409.895901040893, 10613.310134841211, 10714.73213169677, 10930.781384372109], "value": 8952.047747783174, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1587.801524809399, 1638.9838300253843, 1785.3394244129665, 1814.2198854492563, 1859.92560297588, 7116.0, 8078.838423231547, 8632.39573229874, 8939.061398553147, 8961.0, 8596.441231319975, 8601.096884827097, 8678.47929124253, 8692.652293908237, 8819.603117859695, 2403.591389463791, 2419.4556224849443, 2447.779699827015, 2451.1788550835404, 2457.6190690442922, 2421.842580232288, 2429.4290841652196, 2431.0397393029743, 2437.390652336916, 2465.050955382173, 2451.742567030463, 2470.530599186158, 2472.0482614193575, 2474.8638824864624, 2495.7129930058004, 2422.2369953464604, 2429.7448767879473, 2429.8056155507516, 2438.317271164068, 2445.8165637577254, 2007.2874129684044, 2390.4621460171325, 2395.077895010416, 2397.1490121006964, 2405.410674384769, 7731.768046958544, 8628.266597339234, 8828.779280518038, 8838.0, 8877.334199934967, 5206.427292997767, 5350.839474815762, 5372.838814835562, 5424.484673955968, 5461.890762184734, 2904.7530959868227, 3519.560054993126, 3557.2529768748336, 3606.711463082947, 3625.6374362563533, 810.6392655268407, 921.9124183202586, 1040.7459011890653, 1046.3042077018774, 1051.621416290135, 11523.19337646365, 11633.767324653545, 11656.825147622878, 11738.765224695459, 11771.352575608338, 4762.261849413347, 4806.639502037361, 4870.9269360959615, 4934.950650493521, 4956.8760780980765, 8599.5700214989, 9245.583948722324, 9451.76370590727, 9907.207423406111, 10092.0, 4638.930416043762, 5315.936812637488, 5386.730663466811, 5429.592780541475, 5453.509184173444, 3858.6527212551005, 4752.429708434941, 4786.521347865255, 4907.1903135982875, 4915.508449155056, 4064.7357921734992, 4080.163566468867, 4080.404488839561, 4087.5299340576216, 4097.0, 1143.2911594811271, 1369.3906211735682, 1370.3079944628098, 1376.8623137686352, 1389.916605003683, 830.66773290683, 903.9367244292837, 920.1994264989369, 935.1350001248953, 949.6723630347519, 5622.409646987007, 5899.292084949833, 5961.850953726105, 5992.460678538952, 6040.577159598786, 446.1523106098439, 451.88928712465656, 454.3979227523478, 455.8518481493528, 457.7779776808245, 3870.6709929656295, 3878.049877779962, 3933.645971862546, 3968.464257325282, 3971.3447281198855, 10449.529771160316, 10753.623623173151, 10925.344479331265, 10952.233343665866, 10961.671149865517, 10686.35881847076, 10749.677509674722, 10766.515506802212, 10768.838467422995, 10786.191035672198, 6258.812235632939, 8201.974753155855, 8236.711715090063, 8257.504549727035, 8277.751667449986, 4768.618510519081, 5733.9965506037115, 5828.009238429392, 5905.055191169392, 5909.556783241188, 9768.755781105245, 9776.871401151564, 9824.852627210455, 9990.250731195045, 10016.549255283675, 9814.822221333485, 10104.84842727345, 10125.139363154214, 10220.284580079324, 10254.436006019741, 6939.16729992384, 7327.340539351484, 7588.27911348421, 7589.6584653689615, 7649.617519124133, 8279.875801863098, 8366.205210504992, 8416.705415310435, 8454.02778680435, 8469.941257342833, 7422.14645315774, 7559.508631938907, 7585.582792946441, 7617.619119044137, 7655.311022008046, 7325.230850760807, 7370.77887663371, 7427.7771666850085, 7466.066741657293, 7523.210062943313, 7138.214796372498, 7157.425366419379, 7176.102987126609, 7199.6760145794615, 7199.928000719926, 4724.1732696777235, 4814.109389762905, 4853.587445067209, 4876.390451193601, 5014.24786282046, 4883.340748998839, 4898.902021959612, 4974.12952733263, 5019.472955339783, 5159.535641792257], "value": 4504.703760515551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.2569283085852, 318.78322740536436, 325.98207098609583, 326.6521154969955, 327.78038714061574, 5861.384554621767, 6095.116208149816, 6191.659458729774, 6204.379562043793, 6219.875602487948, 6157.661328626924, 6163.428325776932, 6169.876602467948, 6188.884391879395, 6193.628382297065, 3848.8075596220215, 4600.125976064537, 4622.976885115578, 4628.26932110055, 4629.444466664005, 3414.8804791832167, 4009.8797036088963, 4013.8595149169787, 4408.2807704995075, 4653.581177694007, 3589.85640574378, 4065.6747460203114, 4474.932876006863, 4515.4129963104915, 4532.0, 4388.473383194005, 4546.1589605922845, 4547.408836851221, 4555.24838401665, 4570.491737726542, 3210.486322188462, 3595.227026189382, 3860.768353898775, 3883.86406475772, 3900.2004589059175, 5602.60781745278, 5902.852428689317, 5913.733881975321, 5927.229460170193, 5956.315023772278, 5244.39689435716, 5265.394479634852, 5267.86740850719, 5281.683099014033, 5287.629865909388, 4918.458969513351, 5705.857353566153, 6208.565400421972, 6256.092866534373, 6538.509611779088, 3877.6122387760997, 3973.205358928197, 4238.4490016298, 4278.935815962763, 4291.871243862689, 6267.2165979252595, 6670.966645166792, 6723.93364928908, 6865.009147347263, 6905.516613837033, 4227.556106608823, 4324.978375108167, 4338.327559228324, 4339.7396156230725, 4350.521442641307, 3522.982385088058, 3745.325841348529, 4406.228909940747, 4414.492333381638, 4426.73439593619, 5195.376554813447, 5198.116320225495, 5200.5059519345605, 5213.504717051874, 5216.947830521722, 6367.840803979845, 6485.319041500575, 6561.3438656134, 6578.76974305897, 6584.50616203777, 1497.3486533358025, 1512.3496896334682, 1515.6514001779653, 1525.4203402707121, 1525.557588299403, 907.3904564810746, 980.5146452506056, 987.6888780034266, 993.9850902236473, 998.4159266828987, 5529.585281103933, 6103.267607887082, 6125.509959203253, 6138.1099740537375, 6164.4143806338325, 5731.455511726463, 5813.186153938452, 6241.313455519889, 6786.0, 6832.627118644072, 5850.572012614717, 6124.785632502841, 6177.135201071854, 6219.782307619212, 6274.341194174637, 3576.445650924111, 3593.982030089885, 3613.2412193439145, 3642.2351306225987, 3643.0145570664054, 5718.68547229898, 6305.833420817164, 6497.772577959749, 6720.697568609425, 7206.135263768381, 6689.862723337044, 6983.511154219257, 7016.508844380946, 7064.646767661597, 7169.5698258104685, 6535.803925882231, 6788.0, 6883.965580172067, 6959.512834101665, 7128.429725622042, 5742.16738572913, 5851.946649603113, 5872.970635146797, 6776.254611992776, 6813.525102198014, 5547.7780888764, 6463.256725476443, 6630.3038180992235, 6655.800325990228, 6727.865442691216, 5898.4101589840675, 6902.654867256718, 7084.64576771149, 7141.535800172972, 7158.355747982707, 4288.678349123766, 4309.892252693708, 4319.8488052918, 4329.090890912944, 4336.371226172124, 6538.496145886568, 6795.490338224752, 6818.693158807965, 6822.11312529368, 6855.828604284933, 5417.918731219113, 6567.343265673395, 6603.966980165068, 6612.206535215805, 6618.7352505899225, 6328.905066423915, 6554.901676474952, 6579.440747536513, 6608.306127856506, 6642.867142657216, 6224.408681175281, 6382.170317858649, 6506.674666266573, 6511.38141876521, 6575.868482630416, 4948.911239527299, 6200.844978875564, 6544.9672751635935, 6607.579370435379, 6620.470362371095, 5973.820785376446, 5980.102984552352, 6438.4527315176865, 6448.709808058742, 6469.805905822833], "value": 4692.15776446486, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.2200092889926, 668.3583759590792, 672.2302963107244, 681.4003676764447, 727.4834867244256, 14208.579142085811, 14559.0, 14954.47659331925, 14986.550403487885, 15025.0, 12288.262704237752, 13054.673633159176, 15820.446284380048, 16011.519654410356, 16174.75737863933, 704.278114932196, 735.1802739944976, 890.3856339125982, 896.4173287363201, 898.5148020069155, 865.0830120072711, 873.6461732998146, 893.0533634347612, 894.8926128864546, 896.995515022424, 921.5392303848076, 929.930255230857, 933.9953300233524, 935.723961431376, 936.5925822267302, 895.3329769321897, 923.3997901364099, 924.9445033298024, 925.0749250749251, 934.9158575728183, 671.0370618162902, 674.0495900779877, 674.2482132422363, 696.7979286007052, 697.3967518096861, 15296.694066118615, 15527.068375897483, 15622.218889055503, 15690.372385104582, 15693.0, 12665.0, 12708.364581770968, 12746.171498852638, 12761.170523915916, 12957.676058098528, 10125.544350504246, 10181.33821301613, 10413.427261500614, 10439.259502683732, 10640.148788096934, 3467.098554375855, 3486.703630191437, 3513.1744040150543, 3513.4027215373194, 3588.102974256436, 67756.98364524536, 77416.22583774147, 84243.0, 85150.72273915895, 85460.57269713611, 9299.628014879328, 9356.578953946953, 9376.8593471098, 9413.576389062371, 9519.143277105093, 19656.11547480338, 19693.901530492254, 19767.011649417473, 20277.69583456249, 20370.28703995353, 7566.1298950620285, 7600.125985511626, 7635.656395462218, 7642.5032372895585, 7962.800929976681, 3271.3457308538386, 3512.613612502623, 3589.964100359015, 3964.266610677034, 4314.892127696833, 3552.769070010441, 3586.1572530455323, 3598.982005089958, 3687.575928768226, 3697.722670799647, 534.6070638081007, 647.2847503508649, 650.06066234291, 661.9271880093186, 662.9138212032501, 543.6004536665548, 572.3086094689271, 578.8494991302208, 587.3832475900288, 603.5594016368076, 6911.550749201387, 7201.171865235565, 7594.544327340267, 7723.691052357843, 8124.146964568753, 430.7932192547595, 500.66705640748495, 582.9533637309005, 589.7344713134231, 604.7218279591349, 3489.4765785131926, 3545.468179773003, 3757.323681737259, 3799.9430008549894, 3830.71269654777, 76883.61558192174, 77114.45771084547, 77283.0, 77712.22287777162, 77782.2221777775, 60561.69719151376, 67090.99363509551, 69839.3016069843, 70321.59356812834, 70412.2958770416, 54335.45664543383, 57764.0, 63377.68311158415, 63803.0, 64150.0, 7457.888131677921, 7466.813329666802, 7475.962620186864, 7532.698692052257, 7560.7955792286975, 41325.38011929884, 42070.57929420667, 42493.787531062146, 42916.78541607272, 43581.564184357754, 45751.0, 50962.490375095775, 53910.46089539054, 59511.809763805344, 59520.51198720067, 9492.477913714536, 9603.855942160733, 9673.613055477703, 9808.509574521102, 10053.597856085675, 10435.426051567236, 10481.0, 10520.947395262974, 10549.683509494727, 10576.947115264375, 8469.5341756204, 9898.10917017472, 10106.848397274194, 10432.321899076536, 10507.369557826554, 10179.694609161737, 10219.386836789816, 10465.319754215952, 10479.161667066464, 10495.73760655959, 9937.304388692723, 10059.798804023732, 10060.647877324258, 10124.69625911224, 10231.0, 7356.0, 8846.336524760798, 9023.774405639911, 9157.0, 9323.067693230623, 8278.958605206935, 8649.481031138152, 8717.52053637056, 8775.597566924336, 8926.419782714103], "value": 6486.85287376701, "unit": "score"}], "name": "dromaeo_css", "value": 7175.026216620177, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 108.0, 117.0, 116.0, 116.0, 127.0, 116.0, 115.0, 119.0, 119.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 145.0, 163.0, 122.0, 142.0, 142.0, 142.0, 215.0, 147.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 214.0, 205.0, 194.0, 197.0, 197.0, 196.0, 201.0, 196.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 77.0, 143.0, 57.0, 54.0, 64.0, 64.0, 75.0, 59.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 135.0, 65.0, 79.0, 111.0, 102.0, 115.0, 67.0, 134.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 133.0, 96.0, 87.0, 92.0, 85.0, 113.0, 90.0, 138.0, 77.0], "value": 102.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 154.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 96.0, 92.0, 93.0, 95.0, 150.0, 145.0, 90.0, 93.0], "value": 104.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 79.0, 63.0, 60.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 71.0, 66.0, 70.0, 76.0, 73.0, 66.0, 65.0, 60.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 89.0, 88.0, 86.0, 85.0, 87.0, 94.0, 108.0, 113.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 165.0, 164.0, 161.0, 156.0, 141.0, 146.0, 219.0, 168.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 171.0, 231.0, 209.0, 173.0, 168.0, 171.0, 169.0, 173.0, 172.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 69.0, 82.0, 95.0, 99.0, 71.0, 71.0, 67.0, 67.0], "value": 76.3, "unit": "score"}], "name": "kraken", "value": 1587.5000000000002, "alertThreshold": 2.0}]} 05:40:39 INFO - Return code: 0 05:40:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:40:39 ERROR - # TBPL SUCCESS # 05:40:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:40:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:40:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:40:39 INFO - 2016-04-14 05:40:39.202 screenresolution[1682:10698] starting screenresolution argv=screenresolution get 05:40:39 INFO - 2016-04-14 05:40:39.206 screenresolution[1682:10698] Display 0: 1600x1200x32@60 05:40:39 INFO - 2016-04-14 05:40:39.212 screenresolution[1683:10701] starting screenresolution argv=screenresolution list 05:40:39 INFO - Available Modes on Display 0 05:40:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:40:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:40:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:40:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:40:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:40:39 INFO - Intel Iris: 05:40:39 INFO - Chipset Model: Intel Iris 05:40:39 INFO - Type: GPU 05:40:39 INFO - Bus: Built-In 05:40:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:40:39 INFO - Vendor: Intel (0x8086) 05:40:39 INFO - Device ID: 0x0a2e 05:40:39 INFO - Revision ID: 0x0009 05:40:39 INFO - Displays: 05:40:39 INFO - Display: 05:40:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:40:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:40:39 INFO - Display Serial Number: 1600x1200 60 05:40:39 INFO - Main Display: Yes 05:40:39 INFO - Mirror: Off 05:40:39 INFO - Online: Yes 05:40:39 INFO - Rotation: Supported 05:40:39 INFO - Return code: 0 05:40:39 INFO - Running post-action listener: _resource_record_post_action 05:40:39 INFO - Running post-run listener: _resource_record_post_run 05:40:40 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 33000960; Write bytes: 695364608; Read time: 332; Write time: 2043 05:40:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 392067584; Write bytes: 387933696; Read time: 30078; Write time: 786 05:40:40 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30383616; Write bytes: 296211968; Read time: 306; Write time: 1203 05:40:40 INFO - Running post-run listener: _upload_blobber_files 05:40:40 INFO - Blob upload gear active. 05:40:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:40 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:40:40 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:40:40 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:40:40 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:40:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:41 INFO - (blobuploader) - INFO - Done attempting. 05:40:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:42 INFO - (blobuploader) - INFO - Done attempting. 05:40:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:42 INFO - Return code: 0 05:40:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08ec1a08ba47ab56fed333ba0da0e6a4fa6e5c4261fe6bbb5d907e0d59405ba97784cffe622e7c707e1c60bf9eff73a87ece1a2abb61ef644322ba1685608891", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c22a1d41bd710e8d8f3c258e4b39c1e094fc66f3cb1f9b7917f7f6318d0ab6c744a8cd2bad02fb874e4386a8cf9798752aaed3e5c9c9bcb0886f06a356d85"} 05:40:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:42 INFO - Contents: 05:40:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08ec1a08ba47ab56fed333ba0da0e6a4fa6e5c4261fe6bbb5d907e0d59405ba97784cffe622e7c707e1c60bf9eff73a87ece1a2abb61ef644322ba1685608891", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c22a1d41bd710e8d8f3c258e4b39c1e094fc66f3cb1f9b7917f7f6318d0ab6c744a8cd2bad02fb874e4386a8cf9798752aaed3e5c9c9bcb0886f06a356d85"} 05:40:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:42 INFO - Copying logs to upload dir... 05:40:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.702731 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 05:40:42.970362) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:40:42.974722) ========= 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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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/08ec1a08ba47ab56fed333ba0da0e6a4fa6e5c4261fe6bbb5d907e0d59405ba97784cffe622e7c707e1c60bf9eff73a87ece1a2abb61ef644322ba1685608891", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c22a1d41bd710e8d8f3c258e4b39c1e094fc66f3cb1f9b7917f7f6318d0ab6c744a8cd2bad02fb874e4386a8cf9798752aaed3e5c9c9bcb0886f06a356d85"} 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.011157 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/08ec1a08ba47ab56fed333ba0da0e6a4fa6e5c4261fe6bbb5d907e0d59405ba97784cffe622e7c707e1c60bf9eff73a87ece1a2abb61ef644322ba1685608891", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c22a1d41bd710e8d8f3c258e4b39c1e094fc66f3cb1f9b7917f7f6318d0ab6c744a8cd2bad02fb874e4386a8cf9798752aaed3e5c9c9bcb0886f06a356d85"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:40:43.006747) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:40:43.007226) ========= 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.CLdV7l3FBg/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.11pJExnKKj/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005021 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:40:43.055811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:40:43.056781) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:40:43.057185) ========= ========= Total master_lag: 6.10 =========