builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0063 starttime: 1460872100.31 results: success (0) buildid: 20160416210219 builduid: ce7806e457d84634b0dbd07c2fd7f25a revision: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.306731) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.307163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.307747) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.366832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.367092) ========= 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.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-04-16 22:48:20 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.491449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.875743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.876051) ========= 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.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.026720 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:20.925460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 22:48:20.925820) ========= 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.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:20,998 truncating revision to first 12 chars 2016-04-16 22:48:20,998 Setting DEBUG logging. 2016-04-16 22:48:20,998 attempt 1/10 2016-04-16 22:48:20,998 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:48:21,775 unpacking tar archive at: fx-team-2f58e7ce1ab8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.016201 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 22:48:21.958795) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:21.959099) ========= script_repo_revision: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:21.959487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:21.959772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 22:48:21.973431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-16 22:48:21.973855) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:22 INFO - MultiFileLogger online at 20160416 22:48:22 in /builds/slave/test 22:48:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:48:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:48:22 INFO - {'append_to_log': False, 22:48:22 INFO - 'base_work_dir': '/builds/slave/test', 22:48:22 INFO - 'blob_upload_branch': 'Fx-Team', 22:48:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:48:22 INFO - 'branch': 'Fx-Team', 22:48:22 INFO - 'buildbot_json_path': 'buildprops.json', 22:48:22 INFO - 'config_files': ('talos/mac_config.py',), 22:48:22 INFO - 'default_actions': ('clobber', 22:48:22 INFO - 'read-buildbot-config', 22:48:22 INFO - 'download-and-extract', 22:48:22 INFO - 'populate-webroot', 22:48:22 INFO - 'create-virtualenv', 22:48:22 INFO - 'install', 22:48:22 INFO - 'run-tests'), 22:48:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:48:22 INFO - 'download_minidump_stackwalk': True, 22:48:22 INFO - 'download_symbols': 'ondemand', 22:48:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:48:22 INFO - 'tooltool.py': '/tools/tooltool.py', 22:48:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:48:22 INFO - '/tools/misc-python/virtualenv.py')}, 22:48:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:48:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:48:22 INFO - 'installer_path': 'installer.exe', 22:48:22 INFO - 'log_level': 'info', 22:48:22 INFO - 'log_name': 'talos', 22:48:22 INFO - 'log_to_console': True, 22:48:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:48:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:48:22 INFO - 'opt_config_files': (), 22:48:22 INFO - 'pip_index': False, 22:48:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:48:22 INFO - 'cmd': ('bash', 22:48:22 INFO - '-c', 22:48:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:48:22 INFO - 'enabled': True, 22:48:22 INFO - 'halt_on_failure': False, 22:48:22 INFO - 'name': 'check_screen_resolution'},), 22:48:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:48:22 INFO - 'cmd': ('bash', 22:48:22 INFO - '-c', 22:48:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:48:22 INFO - 'enabled': True, 22:48:22 INFO - 'halt_on_failure': False, 22:48:22 INFO - 'name': 'check_screen_resolution'},), 22:48:22 INFO - 'run_cmd_checks_enabled': True, 22:48:22 INFO - 'sps_profile': False, 22:48:22 INFO - 'sps_profile_interval': 0, 22:48:22 INFO - 'suite': 'dromaeojs-e10s', 22:48:22 INFO - 'system_bits': '32', 22:48:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:48:22 INFO - 'title': 't-yosemite-r7-0063', 22:48:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:48:22 INFO - 'use_talos_json': True, 22:48:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:48:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:48:22 INFO - 'work_dir': 'build'} 22:48:22 INFO - ##### 22:48:22 INFO - ##### Running clobber step. 22:48:22 INFO - ##### 22:48:22 INFO - Running pre-action listener: _resource_record_pre_action 22:48:22 INFO - Running main action method: clobber 22:48:22 INFO - rmtree: /builds/slave/test/build 22:48:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:48:23 INFO - Running post-action listener: _resource_record_post_action 22:48:23 INFO - ##### 22:48:23 INFO - ##### Running read-buildbot-config step. 22:48:23 INFO - ##### 22:48:23 INFO - Running pre-action listener: _resource_record_pre_action 22:48:23 INFO - Running main action method: read_buildbot_config 22:48:23 INFO - Using buildbot properties: 22:48:23 INFO - { 22:48:23 INFO - "project": "", 22:48:23 INFO - "product": "firefox", 22:48:23 INFO - "script_repo_revision": "production", 22:48:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:48:23 INFO - "repository": "", 22:48:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:48:23 INFO - "buildid": "20160416210219", 22:48:23 INFO - "pgo_build": "False", 22:48:23 INFO - "basedir": "/builds/slave/test", 22:48:23 INFO - "buildnumber": 605, 22:48:23 INFO - "slavename": "t-yosemite-r7-0063", 22:48:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:48:23 INFO - "platform": "yosemite_r7", 22:48:23 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:48:23 INFO - "branch": "fx-team", 22:48:23 INFO - "repo_path": "integration/fx-team", 22:48:23 INFO - "revision": "2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799", 22:48:23 INFO - "stage_platform": "macosx64", 22:48:23 INFO - "builduid": "ce7806e457d84634b0dbd07c2fd7f25a", 22:48:23 INFO - "slavebuilddir": "test" 22:48:23 INFO - } 22:48:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:48:23 INFO - Running post-action listener: _resource_record_post_action 22:48:23 INFO - ##### 22:48:23 INFO - ##### Running download-and-extract step. 22:48:23 INFO - ##### 22:48:23 INFO - Running pre-action listener: _resource_record_pre_action 22:48:23 INFO - Running main action method: download_and_extract 22:48:23 INFO - mkdir: /builds/slave/test/build/tests 22:48: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:48:23 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:48:23 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:48:23 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:48:23 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:48:23 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:48:25 INFO - Downloaded 1199 bytes. 22:48:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:48:25 INFO - Using the following test package requirements: 22:48:25 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:48:25 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:48:25 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:48:25 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'jsshell-mac.zip'], 22:48:25 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:48:25 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:48:25 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:48:25 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:48:25 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:48:25 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:48:25 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:48:25 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:25 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:48:25 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:48:25 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:48:25 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:48:25 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:48:27 INFO - Downloaded 18031996 bytes. 22:48:27 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:48:27 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:48:30 INFO - Return code: 0 22:48:30 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:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:30 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:48:30 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:48:30 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:48:30 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:48:30 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:48:33 INFO - Downloaded 18031996 bytes. 22:48:33 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:48:33 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:48:36 INFO - Return code: 0 22:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:36 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:48:36 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:48:36 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:48:36 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:48:36 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:48:37 INFO - Downloaded 10841076 bytes. 22:48:37 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:48:37 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:48:38 INFO - Return code: 0 22:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:38 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:48:38 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:48:38 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:48:38 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:48:38 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:48:41 INFO - Downloaded 109839867 bytes. 22:48:41 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:48:41 INFO - mkdir: /builds/slave/test/properties 22:48:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:48:41 INFO - Writing to file /builds/slave/test/properties/build_url 22:48:41 INFO - Contents: 22:48:41 INFO - build_url:https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:48:42 INFO - Running post-action listener: _resource_record_post_action 22:48:42 INFO - Running post-action listener: set_extra_try_arguments 22:48:42 INFO - ##### 22:48:42 INFO - ##### Running populate-webroot step. 22:48:42 INFO - ##### 22:48:42 INFO - Running pre-action listener: _resource_record_pre_action 22:48:42 INFO - Running main action method: populate_webroot 22:48:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:48:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:48:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:48:42 INFO - '--tppagecycles', 22:48:42 INFO - '7'], 22:48:42 INFO - 'tests': ['tsvgm']}}, 22:48:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:48:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:48:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:48:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:48:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['damp', 'tps']}, 22:48:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'tests': ['damp', 'tps']}, 22:48:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['dromaeo_dom']}, 22:48:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:48:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'other-e10s': {'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['a11yr', 22:48:42 INFO - 'ts_paint', 22:48:42 INFO - 'tpaint', 22:48:42 INFO - 'sessionrestore', 22:48:42 INFO - 'sessionrestore_no_auto_restore', 22:48:42 INFO - 'tabpaint']}, 22:48:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['tsvgx', 22:48:42 INFO - 'tsvgr_opacity', 22:48:42 INFO - 'tart', 22:48:42 INFO - 'tscrollx', 22:48:42 INFO - 'cart']}, 22:48:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:48:42 INFO - 'tsvgr_opacity', 22:48:42 INFO - 'tart', 22:48:42 INFO - 'tscrollx', 22:48:42 INFO - 'cart']}, 22:48:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'talos_options': ['--disable-e10s'], 22:48:42 INFO - 'tests': ['tp5o']}, 22:48:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'tests': ['tp5o']}, 22:48:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'talos_options': ['--disable-e10s', 22:48:42 INFO - '--xperf_path', 22:48:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:42 INFO - 'tests': ['tp5n']}, 22:48:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:48:42 INFO - 'talos_options': ['--xperf_path', 22:48:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:42 INFO - 'tests': ['tp5n']}}, 22:48:42 INFO - 'talos.zip': {'path': '', 22:48:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:48:42 INFO - Running post-action listener: _resource_record_post_action 22:48:42 INFO - ##### 22:48:42 INFO - ##### Running create-virtualenv step. 22:48:42 INFO - ##### 22:48:42 INFO - Running pre-action listener: _resource_record_pre_action 22:48:42 INFO - Running main action method: create_virtualenv 22:48:42 INFO - Creating virtualenv /builds/slave/test/build/venv 22:48:42 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:48:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:48:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:48:42 INFO - Using real prefix '/tools/python27' 22:48:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:48:43 INFO - Installing distribute.............................................................................................................................................................................................done. 22:48:45 INFO - Installing pip.................done. 22:48:45 INFO - Return code: 0 22:48:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:48:45 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:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:45 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:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:45 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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:45 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:48:45 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:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:45 INFO - 'HOME': '/Users/cltbld', 22:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:45 INFO - 'LOGNAME': 'cltbld', 22:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:45 INFO - 'MOZ_NO_REMOTE': '1', 22:48:45 INFO - 'NO_EM_RESTART': '1', 22:48:45 INFO - 'PAGER': '/bin/cat', 22:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:45 INFO - 'PWD': '/builds/slave/test', 22:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:45 INFO - 'SHELL': '/bin/bash', 22:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:48:45 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:45 INFO - 'USER': 'cltbld', 22:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:45 INFO - 'XPC_FLAGS': '0x0', 22:48:45 INFO - 'XPC_SERVICE_NAME': '0', 22:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:46 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:48:46 INFO - Cleaning up... 22:48:46 INFO - Return code: 0 22:48:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:46 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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:46 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:48:46 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:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:46 INFO - 'HOME': '/Users/cltbld', 22:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:46 INFO - 'LOGNAME': 'cltbld', 22:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:46 INFO - 'MOZ_NO_REMOTE': '1', 22:48:46 INFO - 'NO_EM_RESTART': '1', 22:48:46 INFO - 'PAGER': '/bin/cat', 22:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:46 INFO - 'PWD': '/builds/slave/test', 22:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:46 INFO - 'SHELL': '/bin/bash', 22:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:48:46 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:46 INFO - 'USER': 'cltbld', 22:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:46 INFO - 'XPC_FLAGS': '0x0', 22:48:46 INFO - 'XPC_SERVICE_NAME': '0', 22:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:46 INFO - Downloading/unpacking psutil>=0.7.1 22:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:48:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:49 INFO - Installing collected packages: psutil 22:48:49 INFO - Running setup.py install for psutil 22:48:49 INFO - building 'psutil._psutil_osx' extension 22:48:49 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:48:50 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:48:50 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:48:50 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:48:50 INFO - building 'psutil._psutil_posix' extension 22:48:50 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:48:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:48:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:48:50 INFO - ^ 22:48:50 INFO - 1 warning generated. 22:48:50 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:48:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:51 INFO - Successfully installed psutil 22:48:51 INFO - Cleaning up... 22:48:51 INFO - Return code: 0 22:48:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:51 INFO - 'HOME': '/Users/cltbld', 22:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:51 INFO - 'LOGNAME': 'cltbld', 22:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:51 INFO - 'MOZ_NO_REMOTE': '1', 22:48:51 INFO - 'NO_EM_RESTART': '1', 22:48:51 INFO - 'PAGER': '/bin/cat', 22:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:51 INFO - 'PWD': '/builds/slave/test', 22:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:51 INFO - 'SHELL': '/bin/bash', 22:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:48:51 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:51 INFO - 'USER': 'cltbld', 22:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:51 INFO - 'XPC_FLAGS': '0x0', 22:48:51 INFO - 'XPC_SERVICE_NAME': '0', 22:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:48:53 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:48:53 INFO - Installing collected packages: mozsystemmonitor 22:48:53 INFO - Running setup.py install for mozsystemmonitor 22:48:53 INFO - Successfully installed mozsystemmonitor 22:48:53 INFO - Cleaning up... 22:48:54 INFO - Return code: 0 22:48:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:54 INFO - 'HOME': '/Users/cltbld', 22:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:54 INFO - 'LOGNAME': 'cltbld', 22:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:54 INFO - 'MOZ_NO_REMOTE': '1', 22:48:54 INFO - 'NO_EM_RESTART': '1', 22:48:54 INFO - 'PAGER': '/bin/cat', 22:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:54 INFO - 'PWD': '/builds/slave/test', 22:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:54 INFO - 'SHELL': '/bin/bash', 22:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:48:54 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:54 INFO - 'USER': 'cltbld', 22:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:54 INFO - 'XPC_FLAGS': '0x0', 22:48:54 INFO - 'XPC_SERVICE_NAME': '0', 22:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:54 INFO - Downloading/unpacking blobuploader==1.2.4 22:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:56 INFO - Downloading blobuploader-1.2.4.tar.gz 22:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:48:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:48:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:57 INFO - Downloading docopt-0.6.1.tar.gz 22:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:48:58 INFO - Installing collected packages: blobuploader, requests, docopt 22:48:58 INFO - Running setup.py install for blobuploader 22:48:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:48:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:48:58 INFO - Running setup.py install for requests 22:48:58 INFO - Running setup.py install for docopt 22:48:58 INFO - Successfully installed blobuploader requests docopt 22:48:58 INFO - Cleaning up... 22:48:58 INFO - Return code: 0 22:48:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:58 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:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:58 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:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:58 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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:48:58 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:48:58 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:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:58 INFO - 'HOME': '/Users/cltbld', 22:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:58 INFO - 'LOGNAME': 'cltbld', 22:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:58 INFO - 'MOZ_NO_REMOTE': '1', 22:48:58 INFO - 'NO_EM_RESTART': '1', 22:48:58 INFO - 'PAGER': '/bin/cat', 22:48:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:58 INFO - 'PWD': '/builds/slave/test', 22:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:58 INFO - 'SHELL': '/bin/bash', 22:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:48:58 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:58 INFO - 'USER': 'cltbld', 22:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:58 INFO - 'XPC_FLAGS': '0x0', 22:48:58 INFO - 'XPC_SERVICE_NAME': '0', 22:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-e6fMAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zufgP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-GTCz2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-rOnryz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-dKEjby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-gc0lcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Wngddc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-5EfhaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-7fBRg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-BsomnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-xF2Q61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-TPM39N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-iWNceK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-JPW0d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-XS7puE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-vThasm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-st_LxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:49:00 INFO - Running setup.py install for manifestparser 22:49:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:49:01 INFO - Running setup.py install for mozcrash 22:49:01 INFO - Running setup.py install for mozdebug 22:49:01 INFO - Running setup.py install for mozdevice 22:49:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:49:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:49:01 INFO - Running setup.py install for mozfile 22:49:01 INFO - Running setup.py install for mozhttpd 22:49:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:49:01 INFO - Running setup.py install for mozinfo 22:49:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:49:01 INFO - Running setup.py install for mozInstall 22:49:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Running setup.py install for mozleak 22:49:02 INFO - Running setup.py install for mozlog 22:49:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Running setup.py install for moznetwork 22:49:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Running setup.py install for mozprocess 22:49:02 INFO - Running setup.py install for mozprofile 22:49:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:49:02 INFO - Running setup.py install for mozrunner 22:49:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:49:03 INFO - Running setup.py install for mozscreenshot 22:49:03 INFO - Running setup.py install for moztest 22:49:03 INFO - Running setup.py install for mozversion 22:49:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:49:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:49:03 INFO - Cleaning up... 22:49:03 INFO - Return code: 0 22:49:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:03 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:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:03 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:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:03 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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:49:03 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:49:03 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:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:03 INFO - 'HOME': '/Users/cltbld', 22:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:03 INFO - 'LOGNAME': 'cltbld', 22:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:03 INFO - 'MOZ_NO_REMOTE': '1', 22:49:03 INFO - 'NO_EM_RESTART': '1', 22:49:03 INFO - 'PAGER': '/bin/cat', 22:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:03 INFO - 'PWD': '/builds/slave/test', 22:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:03 INFO - 'SHELL': '/bin/bash', 22:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:49:03 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:03 INFO - 'USER': 'cltbld', 22:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:03 INFO - 'XPC_FLAGS': '0x0', 22:49:03 INFO - 'XPC_SERVICE_NAME': '0', 22:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-00TX5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:03 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:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-OnMBN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-sYB7W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-dSqLgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-6t59KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-4HuA1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-94S4Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-RzqJkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-8zlt7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-MdH0u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-69mvI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:04 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:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-2xEbkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:05 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:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-nyQmGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:05 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:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-SQeHKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:05 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:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-8UNW8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:05 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:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-JHiaWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:05 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:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zJUfJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:05 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:49:05 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:49:05 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:49:05 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:49:05 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:49:05 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:49:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:07 INFO - Downloading blessings-1.6.tar.gz 22:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:49:08 INFO - Installing collected packages: blessings 22:49:08 INFO - Running setup.py install for blessings 22:49:08 INFO - Successfully installed blessings 22:49:08 INFO - Cleaning up... 22:49:08 INFO - Return code: 0 22:49:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:08 INFO - Reading from file tmpfile_stdout 22:49:08 INFO - Current package versions: 22:49:08 INFO - blessings == 1.6 22:49:08 INFO - blobuploader == 1.2.4 22:49:08 INFO - docopt == 0.6.1 22:49:08 INFO - manifestparser == 1.1 22:49:08 INFO - mozInstall == 1.12 22:49:08 INFO - mozcrash == 0.17 22:49:08 INFO - mozdebug == 0.1 22:49:08 INFO - mozdevice == 0.48 22:49:08 INFO - mozfile == 1.2 22:49:08 INFO - mozhttpd == 0.7 22:49:08 INFO - mozinfo == 0.9 22:49:08 INFO - mozleak == 0.1 22:49:08 INFO - mozlog == 3.1 22:49:08 INFO - moznetwork == 0.27 22:49:08 INFO - mozprocess == 0.22 22:49:08 INFO - mozprofile == 0.28 22:49:08 INFO - mozrunner == 6.11 22:49:08 INFO - mozscreenshot == 0.1 22:49:08 INFO - mozsystemmonitor == 0.0 22:49:08 INFO - moztest == 0.7 22:49:08 INFO - mozversion == 1.4 22:49:08 INFO - psutil == 3.1.1 22:49:08 INFO - requests == 1.2.3 22:49:08 INFO - wsgiref == 0.1.2 22:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:08 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 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:49:08 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:49:08 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:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:08 INFO - 'HOME': '/Users/cltbld', 22:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:08 INFO - 'LOGNAME': 'cltbld', 22:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:08 INFO - 'MOZ_NO_REMOTE': '1', 22:49:08 INFO - 'NO_EM_RESTART': '1', 22:49:08 INFO - 'PAGER': '/bin/cat', 22:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:08 INFO - 'PWD': '/builds/slave/test', 22:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:08 INFO - 'SHELL': '/bin/bash', 22:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:49:08 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:08 INFO - 'USER': 'cltbld', 22:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:08 INFO - 'XPC_FLAGS': '0x0', 22:49:08 INFO - 'XPC_SERVICE_NAME': '0', 22:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 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:49:08 INFO - Cleaning up... 22:49:08 INFO - Return code: 0 22:49:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:08 INFO - 'HOME': '/Users/cltbld', 22:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:08 INFO - 'LOGNAME': 'cltbld', 22:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:08 INFO - 'MOZ_NO_REMOTE': '1', 22:49:08 INFO - 'NO_EM_RESTART': '1', 22:49:08 INFO - 'PAGER': '/bin/cat', 22:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:08 INFO - 'PWD': '/builds/slave/test', 22:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:08 INFO - 'SHELL': '/bin/bash', 22:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:49:08 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:08 INFO - 'USER': 'cltbld', 22:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:08 INFO - 'XPC_FLAGS': '0x0', 22:49:08 INFO - 'XPC_SERVICE_NAME': '0', 22:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:09 INFO - Downloading/unpacking jsonschema 22:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:49:11 INFO - Downloading/unpacking functools32 (from jsonschema) 22:49: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:49: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:49: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:49: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:49: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:49: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:49:12 INFO - Downloading functools32-3.2.3-2.tar.gz 22:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:49:12 INFO - warning: no files found matching '*.txt' 22:49:12 INFO - no previously-included directories found matching 'build' 22:49:12 INFO - no previously-included directories found matching 'dist' 22:49:12 INFO - no previously-included directories found matching '.git*' 22:49:12 INFO - Installing collected packages: jsonschema, functools32 22:49:12 INFO - Running setup.py install for functools32 22:49:12 INFO - warning: no files found matching '*.txt' 22:49:12 INFO - no previously-included directories found matching 'build' 22:49:12 INFO - no previously-included directories found matching 'dist' 22:49:12 INFO - no previously-included directories found matching '.git*' 22:49:12 INFO - Successfully installed jsonschema functools32 22:49:12 INFO - Cleaning up... 22:49:12 INFO - Return code: 0 22:49:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105002a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105012420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d1dbed40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, '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.emrIdd8xcQ/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.SR5ts6ddua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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:49:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:12 INFO - 'HOME': '/Users/cltbld', 22:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:12 INFO - 'LOGNAME': 'cltbld', 22:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:12 INFO - 'MOZ_NO_REMOTE': '1', 22:49:12 INFO - 'NO_EM_RESTART': '1', 22:49:12 INFO - 'PAGER': '/bin/cat', 22:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:12 INFO - 'PWD': '/builds/slave/test', 22:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:12 INFO - 'SHELL': '/bin/bash', 22:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:49:12 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:12 INFO - 'USER': 'cltbld', 22:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:12 INFO - 'XPC_FLAGS': '0x0', 22:49:12 INFO - 'XPC_SERVICE_NAME': '0', 22:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:12 INFO - Downloading/unpacking flake8 22:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:49:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:49:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:15 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:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:49:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:49:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:49:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:49:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:49:16 INFO - no previously-included directories found matching 'docs/_build' 22:49:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:49:16 INFO - Running setup.py install for pep8 22:49:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:49:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:49:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:49:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:49:16 INFO - no previously-included directories found matching 'docs/_build' 22:49:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:49:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:49:16 INFO - Cleaning up... 22:49:16 INFO - Return code: 0 22:49:16 INFO - Running post-action listener: _resource_record_post_action 22:49:16 INFO - Running post-action listener: _start_resource_monitoring 22:49:16 INFO - Starting resource monitoring. 22:49:16 INFO - ##### 22:49:16 INFO - ##### Running install step. 22:49:16 INFO - ##### 22:49:16 INFO - Running pre-action listener: _resource_record_pre_action 22:49:16 INFO - Running main action method: install 22:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:17 INFO - Reading from file tmpfile_stdout 22:49:17 INFO - Detecting whether we're running mozinstall >=1.0... 22:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:49:17 INFO - Reading from file tmpfile_stdout 22:49:17 INFO - Output received: 22:49:17 INFO - Usage: mozinstall [options] installer 22:49:17 INFO - Options: 22:49:17 INFO - -h, --help show this help message and exit 22:49:17 INFO - -d DEST, --destination=DEST 22:49:17 INFO - Directory to install application into. [default: 22:49:17 INFO - "/builds/slave/test"] 22:49:17 INFO - --app=APP Application being installed. [default: firefox] 22:49:17 INFO - mkdir: /builds/slave/test/build/application 22:49:17 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:49:17 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:49:50 INFO - Reading from file tmpfile_stdout 22:49:50 INFO - Output received: 22:49:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:49:50 INFO - Running post-action listener: _resource_record_post_action 22:49:50 INFO - ##### 22:49:50 INFO - ##### Running run-tests step. 22:49:50 INFO - ##### 22:49:50 INFO - Running pre-action listener: _resource_record_pre_action 22:49:50 INFO - Running main action method: run_tests 22:49:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:49:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:49:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:49:50 INFO - 2016-04-16 22:49:50.786 screenresolution[1760:8566] starting screenresolution argv=screenresolution get 22:49:50 INFO - 2016-04-16 22:49:50.789 screenresolution[1760:8566] Display 0: 1600x1200x32@60 22:49:50 INFO - 2016-04-16 22:49:50.795 screenresolution[1761:8568] starting screenresolution argv=screenresolution list 22:49:50 INFO - Available Modes on Display 0 22:49:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:49:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:49:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:49:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:49:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:49:51 INFO - Intel Iris: 22:49:51 INFO - Chipset Model: Intel Iris 22:49:51 INFO - Type: GPU 22:49:51 INFO - Bus: Built-In 22:49:51 INFO - VRAM (Dynamic, Max): 1536 MB 22:49:51 INFO - Vendor: Intel (0x8086) 22:49:51 INFO - Device ID: 0x0a2e 22:49:51 INFO - Revision ID: 0x0009 22:49:51 INFO - Displays: 22:49:51 INFO - Display: 22:49:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:49:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:49:51 INFO - Display Serial Number: 1600x1200 60 22:49:51 INFO - Main Display: Yes 22:49:51 INFO - Mirror: Off 22:49:51 INFO - Online: Yes 22:49:51 INFO - Rotation: Supported 22:49:51 INFO - Return code: 0 22:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:49:51 INFO - Python 2.7.3 22:49:51 INFO - Return code: 0 22:49:51 INFO - grabbing minidump binary from tooltool 22:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:51 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 0x104ea1e00>, '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 0x104d0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e9cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:49:51 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:49:51 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:49:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:49:51 INFO - Return code: 0 22:49:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:49:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:49:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:49:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:49:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:49:52 INFO - Return code: 0 22:49:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0063 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emrIdd8xcQ/Render', 22:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:52 INFO - 'HOME': '/Users/cltbld', 22:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:52 INFO - 'LOGNAME': 'cltbld', 22:49:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:49:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:52 INFO - 'MOZ_NO_REMOTE': '1', 22:49:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:49:52 INFO - 'NO_EM_RESTART': '1', 22:49:52 INFO - 'PAGER': '/bin/cat', 22:49:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:52 INFO - 'PWD': '/builds/slave/test', 22:49:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:52 INFO - 'SHELL': '/bin/bash', 22:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SR5ts6ddua/Listeners', 22:49:52 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 22:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:52 INFO - 'USER': 'cltbld', 22:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:52 INFO - 'XPC_FLAGS': '0x0', 22:49:52 INFO - 'XPC_SERVICE_NAME': '0', 22:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:49:52 INFO - mozversion application_buildid: 20160416210219 22:49:52 INFO - mozversion application_changeset: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 22:49:52 INFO - mozversion application_display_name: Nightly 22:49:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:49:52 INFO - mozversion application_name: Firefox 22:49:52 INFO - mozversion application_remotingname: firefox 22:49:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:49:52 INFO - mozversion application_vendor: Mozilla 22:49:52 INFO - mozversion application_version: 48.0a1 22:49:52 INFO - mozversion platform_buildid: 20160416210219 22:49:52 INFO - mozversion platform_changeset: 2f58e7ce1ab8ff2ed3181a80cb3ac45ee3975799 22:49:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:49:52 INFO - mozversion platform_version: 48.0a1 22:49:52 INFO - using testdate: 1460872192 22:49:52 INFO - actual date: 1460872192 22:49:52 INFO - starting webserver on 'localhost:49276' 22:49:52 INFO - SUITE-START | Running 2 tests 22:49:52 INFO - TEST-START | dromaeo_css 22:49:52 INFO - operating with platform_type : mac_ 22:49:52 INFO - Initialising browser for dromaeo_css test... 22:49:52 INFO - TEST-INFO | started process 1779 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmph1QWtG/profile http://localhost:49276/getInfo.html) 22:49:55 INFO - PROCESS | 1779 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 22:49:55 INFO - PROCESS | 1779 | __metrics Screen width/height:1600/1200 22:49:55 INFO - PROCESS | 1779 | colorDepth:24 22:49:55 INFO - PROCESS | 1779 | Browser inner width/height: 1024/689 22:49:55 INFO - PROCESS | 1779 | __metrics 22:49:55 ERROR - PROCESS | 1779 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:49:56 INFO - TEST-INFO | 1779: exit 0 22:49:56 INFO - Browser initialized. 22:49:56 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:56 INFO - TEST-INFO | started process 1784 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmph1QWtG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:08 INFO - PROCESS | 1784 | RSS: Main: 247881728 22:50:08 INFO - PROCESS | 1784 | 22:50:57 INFO - PROCESS | 1784 | 1460872257946 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:53:09 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 22:53:11 INFO - PROCESS | 1784 | RSS: Main: 263184384 22:53:11 INFO - PROCESS | 1784 | 22:56:13 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 22:56:14 INFO - PROCESS | 1784 | RSS: Main: 274841600 22:56:14 INFO - PROCESS | 1784 | 22:59:15 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 22:59:16 INFO - PROCESS | 1784 | RSS: Main: 276934656 22:59:16 INFO - PROCESS | 1784 | 23:02:18 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 23:02:18 INFO - PROCESS | 1784 | RSS: Main: 278265856 23:02:18 INFO - PROCESS | 1784 | 23:05:21 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 23:05:22 INFO - PROCESS | 1784 | RSS: Main: 281288704 23:05:22 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 23:08:24 INFO - PROCESS | 1784 | RSS: Main: 282398720 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | __start_tp_report 23:08:24 INFO - PROCESS | 1784 | _x_x_mozilla_page_load 23:08:24 INFO - PROCESS | 1784 | _x_x_mozilla_page_load_details 23:08:24 INFO - PROCESS | 1784 | |i|pagename|runs| 23:08:24 INFO - PROCESS | 1784 | |0;dojo.html;3473.531073305103;3478.4434490481526;3517.56030496188;3521.225330427305;3559.9110022249456;5788.6237394569325;5810.273715785527;5812.186293918844;5816.069428891378;5859.121131830222;5703;5811.418858114186;5873.2658417697785;5917.9408205917925;5954.523638108952;4378.956210437895;5253.658512196714;5320.042392369372;5339.5461385782255;5384.542313903323;4562.9771851140695;5385.165299378603;5404.378496472897;5417.64785288957;5426.050441172799;4481.417415735966;5179.637425380225;5329.094054010828;5384.1923711443205;5411.918821217685;5232.476752324776;5239.1093514102695;5287.312649355597;5304.522592966651;5330.813421530266;5232.450592687751;5272.472752724735;5299.973500132513;5308.6549374290935;5369.6509726867625;4405.405270288534;5678.290213723285;5681.119426488901;5727.427257274281;5785.305763308388;5125.410577783566;6247.531435142337;6280.15217945576;6302.149209856657;6303.117563541108;5458.781648734046;6225.377851675462;6245.781397651061;6279.183706118175;6283.057541368831;4264.232438161161;5221.008008478377;5651.15232715096;5941.55438342126;5954.374790647006;6292.402221788922;6326.5255105867245;6338.30278669346;6478.676066196671;6578.572392794453;6061.090836374491;6226.501879849601;6240.126382306481;6241.625502469867;6248.656323902229;4713.646476514206;5423.783048678088;6007.339192688801;6182.938170618237;6249.75000999964;6123.252118380239;6146.938530614726;6252.656103914237;6289.937100629027;6316.905246421307;6206.286277078104;6260.248770147611;6266.091416744546;6314.873702526015;6334.398232167937;4850.927236091461;4929.260610908393;4985.875353116201;4994.2508623706735;5048.722320272347;4432.312991486325;4463.995600989751;4473.597376236155;4481.462224533077;4513.390692256569;4905.3623029006;5681.147827825859;5760.654360738369;5847.298324201038;5899.793507227227;6192.442680158807;6255.906161407583;6262.937370626327;6263.6241825490615;6266.436020758154;5838.854028649318;5844.977129002407;5881.147233651097;5887.735051922674;5929.14027466015;5321.095413779667;5431.619786614899;5451.972740136274;5475.206095116266;5481.862953426196;6317.5893566918;6337.8732425352155;6339.936600634027;6347.55567110307;6357.936420635734;6267.1539342188225;6272.686365681789;6286.654234017173;6287.685615719104;6305.4009869062365;6315.4947604192475;6336.303006669171;6354.93645063562;6359.841003974938;6360.395762402564;6097.585173720644;6119.624418755179;6119.785807496716;6120.479759220516;6122.6727265478185;4949.7030178189425;6136.20229370179;6148.44663980244;6174.197354343906;6206.565540412128;5762.510186634016;6013.849653758691;6046.3046749624355;6083;6104.156010547808;5199.11615025439;6059.697015149313;6109.283207920342;6226.719797609209;6251.593646412968;5010.599152067753;5178.61160412963;6042.758289668364;6044.879102418015;6075.453209211192;4930.654854160174;5024.271480635361;6150.477209437248;6186.1648677427975;6200.3179650239335;6065.72704228302;6094.939050609437;6104.6642434664745;6117.602355846856;6118.357572454828 23:08:24 INFO - PROCESS | 1784 | |1;ext.html;523.8664140644136;526.4682670502792;567.6906086183031;571.6598623818826;601.8044135655913;37339.813300933456;37889.810550947244;38710;38823.61176388232;38883.61116388835;34099;36099.195040248014;36272.818635906784;41793.373099403456;41836.58163418373;9415.67045153418;9575.664851730191;9614.855777163348;9651.855222171673;9684.854727179098;8219.506829590215;9467.71596852092;9506.334556581041;9586.856197157049;9660.323777335589;9545.713628591153;9548.713538593853;9568.90431095687;9603.615855365777;9687.564059617334;9511.476868772215;9539.713808585753;9581.952090239574;9616.134547890724;9624.95187524065;9490;9520.90479095214;9563.760905977337;9588.856167157499;9626.855597166048;37697.24605507902;37893;38089;38150.23699525994;38188.80905595482;3191.776575639706;3212.5984251968503;3496.1783980764753;3496.493008513751;3849.634284742973;26439.603405948925;26903;26922.865385673143;27370.315742106606;27586.862065689744;2679.1025006622804;2699.1227850948326;2729.3586007288272;2773.62556054934;2847.501687204731;529619;534643;540155;542348;542866;58878.41121588815;58879;59265.40734592599;59281.110783338285;59317.703411483526;66579;67447;67449.66275168593;67679;68609;16515.6696866062;16681.582960425843;16719.247633856507;16725.498235052968;16747.246373913204;10829.945850270857;12447.377631118588;12779.488820447077;12847.48610055606;12915.902148317498;18668.346607868658;19508.51228719265;19525.414237572895;19846.801531984784;20018.59962800736;7976;8037.557934313552;8094.31198348147;8107.594620268963;8134.94245748049;1008.178334657252;1040.4433628008978;1072.8658917635296;1082.480409403475;1105.784372047443;29878.40243195124;29964.101076967727;30016.54975175374;30038;30277;1099.6426161497545;1102.6802227354137;1190.571394298052;1262.5291535361384;1269.911106222574;12981.156224845356;13577.524786632617;13970.301484925712;14723.043002204822;15164.772528412082;634114;637965;638076;638541;642416;58721.70639146862;59688.40311596915;60278;60293.99999999912;60579;1354.2348573056192;1444.7399468095844;1491.9328630211667;1495.4317359403358;1497.2588568658584;24846.84639996089;24873.129440469496;28877.700503476975;29335.413291734472;29833;52348.738256308476;57735.71132144312;59414.70292648509;59673;59907.400925991926;543400;548432;550562;552568;555652.4434755762;40103.3984490227;41810.37284440675;42238;42532;42638;46313.073738525716;46345.76827115843;46944.53055469538;47027;48248.276275856595;37333.4399983995;38156.42765358577;38795;38992.41511377388;39153.80423097866;37653.246935061696;37656.81171594124;37686.62313376831;38259;39484.61803836852;31878.68121318757;37580.624193757714;37771.62228377681;37787;37859.62140378671;31352.37295254128;32597.674023260413;32712.83643581767;32762.34475310528;32766;21256.787432125482;24373.708389705575;24727.25818225456;24823.627645585693;24938.62592061084 23:08:24 INFO - PROCESS | 1784 | |2;jquery.html;384.49055002122185;394.6487626012849;395.95644479107295;397.4892263441478;408.08181591419304;19732.506687332825;20312;20497.077631506563;20516.87157206353;21654.0255688494;20431.693524597107;20944.26695065673;20963.580728385463;21501.462463438424;21668.783312166874;5075.822346217883;5472.151816468444;5839.299284085916;5852.268466441695;5986.940130598682;5352.732363381835;5759.308882934054;5810.418958104198;5858.5606079543995;5877.265341832271;5624.15637654353;5709.257796486471;5710.600257981943;5713.8285851424525;5715.228444160048;5641.915371269434;5731.799387021455;5855.970720146414;6006.519478441714;6007.8197654070445;5525.4474552544825;5653.180288858133;5744.22452968848;5752.165935939307;5759.452851979056;13631.591052268448;13748.518801841987;13795.517156899461;13796.034277600573;14103.647408814759;1891.9527011824678;2041.2957529652247;2069.14130635786;2095.549456866766;2197.791209835079;9800.36297640651;9898.208143348515;10016.449095299755;10184.694459166238;10242.334248273914;1702.8212037736107;1722.552136444521;1724.9655006899918;1743.8953662780273;1752.0538908989258;112672.32975908606;117767.41116294364;117978;118965.06239391572;121891;11668.70828229301;11703.648890533297;11714.88285117138;11718.06255499558;11753.941230293965;14552.126872387691;14774.556763297118;14818;14844.851551484562;14849.40602375893;7308.96345518269;7480.877868319687;8820.029796722356;8842.690505832265;8902.510361930026;7542.811429714191;8725.345599080094;8749.687546868021;8787.297016238686;8790.164934331227;4561.361409402686;4599;4653.208954477747;4653.674242802971;4689.249720044776;1649.5381293237913;1680.7058764716126;1980.2870966452065;1984.583237520137;2004.6692295771034;901.9909800902038;927.0497739816742;946.1626460582297;954.0888451528773;1011.2567263061644;18580.81419185818;19960.90019549893;19979.900100499406;20047.899760501397;20112.49718757043;971.3977334052933;992.811365840488;993.3642468819961;1013.6705570689555;1038.1020417339098;10350.757909050963;10463.372197668165;10492.842607360895;10674.679759607225;10863.945680271549;556867;585650;600541;604606;606069;90812.09187908168;103995.48002260091;129368.05947910789;130666;132870;984.8719666443317;1024.3188279794003;1026.2867307221409;1029.53156313877;1056.3978532236706;17436.47690569285;17797;17830.286788528832;17874.910625446788;17980.550486237426;29469.557956631095;45600;49211.753941231495;50423.74788126183;50892.74553627208;70427;73740.26259737334;74634.62682686551;75273;77440.61279693566;7688.807779805549;7742.109657389247;7869.8819517708425;8066.072401673882;8147.75556733299;10456.843147352643;12307.69230769238;12334.629961101182;12646.241225526497;12849.357532123544;10868.565257389932;11091.389973551532;11110.888891110986;11134.554617815196;11259.718507037389;5093.797616965944;8943.23982461472;9234.33781919261;10748.656417947757;11404.258723182966;9523.238140948846;11312.886871131182;11344.035756960751;11592.221967255666;11797.882021180021;6995.685194166376;7413.555186688816;7498.550086994798;7946.125926148004;8570.871436928326;6812.284710105368;9794.951025244829;11117.888821111685;11221.102311815199;11264 23:08:24 INFO - PROCESS | 1784 | |3;mootools.html;1584.5246426072179;1615.022911138761;1885.7171424286355;1966.862319637625;1969.1729473621078;7959.840803183933;8494.065652778205;8594.957025214873;8847.203751662357;8878.689245876396;8582.442141260814;8625.95687021564;8715.041345451995;8728.781780455492;8747.387682862201;2428.878556072198;2443.181534186041;2448.204333591581;2464.6198129047702;2483.267436106344;2184.737831460227;2194.7914948079906;2204.0853045985896;2238.149503188786;2248.325502349297;2385.153270588942;2478.962815557759;2482.7889629381525;2483.341914392687;2502.336880726609;1959.7158412030246;2033.806788355104;2152.762161756996;2181.19295860531;2217.15748015753;2444.767747064026;2454.4232105455203;2455.1407007547396;2461.310832966772;2471.8022558195303;8844.38089333747;8877.689280875138;8907.55462226886;8914.465132092031;8922.821543569093;5266.8419947401635;5358.383785864637;5389.245505629176;5446.618736688433;5491.093969495028;3566.1619519413175;3582.3551760683067;3636.7272454566014;3651.4522821576975;3702.9074273143387;935.9672411465566;1013.9138173255283;1052.85786418834;1062.8033813744405;1068.380339403152;11538;11644.767104657943;11658.825117623242;11727.882721172764;11745.765084698258;4609;4900.362952816111;4965.35450391454;4968.105740966588;4982.451930287665;9865.161461275728;9876.061774131445;10010.54952527138;10019.699409017741;10038;5400.783968641289;5437.075697131497;5474.781008759605;5479.75341109651;5498.862528436741;4453.532379100147;4805.6155507559315;4968.90062198754;4970.378702662168;4982.825601103944;3843.808419389998;4016.357382818735;4017.3371393720295;4066.8373265069067;4122.711410201257;1229.5266322465916;1350.3248375812093;1364.3928451838835;1369.431685850361;1374.5326588959801;661.3981277037968;828.5732847583499;919.2691810011057;948.8529277961927;985.7141428985657;5681.204631351614;5755.510781583613;5847.8245652630485;6041.2750469943885;6075.787347442907;936.8875734911718;939.5208443693765;1011.7723512209841;1012.3723291559133;1012.5443550402348;3981.7013723970817;4044.51465824103;4045.4134150548007;4053.959460405358;4072.511298644182;10587;10874;10880.945595271973;10898.128149748001;10906.400147991937;10677.93220677926;10834.241603087867;10915.836262456069;10940.015398614163;10963.013328800445;8115.860910935347;8205.876911846328;8306.210909963544;8357.58212089393;8380.832383352421;5777.133429985536;5887.1757953887345;5912.349641539513;6031.517478601613;6176.258848938156;9215.124563166488;9371.531423428938;9385.436873787594;9593.808123837345;9640.228781697588;9824.21406287481;9934.403935763878;10026.548805303633;10086.6469673564;10222.846657299997;7251.5286506376915;8115.878261825959;8130.268275855202;8149.225823546753;8311.667533298842;8339.291160251447;8397.160283971554;8399.076101628694;8424.747257582283;8428.95785521093;7354.374878135419;7526;7572.886406703794;7598.12621548529;7627.237276272328;7317.597532135784;7399.18608953004;7401.7039318428815;7484.401247900046;7515.398768098648;7076.221615622175;7181.210066892533;7212.062431883822;7219.205887352492;7226.855462890818;4423.071155057474;4840.7337596432535;4936.259561065869;5024.773885175249;5160.535551800356;4955.45489996093;5019.799208031638;5025.7989680412375;5081.517255860687;5267.920981185362 23:08:24 INFO - PROCESS | 1784 | |4;prototype.html;269.8691134799623;300.17152658662087;311.7396973527105;321.4679705088079;328.74193757900053;5060.493950604942;5945.940540594593;6004.969975150129;6178.876422471553;6198.659073750948;4770.7853146608395;5373.656585853537;5512.283403157594;6105.297890742566;6139.342377558068;3609.7473176877565;4231.894202644928;4562.133194693014;4607.516210797869;4689.554492323241;3901.6683581895572;3937.310970580151;4581.129585378784;4582.564656357641;4641.187792136379;4302.526722060571;4492.640588752892;4493.820247190109;4499.460064792213;4522.4346956630425;3827.6172382761642;4383.59724888036;4455.933161002571;4511.2330903746115;4588.357629931812;3659;3802.524684414448;3806.276807406584;3815.771053736785;3837.4052021936645;4794.640401969831;4973.756560859785;5114.897702045975;5673.26247587815;5796.710164491758;4406.361077643755;4485.596296333346;5126.590187698375;5134.435212126663;5149.742512874379;5833.125031245305;5891.558133140032;6181.907271390933;6411.903821442682;6445.065465507522;3527.9294414111832;4172.374143878443;4188.916221675549;4200.810963506649;4217.030083080878;5514.724263786795;6700.836625496286;6753.797386078377;6796.218434880003;6853.17761868579;4272.850450234227;4307.8276868924895;4314.697971142053;4315.136972605492;4336.457942757155;4395.406620106309;4400.053988392511;4412.426384569985;4414.977925110418;4416.38170656102;4788.640851936118;5051.3433253676785;5153.458886816905;5215.68705877641;5231.9476805232225;6072.605280656743;6099.817005489843;6325.5255855811;6515.641639709862;6583.539152259392;1309.9083064185415;1453.571196497036;1499.272852666456;1508.3287936868207;1510.9093454392553;937.1893312284825;962.605331813953;993.101243374747;997.8503224516236;999.7200783780553;5854.677992710356;5879.500242479352;5891.23413956183;5908.320543137508;5925.170476133345;6623.801285961429;6626.801195964129;6636.767713130017;6648.634325112165;6696.5647232929705;4957.652964292537;5204.4535323791215;5527.944720552743;5868.530517558585;5927.703614819242;2951.5130003549602;3485.7734247273847;3497.877574284888;3517.753757236969;3547;6656.667166641745;6956.5478243914995;6971.4314279286755;6998.720051197895;7014.614196219257;6840.31596840322;6905.482088843256;6915.550489218184;6933.965330173317;6938.375546200866;5519.39286678465;6616.569922954993;6960.79117626472;7014.789556313319;7037.92962070383;5756.71216439168;6695.933040669531;6701.1623547056615;6713.899291510729;6714.529982901346;5647.858803529944;6058.787942421994;6330.8733825322315;6609.834754131185;6617.470602351897;6549.672516374258;6671.432928201158;6958.965205173941;6997.545159564612;7045.11936007999;3710.6474884885893;3870.651641352292;4331.415258940003;4332.566743325642;4356.6950313477755;5002.574781143643;6071.392860713893;6596.406323430914;6668.133142691419;6676.6661666917435;5340.1963968916625;5492.571931297812;6343.682815859096;6436.485081193399;6472.708728107341;6104.084387341934;6393.808185754435;6398.872022559616;6455.386738259858;6489.221293444817;6201.3178550358525;6229.813105606839;6255;6289.905651415141;6301.96849015752;5746.080627099644;5787.565932554991;6416;6417.711202995783;6421.582597131359;5145.7650163961125;5419.403865574863;5961.52307815365;6099.664518451527;6351.2060992375955 23:08:24 INFO - PROCESS | 1784 | |5;yui.html;624.6470744029623;671.1845108193545;700.6391708270238;746.9813254668633;789.3961119743398;14503.057301275423;14850;14977.47578834741;14996.025258358199;15074.170920599363;11755.235909665887;13670.863291367083;13712.24582647954;13837.308134593255;13860.306984650751;618.7586841131962;754.3625636337287;873.6022364217265;889.1508609278156;889.5952341684518;885.9512726800024;910.41733290694;914.0174312613933;920.185635712396;922.3820040572832;734.8273155808381;850.3154960256991;942.5664194470552;959.3140904253457;973.1679414100948;851.1445996773203;958.3339578992603;960.3181740963889;960.5965494492295;966.2415004221688;694.5658963148032;709.8544798316333;715.6421789105448;721.3651986252117;724.4023680463586;14845;14884.925575372163;14915.477958271516;14968.850311496963;15009.549713508612;10982.341059536373;12809.679758006125;12877.871221287762;12943.093983421164;12986.35068246584;10002.149817265541;10053.195744340508;10145.949270253675;10265.486725663686;10536;3411.8294085295884;3459.723222142248;3505.2989402119424;3525.988190135812;3631.1103779574173;86586;87377.56311218467;90545.99999999935;90835.54582227046;91720;9516.143547080657;9603.279754018477;9613.903860961302;9774.804503910023;9849.50752462374;20700.482487937923;21994.230201943174;22087.558248834932;22169.667454988186;22354.66468002981;7196.316349946746;7462.291082347168;7585.089789225218;7628.504147230412;7776.922230777733;3972.0467087898696;4003.779792111462;4009.599040095967;4016.2770701274053;4025.9597404025585;3552.751307408457;3603.3153700796765;3656.8902932911524;3660.615635358249;3670.963290367062;638.7604648256904;645.2128403347908;649.3636236488268;660.2374257732326;661.9172603424572;505.88617561049205;520.9426963034064;553.1177771454524;564.6132399306488;610.9725062372204;6474.4496717778575;7931.801704957422;8111.594420278962;8127;8131.227533384319;537.8278950735727;566.7591273708657;568.1080703295786;596.588354035714;598.1267349669445;3812.8474861005247;3837.520309961255;3838.347480928249;3973.1133699606835;3983.940240896389;98618.52072218922;98778;98805;100321.47630664981;101034;78366;79222.6038869802;82081;82347.5882620583;84205;60700.39299606947;61487.15538533851;62834.68582657149;68304;73933.63033184799;7491.175970643116;7510.8497830042;7593.734219302298;7785.883211751715;7946.284834364704;42300;44493;45052;45149.322760157964;45173.322400163364;50518;66887.33112669006;67226.327736722;67448;69171.61656766736;10079.24405669563;10146.695599131743;10228.744281393025;10279.94860025695;10965.232433729563;11833.11251656112;11980.161388702707;12365;12461.688457788265;12965.15726477776;11025.393603351893;11458.713532161762;11479.770404592029;11887.346195959306;12015.639530814089;9885.703428897144;11506;11558.248713833575;11896.940515297369;12059.698507537381;10988.560457581927;11313.264637798517;11546.82679759821;11632;11721.179517433698;9412.95293523528;9758.46328451942;9761.219102471927;9782.070703283176;9864.309498335047;9751.56117974707;9823.901760982299;9925.057119573918;9956.950215248877;10039.548220330249 23:08:24 INFO - PROCESS | 1784 | __end_tp_report 23:08:24 INFO - PROCESS | 1784 | __start_cc_report 23:08:24 INFO - PROCESS | 1784 | _x_x_mozilla_cycle_collect,399 23:08:24 INFO - PROCESS | 1784 | __end_cc_report 23:08:24 INFO - PROCESS | 1784 | __startTimestamp1460873304713__endTimestamp 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | ------- Summary: start ------- 23:08:24 INFO - PROCESS | 1784 | Number of tests: 6 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#0] dojo.html Cycles:165 Average:5702.50 Median:5929.14 stddev:672.31 (11.3%) stddev-sans-first:651.24 23:08:24 INFO - PROCESS | 1784 | Values: 3473.5 3478.4 3517.6 3521.2 3559.9 5788.6 5810.3 5812.2 5816.1 5859.1 5703.0 5811.4 5873.3 5917.9 5954.5 4379.0 5253.7 5320.0 5339.5 5384.5 4563.0 5385.2 5404.4 5417.6 5426.1 4481.4 5179.6 5329.1 5384.2 5411.9 5232.5 5239.1 5287.3 5304.5 5330.8 5232.5 5272.5 5300.0 5308.7 5369.7 4405.4 5678.3 5681.1 5727.4 5785.3 5125.4 6247.5 6280.2 6302.1 6303.1 5458.8 6225.4 6245.8 6279.2 6283.1 4264.2 5221.0 5651.2 5941.6 5954.4 6292.4 6326.5 6338.3 6478.7 6578.6 6061.1 6226.5 6240.1 6241.6 6248.7 4713.6 5423.8 6007.3 6182.9 6249.8 6123.3 6146.9 6252.7 6289.9 6316.9 6206.3 6260.2 6266.1 6314.9 6334.4 4850.9 4929.3 4985.9 4994.3 5048.7 4432.3 4464.0 4473.6 4481.5 4513.4 4905.4 5681.1 5760.7 5847.3 5899.8 6192.4 6255.9 6262.9 6263.6 6266.4 5838.9 5845.0 5881.1 5887.7 5929.1 5321.1 5431.6 5452.0 5475.2 5481.9 6317.6 6337.9 6339.9 6347.6 6357.9 6267.2 6272.7 6286.7 6287.7 6305.4 6315.5 6336.3 6354.9 6359.8 6360.4 6097.6 6119.6 6119.8 6120.5 6122.7 4949.7 6136.2 6148.4 6174.2 6206.6 5762.5 6013.8 6046.3 6083.0 6104.2 5199.1 6059.7 6109.3 6226.7 6251.6 5010.6 5178.6 6042.8 6044.9 6075.5 4930.7 5024.3 6150.5 6186.2 6200.3 6065.7 6094.9 6104.7 6117.6 6118.4 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#1] ext.html Cycles:180 Average:71026.14 Median:21279.82 stddev:154174.94 (724.5%) stddev-sans-first:154516.57 23:08:24 INFO - PROCESS | 1784 | Values: 523.9 526.5 567.7 571.7 601.8 37339.8 37889.8 38710.0 38823.6 38883.6 34099.0 36099.2 36272.8 41793.4 41836.6 9415.7 9575.7 9614.9 9651.9 9684.9 8219.5 9467.7 9506.3 9586.9 9660.3 9545.7 9548.7 9568.9 9603.6 9687.6 9511.5 9539.7 9582.0 9616.1 9625.0 9490.0 9520.9 9563.8 9588.9 9626.9 37697.2 37893.0 38089.0 38150.2 38188.8 3191.8 3212.6 3496.2 3496.5 3849.6 26439.6 26903.0 26922.9 27370.3 27586.9 2679.1 2699.1 2729.4 2773.6 2847.5 529619.0 534643.0 540155.0 542348.0 542866.0 58878.4 58879.0 59265.4 59281.1 59317.7 66579.0 67447.0 67449.7 67679.0 68609.0 16515.7 16681.6 16719.2 16725.5 16747.2 10829.9 12447.4 12779.5 12847.5 12915.9 18668.3 19508.5 19525.4 19846.8 20018.6 7976.0 8037.6 8094.3 8107.6 8134.9 1008.2 1040.4 1072.9 1082.5 1105.8 29878.4 29964.1 30016.5 30038.0 30277.0 1099.6 1102.7 1190.6 1262.5 1269.9 12981.2 13577.5 13970.3 14723.0 15164.8 634114.0 637965.0 638076.0 638541.0 642416.0 58721.7 59688.4 60278.0 60294.0 60579.0 1354.2 1444.7 1491.9 1495.4 1497.3 24846.8 24873.1 28877.7 29335.4 29833.0 52348.7 57735.7 59414.7 59673.0 59907.4 543400.0 548432.0 550562.0 552568.0 555652.4 40103.4 41810.4 42238.0 42532.0 42638.0 46313.1 46345.8 46944.5 47027.0 48248.3 37333.4 38156.4 38795.0 38992.4 39153.8 37653.2 37656.8 37686.6 38259.0 39484.6 31878.7 37580.6 37771.6 37787.0 37859.6 31352.4 32597.7 32712.8 32762.3 32766.0 21256.8 24373.7 24727.3 24823.6 24938.6 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#2] jquery.html Cycles:180 Average:33714.21 Median:21661.40 stddev:98510.31 (454.8%) stddev-sans-first:98754.86 23:08:24 INFO - PROCESS | 1784 | Values: 384.5 394.6 396.0 397.5 408.1 19732.5 20312.0 20497.1 20516.9 21654.0 20431.7 20944.3 20963.6 21501.5 21668.8 5075.8 5472.2 5839.3 5852.3 5986.9 5352.7 5759.3 5810.4 5858.6 5877.3 5624.2 5709.3 5710.6 5713.8 5715.2 5641.9 5731.8 5856.0 6006.5 6007.8 5525.4 5653.2 5744.2 5752.2 5759.5 13631.6 13748.5 13795.5 13796.0 14103.6 1892.0 2041.3 2069.1 2095.5 2197.8 9800.4 9898.2 10016.4 10184.7 10242.3 1702.8 1722.6 1725.0 1743.9 1752.1 112672.3 117767.4 117978.0 118965.1 121891.0 11668.7 11703.6 11714.9 11718.1 11753.9 14552.1 14774.6 14818.0 14844.9 14849.4 7309.0 7480.9 8820.0 8842.7 8902.5 7542.8 8725.3 8749.7 8787.3 8790.2 4561.4 4599.0 4653.2 4653.7 4689.2 1649.5 1680.7 1980.3 1984.6 2004.7 902.0 927.0 946.2 954.1 1011.3 18580.8 19960.9 19979.9 20047.9 20112.5 971.4 992.8 993.4 1013.7 1038.1 10350.8 10463.4 10492.8 10674.7 10863.9 556867.0 585650.0 600541.0 604606.0 606069.0 90812.1 103995.5 129368.1 130666.0 132870.0 984.9 1024.3 1026.3 1029.5 1056.4 17436.5 17797.0 17830.3 17874.9 17980.6 29469.6 45600.0 49211.8 50423.7 50892.7 70427.0 73740.3 74634.6 75273.0 77440.6 7688.8 7742.1 7869.9 8066.1 8147.8 10456.8 12307.7 12334.6 12646.2 12849.4 10868.6 11091.4 11110.9 11134.6 11259.7 5093.8 8943.2 9234.3 10748.7 11404.3 9523.2 11312.9 11344.0 11592.2 11797.9 6995.7 7413.6 7498.6 7946.1 8570.9 6812.3 9795.0 11117.9 11221.1 11264.0 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#3] mootools.html Cycles:180 Average:5763.06 Median:4982.64 stddev:3195.66 (64.1%) stddev-sans-first:3189.20 23:08:24 INFO - PROCESS | 1784 | Values: 1584.5 1615.0 1885.7 1966.9 1969.2 7959.8 8494.1 8595.0 8847.2 8878.7 8582.4 8626.0 8715.0 8728.8 8747.4 2428.9 2443.2 2448.2 2464.6 2483.3 2184.7 2194.8 2204.1 2238.1 2248.3 2385.2 2479.0 2482.8 2483.3 2502.3 1959.7 2033.8 2152.8 2181.2 2217.2 2444.8 2454.4 2455.1 2461.3 2471.8 8844.4 8877.7 8907.6 8914.5 8922.8 5266.8 5358.4 5389.2 5446.6 5491.1 3566.2 3582.4 3636.7 3651.5 3702.9 936.0 1013.9 1052.9 1062.8 1068.4 11538.0 11644.8 11658.8 11727.9 11745.8 4609.0 4900.4 4965.4 4968.1 4982.5 9865.2 9876.1 10010.5 10019.7 10038.0 5400.8 5437.1 5474.8 5479.8 5498.9 4453.5 4805.6 4968.9 4970.4 4982.8 3843.8 4016.4 4017.3 4066.8 4122.7 1229.5 1350.3 1364.4 1369.4 1374.5 661.4 828.6 919.3 948.9 985.7 5681.2 5755.5 5847.8 6041.3 6075.8 936.9 939.5 1011.8 1012.4 1012.5 3981.7 4044.5 4045.4 4054.0 4072.5 10587.0 10874.0 10880.9 10898.1 10906.4 10677.9 10834.2 10915.8 10940.0 10963.0 8115.9 8205.9 8306.2 8357.6 8380.8 5777.1 5887.2 5912.3 6031.5 6176.3 9215.1 9371.5 9385.4 9593.8 9640.2 9824.2 9934.4 10026.5 10086.6 10222.8 7251.5 8115.9 8130.3 8149.2 8311.7 8339.3 8397.2 8399.1 8424.7 8429.0 7354.4 7526.0 7572.9 7598.1 7627.2 7317.6 7399.2 7401.7 7484.4 7515.4 7076.2 7181.2 7212.1 7219.2 7226.9 4423.1 4840.7 4936.3 5024.8 5160.5 4955.5 5019.8 5025.8 5081.5 5267.9 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#4] prototype.html Cycles:180 Average:5135.70 Median:5772.14 stddev:1647.41 (28.5%) stddev-sans-first:1611.04 23:08:24 INFO - PROCESS | 1784 | Values: 269.9 300.2 311.7 321.5 328.7 5060.5 5945.9 6005.0 6178.9 6198.7 4770.8 5373.7 5512.3 6105.3 6139.3 3609.7 4231.9 4562.1 4607.5 4689.6 3901.7 3937.3 4581.1 4582.6 4641.2 4302.5 4492.6 4493.8 4499.5 4522.4 3827.6 4383.6 4455.9 4511.2 4588.4 3659.0 3802.5 3806.3 3815.8 3837.4 4794.6 4973.8 5114.9 5673.3 5796.7 4406.4 4485.6 5126.6 5134.4 5149.7 5833.1 5891.6 6181.9 6411.9 6445.1 3527.9 4172.4 4188.9 4200.8 4217.0 5514.7 6700.8 6753.8 6796.2 6853.2 4272.9 4307.8 4314.7 4315.1 4336.5 4395.4 4400.1 4412.4 4415.0 4416.4 4788.6 5051.3 5153.5 5215.7 5231.9 6072.6 6099.8 6325.5 6515.6 6583.5 1309.9 1453.6 1499.3 1508.3 1510.9 937.2 962.6 993.1 997.9 999.7 5854.7 5879.5 5891.2 5908.3 5925.2 6623.8 6626.8 6636.8 6648.6 6696.6 4957.7 5204.5 5527.9 5868.5 5927.7 2951.5 3485.8 3497.9 3517.8 3547.0 6656.7 6956.5 6971.4 6998.7 7014.6 6840.3 6905.5 6915.6 6934.0 6938.4 5519.4 6616.6 6960.8 7014.8 7037.9 5756.7 6695.9 6701.2 6713.9 6714.5 5647.9 6058.8 6330.9 6609.8 6617.5 6549.7 6671.4 6959.0 6997.5 7045.1 3710.6 3870.7 4331.4 4332.6 4356.7 5002.6 6071.4 6596.4 6668.1 6676.7 5340.2 5492.6 6343.7 6436.5 6472.7 6104.1 6393.8 6398.9 6455.4 6489.2 6201.3 6229.8 6255.0 6289.9 6302.0 5746.1 5787.6 6416.0 6417.7 6421.6 5145.8 5419.4 5961.5 6099.7 6351.2 23:08:24 INFO - PROCESS | 1784 | 23:08:24 INFO - PROCESS | 1784 | [#5] yui.html Cycles:180 Average:18420.13 Median:597.36 stddev:26581.01 (4449.8%) stddev-sans-first:26622.00 23:08:24 INFO - PROCESS | 1784 | Values: 624.6 671.2 700.6 747.0 789.4 14503.1 14850.0 14977.5 14996.0 15074.2 11755.2 13670.9 13712.2 13837.3 13860.3 618.8 754.4 873.6 889.2 889.6 886.0 910.4 914.0 920.2 922.4 734.8 850.3 942.6 959.3 973.2 851.1 958.3 960.3 960.6 966.2 694.6 709.9 715.6 721.4 724.4 14845.0 14884.9 14915.5 14968.9 15009.5 10982.3 12809.7 12877.9 12943.1 12986.4 10002.1 10053.2 10145.9 10265.5 10536.0 3411.8 3459.7 3505.3 3526.0 3631.1 86586.0 87377.6 90546.0 90835.5 91720.0 9516.1 9603.3 9613.9 9774.8 9849.5 20700.5 21994.2 22087.6 22169.7 22354.7 7196.3 7462.3 7585.1 7628.5 7776.9 3972.0 4003.8 4009.6 4016.3 4026.0 3552.8 3603.3 3656.9 3660.6 3671.0 638.8 645.2 649.4 660.2 661.9 505.9 520.9 553.1 564.6 611.0 6474.4 7931.8 8111.6 8127.0 8131.2 537.8 566.8 568.1 596.6 598.1 3812.8 3837.5 3838.3 3973.1 3983.9 98618.5 98778.0 98805.0 100321.5 101034.0 78366.0 79222.6 82081.0 82347.6 84205.0 60700.4 61487.2 62834.7 68304.0 73933.6 7491.2 7510.8 7593.7 7785.9 7946.3 42300.0 44493.0 45052.0 45149.3 45173.3 50518.0 66887.3 67226.3 67448.0 69171.6 10079.2 10146.7 10228.7 10279.9 10965.2 11833.1 11980.2 12365.0 12461.7 12965.2 11025.4 11458.7 11479.8 11887.3 12015.6 9885.7 11506.0 11558.2 11896.9 12059.7 10988.6 11313.3 11546.8 11632.0 11721.2 9413.0 9758.5 9761.2 9782.1 9864.3 9751.6 9823.9 9925.1 9957.0 10039.5 23:08:24 INFO - PROCESS | 1784 | -------- Summary: end -------- 23:08:24 INFO - PROCESS | 1784 | 23:08:25 INFO - TEST-INFO | 1784: exit 0 23:08:25 INFO - TEST-OK | dromaeo_css | took 1112723ms 23:08:25 INFO - TEST-START | kraken 23:08:25 INFO - operating with platform_type : mac_ 23:08:25 INFO - Initialising browser for kraken test... 23:08:25 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpwqzpiJ/profile http://localhost:49276/getInfo.html) 23:08:27 INFO - PROCESS | 1902 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 23:08:28 INFO - PROCESS | 1902 | __metrics Screen width/height:1600/1200 23:08:28 INFO - PROCESS | 1902 | colorDepth:24 23:08:28 INFO - PROCESS | 1902 | Browser inner width/height: 1024/689 23:08:28 INFO - PROCESS | 1902 | __metrics 23:08:28 ERROR - PROCESS | 1902 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:08:28 INFO - TEST-INFO | 1902: exit 0 23:08:28 INFO - Browser initialized. 23:08:28 INFO - Running cycle 1/1 for kraken test... 23:08:28 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpwqzpiJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:40 INFO - PROCESS | 1906 | RSS: Main: 247832576 23:08:40 INFO - PROCESS | 1906 | 23:08:43 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 23:08:44 INFO - PROCESS | 1906 | RSS: Main: 260145152 23:08:44 INFO - PROCESS | 1906 | 23:08:47 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 23:08:47 INFO - PROCESS | 1906 | RSS: Main: 268967936 23:08:47 INFO - PROCESS | 1906 | 23:08:51 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 23:08:51 INFO - PROCESS | 1906 | RSS: Main: 272273408 23:08:51 INFO - PROCESS | 1906 | 23:08:53 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 23:08:54 INFO - PROCESS | 1906 | RSS: Main: 273371136 23:08:54 INFO - PROCESS | 1906 | 23:08:56 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:08:57 INFO - PROCESS | 1906 | RSS: Main: 274403328 23:08:57 INFO - PROCESS | 1906 | 23:09:00 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 23:09:00 INFO - PROCESS | 1906 | RSS: Main: 273739776 23:09:00 INFO - PROCESS | 1906 | 23:09:03 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 23:09:04 INFO - PROCESS | 1906 | RSS: Main: 275189760 23:09:04 INFO - PROCESS | 1906 | 23:09:07 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 23:09:08 INFO - PROCESS | 1906 | RSS: Main: 275599360 23:09:08 INFO - PROCESS | 1906 | 23:09:09 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:09:10 INFO - PROCESS | 1906 | RSS: Main: 275210240 23:09:10 INFO - PROCESS | 1906 | 23:09:12 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:09:12 INFO - PROCESS | 1906 | RSS: Main: 275386368 23:09:12 INFO - PROCESS | 1906 | 23:09:15 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:09:15 INFO - PROCESS | 1906 | RSS: Main: 275644416 23:09:15 INFO - PROCESS | 1906 | 23:09:18 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:09:19 INFO - PROCESS | 1906 | RSS: Main: 275812352 23:09:19 INFO - PROCESS | 1906 | 23:09:22 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:09:23 INFO - PROCESS | 1906 | RSS: Main: 275648512 23:09:23 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 23:09:25 INFO - PROCESS | 1906 | RSS: Main: 277204992 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | __start_tp_report 23:09:25 INFO - PROCESS | 1906 | _x_x_mozilla_page_load 23:09:25 INFO - PROCESS | 1906 | _x_x_mozilla_page_load_details 23:09:25 INFO - PROCESS | 1906 | |i|pagename|runs| 23:09:25 INFO - PROCESS | 1906 | |0;ai-astar;117;110;118;117;115;128;117;115;116;117 23:09:25 INFO - PROCESS | 1906 | |1;audio-beat-detection;153;108;171;202;121;143;139;126;190;161 23:09:25 INFO - PROCESS | 1906 | |2;audio-dft;195;197;205;238;251;290;259;202;203;201 23:09:25 INFO - PROCESS | 1906 | |3;audio-fft;69;81;74;145;56;56;60;99;153;81 23:09:25 INFO - PROCESS | 1906 | |4;audio-oscillator;77;110;176;115;88;83;120;77;83;122 23:09:25 INFO - PROCESS | 1906 | |5;imaging-gaussian-blur;91;88;89;88;90;87;92;89;90;88 23:09:25 INFO - PROCESS | 1906 | |6;imaging-darkroom;95;97;103;90;126;132;120;152;113;153 23:09:25 INFO - PROCESS | 1906 | |7;imaging-desaturate;90;108;94;91;93;89;87;104;145;126 23:09:25 INFO - PROCESS | 1906 | |8;json-parse-financial;61;64;61;61;62;61;62;78;63;61 23:09:25 INFO - PROCESS | 1906 | |9;json-stringify-tinderbox;65;70;87;106;108;78;70;67;70;67 23:09:25 INFO - PROCESS | 1906 | |10;stanford-crypto-aes;96;79;96;102;107;110;111;107;82;85 23:09:25 INFO - PROCESS | 1906 | |11;stanford-crypto-ccm;141;139;139;158;175;162;169;158;197;207 23:09:25 INFO - PROCESS | 1906 | |12;stanford-crypto-pbkdf2;226;164;230;170;213;226;173;175;171;173 23:09:25 INFO - PROCESS | 1906 | |13;stanford-crypto-sha256-iterative;61;100;100;100;99;91;68;68;69;65 23:09:25 INFO - PROCESS | 1906 | __end_tp_report 23:09:25 INFO - PROCESS | 1906 | __start_cc_report 23:09:25 INFO - PROCESS | 1906 | _x_x_mozilla_cycle_collect,1044 23:09:25 INFO - PROCESS | 1906 | __end_cc_report 23:09:25 INFO - PROCESS | 1906 | __startTimestamp1460873365425__endTimestamp 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | ------- Summary: start ------- 23:09:25 INFO - PROCESS | 1906 | Number of tests: 14 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.00 stddev:4.47 (3.8%) stddev-sans-first:4.74 23:09:25 INFO - PROCESS | 1906 | Values: 117.0 110.0 118.0 117.0 115.0 128.0 117.0 115.0 116.0 117.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:157.00 stddev:30.20 (19.2%) stddev-sans-first:32.02 23:09:25 INFO - PROCESS | 1906 | Values: 153.0 108.0 171.0 202.0 121.0 143.0 139.0 126.0 190.0 161.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#2] audio-dft Cycles:10 Average:224.10 Median:221.50 stddev:33.15 (15.0%) stddev-sans-first:33.45 23:09:25 INFO - PROCESS | 1906 | Values: 195.0 197.0 205.0 238.0 251.0 290.0 259.0 202.0 203.0 201.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#3] audio-fft Cycles:10 Average:87.40 Median:71.50 stddev:35.08 (49.1%) stddev-sans-first:36.58 23:09:25 INFO - PROCESS | 1906 | Values: 69.0 81.0 74.0 145.0 56.0 56.0 60.0 99.0 153.0 81.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:77.00 stddev:30.74 (39.9%) stddev-sans-first:30.88 23:09:25 INFO - PROCESS | 1906 | Values: 77.0 110.0 176.0 115.0 88.0 83.0 120.0 77.0 83.0 122.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.50 stddev:1.55 (1.7%) stddev-sans-first:1.50 23:09:25 INFO - PROCESS | 1906 | Values: 91.0 88.0 89.0 88.0 90.0 87.0 92.0 89.0 90.0 88.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#6] imaging-darkroom Cycles:10 Average:118.10 Median:152.50 stddev:22.73 (14.9%) stddev-sans-first:22.52 23:09:25 INFO - PROCESS | 1906 | Values: 95.0 97.0 103.0 90.0 126.0 132.0 120.0 152.0 113.0 153.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:89.50 stddev:19.04 (21.3%) stddev-sans-first:19.64 23:09:25 INFO - PROCESS | 1906 | Values: 90.0 108.0 94.0 91.0 93.0 89.0 87.0 104.0 145.0 126.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:62.00 stddev:5.23 (8.4%) stddev-sans-first:5.48 23:09:25 INFO - PROCESS | 1906 | Values: 61.0 64.0 61.0 61.0 62.0 61.0 62.0 78.0 63.0 61.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:70.00 stddev:16.20 (23.1%) stddev-sans-first:16.39 23:09:25 INFO - PROCESS | 1906 | Values: 65.0 70.0 87.0 106.0 108.0 78.0 70.0 67.0 70.0 67.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:80.50 stddev:11.94 (14.8%) stddev-sans-first:12.65 23:09:25 INFO - PROCESS | 1906 | Values: 96.0 79.0 96.0 102.0 107.0 110.0 111.0 107.0 82.0 85.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#11] stanford-crypto-ccm Cycles:10 Average:164.50 Median:165.50 stddev:23.42 (14.2%) stddev-sans-first:23.25 23:09:25 INFO - PROCESS | 1906 | Values: 141.0 139.0 139.0 158.0 175.0 162.0 169.0 158.0 197.0 207.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:194.00 stddev:27.72 (14.3%) stddev-sans-first:26.55 23:09:25 INFO - PROCESS | 1906 | Values: 226.0 164.0 230.0 170.0 213.0 226.0 173.0 175.0 171.0 173.0 23:09:25 INFO - PROCESS | 1906 | 23:09:25 INFO - PROCESS | 1906 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.10 Median:68.00 stddev:17.10 (25.2%) stddev-sans-first:16.35 23:09:25 INFO - PROCESS | 1906 | Values: 61.0 100.0 100.0 100.0 99.0 91.0 68.0 68.0 69.0 65.0 23:09:25 INFO - PROCESS | 1906 | -------- Summary: end -------- 23:09:25 INFO - PROCESS | 1906 | 23:09:26 INFO - TEST-INFO | 1906: exit 0 23:09:26 INFO - TEST-OK | kraken | took 60908ms 23:09:26 INFO - SUITE-END | took 1173s 23:09:26 INFO - Completed test suite (00:19:34) 23:09:26 INFO - javascript benchmark 23:09:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3473.531073305103, 3478.4434490481526, 3517.56030496188, 3521.225330427305, 3559.9110022249456, 5788.6237394569325, 5810.273715785527, 5812.186293918844, 5816.069428891378, 5859.121131830222, 5703.0, 5811.418858114186, 5873.2658417697785, 5917.9408205917925, 5954.523638108952, 4378.956210437895, 5253.658512196714, 5320.042392369372, 5339.5461385782255, 5384.542313903323, 4562.9771851140695, 5385.165299378603, 5404.378496472897, 5417.64785288957, 5426.050441172799, 4481.417415735966, 5179.637425380225, 5329.094054010828, 5384.1923711443205, 5411.918821217685, 5232.476752324776, 5239.1093514102695, 5287.312649355597, 5304.522592966651, 5330.813421530266, 5232.450592687751, 5272.472752724735, 5299.973500132513, 5308.6549374290935, 5369.6509726867625, 4405.405270288534, 5678.290213723285, 5681.119426488901, 5727.427257274281, 5785.305763308388, 5125.410577783566, 6247.531435142337, 6280.15217945576, 6302.149209856657, 6303.117563541108, 5458.781648734046, 6225.377851675462, 6245.781397651061, 6279.183706118175, 6283.057541368831, 4264.232438161161, 5221.008008478377, 5651.15232715096, 5941.55438342126, 5954.374790647006, 6292.402221788922, 6326.5255105867245, 6338.30278669346, 6478.676066196671, 6578.572392794453, 6061.090836374491, 6226.501879849601, 6240.126382306481, 6241.625502469867, 6248.656323902229, 4713.646476514206, 5423.783048678088, 6007.339192688801, 6182.938170618237, 6249.75000999964, 6123.252118380239, 6146.938530614726, 6252.656103914237, 6289.937100629027, 6316.905246421307, 6206.286277078104, 6260.248770147611, 6266.091416744546, 6314.873702526015, 6334.398232167937, 4850.927236091461, 4929.260610908393, 4985.875353116201, 4994.2508623706735, 5048.722320272347, 4432.312991486325, 4463.995600989751, 4473.597376236155, 4481.462224533077, 4513.390692256569, 4905.3623029006, 5681.147827825859, 5760.654360738369, 5847.298324201038, 5899.793507227227, 6192.442680158807, 6255.906161407583, 6262.937370626327, 6263.6241825490615, 6266.436020758154, 5838.854028649318, 5844.977129002407, 5881.147233651097, 5887.735051922674, 5929.14027466015, 5321.095413779667, 5431.619786614899, 5451.972740136274, 5475.206095116266, 5481.862953426196, 6317.5893566918, 6337.8732425352155, 6339.936600634027, 6347.55567110307, 6357.936420635734, 6267.1539342188225, 6272.686365681789, 6286.654234017173, 6287.685615719104, 6305.4009869062365, 6315.4947604192475, 6336.303006669171, 6354.93645063562, 6359.841003974938, 6360.395762402564, 6097.585173720644, 6119.624418755179, 6119.785807496716, 6120.479759220516, 6122.6727265478185, 4949.7030178189425, 6136.20229370179, 6148.44663980244, 6174.197354343906, 6206.565540412128, 5762.510186634016, 6013.849653758691, 6046.3046749624355, 6083.0, 6104.156010547808, 5199.11615025439, 6059.697015149313, 6109.283207920342, 6226.719797609209, 6251.593646412968, 5010.599152067753, 5178.61160412963, 6042.758289668364, 6044.879102418015, 6075.453209211192, 4930.654854160174, 5024.271480635361, 6150.477209437248, 6186.1648677427975, 6200.3179650239335, 6065.72704228302, 6094.939050609437, 6104.6642434664745, 6117.602355846856, 6118.357572454828], "value": 5665.740234025059, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.8664140644136, 526.4682670502792, 567.6906086183031, 571.6598623818826, 601.8044135655913, 37339.813300933456, 37889.810550947244, 38710.0, 38823.61176388232, 38883.61116388835, 34099.0, 36099.195040248014, 36272.818635906784, 41793.373099403456, 41836.58163418373, 9415.67045153418, 9575.664851730191, 9614.855777163348, 9651.855222171673, 9684.854727179098, 8219.506829590215, 9467.71596852092, 9506.334556581041, 9586.856197157049, 9660.323777335589, 9545.713628591153, 9548.713538593853, 9568.90431095687, 9603.615855365777, 9687.564059617334, 9511.476868772215, 9539.713808585753, 9581.952090239574, 9616.134547890724, 9624.95187524065, 9490.0, 9520.90479095214, 9563.760905977337, 9588.856167157499, 9626.855597166048, 37697.24605507902, 37893.0, 38089.0, 38150.23699525994, 38188.80905595482, 3191.776575639706, 3212.5984251968503, 3496.1783980764753, 3496.493008513751, 3849.634284742973, 26439.603405948925, 26903.0, 26922.865385673143, 27370.315742106606, 27586.862065689744, 2679.1025006622804, 2699.1227850948326, 2729.3586007288272, 2773.62556054934, 2847.501687204731, 529619.0, 534643.0, 540155.0, 542348.0, 542866.0, 58878.41121588815, 58879.0, 59265.40734592599, 59281.110783338285, 59317.703411483526, 66579.0, 67447.0, 67449.66275168593, 67679.0, 68609.0, 16515.6696866062, 16681.582960425843, 16719.247633856507, 16725.498235052968, 16747.246373913204, 10829.945850270857, 12447.377631118588, 12779.488820447077, 12847.48610055606, 12915.902148317498, 18668.346607868658, 19508.51228719265, 19525.414237572895, 19846.801531984784, 20018.59962800736, 7976.0, 8037.557934313552, 8094.31198348147, 8107.594620268963, 8134.94245748049, 1008.178334657252, 1040.4433628008978, 1072.8658917635296, 1082.480409403475, 1105.784372047443, 29878.40243195124, 29964.101076967727, 30016.54975175374, 30038.0, 30277.0, 1099.6426161497545, 1102.6802227354137, 1190.571394298052, 1262.5291535361384, 1269.911106222574, 12981.156224845356, 13577.524786632617, 13970.301484925712, 14723.043002204822, 15164.772528412082, 634114.0, 637965.0, 638076.0, 638541.0, 642416.0, 58721.70639146862, 59688.40311596915, 60278.0, 60293.99999999912, 60579.0, 1354.2348573056192, 1444.7399468095844, 1491.9328630211667, 1495.4317359403358, 1497.2588568658584, 24846.84639996089, 24873.129440469496, 28877.700503476975, 29335.413291734472, 29833.0, 52348.738256308476, 57735.71132144312, 59414.70292648509, 59673.0, 59907.400925991926, 543400.0, 548432.0, 550562.0, 552568.0, 555652.4434755762, 40103.3984490227, 41810.37284440675, 42238.0, 42532.0, 42638.0, 46313.073738525716, 46345.76827115843, 46944.53055469538, 47027.0, 48248.276275856595, 37333.4399983995, 38156.42765358577, 38795.0, 38992.41511377388, 39153.80423097866, 37653.246935061696, 37656.81171594124, 37686.62313376831, 38259.0, 39484.61803836852, 31878.68121318757, 37580.624193757714, 37771.62228377681, 37787.0, 37859.62140378671, 31352.37295254128, 32597.674023260413, 32712.83643581767, 32762.34475310528, 32766.0, 21256.787432125482, 24373.708389705575, 24727.25818225456, 24823.627645585693, 24938.62592061084], "value": 20167.48849168272, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.49055002122185, 394.6487626012849, 395.95644479107295, 397.4892263441478, 408.08181591419304, 19732.506687332825, 20312.0, 20497.077631506563, 20516.87157206353, 21654.0255688494, 20431.693524597107, 20944.26695065673, 20963.580728385463, 21501.462463438424, 21668.783312166874, 5075.822346217883, 5472.151816468444, 5839.299284085916, 5852.268466441695, 5986.940130598682, 5352.732363381835, 5759.308882934054, 5810.418958104198, 5858.5606079543995, 5877.265341832271, 5624.15637654353, 5709.257796486471, 5710.600257981943, 5713.8285851424525, 5715.228444160048, 5641.915371269434, 5731.799387021455, 5855.970720146414, 6006.519478441714, 6007.8197654070445, 5525.4474552544825, 5653.180288858133, 5744.22452968848, 5752.165935939307, 5759.452851979056, 13631.591052268448, 13748.518801841987, 13795.517156899461, 13796.034277600573, 14103.647408814759, 1891.9527011824678, 2041.2957529652247, 2069.14130635786, 2095.549456866766, 2197.791209835079, 9800.36297640651, 9898.208143348515, 10016.449095299755, 10184.694459166238, 10242.334248273914, 1702.8212037736107, 1722.552136444521, 1724.9655006899918, 1743.8953662780273, 1752.0538908989258, 112672.32975908606, 117767.41116294364, 117978.0, 118965.06239391572, 121891.0, 11668.70828229301, 11703.648890533297, 11714.88285117138, 11718.06255499558, 11753.941230293965, 14552.126872387691, 14774.556763297118, 14818.0, 14844.851551484562, 14849.40602375893, 7308.96345518269, 7480.877868319687, 8820.029796722356, 8842.690505832265, 8902.510361930026, 7542.811429714191, 8725.345599080094, 8749.687546868021, 8787.297016238686, 8790.164934331227, 4561.361409402686, 4599.0, 4653.208954477747, 4653.674242802971, 4689.249720044776, 1649.5381293237913, 1680.7058764716126, 1980.2870966452065, 1984.583237520137, 2004.6692295771034, 901.9909800902038, 927.0497739816742, 946.1626460582297, 954.0888451528773, 1011.2567263061644, 18580.81419185818, 19960.90019549893, 19979.900100499406, 20047.899760501397, 20112.49718757043, 971.3977334052933, 992.811365840488, 993.3642468819961, 1013.6705570689555, 1038.1020417339098, 10350.757909050963, 10463.372197668165, 10492.842607360895, 10674.679759607225, 10863.945680271549, 556867.0, 585650.0, 600541.0, 604606.0, 606069.0, 90812.09187908168, 103995.48002260091, 129368.05947910789, 130666.0, 132870.0, 984.8719666443317, 1024.3188279794003, 1026.2867307221409, 1029.53156313877, 1056.3978532236706, 17436.47690569285, 17797.0, 17830.286788528832, 17874.910625446788, 17980.550486237426, 29469.557956631095, 45600.0, 49211.753941231495, 50423.74788126183, 50892.74553627208, 70427.0, 73740.26259737334, 74634.62682686551, 75273.0, 77440.61279693566, 7688.807779805549, 7742.109657389247, 7869.8819517708425, 8066.072401673882, 8147.75556733299, 10456.843147352643, 12307.69230769238, 12334.629961101182, 12646.241225526497, 12849.357532123544, 10868.565257389932, 11091.389973551532, 11110.888891110986, 11134.554617815196, 11259.718507037389, 5093.797616965944, 8943.23982461472, 9234.33781919261, 10748.656417947757, 11404.258723182966, 9523.238140948846, 11312.886871131182, 11344.035756960751, 11592.221967255666, 11797.882021180021, 6995.685194166376, 7413.555186688816, 7498.550086994798, 7946.125926148004, 8570.871436928326, 6812.284710105368, 9794.951025244829, 11117.888821111685, 11221.102311815199, 11264.0], "value": 9174.344350432802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1584.5246426072179, 1615.022911138761, 1885.7171424286355, 1966.862319637625, 1969.1729473621078, 7959.840803183933, 8494.065652778205, 8594.957025214873, 8847.203751662357, 8878.689245876396, 8582.442141260814, 8625.95687021564, 8715.041345451995, 8728.781780455492, 8747.387682862201, 2428.878556072198, 2443.181534186041, 2448.204333591581, 2464.6198129047702, 2483.267436106344, 2184.737831460227, 2194.7914948079906, 2204.0853045985896, 2238.149503188786, 2248.325502349297, 2385.153270588942, 2478.962815557759, 2482.7889629381525, 2483.341914392687, 2502.336880726609, 1959.7158412030246, 2033.806788355104, 2152.762161756996, 2181.19295860531, 2217.15748015753, 2444.767747064026, 2454.4232105455203, 2455.1407007547396, 2461.310832966772, 2471.8022558195303, 8844.38089333747, 8877.689280875138, 8907.55462226886, 8914.465132092031, 8922.821543569093, 5266.8419947401635, 5358.383785864637, 5389.245505629176, 5446.618736688433, 5491.093969495028, 3566.1619519413175, 3582.3551760683067, 3636.7272454566014, 3651.4522821576975, 3702.9074273143387, 935.9672411465566, 1013.9138173255283, 1052.85786418834, 1062.8033813744405, 1068.380339403152, 11538.0, 11644.767104657943, 11658.825117623242, 11727.882721172764, 11745.765084698258, 4609.0, 4900.362952816111, 4965.35450391454, 4968.105740966588, 4982.451930287665, 9865.161461275728, 9876.061774131445, 10010.54952527138, 10019.699409017741, 10038.0, 5400.783968641289, 5437.075697131497, 5474.781008759605, 5479.75341109651, 5498.862528436741, 4453.532379100147, 4805.6155507559315, 4968.90062198754, 4970.378702662168, 4982.825601103944, 3843.808419389998, 4016.357382818735, 4017.3371393720295, 4066.8373265069067, 4122.711410201257, 1229.5266322465916, 1350.3248375812093, 1364.3928451838835, 1369.431685850361, 1374.5326588959801, 661.3981277037968, 828.5732847583499, 919.2691810011057, 948.8529277961927, 985.7141428985657, 5681.204631351614, 5755.510781583613, 5847.8245652630485, 6041.2750469943885, 6075.787347442907, 936.8875734911718, 939.5208443693765, 1011.7723512209841, 1012.3723291559133, 1012.5443550402348, 3981.7013723970817, 4044.51465824103, 4045.4134150548007, 4053.959460405358, 4072.511298644182, 10587.0, 10874.0, 10880.945595271973, 10898.128149748001, 10906.400147991937, 10677.93220677926, 10834.241603087867, 10915.836262456069, 10940.015398614163, 10963.013328800445, 8115.860910935347, 8205.876911846328, 8306.210909963544, 8357.58212089393, 8380.832383352421, 5777.133429985536, 5887.1757953887345, 5912.349641539513, 6031.517478601613, 6176.258848938156, 9215.124563166488, 9371.531423428938, 9385.436873787594, 9593.808123837345, 9640.228781697588, 9824.21406287481, 9934.403935763878, 10026.548805303633, 10086.6469673564, 10222.846657299997, 7251.5286506376915, 8115.878261825959, 8130.268275855202, 8149.225823546753, 8311.667533298842, 8339.291160251447, 8397.160283971554, 8399.076101628694, 8424.747257582283, 8428.95785521093, 7354.374878135419, 7526.0, 7572.886406703794, 7598.12621548529, 7627.237276272328, 7317.597532135784, 7399.18608953004, 7401.7039318428815, 7484.401247900046, 7515.398768098648, 7076.221615622175, 7181.210066892533, 7212.062431883822, 7219.205887352492, 7226.855462890818, 4423.071155057474, 4840.7337596432535, 4936.259561065869, 5024.773885175249, 5160.535551800356, 4955.45489996093, 5019.799208031638, 5025.7989680412375, 5081.517255860687, 5267.920981185362], "value": 4640.829348532957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.8691134799623, 300.17152658662087, 311.7396973527105, 321.4679705088079, 328.74193757900053, 5060.493950604942, 5945.940540594593, 6004.969975150129, 6178.876422471553, 6198.659073750948, 4770.7853146608395, 5373.656585853537, 5512.283403157594, 6105.297890742566, 6139.342377558068, 3609.7473176877565, 4231.894202644928, 4562.133194693014, 4607.516210797869, 4689.554492323241, 3901.6683581895572, 3937.310970580151, 4581.129585378784, 4582.564656357641, 4641.187792136379, 4302.526722060571, 4492.640588752892, 4493.820247190109, 4499.460064792213, 4522.4346956630425, 3827.6172382761642, 4383.59724888036, 4455.933161002571, 4511.2330903746115, 4588.357629931812, 3659.0, 3802.524684414448, 3806.276807406584, 3815.771053736785, 3837.4052021936645, 4794.640401969831, 4973.756560859785, 5114.897702045975, 5673.26247587815, 5796.710164491758, 4406.361077643755, 4485.596296333346, 5126.590187698375, 5134.435212126663, 5149.742512874379, 5833.125031245305, 5891.558133140032, 6181.907271390933, 6411.903821442682, 6445.065465507522, 3527.9294414111832, 4172.374143878443, 4188.916221675549, 4200.810963506649, 4217.030083080878, 5514.724263786795, 6700.836625496286, 6753.797386078377, 6796.218434880003, 6853.17761868579, 4272.850450234227, 4307.8276868924895, 4314.697971142053, 4315.136972605492, 4336.457942757155, 4395.406620106309, 4400.053988392511, 4412.426384569985, 4414.977925110418, 4416.38170656102, 4788.640851936118, 5051.3433253676785, 5153.458886816905, 5215.68705877641, 5231.9476805232225, 6072.605280656743, 6099.817005489843, 6325.5255855811, 6515.641639709862, 6583.539152259392, 1309.9083064185415, 1453.571196497036, 1499.272852666456, 1508.3287936868207, 1510.9093454392553, 937.1893312284825, 962.605331813953, 993.101243374747, 997.8503224516236, 999.7200783780553, 5854.677992710356, 5879.500242479352, 5891.23413956183, 5908.320543137508, 5925.170476133345, 6623.801285961429, 6626.801195964129, 6636.767713130017, 6648.634325112165, 6696.5647232929705, 4957.652964292537, 5204.4535323791215, 5527.944720552743, 5868.530517558585, 5927.703614819242, 2951.5130003549602, 3485.7734247273847, 3497.877574284888, 3517.753757236969, 3547.0, 6656.667166641745, 6956.5478243914995, 6971.4314279286755, 6998.720051197895, 7014.614196219257, 6840.31596840322, 6905.482088843256, 6915.550489218184, 6933.965330173317, 6938.375546200866, 5519.39286678465, 6616.569922954993, 6960.79117626472, 7014.789556313319, 7037.92962070383, 5756.71216439168, 6695.933040669531, 6701.1623547056615, 6713.899291510729, 6714.529982901346, 5647.858803529944, 6058.787942421994, 6330.8733825322315, 6609.834754131185, 6617.470602351897, 6549.672516374258, 6671.432928201158, 6958.965205173941, 6997.545159564612, 7045.11936007999, 3710.6474884885893, 3870.651641352292, 4331.415258940003, 4332.566743325642, 4356.6950313477755, 5002.574781143643, 6071.392860713893, 6596.406323430914, 6668.133142691419, 6676.6661666917435, 5340.1963968916625, 5492.571931297812, 6343.682815859096, 6436.485081193399, 6472.708728107341, 6104.084387341934, 6393.808185754435, 6398.872022559616, 6455.386738259858, 6489.221293444817, 6201.3178550358525, 6229.813105606839, 6255.0, 6289.905651415141, 6301.96849015752, 5746.080627099644, 5787.565932554991, 6416.0, 6417.711202995783, 6421.582597131359, 5145.7650163961125, 5419.403865574863, 5961.52307815365, 6099.664518451527, 6351.2060992375955], "value": 4598.643296648395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.6470744029623, 671.1845108193545, 700.6391708270238, 746.9813254668633, 789.3961119743398, 14503.057301275423, 14850.0, 14977.47578834741, 14996.025258358199, 15074.170920599363, 11755.235909665887, 13670.863291367083, 13712.24582647954, 13837.308134593255, 13860.306984650751, 618.7586841131962, 754.3625636337287, 873.6022364217265, 889.1508609278156, 889.5952341684518, 885.9512726800024, 910.41733290694, 914.0174312613933, 920.185635712396, 922.3820040572832, 734.8273155808381, 850.3154960256991, 942.5664194470552, 959.3140904253457, 973.1679414100948, 851.1445996773203, 958.3339578992603, 960.3181740963889, 960.5965494492295, 966.2415004221688, 694.5658963148032, 709.8544798316333, 715.6421789105448, 721.3651986252117, 724.4023680463586, 14845.0, 14884.925575372163, 14915.477958271516, 14968.850311496963, 15009.549713508612, 10982.341059536373, 12809.679758006125, 12877.871221287762, 12943.093983421164, 12986.35068246584, 10002.149817265541, 10053.195744340508, 10145.949270253675, 10265.486725663686, 10536.0, 3411.8294085295884, 3459.723222142248, 3505.2989402119424, 3525.988190135812, 3631.1103779574173, 86586.0, 87377.56311218467, 90545.99999999935, 90835.54582227046, 91720.0, 9516.143547080657, 9603.279754018477, 9613.903860961302, 9774.804503910023, 9849.50752462374, 20700.482487937923, 21994.230201943174, 22087.558248834932, 22169.667454988186, 22354.66468002981, 7196.316349946746, 7462.291082347168, 7585.089789225218, 7628.504147230412, 7776.922230777733, 3972.0467087898696, 4003.779792111462, 4009.599040095967, 4016.2770701274053, 4025.9597404025585, 3552.751307408457, 3603.3153700796765, 3656.8902932911524, 3660.615635358249, 3670.963290367062, 638.7604648256904, 645.2128403347908, 649.3636236488268, 660.2374257732326, 661.9172603424572, 505.88617561049205, 520.9426963034064, 553.1177771454524, 564.6132399306488, 610.9725062372204, 6474.4496717778575, 7931.801704957422, 8111.594420278962, 8127.0, 8131.227533384319, 537.8278950735727, 566.7591273708657, 568.1080703295786, 596.588354035714, 598.1267349669445, 3812.8474861005247, 3837.520309961255, 3838.347480928249, 3973.1133699606835, 3983.940240896389, 98618.52072218922, 98778.0, 98805.0, 100321.47630664981, 101034.0, 78366.0, 79222.6038869802, 82081.0, 82347.5882620583, 84205.0, 60700.39299606947, 61487.15538533851, 62834.68582657149, 68304.0, 73933.63033184799, 7491.175970643116, 7510.8497830042, 7593.734219302298, 7785.883211751715, 7946.284834364704, 42300.0, 44493.0, 45052.0, 45149.322760157964, 45173.322400163364, 50518.0, 66887.33112669006, 67226.327736722, 67448.0, 69171.61656766736, 10079.24405669563, 10146.695599131743, 10228.744281393025, 10279.94860025695, 10965.232433729563, 11833.11251656112, 11980.161388702707, 12365.0, 12461.688457788265, 12965.15726477776, 11025.393603351893, 11458.713532161762, 11479.770404592029, 11887.346195959306, 12015.639530814089, 9885.703428897144, 11506.0, 11558.248713833575, 11896.940515297369, 12059.698507537381, 10988.560457581927, 11313.264637798517, 11546.82679759821, 11632.0, 11721.179517433698, 9412.95293523528, 9758.46328451942, 9761.219102471927, 9782.070703283176, 9864.309498335047, 9751.56117974707, 9823.901760982299, 9925.057119573918, 9956.950215248877, 10039.548220330249], "value": 6837.983485034081, "unit": "score"}], "name": "dromaeo_css", "value": 7313.292398859986, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 110.0, 118.0, 117.0, 115.0, 128.0, 117.0, 115.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 108.0, 171.0, 202.0, 121.0, 143.0, 139.0, 126.0, 190.0, 161.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 205.0, 238.0, 251.0, 290.0, 259.0, 202.0, 203.0, 201.0], "value": 224.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 74.0, 145.0, 56.0, 56.0, 60.0, 99.0, 153.0, 81.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 110.0, 176.0, 115.0, 88.0, 83.0, 120.0, 77.0, 83.0, 122.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 89.0, 88.0, 90.0, 87.0, 92.0, 89.0, 90.0, 88.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 103.0, 90.0, 126.0, 132.0, 120.0, 152.0, 113.0, 153.0], "value": 118.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 108.0, 94.0, 91.0, 93.0, 89.0, 87.0, 104.0, 145.0, 126.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 61.0, 61.0, 62.0, 61.0, 62.0, 78.0, 63.0, 61.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 87.0, 106.0, 108.0, 78.0, 70.0, 67.0, 70.0, 67.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 79.0, 96.0, 102.0, 107.0, 110.0, 111.0, 107.0, 82.0, 85.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 139.0, 158.0, 175.0, 162.0, 169.0, 158.0, 197.0, 207.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 164.0, 230.0, 170.0, 213.0, 226.0, 173.0, 175.0, 171.0, 173.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 100.0, 100.0, 100.0, 99.0, 91.0, 68.0, 68.0, 69.0, 65.0], "value": 82.1, "unit": "score"}], "name": "kraken", "value": 1673.3999999999999, "alertThreshold": 2.0}]} 23:09:26 INFO - Return code: 0 23:09:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:09:26 ERROR - # TBPL SUCCESS # 23:09:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:09:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:09:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:09:26 INFO - 2016-04-16 23:09:26.741 screenresolution[1916:11452] starting screenresolution argv=screenresolution get 23:09:26 INFO - 2016-04-16 23:09:26.744 screenresolution[1916:11452] Display 0: 1600x1200x32@60 23:09:26 INFO - 2016-04-16 23:09:26.750 screenresolution[1917:11455] starting screenresolution argv=screenresolution list 23:09:26 INFO - Available Modes on Display 0 23:09:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:09:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:09:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:09:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:09:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:09:26 INFO - Intel Iris: 23:09:26 INFO - Chipset Model: Intel Iris 23:09:26 INFO - Type: GPU 23:09:26 INFO - Bus: Built-In 23:09:26 INFO - VRAM (Dynamic, Max): 1536 MB 23:09:26 INFO - Vendor: Intel (0x8086) 23:09:26 INFO - Device ID: 0x0a2e 23:09:26 INFO - Revision ID: 0x0009 23:09:26 INFO - Displays: 23:09:26 INFO - Display: 23:09:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:09:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:09:26 INFO - Display Serial Number: 1600x1200 60 23:09:26 INFO - Main Display: Yes 23:09:26 INFO - Mirror: Off 23:09:26 INFO - Online: Yes 23:09:26 INFO - Rotation: Supported 23:09:26 INFO - Return code: 0 23:09:26 INFO - Running post-action listener: _resource_record_post_action 23:09:26 INFO - Running post-run listener: _resource_record_post_run 23:09:28 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32407040; Write bytes: 701127168; Read time: 327; Write time: 1904 23:09:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373268992; Write bytes: 367384064; Read time: 27918; Write time: 694 23:09:28 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31456768; Write bytes: 304391168; Read time: 311; Write time: 1144 23:09:28 INFO - Running post-run listener: _upload_blobber_files 23:09:28 INFO - Blob upload gear active. 23:09:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:09:28 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:09:28 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:09:28 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:09:28 INFO - (blobuploader) - INFO - Open directory for files ... 23:09:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:09:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:09:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:29 INFO - (blobuploader) - INFO - Done attempting. 23:09:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:09:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:09:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:30 INFO - (blobuploader) - INFO - Done attempting. 23:09:30 INFO - (blobuploader) - INFO - Iteration through files over. 23:09:30 INFO - Return code: 0 23:09:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:09:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:09:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/219c12120b131aa5503cb250649724f1846c61e30558df4b7ae1e4c97d920fd7888153d67443a1506b3dba2a86c0b7c4cd3aed9d5e5d4bbea79ca1ea6322fc01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bab1f319ed4e8eb341f95c0edf04a36ae6062e1055cdb958060aabbf5319bdc10c796c231a58880d5131055369bd678d64ac642109aee64aced215cd59cafdfa"} 23:09:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:09:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:09:30 INFO - Contents: 23:09:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/219c12120b131aa5503cb250649724f1846c61e30558df4b7ae1e4c97d920fd7888153d67443a1506b3dba2a86c0b7c4cd3aed9d5e5d4bbea79ca1ea6322fc01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bab1f319ed4e8eb341f95c0edf04a36ae6062e1055cdb958060aabbf5319bdc10c796c231a58880d5131055369bd678d64ac642109aee64aced215cd59cafdfa"} 23:09:30 INFO - Running post-run listener: copy_logs_to_upload_dir 23:09:30 INFO - Copying logs to upload dir... 23:09:30 INFO - mkdir: /builds/slave/test/build/upload/logs 23:09:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.159811 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-16 23:09:30.278491) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 23:09:30.282083) ========= 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.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/219c12120b131aa5503cb250649724f1846c61e30558df4b7ae1e4c97d920fd7888153d67443a1506b3dba2a86c0b7c4cd3aed9d5e5d4bbea79ca1ea6322fc01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bab1f319ed4e8eb341f95c0edf04a36ae6062e1055cdb958060aabbf5319bdc10c796c231a58880d5131055369bd678d64ac642109aee64aced215cd59cafdfa"} 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.011335 build_url: 'https://queue.taskcluster.net/v1/task/AyGxp-KyTGyMR0KIb5wudg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/219c12120b131aa5503cb250649724f1846c61e30558df4b7ae1e4c97d920fd7888153d67443a1506b3dba2a86c0b7c4cd3aed9d5e5d4bbea79ca1ea6322fc01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bab1f319ed4e8eb341f95c0edf04a36ae6062e1055cdb958060aabbf5319bdc10c796c231a58880d5131055369bd678d64ac642109aee64aced215cd59cafdfa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 23:09:30.315321) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 23:09:30.315648) ========= 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.emrIdd8xcQ/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.SR5ts6ddua/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.005260 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 23:09:30.364259) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 23:09:30.364563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 23:09:30.367970) ========= ========= Total master_lag: 0.27 =========