builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0059 starttime: 1460871733.81 results: success (0) buildid: 20160416210219 builduid: ce7806e457d84634b0dbd07c2fd7f25a revision: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:13.815060) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:13.815445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:13.815739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:13.842461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:13.842745) ========= 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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-16 22:42:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2016-04-16 22:42:14 (20.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.476606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:14.340084) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:14.340881) ========= 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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.020392 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:14.394231) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 22:42:14.394586) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 --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 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-16 22:42:14,467 truncating revision to first 12 chars 2016-04-16 22:42:14,468 Setting DEBUG logging. 2016-04-16 22:42:14,468 attempt 1/10 2016-04-16 22:42:14,468 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2f58e7ce1ab8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-16 22:42:15,486 unpacking tar archive at: fx-team-2f58e7ce1ab8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.271218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 22:42:15.683021) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:15.683391) ========= script_repo_revision: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:15.684011) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:15.684326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 22:42:15.709586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-04-16 22:42:15.709957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:42:15 INFO - MultiFileLogger online at 20160416 22:42:15 in /builds/slave/test 22:42:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:42:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:42:15 INFO - {'append_to_log': False, 22:42:15 INFO - 'base_work_dir': '/builds/slave/test', 22:42:15 INFO - 'blob_upload_branch': 'Fx-Team', 22:42:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:42:15 INFO - 'branch': 'Fx-Team', 22:42:15 INFO - 'buildbot_json_path': 'buildprops.json', 22:42:15 INFO - 'config_files': ('talos/mac_config.py',), 22:42:15 INFO - 'default_actions': ('clobber', 22:42:15 INFO - 'read-buildbot-config', 22:42:15 INFO - 'download-and-extract', 22:42:15 INFO - 'populate-webroot', 22:42:15 INFO - 'create-virtualenv', 22:42:15 INFO - 'install', 22:42:15 INFO - 'run-tests'), 22:42:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:42:15 INFO - 'download_minidump_stackwalk': True, 22:42:15 INFO - 'download_symbols': 'ondemand', 22:42:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:42:15 INFO - 'tooltool.py': '/tools/tooltool.py', 22:42:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:42:15 INFO - '/tools/misc-python/virtualenv.py')}, 22:42:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:42:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:42:15 INFO - 'installer_path': 'installer.exe', 22:42:15 INFO - 'log_level': 'info', 22:42:15 INFO - 'log_name': 'talos', 22:42:15 INFO - 'log_to_console': True, 22:42:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:42:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:42:15 INFO - 'opt_config_files': (), 22:42:15 INFO - 'pip_index': False, 22:42:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:42:15 INFO - 'cmd': ('bash', 22:42:15 INFO - '-c', 22:42:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:42:15 INFO - 'enabled': True, 22:42:15 INFO - 'halt_on_failure': False, 22:42:15 INFO - 'name': 'check_screen_resolution'},), 22:42:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:42:15 INFO - 'cmd': ('bash', 22:42:15 INFO - '-c', 22:42:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:42:15 INFO - 'enabled': True, 22:42:15 INFO - 'halt_on_failure': False, 22:42:15 INFO - 'name': 'check_screen_resolution'},), 22:42:15 INFO - 'run_cmd_checks_enabled': True, 22:42:15 INFO - 'sps_profile': False, 22:42:15 INFO - 'sps_profile_interval': 0, 22:42:15 INFO - 'suite': 'dromaeojs', 22:42:15 INFO - 'system_bits': '32', 22:42:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:42:15 INFO - 'title': 't-yosemite-r7-0059', 22:42:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:42:15 INFO - 'use_talos_json': True, 22:42:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:42:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:42:15 INFO - 'work_dir': 'build'} 22:42:15 INFO - ##### 22:42:15 INFO - ##### Running clobber step. 22:42:15 INFO - ##### 22:42:15 INFO - Running pre-action listener: _resource_record_pre_action 22:42:15 INFO - Running main action method: clobber 22:42:15 INFO - rmtree: /builds/slave/test/build 22:42:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:42:17 INFO - Running post-action listener: _resource_record_post_action 22:42:17 INFO - ##### 22:42:17 INFO - ##### Running read-buildbot-config step. 22:42:17 INFO - ##### 22:42:17 INFO - Running pre-action listener: _resource_record_pre_action 22:42:17 INFO - Running main action method: read_buildbot_config 22:42:17 INFO - Using buildbot properties: 22:42:17 INFO - { 22:42:17 INFO - "project": "", 22:42:17 INFO - "product": "firefox", 22:42:17 INFO - "script_repo_revision": "production", 22:42:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:42:17 INFO - "repository": "", 22:42:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:42:17 INFO - "buildid": "20160416210219", 22:42:17 INFO - "pgo_build": "False", 22:42:17 INFO - "basedir": "/builds/slave/test", 22:42:17 INFO - "buildnumber": 627, 22:42:17 INFO - "slavename": "t-yosemite-r7-0059", 22:42:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:42:17 INFO - "platform": "yosemite_r7", 22:42:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:42:17 INFO - "branch": "fx-team", 22:42:17 INFO - "repo_path": "integration/fx-team", 22:42:17 INFO - "revision": "2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799", 22:42:17 INFO - "stage_platform": "macosx64", 22:42:17 INFO - "builduid": "ce7806e457d84634b0dbd07c2fd7f25a", 22:42:17 INFO - "slavebuilddir": "test" 22:42:17 INFO - } 22:42:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:42:17 INFO - Running post-action listener: _resource_record_post_action 22:42:17 INFO - ##### 22:42:17 INFO - ##### Running download-and-extract step. 22:42:17 INFO - ##### 22:42:17 INFO - Running pre-action listener: _resource_record_pre_action 22:42:17 INFO - Running main action method: download_and_extract 22:42:17 INFO - mkdir: /builds/slave/test/build/tests 22:42: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')]} 22:42:17 INFO - https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:42:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:42:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:42:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:40 INFO - Downloaded 1199 bytes. 22:42:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:42:40 INFO - Using the following test package requirements: 22:42:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:42:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:42:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:42:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'jsshell-mac.zip'], 22:42:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:42:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:42:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:42:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:42:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:42:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:42:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:42:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:42:40 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')]} 22:42:40 INFO - https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:42:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:42:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:42:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:44 INFO - Downloaded 18031996 bytes. 22:42:44 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'] 22:42:44 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 22:42:47 INFO - Return code: 0 22:42:47 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 22:42:47 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')]} 22:42:47 INFO - https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:42:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:42:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:42:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:50 INFO - Downloaded 18031996 bytes. 22:42:50 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'] 22:42:50 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 22:42:53 INFO - Return code: 0 22:42:53 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')]} 22:42:53 INFO - https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:42:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:42:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:42:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:42:54 INFO - Downloaded 10841076 bytes. 22:42:54 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'] 22:42:54 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 22:42:55 INFO - Return code: 0 22:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:42:55 INFO - https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:42:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:42:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:42:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:42:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AyGxp-KyTGyMR0KIb5wudg/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 22:43:01 INFO - Downloaded 109839867 bytes. 22:43:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:01 INFO - mkdir: /builds/slave/test/properties 22:43:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:43:01 INFO - Writing to file /builds/slave/test/properties/build_url 22:43:01 INFO - Contents: 22:43:01 INFO - build_url:https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:43:02 INFO - Running post-action listener: _resource_record_post_action 22:43:02 INFO - Running post-action listener: set_extra_try_arguments 22:43:02 INFO - ##### 22:43:02 INFO - ##### Running populate-webroot step. 22:43:02 INFO - ##### 22:43:02 INFO - Running pre-action listener: _resource_record_pre_action 22:43:02 INFO - Running main action method: populate_webroot 22:43:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:43:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:43:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:43:02 INFO - '--tppagecycles', 22:43:02 INFO - '7'], 22:43:02 INFO - 'tests': ['tsvgm']}}, 22:43:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:43:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:43:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:43:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:43:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['damp', 'tps']}, 22:43:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'tests': ['damp', 'tps']}, 22:43:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['dromaeo_dom']}, 22:43:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:43:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'other-e10s': {'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['a11yr', 22:43:02 INFO - 'ts_paint', 22:43:02 INFO - 'tpaint', 22:43:02 INFO - 'sessionrestore', 22:43:02 INFO - 'sessionrestore_no_auto_restore', 22:43:02 INFO - 'tabpaint']}, 22:43:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['tsvgx', 22:43:02 INFO - 'tsvgr_opacity', 22:43:02 INFO - 'tart', 22:43:02 INFO - 'tscrollx', 22:43:02 INFO - 'cart']}, 22:43:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:43:02 INFO - 'tsvgr_opacity', 22:43:02 INFO - 'tart', 22:43:02 INFO - 'tscrollx', 22:43:02 INFO - 'cart']}, 22:43:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'talos_options': ['--disable-e10s'], 22:43:02 INFO - 'tests': ['tp5o']}, 22:43:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'tests': ['tp5o']}, 22:43:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'talos_options': ['--disable-e10s', 22:43:02 INFO - '--xperf_path', 22:43:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:02 INFO - 'tests': ['tp5n']}, 22:43:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:02 INFO - 'talos_options': ['--xperf_path', 22:43:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:02 INFO - 'tests': ['tp5n']}}, 22:43:02 INFO - 'talos.zip': {'path': '', 22:43:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:43:02 INFO - Running post-action listener: _resource_record_post_action 22:43:02 INFO - ##### 22:43:02 INFO - ##### Running create-virtualenv step. 22:43:02 INFO - ##### 22:43:02 INFO - Running pre-action listener: _resource_record_pre_action 22:43:02 INFO - Running main action method: create_virtualenv 22:43:02 INFO - Creating virtualenv /builds/slave/test/build/venv 22:43:02 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 22:43:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:43:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:43:02 INFO - Using real prefix '/tools/python27' 22:43:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:43:03 INFO - Installing distribute.............................................................................................................................................................................................done. 22:43:06 INFO - Installing pip.................done. 22:43:06 INFO - Return code: 0 22:43:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:43:06 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')]} 22:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:06 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')]} 22:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:06 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:06 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 22:43:06 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 22:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:06 INFO - 'HOME': '/Users/cltbld', 22:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:06 INFO - 'LOGNAME': 'cltbld', 22:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:06 INFO - 'MOZ_NO_REMOTE': '1', 22:43:06 INFO - 'NO_EM_RESTART': '1', 22:43:06 INFO - 'PAGER': '/bin/cat', 22:43:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:06 INFO - 'PWD': '/builds/slave/test', 22:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:06 INFO - 'SHELL': '/bin/bash', 22:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:06 INFO - 'USER': 'cltbld', 22:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:06 INFO - 'XPC_FLAGS': '0x0', 22:43:06 INFO - 'XPC_SERVICE_NAME': '0', 22:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:06 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 22:43:06 INFO - Cleaning up... 22:43:06 INFO - Return code: 0 22:43:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:43:06 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')]} 22:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:06 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')]} 22:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:06 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:06 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 22:43:06 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 22:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:06 INFO - 'HOME': '/Users/cltbld', 22:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:06 INFO - 'LOGNAME': 'cltbld', 22:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:06 INFO - 'MOZ_NO_REMOTE': '1', 22:43:06 INFO - 'NO_EM_RESTART': '1', 22:43:06 INFO - 'PAGER': '/bin/cat', 22:43:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:06 INFO - 'PWD': '/builds/slave/test', 22:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:06 INFO - 'SHELL': '/bin/bash', 22:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:06 INFO - 'USER': 'cltbld', 22:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:06 INFO - 'XPC_FLAGS': '0x0', 22:43:06 INFO - 'XPC_SERVICE_NAME': '0', 22:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:06 INFO - Downloading/unpacking psutil>=0.7.1 22:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:43:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:10 INFO - Installing collected packages: psutil 22:43:10 INFO - Running setup.py install for psutil 22:43:10 INFO - building 'psutil._psutil_osx' extension 22:43:10 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 22:43:10 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 22:43:10 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 22:43:10 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 22:43:10 INFO - building 'psutil._psutil_posix' extension 22:43:10 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 22:43:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:43:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:43:10 INFO - ^ 22:43:10 INFO - 1 warning generated. 22:43:10 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 22:43:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:10 INFO - Successfully installed psutil 22:43:10 INFO - Cleaning up... 22:43:11 INFO - Return code: 0 22:43:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:43:11 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')]} 22:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:11 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')]} 22:43:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:11 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:11 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 22:43:11 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 22:43:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:11 INFO - 'HOME': '/Users/cltbld', 22:43:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:11 INFO - 'LOGNAME': 'cltbld', 22:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:11 INFO - 'MOZ_NO_REMOTE': '1', 22:43:11 INFO - 'NO_EM_RESTART': '1', 22:43:11 INFO - 'PAGER': '/bin/cat', 22:43:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:11 INFO - 'PWD': '/builds/slave/test', 22:43:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:11 INFO - 'SHELL': '/bin/bash', 22:43:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:11 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:11 INFO - 'USER': 'cltbld', 22:43:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:11 INFO - 'XPC_FLAGS': '0x0', 22:43:11 INFO - 'XPC_SERVICE_NAME': '0', 22:43:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:43:13 INFO - Installing collected packages: mozsystemmonitor 22:43:13 INFO - Running setup.py install for mozsystemmonitor 22:43:13 INFO - Successfully installed mozsystemmonitor 22:43:13 INFO - Cleaning up... 22:43:14 INFO - Return code: 0 22:43:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:43:14 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')]} 22:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:14 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')]} 22:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:14 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:14 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 22:43:14 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 22:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:14 INFO - 'HOME': '/Users/cltbld', 22:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:14 INFO - 'LOGNAME': 'cltbld', 22:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:14 INFO - 'MOZ_NO_REMOTE': '1', 22:43:14 INFO - 'NO_EM_RESTART': '1', 22:43:14 INFO - 'PAGER': '/bin/cat', 22:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:14 INFO - 'PWD': '/builds/slave/test', 22:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:14 INFO - 'SHELL': '/bin/bash', 22:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:14 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:14 INFO - 'USER': 'cltbld', 22:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:14 INFO - 'XPC_FLAGS': '0x0', 22:43:14 INFO - 'XPC_SERVICE_NAME': '0', 22:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:14 INFO - Downloading/unpacking blobuploader==1.2.4 22:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:43:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:43:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 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 22:43:17 INFO - Downloading docopt-0.6.1.tar.gz 22:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:43:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:43:17 INFO - Running setup.py install for blobuploader 22:43:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:43:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:43:18 INFO - Running setup.py install for requests 22:43:18 INFO - Running setup.py install for docopt 22:43:18 INFO - Successfully installed blobuploader requests docopt 22:43:18 INFO - Cleaning up... 22:43:18 INFO - Return code: 0 22:43:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:19 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:19 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 22:43:19 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 22:43:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:19 INFO - 'HOME': '/Users/cltbld', 22:43:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:19 INFO - 'LOGNAME': 'cltbld', 22:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:19 INFO - 'MOZ_NO_REMOTE': '1', 22:43:19 INFO - 'NO_EM_RESTART': '1', 22:43:19 INFO - 'PAGER': '/bin/cat', 22:43:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:19 INFO - 'PWD': '/builds/slave/test', 22:43:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:19 INFO - 'SHELL': '/bin/bash', 22:43:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:19 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:19 INFO - 'USER': 'cltbld', 22:43:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:19 INFO - 'XPC_FLAGS': '0x0', 22:43:19 INFO - 'XPC_SERVICE_NAME': '0', 22:43:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-GoeC5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-3i1t13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-DEJsUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-V5YABa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Ju0FlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:19 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-i_4Xca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-tkbeHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-SYLJ_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-euPSNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-14YUdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ePmqKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-NxleJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-vB2P9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-83b2vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:20 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-1OBc2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:21 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-0iwqT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:21 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-fzIXtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:43:21 INFO - Running setup.py install for manifestparser 22:43:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:43:21 INFO - Running setup.py install for mozcrash 22:43:21 INFO - Running setup.py install for mozdebug 22:43:21 INFO - Running setup.py install for mozdevice 22:43:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:43:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:43:21 INFO - Running setup.py install for mozfile 22:43:22 INFO - Running setup.py install for mozhttpd 22:43:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Running setup.py install for mozinfo 22:43:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Running setup.py install for mozInstall 22:43:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Running setup.py install for mozleak 22:43:22 INFO - Running setup.py install for mozlog 22:43:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Running setup.py install for moznetwork 22:43:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:43:22 INFO - Running setup.py install for mozprocess 22:43:23 INFO - Running setup.py install for mozprofile 22:43:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:43:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:43:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:43:23 INFO - Running setup.py install for mozrunner 22:43:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:43:23 INFO - Running setup.py install for mozscreenshot 22:43:23 INFO - Running setup.py install for moztest 22:43:23 INFO - Running setup.py install for mozversion 22:43:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:43:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:43:23 INFO - Cleaning up... 22:43:23 INFO - Return code: 0 22:43:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43: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')]} 22:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43: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')]} 22:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:23 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:23 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 22:43:23 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 22:43:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:23 INFO - 'HOME': '/Users/cltbld', 22:43:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:23 INFO - 'LOGNAME': 'cltbld', 22:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:23 INFO - 'MOZ_NO_REMOTE': '1', 22:43:23 INFO - 'NO_EM_RESTART': '1', 22:43:23 INFO - 'PAGER': '/bin/cat', 22:43:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:23 INFO - 'PWD': '/builds/slave/test', 22:43:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:23 INFO - 'SHELL': '/bin/bash', 22:43:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:23 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:23 INFO - 'USER': 'cltbld', 22:43:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:23 INFO - 'XPC_FLAGS': '0x0', 22:43:23 INFO - 'XPC_SERVICE_NAME': '0', 22:43:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-qvh7X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-cL8cdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-MqiPWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-MYA4Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-kMZ6CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-TiHlxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-KWMVTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-2um5mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:43:24 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)) 22:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:43:24 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-4rvjEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-X11RTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-BNZLUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-tMjZFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-fQp9yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-347lYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-GHQHVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-1ZPdfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:43:25 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)) 22:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:43:25 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ehiWn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:43:25 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)) 22:43:25 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)) 22:43:25 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)) 22:43:25 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)) 22:43:25 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)) 22:43:25 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)) 22:43:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43:28 INFO - Downloading blessings-1.6.tar.gz 22:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:43:28 INFO - Installing collected packages: blessings 22:43:28 INFO - Running setup.py install for blessings 22:43:29 INFO - Successfully installed blessings 22:43:29 INFO - Cleaning up... 22:43:29 INFO - Return code: 0 22:43:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:43:29 INFO - Reading from file tmpfile_stdout 22:43:29 INFO - Current package versions: 22:43:29 INFO - blessings == 1.6 22:43:29 INFO - blobuploader == 1.2.4 22:43:29 INFO - docopt == 0.6.1 22:43:29 INFO - manifestparser == 1.1 22:43:29 INFO - mozInstall == 1.12 22:43:29 INFO - mozcrash == 0.17 22:43:29 INFO - mozdebug == 0.1 22:43:29 INFO - mozdevice == 0.48 22:43:29 INFO - mozfile == 1.2 22:43:29 INFO - mozhttpd == 0.7 22:43:29 INFO - mozinfo == 0.9 22:43:29 INFO - mozleak == 0.1 22:43:29 INFO - mozlog == 3.1 22:43:29 INFO - moznetwork == 0.27 22:43:29 INFO - mozprocess == 0.22 22:43:29 INFO - mozprofile == 0.28 22:43:29 INFO - mozrunner == 6.11 22:43:29 INFO - mozscreenshot == 0.1 22:43:29 INFO - mozsystemmonitor == 0.0 22:43:29 INFO - moztest == 0.7 22:43:29 INFO - mozversion == 1.4 22:43:29 INFO - psutil == 3.1.1 22:43:29 INFO - requests == 1.2.3 22:43:29 INFO - wsgiref == 0.1.2 22:43:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:29 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:29 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 22:43:29 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 22:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:29 INFO - 'HOME': '/Users/cltbld', 22:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:29 INFO - 'LOGNAME': 'cltbld', 22:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:29 INFO - 'MOZ_NO_REMOTE': '1', 22:43:29 INFO - 'NO_EM_RESTART': '1', 22:43:29 INFO - 'PAGER': '/bin/cat', 22:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:29 INFO - 'PWD': '/builds/slave/test', 22:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:29 INFO - 'SHELL': '/bin/bash', 22:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:29 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:29 INFO - 'USER': 'cltbld', 22:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:29 INFO - 'XPC_FLAGS': '0x0', 22:43:29 INFO - 'XPC_SERVICE_NAME': '0', 22:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 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)) 22:43:29 INFO - Cleaning up... 22:43:29 INFO - Return code: 0 22:43:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:29 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 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:29 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 22:43:29 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 22:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:29 INFO - 'HOME': '/Users/cltbld', 22:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:29 INFO - 'LOGNAME': 'cltbld', 22:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:29 INFO - 'MOZ_NO_REMOTE': '1', 22:43:29 INFO - 'NO_EM_RESTART': '1', 22:43:29 INFO - 'PAGER': '/bin/cat', 22:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:29 INFO - 'PWD': '/builds/slave/test', 22:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:29 INFO - 'SHELL': '/bin/bash', 22:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:29 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:29 INFO - 'USER': 'cltbld', 22:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:29 INFO - 'XPC_FLAGS': '0x0', 22:43:29 INFO - 'XPC_SERVICE_NAME': '0', 22:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:29 INFO - Downloading/unpacking jsonschema 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43: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 22:43:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:43:32 INFO - Downloading/unpacking functools32 (from jsonschema) 22:43:32 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 22:43:32 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 22:43:32 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 22:43:32 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 22:43:32 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 22:43:32 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 22:43:32 INFO - Downloading functools32-3.2.3-2.tar.gz 22:43:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:43:32 INFO - warning: no files found matching '*.txt' 22:43:32 INFO - no previously-included directories found matching 'build' 22:43:32 INFO - no previously-included directories found matching 'dist' 22:43:32 INFO - no previously-included directories found matching '.git*' 22:43:32 INFO - Installing collected packages: jsonschema, functools32 22:43:32 INFO - Running setup.py install for functools32 22:43:32 INFO - warning: no files found matching '*.txt' 22:43:32 INFO - no previously-included directories found matching 'build' 22:43:32 INFO - no previously-included directories found matching 'dist' 22:43:32 INFO - no previously-included directories found matching '.git*' 22:43:32 INFO - Successfully installed jsonschema functools32 22:43:32 INFO - Cleaning up... 22:43:33 INFO - Return code: 0 22:43:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4aae15f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 22:43:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:43:33 INFO - 'HOME': '/Users/cltbld', 22:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:43:33 INFO - 'LOGNAME': 'cltbld', 22:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:33 INFO - 'MOZ_NO_REMOTE': '1', 22:43:33 INFO - 'NO_EM_RESTART': '1', 22:43:33 INFO - 'PAGER': '/bin/cat', 22:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:33 INFO - 'PWD': '/builds/slave/test', 22:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:43:33 INFO - 'SHELL': '/bin/bash', 22:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:43:33 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:43:33 INFO - 'USER': 'cltbld', 22:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:33 INFO - 'XPC_FLAGS': '0x0', 22:43:33 INFO - 'XPC_SERVICE_NAME': '0', 22:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:33 INFO - Downloading/unpacking flake8 22:43:33 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 22:43:33 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 22:43:33 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 22:43:33 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 22:43:33 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 22:43:33 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 22:43:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:43:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:43:35 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 22:43:35 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 22:43:35 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 22:43:35 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 22:43:35 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 22:43:35 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 22:43:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:43:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:43:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:43:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:43:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:43:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:43:37 INFO - no previously-included directories found matching 'docs/_build' 22:43:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:43:37 INFO - Running setup.py install for pep8 22:43:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:43:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:43:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:43:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:43:37 INFO - no previously-included directories found matching 'docs/_build' 22:43:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:43:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:43:37 INFO - Cleaning up... 22:43:37 INFO - Return code: 0 22:43:37 INFO - Running post-action listener: _resource_record_post_action 22:43:37 INFO - Running post-action listener: _start_resource_monitoring 22:43:37 INFO - Starting resource monitoring. 22:43:37 INFO - ##### 22:43:37 INFO - ##### Running install step. 22:43:37 INFO - ##### 22:43:37 INFO - Running pre-action listener: _resource_record_pre_action 22:43:37 INFO - Running main action method: install 22:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:43:37 INFO - Reading from file tmpfile_stdout 22:43:37 INFO - Detecting whether we're running mozinstall >=1.0... 22:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:43:37 INFO - Reading from file tmpfile_stdout 22:43:37 INFO - Output received: 22:43:37 INFO - Usage: mozinstall [options] installer 22:43:37 INFO - Options: 22:43:37 INFO - -h, --help show this help message and exit 22:43:37 INFO - -d DEST, --destination=DEST 22:43:37 INFO - Directory to install application into. [default: 22:43:37 INFO - "/builds/slave/test"] 22:43:37 INFO - --app=APP Application being installed. [default: firefox] 22:43:37 INFO - mkdir: /builds/slave/test/build/application 22:43:37 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'] 22:43:37 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 22:44:11 INFO - Reading from file tmpfile_stdout 22:44:11 INFO - Output received: 22:44:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:44:11 INFO - Running post-action listener: _resource_record_post_action 22:44:11 INFO - ##### 22:44:11 INFO - ##### Running run-tests step. 22:44:11 INFO - ##### 22:44:11 INFO - Running pre-action listener: _resource_record_pre_action 22:44:11 INFO - Running main action method: run_tests 22:44:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:11 INFO - 2016-04-16 22:44:11.246 screenresolution[2194:14588] starting screenresolution argv=screenresolution get 22:44:11 INFO - 2016-04-16 22:44:11.248 screenresolution[2194:14588] Display 0: 1600x1200x32@60 22:44:11 INFO - 2016-04-16 22:44:11.254 screenresolution[2195:14590] starting screenresolution argv=screenresolution list 22:44:11 INFO - Available Modes on Display 0 22:44:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:11 INFO - Intel Iris: 22:44:11 INFO - Chipset Model: Intel Iris 22:44:11 INFO - Type: GPU 22:44:11 INFO - Bus: Built-In 22:44:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:11 INFO - Vendor: Intel (0x8086) 22:44:11 INFO - Device ID: 0x0a2e 22:44:11 INFO - Revision ID: 0x0009 22:44:11 INFO - Displays: 22:44:11 INFO - Display: 22:44:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:11 INFO - Display Serial Number: 1600x1200 60 22:44:11 INFO - Main Display: Yes 22:44:11 INFO - Mirror: Off 22:44:11 INFO - Online: Yes 22:44:11 INFO - Rotation: Supported 22:44:11 INFO - Return code: 0 22:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:44:11 INFO - Python 2.7.3 22:44:11 INFO - Return code: 0 22:44:11 INFO - grabbing minidump binary from tooltool 22:44:11 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')]} 22:44:11 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 0x10c846e00>, '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 0x10c66fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:44:11 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 22:44:11 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 22:44:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:11 INFO - Return code: 0 22:44:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:44:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:44:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:44:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:44:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:44:12 INFO - Return code: 0 22:44:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BaVNf3NQjo/Render', 22:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:12 INFO - 'HOME': '/Users/cltbld', 22:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:12 INFO - 'LOGNAME': 'cltbld', 22:44:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:44:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:12 INFO - 'MOZ_NO_REMOTE': '1', 22:44:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:44:12 INFO - 'NO_EM_RESTART': '1', 22:44:12 INFO - 'PAGER': '/bin/cat', 22:44:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:12 INFO - 'PWD': '/builds/slave/test', 22:44:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:12 INFO - 'SHELL': '/bin/bash', 22:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AhzHsbWKWj/Listeners', 22:44:12 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 22:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:12 INFO - 'USER': 'cltbld', 22:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:12 INFO - 'XPC_FLAGS': '0x0', 22:44:12 INFO - 'XPC_SERVICE_NAME': '0', 22:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:44:12 INFO - mozversion application_buildid: 20160416210219 22:44:12 INFO - mozversion application_changeset: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 22:44:12 INFO - mozversion application_display_name: Nightly 22:44:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:12 INFO - mozversion application_name: Firefox 22:44:12 INFO - mozversion application_remotingname: firefox 22:44:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:44:12 INFO - mozversion application_vendor: Mozilla 22:44:12 INFO - mozversion application_version: 48.0a1 22:44:12 INFO - mozversion platform_buildid: 20160416210219 22:44:12 INFO - mozversion platform_changeset: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 22:44:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:44:12 INFO - mozversion platform_version: 48.0a1 22:44:12 INFO - using testdate: 1460871852 22:44:12 INFO - actual date: 1460871852 22:44:12 INFO - starting webserver on 'localhost:51367' 22:44:12 INFO - SUITE-START | Running 2 tests 22:44:12 INFO - TEST-START | dromaeo_css 22:44:12 INFO - operating with platform_type : mac_ 22:44:12 INFO - Initialising browser for dromaeo_css test... 22:44:12 INFO - TEST-INFO | started process 2213 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpLm7CrL/profile http://localhost:51367/getInfo.html) 22:44:14 INFO - PROCESS | 2213 | __metrics Screen width/height:1600/1200 22:44:14 INFO - PROCESS | 2213 | colorDepth:24 22:44:14 INFO - PROCESS | 2213 | Browser inner width/height: 1024/689 22:44:14 INFO - PROCESS | 2213 | __metrics 22:44:15 INFO - PROCESS | 2213 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:44:15 ERROR - PROCESS | 2213 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:44:15 INFO - TEST-INFO | 2213: exit 0 22:44:15 INFO - Browser initialized. 22:44:15 INFO - Running cycle 1/1 for dromaeo_css test... 22:44:15 INFO - TEST-INFO | started process 2215 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpLm7CrL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:27 INFO - PROCESS | 2215 | RSS: Main: 237481984 22:44:27 INFO - PROCESS | 2215 | 22:45:19 INFO - PROCESS | 2215 | 1460871919383 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] 22:47:29 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51367/tests/dromaeo/cssquery-ext.html) 22:47:30 INFO - PROCESS | 2215 | RSS: Main: 375713792 22:47:30 INFO - PROCESS | 2215 | 22:50:33 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-ext.html (next: http://localhost:51367/tests/dromaeo/cssquery-jquery.html) 22:50:34 INFO - PROCESS | 2215 | RSS: Main: 381501440 22:50:34 INFO - PROCESS | 2215 | 22:53:36 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51367/tests/dromaeo/cssquery-mootools.html) 22:53:37 INFO - PROCESS | 2215 | RSS: Main: 378466304 22:53:37 INFO - PROCESS | 2215 | 22:56:39 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51367/tests/dromaeo/cssquery-prototype.html) 22:56:40 INFO - PROCESS | 2215 | RSS: Main: 378667008 22:56:40 INFO - PROCESS | 2215 | 22:59:43 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51367/tests/dromaeo/cssquery-yui.html) 22:59:43 INFO - PROCESS | 2215 | RSS: Main: 349384704 22:59:43 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-yui.html (next: http://localhost:51367/tests/dromaeo/cssquery-dojo.html) 23:02:46 INFO - PROCESS | 2215 | RSS: Main: 382459904 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | __start_tp_report 23:02:46 INFO - PROCESS | 2215 | _x_x_mozilla_page_load 23:02:46 INFO - PROCESS | 2215 | _x_x_mozilla_page_load_details 23:02:46 INFO - PROCESS | 2215 | |i|pagename|runs| 23:02:46 INFO - PROCESS | 2215 | |0;dojo.html;3370.2753907909796;3374.443216869216;3425.1094715373997;3463.1342164458883;3474.91312717182;5536.501714845663;5635.3237611486575;5652.34997975233;5652.802151924683;5664.0937450008005;5629.9866024115645;5647.745851436685;5759.884802303952;5776.711164441782;5783.016887129188;5087.79648814047;5124.385073791141;5148.073346797575;5162.716050617215;5289.391719952197;5255.185446255837;5281.471852814707;5294.973525132388;5307.575393968474;5342.866428339284;5116.692998420107;5135.922961155586;5194;5217.686938783685;5248.107821670306;3713.832877520518;4815.927761083586;5229.869253268661;5241.842744717665;5265.921011184835;5136.460671629462;5180.559652429548;5187.922181167285;5193.402758682762;5230.398504172031;5476;5542.612017158801;5548.9722551386985;5659.179418984266;5662.5753068520025;6126.448619624211;6167.845803854894;6176.135341052256;6195;6209.441150296495;6196.8760624787255;6239.87520249597;6265.8120256392385;6283.151774510428;6285.622862628257;5620.831375058755;5660.745266462978;5716.1711551825165;5742.167385729046;5768.307803063659;5386.407495175527;6106.786262480792;6256.968715156395;6287.308396076428;6294.685265736786;6041.8489537761025;6056.031035034373;6067.0292753160165;6070.484008859296;6082.60463069899;5019.974900125476;6064.757409703562;6120.234970628671;6159.291681456601;6229.906551401733;6088.634681919099;6097.176881120992;6153.907691384633;6161.168242287324;6167.722452489649;6199.039148931923;6201.720922558496;6210.161628180228;6221.3156552779155;6224.190855188796;4795.808167673254;4886.218205087169;4886.682365646293;4888.828890988798;4895.192293271642;4368.017196130845;4424.60178583929;4449.488308844524;4454.086912182995;4456.46522417312;5610.831675049755;5611;5616.85957851057;5626.662400255997;5627.662340259598;6113.385090541919;6114.875284800388;6159.353267906805;6239.781607643801;6262.060690896402;5571.359293681197;5606.803761868315;5609.747561359749;5612.607117501736;5637.23897273871;5246.842594722164;5303.814366497154;5315;5325.946740532545;5334.67991920486;6198.039303907902;6247.96876015617;6276.403741644536;6279.403456671609;6281.089242059968;6203.069539569101;6209.410106039919;6229.470495007975;6232.875342493216;6244.188255526752;6172.475339596184;6260.843478912881;6271.3728627136925;6290.182276304051;6304.211973503312;6066.696665166813;6139.416755408229;6140.293866205444;6155.446009858955;6156.907646385218;6065.181200537871;6205.131281620667;6222.408871157233;6242.937570624417;6244.250689917239;5873.148393482836;5929.347771745191;6061.393860613904;6067.180930574493;6078.483328917092;4841.564259216688;6016.308124565731;6025.578209525291;6028;6088.78689245892;5792.247007888947;5839.474447299764;5854.590178687451;5862.648241105548;5864.06175011996;5784.421557844182;5986.102084687332;6010.398960103955;6038.698065096815;6060.878782424415;5886.6173698709445;5894.528437725057;5895.086261629454;5895.94104058954;5920.644761314335 23:02:46 INFO - PROCESS | 2215 | |1;ext.html;521.475916703713;532.5100907165408;552.991705124423;576.85290250986;584.033424682151;21696.566068678618;29972.25069373267;29986.85006574967;30019.24951876204;30226.99999999997;39333.409998849966;39467.407988880186;40175.995600110014;40188.99527511813;40496;9452.196563292146;9643.951780241088;9694.806103877918;9697.757556061084;9703.369280996749;8577.571121443934;9522.476263805487;9701.563429645683;9716.514174291293;11659.533618655287;11465.885341146564;11638.767224655501;11650;11657.533698652087;11663.766724665544;11609.129315301345;11702.765944681143;11705.590304339306;11706.12204084697;11711.648650540455;11551.884481155164;11691;11693;11706.297622142698;11737.178397512178;37831.62168378309;39942;39965.60034399648;40049;40065.19869602621;3264.2002709336366;3362.5796775403073;3405.3529829332347;3487.808170550619;3572.8570857165682;25507.362315942064;28984.855075724485;29698.515074246417;30226.54660180099;30810.53784193239;2703.4998525272886;2710.891564337424;2717.945641087167;2726.836389816617;2826.4771017361854;387375;407648;410985;411281;411373.99999999703;61467;61851.38148618456;62326.441838954386;62483;62501.56241406676;65634.34365656378;65988;66062.33937660659;66135;66136.6693166531;16748.24632891523;16947.915260423866;16953.406630767862;16985.15074246283;17005.744913826056;12955.481780728855;13019.674508137372;13023.869761302265;13026.80459793104;13035.674108147372;16329.18354082291;19022.334218302294;19797.901010494854;19823.702644460343;19903.701444478054;7990;8148.755537333889;8149.470284431493;8167;8230;990.277097718655;1052.3580615824342;1053.6786280184617;1059.5496913811753;1131.4286285425972;27584.44831103407;27900;28406;30032;30215;929.697848199338;1053.3890343600692;1116.625930313338;1117.8658560972735;1164.3130552973787;14285.928570357082;14618.780718289234;14733.325710990555;14836.851631483763;14904.776428353583;616660.9166954136;628204;631639;631712;633950;58804.7059764707;59667.403325967054;59772.70113649491;59785.40214597798;59897.40102599005;1453.4694836384645;1481.5185064853965;1508.3815635589356;1522.5812901452189;1523.177484158564;28154.71845281521;28160;28474.715252848036;28673.996410125546;28894.71105289004;50354.244686329;50711.91457025181;50930.745346273034;50960.49039509558;51005;512825;515223;515328;516661;517110;27064.72935270622;41841.79079104585;42778.93052673708;43255;43918.780406097765;36007.63992360043;42445;44635;44724.32913506235;45914.770426147654;35773.64226357703;36138.63861361458;36158.73444429432;37119.62880371162;37223.25553488861;35356.64643353636;35650;35824.82087589546;35918.43264294039;36306.636933630325;32876.34247315088;33674.98975030753;35778.821105894305;37010.44484332683;37188.07029824276;24341.026358945444;30870.699217539826;32423.189420263738;32831.8358408216;32984.67015329816;17718.291268349636;24259;24522.877385613672;24895.00419983253;25025.624615630415 23:02:46 INFO - PROCESS | 2215 | |2;jquery.html;378.2133163020916;379.3569899021159;385.68373933374625;390.7011136480593;400.94587230723846;19038.619227615458;20458.283960061402;20800.47998800031;20809.959502024896;20819.583608327823;20364.08361623727;20852.68720969183;21301.25445609404;21652.458688532795;21684.566308673817;5333.280007199037;5774.740136693859;5984.281886173643;6007.6395416274945;6020.729067191987;5679.517241034517;5902;5958.702064896759;5962.224910761595;5971.790987315445;5580.358258800249;5853.7951171709;5875.559333050039;5880.117982302668;5885.529157667398;5767.971160144172;5815.73829177688;5852.76588936442;5867;5874.3244526879525;4143.606357396073;5112.795488180468;5152.639315247934;5153.613478989091;5159.535641792219;24587.38531536708;25375.492490150093;25786.226413207634;25966.09118680837;26240.60639090415;1949.6198241342943;2058.197303051811;2206.7793220677963;2284.851484653492;2329.2313536533075;14792.964492485531;14975.176365299903;15018.69962600742;15071.171085590287;15288.77066843987;1739.3738254228467;1815.1922394534438;1827.7349784281337;1829.2500074969332;1832.230463205457;106919.46540267326;107219.92780072255;108204.91795082027;108525.45737271263;110506.44746776295;8938.061503542165;9431.858522122173;9646.565904534313;9696.6121355145;10398.532066056894;9745.756356091013;12033.63899083029;12059;12122.151449398634;12162.5134994601;6353.936460635427;7926.603669816486;7939.483933544301;8058.959705201437;8072.434929554992;8372.874406883902;8380.497170169809;8476.152384761597;8482;8486.618102185417;3694.0949467380665;3722.4974628425357;3726.4782930389765;3791.8483260669263;3792.2984247914223;1825.4158669225985;1861.702127659568;1882.1542969235027;1882.8493720502327;1885.340130954155;910.9407888487227;919.6045700348781;932.7613727054986;1009.3035805294323;1018.4144117132678;15033.699326013419;16370.918145409434;19606;19630.607387852164;20059.699104513445;940.1773448232797;959.8512230604267;967.3905439573024;980.9950950245346;990.6730778842965;9127.991841794794;11040.447977601088;11057.889421105847;11068.778624427467;11081.33511989267;602322;612650;620210;621313;626604;128352.52298508708;131210;133962;138118;138971;1042.7653777899911;1052.0741747262514;1065.824139017069;1101.8512500812287;1104.320842681745;18262.08689565543;18524.44426667202;18595.81404185941;18595.90702046535;18636.813631863508;50712;51646.48353516567;52170.47829521656;52302.215466767266;52895.47104528906;52635;73622;73708.26291737014;74396;75252.62373688097;6389.648569328546;6835.863282734417;7899.565523896241;7927.128015918129;7942.801429964297;8988.235999940078;11906.702332441759;12219.327936963553;12294.139410241198;12707;10635.20235982289;10916.454177290945;10951.945240274066;11135.220534562826;11270.154738394816;10064.948041566864;10418.166546676399;10612.893871061191;10724.873888241622;10994.395308258123;10410.583576656849;10657.626983055867;10977.945110274397;11036.172287078662;11310.491027903929;7408.444366672414;7950.602469876599;7961.243681850215;8007.119216886254;8226.136255693305;9367.812643747224;10697.732556686145;10847.511861966388;10868.347899126078;11003.61487347939 23:02:46 INFO - PROCESS | 2215 | |3;mootools.html;1391.0610338021834;1555.657755293835;1577.313868467217;1785.2858856457415;1938.6607343714848;7926.999999999993;8764.956175219122;8952.955235223832;8967.865482017774;8997.91002089979;8533.829323413527;8762.21140097391;8773.385862989593;8775.210231079218;8776.473411595309;2124.6600543912978;2280.635098384259;2322.674825524428;2358.9528209435803;2359.8230132740027;2430.970139933158;2456.9754302457013;2460.7047154341412;2461.716902556211;2465.1248806673643;2386.820988425875;2407.3620490570015;2423.2245681381964;2424.612062070069;2429.101232543961;2079.1787244038596;2307.10023090993;2391.7488663690237;2402.6516155157406;2406.6871306730186;2403.0267741564694;2421.6609674645565;2424.866632335221;2427;2431.4043059450546;8603;8679.218870301704;8683.869741953877;8684.13158684133;8688.218060374596;5021.698698078127;5124.499886952312;5259.237410575483;5303.071962406563;5324.361076670785;2898.9130326090253;3382.7970321780526;3418.9829050854837;3447.8620855165764;3470.1151206442196;1018.3482571154467;1589.7853789738353;1637.1486826850091;1671.779600891356;1673.840985106413;11333.206675532716;11447.885521144764;11454.140939429577;11486.310821350746;11486.42567871603;4367.781610919441;4402.537733537996;4529.139463501924;4567.223571992769;4588.2658774596575;9080.727578172533;9093.136152065543;9095.31785116119;9095.727128186165;9102.499362535124;5192.935448233103;5193.324867767242;5200.81797137106;5229.424763276036;5247.10799164143;4355.455568053993;4487.147441986078;4714.245720684674;4806.7836947337455;4818.084563932842;3590.6588874056924;3616.367135751233;3691.2802003609577;3729.1982223821465;3734.047817806442;1121.8092924202906;1129.8587676540433;1133.4729350851894;1139.4530625299903;1142.9085673146128;772.6098320348189;836.7489753074053;890.1365675294953;902.9683961061331;928.9767755806024;4737.265723812815;5495.093309603951;5512.972435137799;5639.351474580476;5778;812.9552874591955;884.4428010353436;911.7857303533664;929.2705226397246;940.4169414962766;3577.5170352002665;3688.797116158637;3742.8877133686033;3766.9075967968984;3785.2240290740333;9858.211343092535;10185.439800810878;10541.209409294333;10657.307275027099;10666.679999600025;10623.20325975555;10623.52194151265;10669.253152279422;10807.135429165804;10850.837237441445;8021.438499304993;8103.878441823377;8207.507549546926;8223.95888020556;8363.205495478041;5271.815486457956;5689.260396148474;5783.913241301299;5789.2763404574425;5922.674252915958;9532.046795320413;9596.712098636774;9846.901530984598;9955.701328960144;9961.551730172016;9777.951110244689;9819.607215711292;9876.950615246878;10028.348157369748;10065.849012264676;6854.691538880663;7106;7125.643717814192;7322.304381083789;7573.129090154703;8106.392020598597;8160.14318496573;8197;8214.671413143407;8225.712100076707;7176.96411517939;7297.270272972661;7306.634668266672;7428.2571742825285;7469.551826890404;7255.963720181366;7316.012338334257;7354.696220145636;7392.852142957218;7445.255474452511;6217.285012223652;6753.628550429774;6850.143732033496;6863.416609588241;6865.27914568963;3955.7033222507853;4667.929981050355;4677.859664210079;4813.95186048149;4891.486393928586;4759.3336932830125;4835.29888166221;4872.1717308057005;4903.215485522299;5104.489551044866 23:02:46 INFO - PROCESS | 2215 | |4;prototype.html;308.9073278016595;312.1618454449801;316.80041573808495;318.7593367007907;325.2778830995193;4592.609628181604;4795.25673520604;5792.884142317156;5856.121581762738;5953.22608060952;4697.553732395426;5401.4868587484225;5760.222369980052;5816.243888294526;5821.621594596349;3675.5589329280456;3950.6839452843847;4305.052888364555;4324.20002299574;4438.3799913031835;3789.2989796887528;4388.758618275993;4395.846145384912;4397.626201772853;4439.911201775963;4298.161858437606;4403.823847046115;4424.960134368421;4425.7565833879125;4431.202383570956;4063.5326937402283;4096.897577560555;4315.568443155691;4320.179165958458;4447.844325448594;2900.7244311790346;3684.7420680552373;3710.9609309393145;3711.9072023199365;3754.662080412776;4851.175300198975;4862.076205520975;5130.692158470466;5598.748056337475;5641.802536911188;4672.813087476497;4745.59662428694;4886;4915.950840491585;4921.926171107436;4798.928016079727;5867.76528938842;6065.120557519135;6081.787137450212;6122.602030867979;3657.2868290683327;3784.8675296364772;4026.57720939303;4035.899102522431;4057.736247143926;6072.787452439144;6394.104825324458;6487.57830741005;6505.284418713938;6559.639219842907;4065.5324637666454;4074.4295798587627;4101.507819061731;4107.404426358161;4125.422440858282;3633.5276414066;3693.9076523087138;4169.228692691861;4175.645070169009;4191.1827193697545;4561.315802629566;4836.637252206098;4928.753562321869;4931.18635425158;4938.4320803107385;6018.21763170785;6060.484858786965;6063.575549711566;6079.179310793074;6108.908366374508;1297.7022977022978;1335.4724883670895;1426.3866537388815;1445.985540144585;1455.6360909772557;771.3173841150622;826.7271800305884;881.5415983688574;898.8606765951288;954.2604481526733;5235.3717553893775;5641.435856414326;5646.971765141148;5691.516221121168;5713.600047996601;5327.254184414185;6265.937340626535;6336.22994318389;6342.5243106767175;6350.983842585164;4871.025794841046;5378.946210537844;5486.807961721321;5647.548196144298;5714;2826.901058462944;3138.5919830422354;3236.7410607151373;3271.329377477611;3323.955767203201;5373.570114390839;5947.769586405098;6328.46208072319;6535.836604084936;6588.604683718992;5082.796688132507;5341;6111.594333303365;6158.076288556752;6417.422431981144;5380.784768609212;5927.3776253493625;6570.868582628321;6590;6595.67021648906;5951.791687290924;6246.750129994749;6257.4681152102585;6293.213348331458;6309.589876658002;6162.198914141133;6167.784127555514;6180.055955235704;6209.068639704081;6225.564210505221;6218.036204388327;6549.967250163719;6551.967240163769;6566.0479230512265;6568.540202185801;3586.2110335726097;4132.876013719593;4134.214499245134;4134.979325103355;4136.21411931732;5364.704941228114;5900.881982360414;5902.881942361043;5959.195508606456;6292.968535157478;4986.601071914166;5836.299644042742;6064.636121832704;6112.1748563945075;6118.418750218722;5660.867826434647;5675.233843431084;6029.758809647565;6055.666938318434;6114.144019837137;4509.616682582017;5745.7988970387505;5925.881482370414;5944.1975333331175;5972.970135149297;5229.607779416635;5832.766689332379;6148.3236843948025;6192.721327540181;6195.535334849814;4969.887797685897;5398.354806145039;6028.668423236764;6046.7581296749395;6108.511319094373 23:02:46 INFO - PROCESS | 2215 | |5;yui.html;650.5901282192219;738.1916801102791;750.6359415683394;775.4378075894973;792.1721800718249;14954.700905981887;15365.231738413091;15385.692286154299;15397.230138493087;15400.075995440267;11896.821547676791;12174.208676436025;12243.69390765229;12864.163829351086;13060.673483162926;747.3946103656028;776.0377132355881;934.6822080492631;939.9671011514598;941.8869735631733;919.9310051746112;922.6632279218071;935.7567032571512;939.2298315381356;939.642935684439;755.7732680195946;979.6718099436662;980.0689345122126;983.8917719050899;988.574912787501;931.2689538712112;965.1169180200144;971.159946646146;972.8492083727034;974.3861367338602;734.5409119300438;735.1692587376231;736.495500582103;737.5832654550162;738.2838646512874;14966.62583435412;15006.249687515581;15006.399744010227;15049;15097.396104155821;12533.811992820116;12605.747885042249;12678.746425071447;12921.353932303347;13231.933840330737;9945.801083978353;10214.08073273409;10249.53877075526;10533.052025317758;10560.894391056068;3504.9299014019575;3527.153483164022;3531.9117022074397;3588.1209103769743;3647.598764135943;80969;83910;84026.57986710027;84125.57937210336;84456.15543844482;9447.669331573363;9706.514674266258;9712.174465170398;9752.26857985654;10084.74788130288;22189.445263868532;22221.666674999888;22228.444288892908;22232.888355582156;22257.777422225572;7429.145648250413;7541.132769731441;7549.622518874145;7625.542467451971;7682.692692292358;4033.3546632538655;4055.3511438169753;4067.328890732996;4130.132672138824;4133.028738246509;3583.605803361628;3688.4467329900194;3696.149885526344;3699.2416554606243;3699.5560532736245;628.1143587541544;655.5476721062452;656.8587753632896;657.1063353838775;660.2044536333707;521.0828941063755;522.801335492518;538.2506852728632;551.4292707048186;597.8475488750341;7654.966579511806;7782.182870798598;7824.687012519549;7950;7984.321332686786;499.2910067703869;528.9497497737709;544.8692313844649;553.3332334536873;595.3867516458055;3497.143199916037;3696.500972368696;3732.869349572806;3883.514560679915;3908.4528166056775;83040.58479707684;83682;84176.57911710402;84526;85447;73926.26073739193;74125.25874741183;74729.25270747223;75088.62455687686;76877;66119;66340.66829665918;66432;66768.33231667621;68406.65796671085;7352.154502232312;7355.264473552602;7444.962775186089;7542.547447153188;7572.886406703794;42515.7874210627;43206.543476522435;43568.69783096133;43660.78169609132;44401.11197776091;53018.73490632522;56931.57671058091;58623.70688146532;58875.70562147333;59175.81648367095;10283.485825708834;10378.117859981987;10484.108850747773;10572.788544229226;10582.947085264525;11198.664040078811;11492.425378730863;11967.281963082243;12011;12373.127114548133;11139.387333696724;11219.326840389604;11315.490802913724;11575.652730418102;11740.58907938218;11726.374687430436;11778.646640600795;11788.646340609796;11832.526698931946;12197.024238060756;10750.139988801033;11196.94401527987;11416.771664566828;11544;11609.941950290195;9436.858447123435;9691.90984131404;9771.912236337463;9790.167835734042;10056.949715251669;8969.730908072768;9027.95486022592;9070.319726020707;9074.546272686472;9281.257499399897 23:02:46 INFO - PROCESS | 2215 | __end_tp_report 23:02:46 INFO - PROCESS | 2215 | __start_cc_report 23:02:46 INFO - PROCESS | 2215 | _x_x_mozilla_cycle_collect,1296 23:02:46 INFO - PROCESS | 2215 | __end_cc_report 23:02:46 INFO - PROCESS | 2215 | __startTimestamp1460872966958__endTimestamp 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | ------- Summary: start ------- 23:02:46 INFO - PROCESS | 2215 | Number of tests: 6 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#0] dojo.html Cycles:165 Average:5671.21 Median:5862.65 stddev:639.63 (10.9%) stddev-sans-first:615.59 23:02:46 INFO - PROCESS | 2215 | Values: 3370.3 3374.4 3425.1 3463.1 3474.9 5536.5 5635.3 5652.3 5652.8 5664.1 5630.0 5647.7 5759.9 5776.7 5783.0 5087.8 5124.4 5148.1 5162.7 5289.4 5255.2 5281.5 5295.0 5307.6 5342.9 5116.7 5135.9 5194.0 5217.7 5248.1 3713.8 4815.9 5229.9 5241.8 5265.9 5136.5 5180.6 5187.9 5193.4 5230.4 5476.0 5542.6 5549.0 5659.2 5662.6 6126.4 6167.8 6176.1 6195.0 6209.4 6196.9 6239.9 6265.8 6283.2 6285.6 5620.8 5660.7 5716.2 5742.2 5768.3 5386.4 6106.8 6257.0 6287.3 6294.7 6041.8 6056.0 6067.0 6070.5 6082.6 5020.0 6064.8 6120.2 6159.3 6229.9 6088.6 6097.2 6153.9 6161.2 6167.7 6199.0 6201.7 6210.2 6221.3 6224.2 4795.8 4886.2 4886.7 4888.8 4895.2 4368.0 4424.6 4449.5 4454.1 4456.5 5610.8 5611.0 5616.9 5626.7 5627.7 6113.4 6114.9 6159.4 6239.8 6262.1 5571.4 5606.8 5609.7 5612.6 5637.2 5246.8 5303.8 5315.0 5325.9 5334.7 6198.0 6248.0 6276.4 6279.4 6281.1 6203.1 6209.4 6229.5 6232.9 6244.2 6172.5 6260.8 6271.4 6290.2 6304.2 6066.7 6139.4 6140.3 6155.4 6156.9 6065.2 6205.1 6222.4 6242.9 6244.3 5873.1 5929.3 6061.4 6067.2 6078.5 4841.6 6016.3 6025.6 6028.0 6088.8 5792.2 5839.5 5854.6 5862.6 5864.1 5784.4 5986.1 6010.4 6038.7 6060.9 5886.6 5894.5 5895.1 5895.9 5920.6 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#1] ext.html Cycles:180 Average:65636.99 Median:19422.23 stddev:139992.91 (720.8%) stddev-sans-first:140300.26 23:02:46 INFO - PROCESS | 2215 | Values: 521.5 532.5 553.0 576.9 584.0 21696.6 29972.3 29986.9 30019.2 30227.0 39333.4 39467.4 40176.0 40189.0 40496.0 9452.2 9644.0 9694.8 9697.8 9703.4 8577.6 9522.5 9701.6 9716.5 11659.5 11465.9 11638.8 11650.0 11657.5 11663.8 11609.1 11702.8 11705.6 11706.1 11711.6 11551.9 11691.0 11693.0 11706.3 11737.2 37831.6 39942.0 39965.6 40049.0 40065.2 3264.2 3362.6 3405.4 3487.8 3572.9 25507.4 28984.9 29698.5 30226.5 30810.5 2703.5 2710.9 2717.9 2726.8 2826.5 387375.0 407648.0 410985.0 411281.0 411374.0 61467.0 61851.4 62326.4 62483.0 62501.6 65634.3 65988.0 66062.3 66135.0 66136.7 16748.2 16947.9 16953.4 16985.2 17005.7 12955.5 13019.7 13023.9 13026.8 13035.7 16329.2 19022.3 19797.9 19823.7 19903.7 7990.0 8148.8 8149.5 8167.0 8230.0 990.3 1052.4 1053.7 1059.5 1131.4 27584.4 27900.0 28406.0 30032.0 30215.0 929.7 1053.4 1116.6 1117.9 1164.3 14285.9 14618.8 14733.3 14836.9 14904.8 616660.9 628204.0 631639.0 631712.0 633950.0 58804.7 59667.4 59772.7 59785.4 59897.4 1453.5 1481.5 1508.4 1522.6 1523.2 28154.7 28160.0 28474.7 28674.0 28894.7 50354.2 50711.9 50930.7 50960.5 51005.0 512825.0 515223.0 515328.0 516661.0 517110.0 27064.7 41841.8 42778.9 43255.0 43918.8 36007.6 42445.0 44635.0 44724.3 45914.8 35773.6 36138.6 36158.7 37119.6 37223.3 35356.6 35650.0 35824.8 35918.4 36306.6 32876.3 33675.0 35778.8 37010.4 37188.1 24341.0 30870.7 32423.2 32831.8 32984.7 17718.3 24259.0 24522.9 24895.0 25025.6 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#2] jquery.html Cycles:180 Average:34971.09 Median:25580.86 stddev:102681.95 (401.4%) stddev-sans-first:102937.14 23:02:46 INFO - PROCESS | 2215 | Values: 378.2 379.4 385.7 390.7 400.9 19038.6 20458.3 20800.5 20810.0 20819.6 20364.1 20852.7 21301.3 21652.5 21684.6 5333.3 5774.7 5984.3 6007.6 6020.7 5679.5 5902.0 5958.7 5962.2 5971.8 5580.4 5853.8 5875.6 5880.1 5885.5 5768.0 5815.7 5852.8 5867.0 5874.3 4143.6 5112.8 5152.6 5153.6 5159.5 24587.4 25375.5 25786.2 25966.1 26240.6 1949.6 2058.2 2206.8 2284.9 2329.2 14793.0 14975.2 15018.7 15071.2 15288.8 1739.4 1815.2 1827.7 1829.3 1832.2 106919.5 107219.9 108204.9 108525.5 110506.4 8938.1 9431.9 9646.6 9696.6 10398.5 9745.8 12033.6 12059.0 12122.2 12162.5 6353.9 7926.6 7939.5 8059.0 8072.4 8372.9 8380.5 8476.2 8482.0 8486.6 3694.1 3722.5 3726.5 3791.8 3792.3 1825.4 1861.7 1882.2 1882.8 1885.3 910.9 919.6 932.8 1009.3 1018.4 15033.7 16370.9 19606.0 19630.6 20059.7 940.2 959.9 967.4 981.0 990.7 9128.0 11040.4 11057.9 11068.8 11081.3 602322.0 612650.0 620210.0 621313.0 626604.0 128352.5 131210.0 133962.0 138118.0 138971.0 1042.8 1052.1 1065.8 1101.9 1104.3 18262.1 18524.4 18595.8 18595.9 18636.8 50712.0 51646.5 52170.5 52302.2 52895.5 52635.0 73622.0 73708.3 74396.0 75252.6 6389.6 6835.9 7899.6 7927.1 7942.8 8988.2 11906.7 12219.3 12294.1 12707.0 10635.2 10916.5 10951.9 11135.2 11270.2 10064.9 10418.2 10612.9 10724.9 10994.4 10410.6 10657.6 10977.9 11036.2 11310.5 7408.4 7950.6 7961.2 8007.1 8226.1 9367.8 10697.7 10847.5 10868.3 11003.6 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#3] mootools.html Cycles:180 Average:5589.83 Median:4962.46 stddev:3125.21 (63.0%) stddev-sans-first:3118.05 23:02:46 INFO - PROCESS | 2215 | Values: 1391.1 1555.7 1577.3 1785.3 1938.7 7927.0 8765.0 8953.0 8967.9 8997.9 8533.8 8762.2 8773.4 8775.2 8776.5 2124.7 2280.6 2322.7 2359.0 2359.8 2431.0 2457.0 2460.7 2461.7 2465.1 2386.8 2407.4 2423.2 2424.6 2429.1 2079.2 2307.1 2391.7 2402.7 2406.7 2403.0 2421.7 2424.9 2427.0 2431.4 8603.0 8679.2 8683.9 8684.1 8688.2 5021.7 5124.5 5259.2 5303.1 5324.4 2898.9 3382.8 3419.0 3447.9 3470.1 1018.3 1589.8 1637.1 1671.8 1673.8 11333.2 11447.9 11454.1 11486.3 11486.4 4367.8 4402.5 4529.1 4567.2 4588.3 9080.7 9093.1 9095.3 9095.7 9102.5 5192.9 5193.3 5200.8 5229.4 5247.1 4355.5 4487.1 4714.2 4806.8 4818.1 3590.7 3616.4 3691.3 3729.2 3734.0 1121.8 1129.9 1133.5 1139.5 1142.9 772.6 836.7 890.1 903.0 929.0 4737.3 5495.1 5513.0 5639.4 5778.0 813.0 884.4 911.8 929.3 940.4 3577.5 3688.8 3742.9 3766.9 3785.2 9858.2 10185.4 10541.2 10657.3 10666.7 10623.2 10623.5 10669.3 10807.1 10850.8 8021.4 8103.9 8207.5 8224.0 8363.2 5271.8 5689.3 5783.9 5789.3 5922.7 9532.0 9596.7 9846.9 9955.7 9961.6 9778.0 9819.6 9877.0 10028.3 10065.8 6854.7 7106.0 7125.6 7322.3 7573.1 8106.4 8160.1 8197.0 8214.7 8225.7 7177.0 7297.3 7306.6 7428.3 7469.6 7256.0 7316.0 7354.7 7392.9 7445.3 6217.3 6753.6 6850.1 6863.4 6865.3 3955.7 4667.9 4677.9 4814.0 4891.5 4759.3 4835.3 4872.2 4903.2 5104.5 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#4] prototype.html Cycles:180 Average:4874.29 Median:5399.92 stddev:1542.39 (28.6%) stddev-sans-first:1508.17 23:02:46 INFO - PROCESS | 2215 | Values: 308.9 312.2 316.8 318.8 325.3 4592.6 4795.3 5792.9 5856.1 5953.2 4697.6 5401.5 5760.2 5816.2 5821.6 3675.6 3950.7 4305.1 4324.2 4438.4 3789.3 4388.8 4395.8 4397.6 4439.9 4298.2 4403.8 4425.0 4425.8 4431.2 4063.5 4096.9 4315.6 4320.2 4447.8 2900.7 3684.7 3711.0 3711.9 3754.7 4851.2 4862.1 5130.7 5598.7 5641.8 4672.8 4745.6 4886.0 4916.0 4921.9 4798.9 5867.8 6065.1 6081.8 6122.6 3657.3 3784.9 4026.6 4035.9 4057.7 6072.8 6394.1 6487.6 6505.3 6559.6 4065.5 4074.4 4101.5 4107.4 4125.4 3633.5 3693.9 4169.2 4175.6 4191.2 4561.3 4836.6 4928.8 4931.2 4938.4 6018.2 6060.5 6063.6 6079.2 6108.9 1297.7 1335.5 1426.4 1446.0 1455.6 771.3 826.7 881.5 898.9 954.3 5235.4 5641.4 5647.0 5691.5 5713.6 5327.3 6265.9 6336.2 6342.5 6351.0 4871.0 5378.9 5486.8 5647.5 5714.0 2826.9 3138.6 3236.7 3271.3 3324.0 5373.6 5947.8 6328.5 6535.8 6588.6 5082.8 5341.0 6111.6 6158.1 6417.4 5380.8 5927.4 6570.9 6590.0 6595.7 5951.8 6246.8 6257.5 6293.2 6309.6 6162.2 6167.8 6180.1 6209.1 6225.6 6218.0 6550.0 6552.0 6566.0 6568.5 3586.2 4132.9 4134.2 4135.0 4136.2 5364.7 5900.9 5902.9 5959.2 6293.0 4986.6 5836.3 6064.6 6112.2 6118.4 5660.9 5675.2 6029.8 6055.7 6114.1 4509.6 5745.8 5925.9 5944.2 5973.0 5229.6 5832.8 6148.3 6192.7 6195.5 4969.9 5398.4 6028.7 6046.8 6108.5 23:02:46 INFO - PROCESS | 2215 | 23:02:46 INFO - PROCESS | 2215 | [#5] yui.html Cycles:180 Average:17460.40 Median:57777.64 stddev:24153.19 (41.8%) stddev-sans-first:24187.96 23:02:46 INFO - PROCESS | 2215 | Values: 650.6 738.2 750.6 775.4 792.2 14954.7 15365.2 15385.7 15397.2 15400.1 11896.8 12174.2 12243.7 12864.2 13060.7 747.4 776.0 934.7 940.0 941.9 919.9 922.7 935.8 939.2 939.6 755.8 979.7 980.1 983.9 988.6 931.3 965.1 971.2 972.8 974.4 734.5 735.2 736.5 737.6 738.3 14966.6 15006.2 15006.4 15049.0 15097.4 12533.8 12605.7 12678.7 12921.4 13231.9 9945.8 10214.1 10249.5 10533.1 10560.9 3504.9 3527.2 3531.9 3588.1 3647.6 80969.0 83910.0 84026.6 84125.6 84456.2 9447.7 9706.5 9712.2 9752.3 10084.7 22189.4 22221.7 22228.4 22232.9 22257.8 7429.1 7541.1 7549.6 7625.5 7682.7 4033.4 4055.4 4067.3 4130.1 4133.0 3583.6 3688.4 3696.1 3699.2 3699.6 628.1 655.5 656.9 657.1 660.2 521.1 522.8 538.3 551.4 597.8 7655.0 7782.2 7824.7 7950.0 7984.3 499.3 528.9 544.9 553.3 595.4 3497.1 3696.5 3732.9 3883.5 3908.5 83040.6 83682.0 84176.6 84526.0 85447.0 73926.3 74125.3 74729.3 75088.6 76877.0 66119.0 66340.7 66432.0 66768.3 68406.7 7352.2 7355.3 7445.0 7542.5 7572.9 42515.8 43206.5 43568.7 43660.8 44401.1 53018.7 56931.6 58623.7 58875.7 59175.8 10283.5 10378.1 10484.1 10572.8 10582.9 11198.7 11492.4 11967.3 12011.0 12373.1 11139.4 11219.3 11315.5 11575.7 11740.6 11726.4 11778.6 11788.6 11832.5 12197.0 10750.1 11196.9 11416.8 11544.0 11609.9 9436.9 9691.9 9771.9 9790.2 10056.9 8969.7 9028.0 9070.3 9074.5 9281.3 23:02:46 INFO - PROCESS | 2215 | -------- Summary: end -------- 23:02:46 INFO - PROCESS | 2215 | 23:02:47 INFO - TEST-INFO | 2215: exit 0 23:02:47 INFO - TEST-OK | dromaeo_css | took 1114553ms 23:02:47 INFO - TEST-START | kraken 23:02:47 INFO - operating with platform_type : mac_ 23:02:47 INFO - Initialising browser for kraken test... 23:02:47 INFO - TEST-INFO | started process 2219 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpBugzOS/profile http://localhost:51367/getInfo.html) 23:02:49 INFO - PROCESS | 2219 | __metrics Screen width/height:1600/1200 23:02:49 INFO - PROCESS | 2219 | colorDepth:24 23:02:49 INFO - PROCESS | 2219 | Browser inner width/height: 1024/689 23:02:49 INFO - PROCESS | 2219 | __metrics 23:02:49 INFO - PROCESS | 2219 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:02:49 ERROR - PROCESS | 2219 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:02:50 INFO - TEST-INFO | 2219: exit 0 23:02:50 INFO - Browser initialized. 23:02:50 INFO - Running cycle 1/1 for kraken test... 23:02:50 INFO - TEST-INFO | started process 2221 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpBugzOS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:02 INFO - PROCESS | 2221 | RSS: Main: 237502464 23:03:02 INFO - PROCESS | 2221 | 23:03:05 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-beat-detection) 23:03:05 INFO - PROCESS | 2221 | RSS: Main: 286597120 23:03:05 INFO - PROCESS | 2221 | 23:03:08 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-dft) 23:03:09 INFO - PROCESS | 2221 | RSS: Main: 301113344 23:03:09 INFO - PROCESS | 2221 | 23:03:12 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-fft) 23:03:13 INFO - PROCESS | 2221 | RSS: Main: 386572288 23:03:13 INFO - PROCESS | 2221 | 23:03:15 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-oscillator) 23:03:15 INFO - PROCESS | 2221 | RSS: Main: 285614080 23:03:15 INFO - PROCESS | 2221 | 23:03:17 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:03:18 INFO - PROCESS | 2221 | RSS: Main: 290119680 23:03:18 INFO - PROCESS | 2221 | 23:03:21 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-darkroom) 23:03:21 INFO - PROCESS | 2221 | RSS: Main: 329318400 23:03:21 INFO - PROCESS | 2221 | 23:03:24 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-desaturate) 23:03:25 INFO - PROCESS | 2221 | RSS: Main: 326594560 23:03:25 INFO - PROCESS | 2221 | 23:03:28 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51367/tests/kraken/driver.html?testName=json-parse-financial) 23:03:28 INFO - PROCESS | 2221 | RSS: Main: 327757824 23:03:28 INFO - PROCESS | 2221 | 23:03:30 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:03:30 INFO - PROCESS | 2221 | RSS: Main: 319180800 23:03:30 INFO - PROCESS | 2221 | 23:03:32 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:03:33 INFO - PROCESS | 2221 | RSS: Main: 326815744 23:03:33 INFO - PROCESS | 2221 | 23:03:35 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:03:36 INFO - PROCESS | 2221 | RSS: Main: 330354688 23:03:36 INFO - PROCESS | 2221 | 23:03:38 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:03:39 INFO - PROCESS | 2221 | RSS: Main: 334536704 23:03:39 INFO - PROCESS | 2221 | 23:03:42 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:03:43 INFO - PROCESS | 2221 | RSS: Main: 332103680 23:03:43 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51367/tests/kraken/driver.html?testName=ai-astar) 23:03:45 INFO - PROCESS | 2221 | RSS: Main: 396013568 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | __start_tp_report 23:03:45 INFO - PROCESS | 2221 | _x_x_mozilla_page_load 23:03:45 INFO - PROCESS | 2221 | _x_x_mozilla_page_load_details 23:03:45 INFO - PROCESS | 2221 | |i|pagename|runs| 23:03:45 INFO - PROCESS | 2221 | |0;ai-astar;114;112;113;114;120;166;163;113;111;111 23:03:45 INFO - PROCESS | 2221 | |1;audio-beat-detection;161;120;146;169;121;145;142;122;173;121 23:03:45 INFO - PROCESS | 2221 | |2;audio-dft;183;186;187;187;188;185;186;186;185;187 23:03:45 INFO - PROCESS | 2221 | |3;audio-fft;77;72;88;91;56;59;58;80;86;54 23:03:45 INFO - PROCESS | 2221 | |4;audio-oscillator;95;92;136;78;78;82;120;79;75;117 23:03:45 INFO - PROCESS | 2221 | |5;imaging-gaussian-blur;89;85;88;85;88;84;90;85;88;85 23:03:45 INFO - PROCESS | 2221 | |6;imaging-darkroom;93;94;94;95;93;94;94;95;94;95 23:03:45 INFO - PROCESS | 2221 | |7;imaging-desaturate;87;84;84;84;84;83;83;85;84;83 23:03:45 INFO - PROCESS | 2221 | |8;json-parse-financial;57;60;60;60;60;59;60;80;58;57 23:03:45 INFO - PROCESS | 2221 | |9;json-stringify-tinderbox;65;63;75;68;78;73;69;69;68;68 23:03:45 INFO - PROCESS | 2221 | |10;stanford-crypto-aes;83;83;83;83;83;82;83;80;80;82 23:03:45 INFO - PROCESS | 2221 | |11;stanford-crypto-ccm;142;141;128;138;137;138;135;127;178;173 23:03:45 INFO - PROCESS | 2221 | |12;stanford-crypto-pbkdf2;163;174;171;168;171;168;195;169;169;167 23:03:45 INFO - PROCESS | 2221 | |13;stanford-crypto-sha256-iterative;71;79;94;100;99;97;99;69;70;67 23:03:45 INFO - PROCESS | 2221 | __end_tp_report 23:03:45 INFO - PROCESS | 2221 | __start_cc_report 23:03:45 INFO - PROCESS | 2221 | _x_x_mozilla_cycle_collect,1997 23:03:45 INFO - PROCESS | 2221 | __end_cc_report 23:03:45 INFO - PROCESS | 2221 | __startTimestamp1460873025276__endTimestamp 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | ------- Summary: start ------- 23:03:45 INFO - PROCESS | 2221 | Number of tests: 14 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#0] ai-astar Cycles:10 Average:123.70 Median:114.00 stddev:21.66 (19.0%) stddev-sans-first:22.69 23:03:45 INFO - PROCESS | 2221 | Values: 114.0 112.0 113.0 114.0 120.0 166.0 163.0 113.0 111.0 111.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:145.50 stddev:20.66 (14.2%) stddev-sans-first:20.74 23:03:45 INFO - PROCESS | 2221 | Values: 161.0 120.0 146.0 169.0 121.0 145.0 142.0 122.0 173.0 121.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#2] audio-dft Cycles:10 Average:186.00 Median:186.50 stddev:1.41 (0.8%) stddev-sans-first:1.00 23:03:45 INFO - PROCESS | 2221 | Values: 183.0 186.0 187.0 187.0 188.0 185.0 186.0 186.0 185.0 187.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#3] audio-fft Cycles:10 Average:72.10 Median:78.50 stddev:14.33 (18.2%) stddev-sans-first:15.08 23:03:45 INFO - PROCESS | 2221 | Values: 77.0 72.0 88.0 91.0 56.0 59.0 58.0 80.0 86.0 54.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:78.50 stddev:21.61 (27.5%) stddev-sans-first:22.92 23:03:45 INFO - PROCESS | 2221 | Values: 95.0 92.0 136.0 78.0 78.0 82.0 120.0 79.0 75.0 117.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.11 (2.4%) stddev-sans-first:2.07 23:03:45 INFO - PROCESS | 2221 | Values: 89.0 85.0 88.0 85.0 88.0 84.0 90.0 85.0 88.0 85.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 23:03:45 INFO - PROCESS | 2221 | Values: 93.0 94.0 94.0 95.0 93.0 94.0 94.0 95.0 94.0 95.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:1.20 (1.4%) stddev-sans-first:0.67 23:03:45 INFO - PROCESS | 2221 | Values: 87.0 84.0 84.0 84.0 84.0 83.0 83.0 85.0 84.0 83.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:60.00 stddev:6.76 (11.3%) stddev-sans-first:7.00 23:03:45 INFO - PROCESS | 2221 | Values: 57.0 60.0 60.0 60.0 60.0 59.0 60.0 80.0 58.0 57.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:69.00 stddev:4.53 (6.6%) stddev-sans-first:4.48 23:03:45 INFO - PROCESS | 2221 | Values: 65.0 63.0 75.0 68.0 78.0 73.0 69.0 69.0 68.0 68.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.00 stddev:1.23 (1.5%) stddev-sans-first:1.27 23:03:45 INFO - PROCESS | 2221 | Values: 83.0 83.0 83.0 83.0 83.0 82.0 83.0 80.0 80.0 82.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:139.50 stddev:17.50 (12.5%) stddev-sans-first:18.55 23:03:45 INFO - PROCESS | 2221 | Values: 142.0 141.0 128.0 138.0 137.0 138.0 135.0 127.0 178.0 173.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:170.00 stddev:8.75 (5.1%) stddev-sans-first:8.72 23:03:45 INFO - PROCESS | 2221 | Values: 163.0 174.0 171.0 168.0 171.0 168.0 195.0 169.0 169.0 167.0 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - PROCESS | 2221 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.50 Median:86.50 stddev:14.44 (16.7%) stddev-sans-first:14.47 23:03:45 INFO - PROCESS | 2221 | Values: 71.0 79.0 94.0 100.0 99.0 97.0 99.0 69.0 70.0 67.0 23:03:45 INFO - PROCESS | 2221 | -------- Summary: end -------- 23:03:45 INFO - PROCESS | 2221 | 23:03:45 INFO - TEST-INFO | 2221: exit 0 23:03:46 INFO - TEST-OK | kraken | took 58504ms 23:03:46 INFO - SUITE-END | took 1173s 23:03:46 INFO - Completed test suite (00:19:33) 23:03:46 INFO - javascript benchmark 23:03:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3370.2753907909796, 3374.443216869216, 3425.1094715373997, 3463.1342164458883, 3474.91312717182, 5536.501714845663, 5635.3237611486575, 5652.34997975233, 5652.802151924683, 5664.0937450008005, 5629.9866024115645, 5647.745851436685, 5759.884802303952, 5776.711164441782, 5783.016887129188, 5087.79648814047, 5124.385073791141, 5148.073346797575, 5162.716050617215, 5289.391719952197, 5255.185446255837, 5281.471852814707, 5294.973525132388, 5307.575393968474, 5342.866428339284, 5116.692998420107, 5135.922961155586, 5194.0, 5217.686938783685, 5248.107821670306, 3713.832877520518, 4815.927761083586, 5229.869253268661, 5241.842744717665, 5265.921011184835, 5136.460671629462, 5180.559652429548, 5187.922181167285, 5193.402758682762, 5230.398504172031, 5476.0, 5542.612017158801, 5548.9722551386985, 5659.179418984266, 5662.5753068520025, 6126.448619624211, 6167.845803854894, 6176.135341052256, 6195.0, 6209.441150296495, 6196.8760624787255, 6239.87520249597, 6265.8120256392385, 6283.151774510428, 6285.622862628257, 5620.831375058755, 5660.745266462978, 5716.1711551825165, 5742.167385729046, 5768.307803063659, 5386.407495175527, 6106.786262480792, 6256.968715156395, 6287.308396076428, 6294.685265736786, 6041.8489537761025, 6056.031035034373, 6067.0292753160165, 6070.484008859296, 6082.60463069899, 5019.974900125476, 6064.757409703562, 6120.234970628671, 6159.291681456601, 6229.906551401733, 6088.634681919099, 6097.176881120992, 6153.907691384633, 6161.168242287324, 6167.722452489649, 6199.039148931923, 6201.720922558496, 6210.161628180228, 6221.3156552779155, 6224.190855188796, 4795.808167673254, 4886.218205087169, 4886.682365646293, 4888.828890988798, 4895.192293271642, 4368.017196130845, 4424.60178583929, 4449.488308844524, 4454.086912182995, 4456.46522417312, 5610.831675049755, 5611.0, 5616.85957851057, 5626.662400255997, 5627.662340259598, 6113.385090541919, 6114.875284800388, 6159.353267906805, 6239.781607643801, 6262.060690896402, 5571.359293681197, 5606.803761868315, 5609.747561359749, 5612.607117501736, 5637.23897273871, 5246.842594722164, 5303.814366497154, 5315.0, 5325.946740532545, 5334.67991920486, 6198.039303907902, 6247.96876015617, 6276.403741644536, 6279.403456671609, 6281.089242059968, 6203.069539569101, 6209.410106039919, 6229.470495007975, 6232.875342493216, 6244.188255526752, 6172.475339596184, 6260.843478912881, 6271.3728627136925, 6290.182276304051, 6304.211973503312, 6066.696665166813, 6139.416755408229, 6140.293866205444, 6155.446009858955, 6156.907646385218, 6065.181200537871, 6205.131281620667, 6222.408871157233, 6242.937570624417, 6244.250689917239, 5873.148393482836, 5929.347771745191, 6061.393860613904, 6067.180930574493, 6078.483328917092, 4841.564259216688, 6016.308124565731, 6025.578209525291, 6028.0, 6088.78689245892, 5792.247007888947, 5839.474447299764, 5854.590178687451, 5862.648241105548, 5864.06175011996, 5784.421557844182, 5986.102084687332, 6010.398960103955, 6038.698065096815, 6060.878782424415, 5886.6173698709445, 5894.528437725057, 5895.086261629454, 5895.94104058954, 5920.644761314335], "value": 5632.218717018096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.475916703713, 532.5100907165408, 552.991705124423, 576.85290250986, 584.033424682151, 21696.566068678618, 29972.25069373267, 29986.85006574967, 30019.24951876204, 30226.99999999997, 39333.409998849966, 39467.407988880186, 40175.995600110014, 40188.99527511813, 40496.0, 9452.196563292146, 9643.951780241088, 9694.806103877918, 9697.757556061084, 9703.369280996749, 8577.571121443934, 9522.476263805487, 9701.563429645683, 9716.514174291293, 11659.533618655287, 11465.885341146564, 11638.767224655501, 11650.0, 11657.533698652087, 11663.766724665544, 11609.129315301345, 11702.765944681143, 11705.590304339306, 11706.12204084697, 11711.648650540455, 11551.884481155164, 11691.0, 11693.0, 11706.297622142698, 11737.178397512178, 37831.62168378309, 39942.0, 39965.60034399648, 40049.0, 40065.19869602621, 3264.2002709336366, 3362.5796775403073, 3405.3529829332347, 3487.808170550619, 3572.8570857165682, 25507.362315942064, 28984.855075724485, 29698.515074246417, 30226.54660180099, 30810.53784193239, 2703.4998525272886, 2710.891564337424, 2717.945641087167, 2726.836389816617, 2826.4771017361854, 387375.0, 407648.0, 410985.0, 411281.0, 411373.99999999703, 61467.0, 61851.38148618456, 62326.441838954386, 62483.0, 62501.56241406676, 65634.34365656378, 65988.0, 66062.33937660659, 66135.0, 66136.6693166531, 16748.24632891523, 16947.915260423866, 16953.406630767862, 16985.15074246283, 17005.744913826056, 12955.481780728855, 13019.674508137372, 13023.869761302265, 13026.80459793104, 13035.674108147372, 16329.18354082291, 19022.334218302294, 19797.901010494854, 19823.702644460343, 19903.701444478054, 7990.0, 8148.755537333889, 8149.470284431493, 8167.0, 8230.0, 990.277097718655, 1052.3580615824342, 1053.6786280184617, 1059.5496913811753, 1131.4286285425972, 27584.44831103407, 27900.0, 28406.0, 30032.0, 30215.0, 929.697848199338, 1053.3890343600692, 1116.625930313338, 1117.8658560972735, 1164.3130552973787, 14285.928570357082, 14618.780718289234, 14733.325710990555, 14836.851631483763, 14904.776428353583, 616660.9166954136, 628204.0, 631639.0, 631712.0, 633950.0, 58804.7059764707, 59667.403325967054, 59772.70113649491, 59785.40214597798, 59897.40102599005, 1453.4694836384645, 1481.5185064853965, 1508.3815635589356, 1522.5812901452189, 1523.177484158564, 28154.71845281521, 28160.0, 28474.715252848036, 28673.996410125546, 28894.71105289004, 50354.244686329, 50711.91457025181, 50930.745346273034, 50960.49039509558, 51005.0, 512825.0, 515223.0, 515328.0, 516661.0, 517110.0, 27064.72935270622, 41841.79079104585, 42778.93052673708, 43255.0, 43918.780406097765, 36007.63992360043, 42445.0, 44635.0, 44724.32913506235, 45914.770426147654, 35773.64226357703, 36138.63861361458, 36158.73444429432, 37119.62880371162, 37223.25553488861, 35356.64643353636, 35650.0, 35824.82087589546, 35918.43264294039, 36306.636933630325, 32876.34247315088, 33674.98975030753, 35778.821105894305, 37010.44484332683, 37188.07029824276, 24341.026358945444, 30870.699217539826, 32423.189420263738, 32831.8358408216, 32984.67015329816, 17718.291268349636, 24259.0, 24522.877385613672, 24895.00419983253, 25025.624615630415], "value": 19969.060715428168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.2133163020916, 379.3569899021159, 385.68373933374625, 390.7011136480593, 400.94587230723846, 19038.619227615458, 20458.283960061402, 20800.47998800031, 20809.959502024896, 20819.583608327823, 20364.08361623727, 20852.68720969183, 21301.25445609404, 21652.458688532795, 21684.566308673817, 5333.280007199037, 5774.740136693859, 5984.281886173643, 6007.6395416274945, 6020.729067191987, 5679.517241034517, 5902.0, 5958.702064896759, 5962.224910761595, 5971.790987315445, 5580.358258800249, 5853.7951171709, 5875.559333050039, 5880.117982302668, 5885.529157667398, 5767.971160144172, 5815.73829177688, 5852.76588936442, 5867.0, 5874.3244526879525, 4143.606357396073, 5112.795488180468, 5152.639315247934, 5153.613478989091, 5159.535641792219, 24587.38531536708, 25375.492490150093, 25786.226413207634, 25966.09118680837, 26240.60639090415, 1949.6198241342943, 2058.197303051811, 2206.7793220677963, 2284.851484653492, 2329.2313536533075, 14792.964492485531, 14975.176365299903, 15018.69962600742, 15071.171085590287, 15288.77066843987, 1739.3738254228467, 1815.1922394534438, 1827.7349784281337, 1829.2500074969332, 1832.230463205457, 106919.46540267326, 107219.92780072255, 108204.91795082027, 108525.45737271263, 110506.44746776295, 8938.061503542165, 9431.858522122173, 9646.565904534313, 9696.6121355145, 10398.532066056894, 9745.756356091013, 12033.63899083029, 12059.0, 12122.151449398634, 12162.5134994601, 6353.936460635427, 7926.603669816486, 7939.483933544301, 8058.959705201437, 8072.434929554992, 8372.874406883902, 8380.497170169809, 8476.152384761597, 8482.0, 8486.618102185417, 3694.0949467380665, 3722.4974628425357, 3726.4782930389765, 3791.8483260669263, 3792.2984247914223, 1825.4158669225985, 1861.702127659568, 1882.1542969235027, 1882.8493720502327, 1885.340130954155, 910.9407888487227, 919.6045700348781, 932.7613727054986, 1009.3035805294323, 1018.4144117132678, 15033.699326013419, 16370.918145409434, 19606.0, 19630.607387852164, 20059.699104513445, 940.1773448232797, 959.8512230604267, 967.3905439573024, 980.9950950245346, 990.6730778842965, 9127.991841794794, 11040.447977601088, 11057.889421105847, 11068.778624427467, 11081.33511989267, 602322.0, 612650.0, 620210.0, 621313.0, 626604.0, 128352.52298508708, 131210.0, 133962.0, 138118.0, 138971.0, 1042.7653777899911, 1052.0741747262514, 1065.824139017069, 1101.8512500812287, 1104.320842681745, 18262.08689565543, 18524.44426667202, 18595.81404185941, 18595.90702046535, 18636.813631863508, 50712.0, 51646.48353516567, 52170.47829521656, 52302.215466767266, 52895.47104528906, 52635.0, 73622.0, 73708.26291737014, 74396.0, 75252.62373688097, 6389.648569328546, 6835.863282734417, 7899.565523896241, 7927.128015918129, 7942.801429964297, 8988.235999940078, 11906.702332441759, 12219.327936963553, 12294.139410241198, 12707.0, 10635.20235982289, 10916.454177290945, 10951.945240274066, 11135.220534562826, 11270.154738394816, 10064.948041566864, 10418.166546676399, 10612.893871061191, 10724.873888241622, 10994.395308258123, 10410.583576656849, 10657.626983055867, 10977.945110274397, 11036.172287078662, 11310.491027903929, 7408.444366672414, 7950.602469876599, 7961.243681850215, 8007.119216886254, 8226.136255693305, 9367.812643747224, 10697.732556686145, 10847.511861966388, 10868.347899126078, 11003.61487347939], "value": 9315.031154629967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.0610338021834, 1555.657755293835, 1577.313868467217, 1785.2858856457415, 1938.6607343714848, 7926.999999999993, 8764.956175219122, 8952.955235223832, 8967.865482017774, 8997.91002089979, 8533.829323413527, 8762.21140097391, 8773.385862989593, 8775.210231079218, 8776.473411595309, 2124.6600543912978, 2280.635098384259, 2322.674825524428, 2358.9528209435803, 2359.8230132740027, 2430.970139933158, 2456.9754302457013, 2460.7047154341412, 2461.716902556211, 2465.1248806673643, 2386.820988425875, 2407.3620490570015, 2423.2245681381964, 2424.612062070069, 2429.101232543961, 2079.1787244038596, 2307.10023090993, 2391.7488663690237, 2402.6516155157406, 2406.6871306730186, 2403.0267741564694, 2421.6609674645565, 2424.866632335221, 2427.0, 2431.4043059450546, 8603.0, 8679.218870301704, 8683.869741953877, 8684.13158684133, 8688.218060374596, 5021.698698078127, 5124.499886952312, 5259.237410575483, 5303.071962406563, 5324.361076670785, 2898.9130326090253, 3382.7970321780526, 3418.9829050854837, 3447.8620855165764, 3470.1151206442196, 1018.3482571154467, 1589.7853789738353, 1637.1486826850091, 1671.779600891356, 1673.840985106413, 11333.206675532716, 11447.885521144764, 11454.140939429577, 11486.310821350746, 11486.42567871603, 4367.781610919441, 4402.537733537996, 4529.139463501924, 4567.223571992769, 4588.2658774596575, 9080.727578172533, 9093.136152065543, 9095.31785116119, 9095.727128186165, 9102.499362535124, 5192.935448233103, 5193.324867767242, 5200.81797137106, 5229.424763276036, 5247.10799164143, 4355.455568053993, 4487.147441986078, 4714.245720684674, 4806.7836947337455, 4818.084563932842, 3590.6588874056924, 3616.367135751233, 3691.2802003609577, 3729.1982223821465, 3734.047817806442, 1121.8092924202906, 1129.8587676540433, 1133.4729350851894, 1139.4530625299903, 1142.9085673146128, 772.6098320348189, 836.7489753074053, 890.1365675294953, 902.9683961061331, 928.9767755806024, 4737.265723812815, 5495.093309603951, 5512.972435137799, 5639.351474580476, 5778.0, 812.9552874591955, 884.4428010353436, 911.7857303533664, 929.2705226397246, 940.4169414962766, 3577.5170352002665, 3688.797116158637, 3742.8877133686033, 3766.9075967968984, 3785.2240290740333, 9858.211343092535, 10185.439800810878, 10541.209409294333, 10657.307275027099, 10666.679999600025, 10623.20325975555, 10623.52194151265, 10669.253152279422, 10807.135429165804, 10850.837237441445, 8021.438499304993, 8103.878441823377, 8207.507549546926, 8223.95888020556, 8363.205495478041, 5271.815486457956, 5689.260396148474, 5783.913241301299, 5789.2763404574425, 5922.674252915958, 9532.046795320413, 9596.712098636774, 9846.901530984598, 9955.701328960144, 9961.551730172016, 9777.951110244689, 9819.607215711292, 9876.950615246878, 10028.348157369748, 10065.849012264676, 6854.691538880663, 7106.0, 7125.643717814192, 7322.304381083789, 7573.129090154703, 8106.392020598597, 8160.14318496573, 8197.0, 8214.671413143407, 8225.712100076707, 7176.96411517939, 7297.270272972661, 7306.634668266672, 7428.2571742825285, 7469.551826890404, 7255.963720181366, 7316.012338334257, 7354.696220145636, 7392.852142957218, 7445.255474452511, 6217.285012223652, 6753.628550429774, 6850.143732033496, 6863.416609588241, 6865.27914568963, 3955.7033222507853, 4667.929981050355, 4677.859664210079, 4813.95186048149, 4891.486393928586, 4759.3336932830125, 4835.29888166221, 4872.1717308057005, 4903.215485522299, 5104.489551044866], "value": 4520.807624857255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9073278016595, 312.1618454449801, 316.80041573808495, 318.7593367007907, 325.2778830995193, 4592.609628181604, 4795.25673520604, 5792.884142317156, 5856.121581762738, 5953.22608060952, 4697.553732395426, 5401.4868587484225, 5760.222369980052, 5816.243888294526, 5821.621594596349, 3675.5589329280456, 3950.6839452843847, 4305.052888364555, 4324.20002299574, 4438.3799913031835, 3789.2989796887528, 4388.758618275993, 4395.846145384912, 4397.626201772853, 4439.911201775963, 4298.161858437606, 4403.823847046115, 4424.960134368421, 4425.7565833879125, 4431.202383570956, 4063.5326937402283, 4096.897577560555, 4315.568443155691, 4320.179165958458, 4447.844325448594, 2900.7244311790346, 3684.7420680552373, 3710.9609309393145, 3711.9072023199365, 3754.662080412776, 4851.175300198975, 4862.076205520975, 5130.692158470466, 5598.748056337475, 5641.802536911188, 4672.813087476497, 4745.59662428694, 4886.0, 4915.950840491585, 4921.926171107436, 4798.928016079727, 5867.76528938842, 6065.120557519135, 6081.787137450212, 6122.602030867979, 3657.2868290683327, 3784.8675296364772, 4026.57720939303, 4035.899102522431, 4057.736247143926, 6072.787452439144, 6394.104825324458, 6487.57830741005, 6505.284418713938, 6559.639219842907, 4065.5324637666454, 4074.4295798587627, 4101.507819061731, 4107.404426358161, 4125.422440858282, 3633.5276414066, 3693.9076523087138, 4169.228692691861, 4175.645070169009, 4191.1827193697545, 4561.315802629566, 4836.637252206098, 4928.753562321869, 4931.18635425158, 4938.4320803107385, 6018.21763170785, 6060.484858786965, 6063.575549711566, 6079.179310793074, 6108.908366374508, 1297.7022977022978, 1335.4724883670895, 1426.3866537388815, 1445.985540144585, 1455.6360909772557, 771.3173841150622, 826.7271800305884, 881.5415983688574, 898.8606765951288, 954.2604481526733, 5235.3717553893775, 5641.435856414326, 5646.971765141148, 5691.516221121168, 5713.600047996601, 5327.254184414185, 6265.937340626535, 6336.22994318389, 6342.5243106767175, 6350.983842585164, 4871.025794841046, 5378.946210537844, 5486.807961721321, 5647.548196144298, 5714.0, 2826.901058462944, 3138.5919830422354, 3236.7410607151373, 3271.329377477611, 3323.955767203201, 5373.570114390839, 5947.769586405098, 6328.46208072319, 6535.836604084936, 6588.604683718992, 5082.796688132507, 5341.0, 6111.594333303365, 6158.076288556752, 6417.422431981144, 5380.784768609212, 5927.3776253493625, 6570.868582628321, 6590.0, 6595.67021648906, 5951.791687290924, 6246.750129994749, 6257.4681152102585, 6293.213348331458, 6309.589876658002, 6162.198914141133, 6167.784127555514, 6180.055955235704, 6209.068639704081, 6225.564210505221, 6218.036204388327, 6549.967250163719, 6551.967240163769, 6566.0479230512265, 6568.540202185801, 3586.2110335726097, 4132.876013719593, 4134.214499245134, 4134.979325103355, 4136.21411931732, 5364.704941228114, 5900.881982360414, 5902.881942361043, 5959.195508606456, 6292.968535157478, 4986.601071914166, 5836.299644042742, 6064.636121832704, 6112.1748563945075, 6118.418750218722, 5660.867826434647, 5675.233843431084, 6029.758809647565, 6055.666938318434, 6114.144019837137, 4509.616682582017, 5745.7988970387505, 5925.881482370414, 5944.1975333331175, 5972.970135149297, 5229.607779416635, 5832.766689332379, 6148.3236843948025, 6192.721327540181, 6195.535334849814, 4969.887797685897, 5398.354806145039, 6028.668423236764, 6046.7581296749395, 6108.511319094373], "value": 4373.539279021671, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.5901282192219, 738.1916801102791, 750.6359415683394, 775.4378075894973, 792.1721800718249, 14954.700905981887, 15365.231738413091, 15385.692286154299, 15397.230138493087, 15400.075995440267, 11896.821547676791, 12174.208676436025, 12243.69390765229, 12864.163829351086, 13060.673483162926, 747.3946103656028, 776.0377132355881, 934.6822080492631, 939.9671011514598, 941.8869735631733, 919.9310051746112, 922.6632279218071, 935.7567032571512, 939.2298315381356, 939.642935684439, 755.7732680195946, 979.6718099436662, 980.0689345122126, 983.8917719050899, 988.574912787501, 931.2689538712112, 965.1169180200144, 971.159946646146, 972.8492083727034, 974.3861367338602, 734.5409119300438, 735.1692587376231, 736.495500582103, 737.5832654550162, 738.2838646512874, 14966.62583435412, 15006.249687515581, 15006.399744010227, 15049.0, 15097.396104155821, 12533.811992820116, 12605.747885042249, 12678.746425071447, 12921.353932303347, 13231.933840330737, 9945.801083978353, 10214.08073273409, 10249.53877075526, 10533.052025317758, 10560.894391056068, 3504.9299014019575, 3527.153483164022, 3531.9117022074397, 3588.1209103769743, 3647.598764135943, 80969.0, 83910.0, 84026.57986710027, 84125.57937210336, 84456.15543844482, 9447.669331573363, 9706.514674266258, 9712.174465170398, 9752.26857985654, 10084.74788130288, 22189.445263868532, 22221.666674999888, 22228.444288892908, 22232.888355582156, 22257.777422225572, 7429.145648250413, 7541.132769731441, 7549.622518874145, 7625.542467451971, 7682.692692292358, 4033.3546632538655, 4055.3511438169753, 4067.328890732996, 4130.132672138824, 4133.028738246509, 3583.605803361628, 3688.4467329900194, 3696.149885526344, 3699.2416554606243, 3699.5560532736245, 628.1143587541544, 655.5476721062452, 656.8587753632896, 657.1063353838775, 660.2044536333707, 521.0828941063755, 522.801335492518, 538.2506852728632, 551.4292707048186, 597.8475488750341, 7654.966579511806, 7782.182870798598, 7824.687012519549, 7950.0, 7984.321332686786, 499.2910067703869, 528.9497497737709, 544.8692313844649, 553.3332334536873, 595.3867516458055, 3497.143199916037, 3696.500972368696, 3732.869349572806, 3883.514560679915, 3908.4528166056775, 83040.58479707684, 83682.0, 84176.57911710402, 84526.0, 85447.0, 73926.26073739193, 74125.25874741183, 74729.25270747223, 75088.62455687686, 76877.0, 66119.0, 66340.66829665918, 66432.0, 66768.33231667621, 68406.65796671085, 7352.154502232312, 7355.264473552602, 7444.962775186089, 7542.547447153188, 7572.886406703794, 42515.7874210627, 43206.543476522435, 43568.69783096133, 43660.78169609132, 44401.11197776091, 53018.73490632522, 56931.57671058091, 58623.70688146532, 58875.70562147333, 59175.81648367095, 10283.485825708834, 10378.117859981987, 10484.108850747773, 10572.788544229226, 10582.947085264525, 11198.664040078811, 11492.425378730863, 11967.281963082243, 12011.0, 12373.127114548133, 11139.387333696724, 11219.326840389604, 11315.490802913724, 11575.652730418102, 11740.58907938218, 11726.374687430436, 11778.646640600795, 11788.646340609796, 11832.526698931946, 12197.024238060756, 10750.139988801033, 11196.94401527987, 11416.771664566828, 11544.0, 11609.941950290195, 9436.858447123435, 9691.90984131404, 9771.912236337463, 9790.167835734042, 10056.949715251669, 8969.730908072768, 9027.95486022592, 9070.319726020707, 9074.546272686472, 9281.257499399897], "value": 6776.683421290935, "unit": "score"}], "name": "dromaeo_css", "value": 7209.224193587433, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 113.0, 114.0, 120.0, 166.0, 163.0, 113.0, 111.0, 111.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 120.0, 146.0, 169.0, 121.0, 145.0, 142.0, 122.0, 173.0, 121.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 187.0, 187.0, 188.0, 185.0, 186.0, 186.0, 185.0, 187.0], "value": 186.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 88.0, 91.0, 56.0, 59.0, 58.0, 80.0, 86.0, 54.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 136.0, 78.0, 78.0, 82.0, 120.0, 79.0, 75.0, 117.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 88.0, 84.0, 90.0, 85.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 84.0, 84.0, 83.0, 83.0, 85.0, 84.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 80.0, 58.0, 57.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 75.0, 68.0, 78.0, 73.0, 69.0, 69.0, 68.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 83.0, 82.0, 83.0, 80.0, 80.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 128.0, 138.0, 137.0, 138.0, 135.0, 127.0, 178.0, 173.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 174.0, 171.0, 168.0, 171.0, 168.0, 195.0, 169.0, 169.0, 167.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 94.0, 100.0, 99.0, 97.0, 99.0, 69.0, 70.0, 67.0], "value": 84.5, "unit": "score"}], "name": "kraken", "value": 1496.5000000000002, "alertThreshold": 2.0}]} 23:03:46 INFO - Return code: 0 23:03:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:03:46 ERROR - # TBPL SUCCESS # 23:03:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:03:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:03:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:03:46 INFO - 2016-04-16 23:03:46.576 screenresolution[2225:16514] starting screenresolution argv=screenresolution get 23:03:46 INFO - 2016-04-16 23:03:46.579 screenresolution[2225:16514] Display 0: 1600x1200x32@60 23:03:46 INFO - 2016-04-16 23:03:46.585 screenresolution[2226:16517] starting screenresolution argv=screenresolution list 23:03:46 INFO - Available Modes on Display 0 23:03:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:03:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:03:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:03:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:03:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:03:46 INFO - Intel Iris: 23:03:46 INFO - Chipset Model: Intel Iris 23:03:46 INFO - Type: GPU 23:03:46 INFO - Bus: Built-In 23:03:46 INFO - VRAM (Dynamic, Max): 1536 MB 23:03:46 INFO - Vendor: Intel (0x8086) 23:03:46 INFO - Device ID: 0x0a2e 23:03:46 INFO - Revision ID: 0x0009 23:03:46 INFO - Displays: 23:03:46 INFO - Display: 23:03:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:03:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:03:46 INFO - Display Serial Number: 1600x1200 60 23:03:46 INFO - Main Display: Yes 23:03:46 INFO - Mirror: Off 23:03:46 INFO - Online: Yes 23:03:46 INFO - Rotation: Supported 23:03:46 INFO - Return code: 0 23:03:46 INFO - Running post-action listener: _resource_record_post_action 23:03:46 INFO - Running post-run listener: _resource_record_post_run 23:03:47 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 798720; Write bytes: 691134976; Read time: 11; Write time: 1859 23:03:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379306496; Write bytes: 372061696; Read time: 28176; Write time: 693 23:03:47 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 798720; Write bytes: 294964224; Read time: 11; Write time: 1117 23:03:47 INFO - Running post-run listener: _upload_blobber_files 23:03:47 INFO - Blob upload gear active. 23:03:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:03:47 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 23:03:47 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'] 23:03:47 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 23:03:48 INFO - (blobuploader) - INFO - Open directory for files ... 23:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:03:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:03:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:03:49 INFO - (blobuploader) - INFO - Done attempting. 23:03:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:03:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:03:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:03:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:03:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:03:50 INFO - (blobuploader) - INFO - Done attempting. 23:03:50 INFO - (blobuploader) - INFO - Iteration through files over. 23:03:50 INFO - Return code: 0 23:03:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:03:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:03:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ea4e7844c55ce08fa39b8941aee4d9c8dd678f25a439b405b78ddd9d9290e9ee1bd5812ee9d5b3addf4d23e433c875805b20a0f8e4f8094b56518bea9fe5aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4425b397e1cf5e0ed9f826cbdc8972e9ba3cf7bce558808e9b00b30ed0057bd896306d361be82471e977b445a835850a8c0c7424fa20eed341c39f9b00bc760"} 23:03:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:03:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:03:50 INFO - Contents: 23:03:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ea4e7844c55ce08fa39b8941aee4d9c8dd678f25a439b405b78ddd9d9290e9ee1bd5812ee9d5b3addf4d23e433c875805b20a0f8e4f8094b56518bea9fe5aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4425b397e1cf5e0ed9f826cbdc8972e9ba3cf7bce558808e9b00b30ed0057bd896306d361be82471e977b445a835850a8c0c7424fa20eed341c39f9b00bc760"} 23:03:50 INFO - Running post-run listener: copy_logs_to_upload_dir 23:03:50 INFO - Copying logs to upload dir... 23:03:50 INFO - mkdir: /builds/slave/test/build/upload/logs 23:03:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.663223 ========= master_lag: 21.55 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-04-16 23:04:11.928041) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 23:04:11.935300) ========= 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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ea4e7844c55ce08fa39b8941aee4d9c8dd678f25a439b405b78ddd9d9290e9ee1bd5812ee9d5b3addf4d23e433c875805b20a0f8e4f8094b56518bea9fe5aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4425b397e1cf5e0ed9f826cbdc8972e9ba3cf7bce558808e9b00b30ed0057bd896306d361be82471e977b445a835850a8c0c7424fa20eed341c39f9b00bc760"} build_url:https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ea4e7844c55ce08fa39b8941aee4d9c8dd678f25a439b405b78ddd9d9290e9ee1bd5812ee9d5b3addf4d23e433c875805b20a0f8e4f8094b56518bea9fe5aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4425b397e1cf5e0ed9f826cbdc8972e9ba3cf7bce558808e9b00b30ed0057bd896306d361be82471e977b445a835850a8c0c7424fa20eed341c39f9b00bc760"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 23:04:12.031432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-16 23:04:12.031784) ========= 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.BaVNf3NQjo/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.AhzHsbWKWj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005284 ========= master_lag: 3.93 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-16 23:04:15.966696) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 23:04:15.967101) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 23:04:15.984715) ========= ========= Total master_lag: 25.64 =========