builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0085 starttime: 1462938395.61 results: success (0) buildid: 20160510185609 builduid: 3125c8f9543b4e939e89f0d8575e97aa revision: 95e7b6e69427410b242d17a2642d2123114c72b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.614438) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.614860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.615154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.636047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.636387) ========= 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.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-05-10 20:46:35-- 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% 10.4M=0.001s 2016-05-10 20:46:35 (10.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.791935) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.792298) ========= 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.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.026378 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:35.848898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:46:35.849296) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 95e7b6e69427410b242d17a2642d2123114c72b7 --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 95e7b6e69427410b242d17a2642d2123114c72b7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-05-10 20:46:35,932 truncating revision to first 12 chars 2016-05-10 20:46:35,932 Setting DEBUG logging. 2016-05-10 20:46:35,933 attempt 1/10 2016-05-10 20:46:35,933 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/95e7b6e69427?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 20:46:37,612 unpacking tar archive at: fx-team-95e7b6e69427/testing/mozharness/ program finished with exit code 0 elapsedTime=1.925876 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:46:37.796639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:37.797016) ========= script_repo_revision: 95e7b6e69427410b242d17a2642d2123114c72b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:37.797454) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:37.797729) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:46:37.811334) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-10 20:46:37.811727) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:46:37 INFO - MultiFileLogger online at 20160510 20:46:37 in /builds/slave/test 20:46:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:46:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:46:38 INFO - {'append_to_log': False, 20:46:38 INFO - 'base_work_dir': '/builds/slave/test', 20:46:38 INFO - 'blob_upload_branch': 'Fx-Team', 20:46:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:46:38 INFO - 'branch': 'Fx-Team', 20:46:38 INFO - 'buildbot_json_path': 'buildprops.json', 20:46:38 INFO - 'config_files': ('talos/mac_config.py',), 20:46:38 INFO - 'default_actions': ('clobber', 20:46:38 INFO - 'read-buildbot-config', 20:46:38 INFO - 'download-and-extract', 20:46:38 INFO - 'populate-webroot', 20:46:38 INFO - 'create-virtualenv', 20:46:38 INFO - 'install', 20:46:38 INFO - 'run-tests'), 20:46:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:46:38 INFO - 'download_minidump_stackwalk': True, 20:46:38 INFO - 'download_symbols': 'ondemand', 20:46:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:46:38 INFO - 'tooltool.py': '/tools/tooltool.py', 20:46:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:46:38 INFO - '/tools/misc-python/virtualenv.py')}, 20:46:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:46:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:46:38 INFO - 'installer_path': 'installer.exe', 20:46:38 INFO - 'log_level': 'info', 20:46:38 INFO - 'log_name': 'talos', 20:46:38 INFO - 'log_to_console': True, 20:46:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:46:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:46:38 INFO - 'opt_config_files': (), 20:46:38 INFO - 'pip_index': False, 20:46:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:46:38 INFO - 'cmd': ('bash', 20:46:38 INFO - '-c', 20:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:46:38 INFO - 'enabled': True, 20:46:38 INFO - 'halt_on_failure': False, 20:46:38 INFO - 'name': 'check_screen_resolution'},), 20:46:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:46:38 INFO - 'cmd': ('bash', 20:46:38 INFO - '-c', 20:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:46:38 INFO - 'enabled': True, 20:46:38 INFO - 'halt_on_failure': False, 20:46:38 INFO - 'name': 'check_screen_resolution'},), 20:46:38 INFO - 'run_cmd_checks_enabled': True, 20:46:38 INFO - 'sps_profile': False, 20:46:38 INFO - 'sps_profile_interval': 0, 20:46:38 INFO - 'suite': 'dromaeojs', 20:46:38 INFO - 'system_bits': '32', 20:46:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:46:38 INFO - 'title': 't-yosemite-r7-0085', 20:46:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:46:38 INFO - 'use_talos_json': True, 20:46:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:46:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:46:38 INFO - 'work_dir': 'build'} 20:46:38 INFO - ##### 20:46:38 INFO - ##### Running clobber step. 20:46:38 INFO - ##### 20:46:38 INFO - Running pre-action listener: _resource_record_pre_action 20:46:38 INFO - Running main action method: clobber 20:46:38 INFO - rmtree: /builds/slave/test/build 20:46:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:46:39 INFO - Running post-action listener: _resource_record_post_action 20:46:39 INFO - ##### 20:46:39 INFO - ##### Finished clobber step (success) 20:46:39 INFO - ##### 20:46:39 INFO - ##### 20:46:39 INFO - ##### Running read-buildbot-config step. 20:46:39 INFO - ##### 20:46:39 INFO - Running pre-action listener: _resource_record_pre_action 20:46:39 INFO - Running main action method: read_buildbot_config 20:46:39 INFO - Using buildbot properties: 20:46:39 INFO - { 20:46:39 INFO - "project": "", 20:46:39 INFO - "product": "firefox", 20:46:39 INFO - "script_repo_revision": "production", 20:46:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:46:39 INFO - "repository": "", 20:46:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:46:39 INFO - "buildid": "20160510185609", 20:46:39 INFO - "pgo_build": "False", 20:46:39 INFO - "basedir": "/builds/slave/test", 20:46:39 INFO - "buildnumber": 9, 20:46:39 INFO - "slavename": "t-yosemite-r7-0085", 20:46:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 20:46:39 INFO - "platform": "yosemite_r7", 20:46:39 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:46:39 INFO - "branch": "fx-team", 20:46:39 INFO - "repo_path": "integration/fx-team", 20:46:39 INFO - "revision": "95e7b6e69427410b242d17a2642d2123114c72b7", 20:46:39 INFO - "stage_platform": "macosx64", 20:46:39 INFO - "builduid": "3125c8f9543b4e939e89f0d8575e97aa", 20:46:39 INFO - "slavebuilddir": "test" 20:46:39 INFO - } 20:46:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:46:39 INFO - Running post-action listener: _resource_record_post_action 20:46:39 INFO - ##### 20:46:39 INFO - ##### Finished read-buildbot-config step (success) 20:46:39 INFO - ##### 20:46:39 INFO - ##### 20:46:39 INFO - ##### Running download-and-extract step. 20:46:39 INFO - ##### 20:46:39 INFO - Running pre-action listener: _resource_record_pre_action 20:46:39 INFO - Running main action method: download_and_extract 20:46:39 INFO - mkdir: /builds/slave/test/build/tests 20:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:46:39 INFO - https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:46:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:46:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:46:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 20:46:41 INFO - Downloaded 1199 bytes. 20:46:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:46:41 INFO - Using the following test package requirements: 20:46:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:46:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:46:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:46:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'jsshell-mac.zip'], 20:46:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:46:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:46:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:46:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:46:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:46:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:46:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:46:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:46:41 INFO - https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:46:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:46:43 INFO - Downloaded 18150615 bytes. 20:46:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:46:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:46:47 INFO - Return code: 0 20:46:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:46:47 INFO - https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:46:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:46:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:46:48 INFO - Downloaded 18150615 bytes. 20:46:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:46:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:46:52 INFO - Return code: 0 20:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:46:52 INFO - https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:46:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:46:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:46:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:46:53 INFO - Downloaded 10840152 bytes. 20:46:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:46:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:46:54 INFO - Return code: 0 20:46: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')]} 20:46:54 INFO - https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:46:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:46:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:46:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 20:46:56 INFO - Downloaded 109849309 bytes. 20:46:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:46:56 INFO - mkdir: /builds/slave/test/properties 20:46:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:46:56 INFO - Writing to file /builds/slave/test/properties/build_url 20:46:56 INFO - Contents: 20:46:56 INFO - build_url:https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:46:57 INFO - Running post-action listener: _resource_record_post_action 20:46:57 INFO - Running post-action listener: set_extra_try_arguments 20:46:57 INFO - ##### 20:46:57 INFO - ##### Finished download-and-extract step (success) 20:46:57 INFO - ##### 20:46:57 INFO - ##### 20:46:57 INFO - ##### Running populate-webroot step. 20:46:57 INFO - ##### 20:46:57 INFO - Running pre-action listener: _resource_record_pre_action 20:46:57 INFO - Running main action method: populate_webroot 20:46:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:46:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:46:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:46:57 INFO - '--tppagecycles', 20:46:57 INFO - '7'], 20:46:57 INFO - 'tests': ['tsvgm']}}, 20:46:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:46:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:46:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:46:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:46:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['damp', 'tps']}, 20:46:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'tests': ['damp', 'tps']}, 20:46:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['dromaeo_dom']}, 20:46:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:46:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['a11yr', 20:46:57 INFO - 'ts_paint', 20:46:57 INFO - 'tpaint', 20:46:57 INFO - 'sessionrestore', 20:46:57 INFO - 'sessionrestore_no_auto_restore', 20:46:57 INFO - 'tabpaint']}, 20:46:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['tsvgx', 20:46:57 INFO - 'tsvgr_opacity', 20:46:57 INFO - 'tart', 20:46:57 INFO - 'tscrollx', 20:46:57 INFO - 'cart']}, 20:46:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:46:57 INFO - 'tsvgr_opacity', 20:46:57 INFO - 'tart', 20:46:57 INFO - 'tscrollx', 20:46:57 INFO - 'cart']}, 20:46:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'talos_options': ['--disable-e10s'], 20:46:57 INFO - 'tests': ['tp5o']}, 20:46:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'tests': ['tp5o']}, 20:46:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'talos_options': ['--disable-e10s', 20:46:57 INFO - '--xperf_path', 20:46:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:57 INFO - 'tests': ['tp5n']}, 20:46:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:57 INFO - 'talos_options': ['--xperf_path', 20:46:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:57 INFO - 'tests': ['tp5n']}}, 20:46:57 INFO - 'talos.zip': {'path': '', 20:46:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:46:57 INFO - Running post-action listener: _resource_record_post_action 20:46:57 INFO - ##### 20:46:57 INFO - ##### Finished populate-webroot step (success) 20:46:57 INFO - ##### 20:46:57 INFO - ##### 20:46:57 INFO - ##### Running create-virtualenv step. 20:46:57 INFO - ##### 20:46:57 INFO - Running pre-action listener: _resource_record_pre_action 20:46:57 INFO - Running main action method: create_virtualenv 20:46:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:46:57 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 20:46:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:46:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:46:57 INFO - Using real prefix '/tools/python27' 20:46:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:46:58 INFO - Installing distribute.............................................................................................................................................................................................done. 20:47:01 INFO - Installing pip.................done. 20:47:01 INFO - Return code: 0 20:47:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:47:01 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')]} 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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')]} 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:01 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 20:47:01 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 20:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:01 INFO - 'HOME': '/Users/cltbld', 20:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:01 INFO - 'LOGNAME': 'cltbld', 20:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:01 INFO - 'MOZ_NO_REMOTE': '1', 20:47:01 INFO - 'NO_EM_RESTART': '1', 20:47:01 INFO - 'PAGER': '/bin/cat', 20:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:01 INFO - 'PWD': '/builds/slave/test', 20:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:01 INFO - 'SHELL': '/bin/bash', 20:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:01 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:01 INFO - 'USER': 'cltbld', 20:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:01 INFO - 'XPC_FLAGS': '0x0', 20:47:01 INFO - 'XPC_SERVICE_NAME': '0', 20:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:01 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 20:47:01 INFO - Cleaning up... 20:47:01 INFO - Return code: 0 20:47:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:47:01 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')]} 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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')]} 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:01 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 20:47:01 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 20:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:01 INFO - 'HOME': '/Users/cltbld', 20:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:01 INFO - 'LOGNAME': 'cltbld', 20:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:01 INFO - 'MOZ_NO_REMOTE': '1', 20:47:01 INFO - 'NO_EM_RESTART': '1', 20:47:01 INFO - 'PAGER': '/bin/cat', 20:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:01 INFO - 'PWD': '/builds/slave/test', 20:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:01 INFO - 'SHELL': '/bin/bash', 20:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:01 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:01 INFO - 'USER': 'cltbld', 20:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:01 INFO - 'XPC_FLAGS': '0x0', 20:47:01 INFO - 'XPC_SERVICE_NAME': '0', 20:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:01 INFO - Downloading/unpacking psutil>=0.7.1 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:47:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:47:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:47:04 INFO - Installing collected packages: psutil 20:47:04 INFO - Running setup.py install for psutil 20:47:04 INFO - building 'psutil._psutil_osx' extension 20:47:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:47:05 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 20:47:05 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 20:47:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:47:05 INFO - building 'psutil._psutil_posix' extension 20:47:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:47:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:47:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:47:05 INFO - ^ 20:47:05 INFO - 1 warning generated. 20:47:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:47:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:47:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:47:06 INFO - Successfully installed psutil 20:47:06 INFO - Cleaning up... 20:47:06 INFO - Return code: 0 20:47:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:06 INFO - 'HOME': '/Users/cltbld', 20:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:06 INFO - 'LOGNAME': 'cltbld', 20:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:06 INFO - 'MOZ_NO_REMOTE': '1', 20:47:06 INFO - 'NO_EM_RESTART': '1', 20:47:06 INFO - 'PAGER': '/bin/cat', 20:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:06 INFO - 'PWD': '/builds/slave/test', 20:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:06 INFO - 'SHELL': '/bin/bash', 20:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:06 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:06 INFO - 'USER': 'cltbld', 20:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:06 INFO - 'XPC_FLAGS': '0x0', 20:47:06 INFO - 'XPC_SERVICE_NAME': '0', 20:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:47:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:47:08 INFO - Installing collected packages: mozsystemmonitor 20:47:08 INFO - Running setup.py install for mozsystemmonitor 20:47:09 INFO - Successfully installed mozsystemmonitor 20:47:09 INFO - Cleaning up... 20:47:09 INFO - Return code: 0 20:47:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:47:09 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')]} 20:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:09 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')]} 20:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:09 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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:09 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 20:47:09 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 20:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:09 INFO - 'HOME': '/Users/cltbld', 20:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:09 INFO - 'LOGNAME': 'cltbld', 20:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:09 INFO - 'MOZ_NO_REMOTE': '1', 20:47:09 INFO - 'NO_EM_RESTART': '1', 20:47:09 INFO - 'PAGER': '/bin/cat', 20:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:09 INFO - 'PWD': '/builds/slave/test', 20:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:09 INFO - 'SHELL': '/bin/bash', 20:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:09 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:09 INFO - 'USER': 'cltbld', 20:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:09 INFO - 'XPC_FLAGS': '0x0', 20:47:09 INFO - 'XPC_SERVICE_NAME': '0', 20:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:09 INFO - Downloading/unpacking blobuploader==1.2.4 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:11 INFO - Downloading blobuploader-1.2.4.tar.gz 20:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:47:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:47:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:12 INFO - Downloading docopt-0.6.1.tar.gz 20:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:47:13 INFO - Installing collected packages: blobuploader, requests, docopt 20:47:13 INFO - Running setup.py install for blobuploader 20:47:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:47:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:47:13 INFO - Running setup.py install for requests 20:47:13 INFO - Running setup.py install for docopt 20:47:13 INFO - Successfully installed blobuploader requests docopt 20:47:13 INFO - Cleaning up... 20:47:13 INFO - Return code: 0 20:47:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:13 INFO - 'HOME': '/Users/cltbld', 20:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:13 INFO - 'LOGNAME': 'cltbld', 20:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:13 INFO - 'MOZ_NO_REMOTE': '1', 20:47:13 INFO - 'NO_EM_RESTART': '1', 20:47:13 INFO - 'PAGER': '/bin/cat', 20:47:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:13 INFO - 'PWD': '/builds/slave/test', 20:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:13 INFO - 'SHELL': '/bin/bash', 20:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:13 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:13 INFO - 'USER': 'cltbld', 20:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:13 INFO - 'XPC_FLAGS': '0x0', 20:47:13 INFO - 'XPC_SERVICE_NAME': '0', 20:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-z1JyaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-n8O_5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ncHLeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-5FADMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-QlUzSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-2OKzai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-fcm64N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:14 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-1sJcW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-hDQpm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-VgtrV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-azTnOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zcxwDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-uantaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0I2uGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0JLAHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-bYQIA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:15 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-GUxBqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:47:15 INFO - Running setup.py install for manifestparser 20:47:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:47:16 INFO - Running setup.py install for mozcrash 20:47:16 INFO - Running setup.py install for mozdebug 20:47:16 INFO - Running setup.py install for mozdevice 20:47:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:47:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:47:16 INFO - Running setup.py install for mozfile 20:47:16 INFO - Running setup.py install for mozhttpd 20:47:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:47:16 INFO - Running setup.py install for mozinfo 20:47:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Running setup.py install for mozInstall 20:47:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Running setup.py install for mozleak 20:47:17 INFO - Running setup.py install for mozlog 20:47:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Running setup.py install for moznetwork 20:47:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Running setup.py install for mozprocess 20:47:17 INFO - Running setup.py install for mozprofile 20:47:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:47:17 INFO - Running setup.py install for mozrunner 20:47:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:47:18 INFO - Running setup.py install for mozscreenshot 20:47:18 INFO - Running setup.py install for moztest 20:47:18 INFO - Running setup.py install for mozversion 20:47:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:47:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:47:18 INFO - Cleaning up... 20:47:18 INFO - Return code: 0 20:47:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:18 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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:18 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 20:47:18 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 20:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:18 INFO - 'HOME': '/Users/cltbld', 20:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:18 INFO - 'LOGNAME': 'cltbld', 20:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:18 INFO - 'MOZ_NO_REMOTE': '1', 20:47:18 INFO - 'NO_EM_RESTART': '1', 20:47:18 INFO - 'PAGER': '/bin/cat', 20:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:18 INFO - 'PWD': '/builds/slave/test', 20:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:18 INFO - 'SHELL': '/bin/bash', 20:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:18 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:18 INFO - 'USER': 'cltbld', 20:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:18 INFO - 'XPC_FLAGS': '0x0', 20:47:18 INFO - 'XPC_SERVICE_NAME': '0', 20:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:18 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-bIxdHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:18 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-hdiKdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-pVBe4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0HSx3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-vPZCX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-nwN7kM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-sGFIqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:19 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)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-dvmMSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-cZboj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-WgMJK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-2ERV4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-C9j8m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:20 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)) 20:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-8g_Ky8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:20 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)) 20:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-6o5PL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:20 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)) 20:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-W_bJcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:20 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)) 20:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-6DBO7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:20 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)) 20:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-IziPaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:22 INFO - Downloading blessings-1.6.tar.gz 20:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:47:23 INFO - Installing collected packages: blessings 20:47:23 INFO - Running setup.py install for blessings 20:47:23 INFO - Successfully installed blessings 20:47:23 INFO - Cleaning up... 20:47:23 INFO - Return code: 0 20:47:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:23 INFO - Reading from file tmpfile_stdout 20:47:23 INFO - Current package versions: 20:47:23 INFO - blessings == 1.6 20:47:23 INFO - blobuploader == 1.2.4 20:47:23 INFO - docopt == 0.6.1 20:47:23 INFO - manifestparser == 1.1 20:47:23 INFO - mozInstall == 1.12 20:47:23 INFO - mozcrash == 0.17 20:47:23 INFO - mozdebug == 0.1 20:47:23 INFO - mozdevice == 0.48 20:47:23 INFO - mozfile == 1.2 20:47:23 INFO - mozhttpd == 0.7 20:47:23 INFO - mozinfo == 0.9 20:47:23 INFO - mozleak == 0.1 20:47:23 INFO - mozlog == 3.1 20:47:23 INFO - moznetwork == 0.27 20:47:23 INFO - mozprocess == 0.22 20:47:23 INFO - mozprofile == 0.28 20:47:23 INFO - mozrunner == 6.11 20:47:23 INFO - mozscreenshot == 0.1 20:47:23 INFO - mozsystemmonitor == 0.0 20:47:23 INFO - moztest == 0.7 20:47:23 INFO - mozversion == 1.4 20:47:23 INFO - psutil == 3.1.1 20:47:23 INFO - requests == 1.2.3 20:47:23 INFO - wsgiref == 0.1.2 20:47:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47: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')]} 20:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47: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')]} 20:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:23 INFO - 'HOME': '/Users/cltbld', 20:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:23 INFO - 'LOGNAME': 'cltbld', 20:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:23 INFO - 'MOZ_NO_REMOTE': '1', 20:47:23 INFO - 'NO_EM_RESTART': '1', 20:47:23 INFO - 'PAGER': '/bin/cat', 20:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:23 INFO - 'PWD': '/builds/slave/test', 20:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:23 INFO - 'SHELL': '/bin/bash', 20:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:23 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:23 INFO - 'USER': 'cltbld', 20:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:23 INFO - 'XPC_FLAGS': '0x0', 20:47:23 INFO - 'XPC_SERVICE_NAME': '0', 20:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:23 INFO - Cleaning up... 20:47:23 INFO - Return code: 0 20:47:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:47: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')]} 20:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47: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')]} 20:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:23 INFO - 'HOME': '/Users/cltbld', 20:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:23 INFO - 'LOGNAME': 'cltbld', 20:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:23 INFO - 'MOZ_NO_REMOTE': '1', 20:47:23 INFO - 'NO_EM_RESTART': '1', 20:47:23 INFO - 'PAGER': '/bin/cat', 20:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:23 INFO - 'PWD': '/builds/slave/test', 20:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:23 INFO - 'SHELL': '/bin/bash', 20:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:23 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:23 INFO - 'USER': 'cltbld', 20:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:23 INFO - 'XPC_FLAGS': '0x0', 20:47:23 INFO - 'XPC_SERVICE_NAME': '0', 20:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:24 INFO - Downloading/unpacking jsonschema 20:47:24 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 20:47:24 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 20:47:24 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 20:47:24 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 20:47:24 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 20:47:24 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 20:47:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:47:26 INFO - Downloading/unpacking functools32 (from jsonschema) 20:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:26 INFO - Downloading functools32-3.2.3-2.tar.gz 20:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:47:27 INFO - warning: no files found matching '*.txt' 20:47:27 INFO - no previously-included directories found matching 'build' 20:47:27 INFO - no previously-included directories found matching 'dist' 20:47:27 INFO - no previously-included directories found matching '.git*' 20:47:27 INFO - Installing collected packages: jsonschema, functools32 20:47:27 INFO - Running setup.py install for functools32 20:47:27 INFO - warning: no files found matching '*.txt' 20:47:27 INFO - no previously-included directories found matching 'build' 20:47:27 INFO - no previously-included directories found matching 'dist' 20:47:27 INFO - no previously-included directories found matching '.git*' 20:47:27 INFO - Successfully installed jsonschema functools32 20:47:27 INFO - Cleaning up... 20:47:27 INFO - Return code: 0 20:47:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:47:27 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')]} 20:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:27 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')]} 20:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:27 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 0x107332a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107338570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faab9595d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, '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.ia4rxNgCVX/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.TB0yoGOylq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 20:47:27 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 20:47:27 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 20:47:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:47:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:27 INFO - 'HOME': '/Users/cltbld', 20:47:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:27 INFO - 'LOGNAME': 'cltbld', 20:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:27 INFO - 'MOZ_NO_REMOTE': '1', 20:47:27 INFO - 'NO_EM_RESTART': '1', 20:47:27 INFO - 'PAGER': '/bin/cat', 20:47:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:27 INFO - 'PWD': '/builds/slave/test', 20:47:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:27 INFO - 'SHELL': '/bin/bash', 20:47:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:47:27 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:47:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:27 INFO - 'USER': 'cltbld', 20:47:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:27 INFO - 'XPC_FLAGS': '0x0', 20:47:27 INFO - 'XPC_SERVICE_NAME': '0', 20:47:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:27 INFO - Downloading/unpacking flake8 20:47:27 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 20:47:27 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 20:47:27 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 20:47:27 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 20:47:27 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 20:47:27 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 20:47:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:47:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:47:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:47:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:47:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:47:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:47:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:47:31 INFO - no previously-included directories found matching 'docs/_build' 20:47:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:47:31 INFO - Running setup.py install for pep8 20:47:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:47:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:47:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:47:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:47:31 INFO - no previously-included directories found matching 'docs/_build' 20:47:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:47:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:47:31 INFO - Cleaning up... 20:47:31 INFO - Return code: 0 20:47:31 INFO - Running post-action listener: _resource_record_post_action 20:47:31 INFO - Running post-action listener: _start_resource_monitoring 20:47:31 INFO - Starting resource monitoring. 20:47:31 INFO - ##### 20:47:31 INFO - ##### Finished create-virtualenv step (success) 20:47:31 INFO - ##### 20:47:31 INFO - ##### 20:47:31 INFO - ##### Running install step. 20:47:31 INFO - ##### 20:47:31 INFO - Running pre-action listener: _resource_record_pre_action 20:47:31 INFO - Running main action method: install 20:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:31 INFO - Reading from file tmpfile_stdout 20:47:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:47:31 INFO - Reading from file tmpfile_stdout 20:47:31 INFO - Output received: 20:47:31 INFO - Usage: mozinstall [options] installer 20:47:31 INFO - Options: 20:47:31 INFO - -h, --help show this help message and exit 20:47:31 INFO - -d DEST, --destination=DEST 20:47:31 INFO - Directory to install application into. [default: 20:47:31 INFO - "/builds/slave/test"] 20:47:31 INFO - --app=APP Application being installed. [default: firefox] 20:47:31 INFO - mkdir: /builds/slave/test/build/application 20:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:48:05 INFO - Reading from file tmpfile_stdout 20:48:05 INFO - Output received: 20:48:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:48:05 INFO - Running post-action listener: _resource_record_post_action 20:48:05 INFO - ##### 20:48:05 INFO - ##### Finished install step (success) 20:48:05 INFO - ##### 20:48:05 INFO - ##### 20:48:05 INFO - ##### Running run-tests step. 20:48:05 INFO - ##### 20:48:05 INFO - Running pre-action listener: _resource_record_pre_action 20:48:05 INFO - Running main action method: run_tests 20:48:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:48:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:48:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:48:05 INFO - 2016-05-10 20:48:05.776 screenresolution[1636:7352] starting screenresolution argv=screenresolution get 20:48:05 INFO - 2016-05-10 20:48:05.779 screenresolution[1636:7352] Display 0: 1600x1200x32@60 20:48:05 INFO - 2016-05-10 20:48:05.785 screenresolution[1637:7354] starting screenresolution argv=screenresolution list 20:48:05 INFO - Available Modes on Display 0 20:48:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:48:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:48:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:48:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:48:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:48:06 INFO - Intel Iris: 20:48:06 INFO - Chipset Model: Intel Iris 20:48:06 INFO - Type: GPU 20:48:06 INFO - Bus: Built-In 20:48:06 INFO - VRAM (Dynamic, Max): 1536 MB 20:48:06 INFO - Vendor: Intel (0x8086) 20:48:06 INFO - Device ID: 0x0a2e 20:48:06 INFO - Revision ID: 0x0009 20:48:06 INFO - Displays: 20:48:06 INFO - Display: 20:48:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:48:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:48:06 INFO - Display Serial Number: 1600x1200 60 20:48:06 INFO - Main Display: Yes 20:48:06 INFO - Mirror: Off 20:48:06 INFO - Online: Yes 20:48:06 INFO - Rotation: Supported 20:48:06 INFO - Return code: 0 20:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:48:06 INFO - Python 2.7.3 20:48:06 INFO - Return code: 0 20:48:06 INFO - grabbing minidump binary from tooltool 20:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:48:06 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071d6d30>, '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 0x106ffadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071cdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:48:06 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 20:48:06 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 20:48:06 INFO - Calling ['/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'] with output_timeout 600 20:48:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:48:06 INFO - Return code: 0 20:48:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:48:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:48:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:48:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:48:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:48:07 INFO - Return code: 0 20:48:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:48:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ia4rxNgCVX/Render', 20:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:48:07 INFO - 'HOME': '/Users/cltbld', 20:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:48:07 INFO - 'LOGNAME': 'cltbld', 20:48:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:48:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:48:07 INFO - 'MOZ_NO_REMOTE': '1', 20:48:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:48:07 INFO - 'NO_EM_RESTART': '1', 20:48:07 INFO - 'PAGER': '/bin/cat', 20:48:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:48:07 INFO - 'PWD': '/builds/slave/test', 20:48:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:48:07 INFO - 'SHELL': '/bin/bash', 20:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TB0yoGOylq/Listeners', 20:48:07 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 20:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:48:07 INFO - 'USER': 'cltbld', 20:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:48:07 INFO - 'XPC_FLAGS': '0x0', 20:48:07 INFO - 'XPC_SERVICE_NAME': '0', 20:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:48:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:48:07 INFO - mozversion application_buildid: 20160510185609 20:48:07 INFO - mozversion application_changeset: 95e7b6e69427410b242d17a2642d2123114c72b7 20:48:07 INFO - mozversion application_display_name: Nightly 20:48:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:48:07 INFO - mozversion application_name: Firefox 20:48:07 INFO - mozversion application_remotingname: firefox 20:48:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:48:07 INFO - mozversion application_vendor: Mozilla 20:48:07 INFO - mozversion application_version: 49.0a1 20:48:07 INFO - mozversion platform_buildid: 20160510185609 20:48:07 INFO - mozversion platform_changeset: 95e7b6e69427410b242d17a2642d2123114c72b7 20:48:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:48:07 INFO - mozversion platform_version: 49.0a1 20:48:07 INFO - using testdate: 1462938487 20:48:07 INFO - actual date: 1462938487 20:48:07 INFO - starting webserver on 'localhost:49276' 20:48:07 INFO - SUITE-START | Running 2 tests 20:48:07 INFO - TEST-START | dromaeo_css 20:48:07 INFO - operating with platform_type : mac_ 20:48:07 INFO - Initialising browser for dromaeo_css test... 20:48:07 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpLgD8fQ/profile http://localhost:49276/getInfo.html) 20:48:09 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 20:48:09 INFO - PROCESS | 1655 | colorDepth:24 20:48:09 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 20:48:09 INFO - PROCESS | 1655 | __metrics 20:48:09 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:48:09 ERROR - PROCESS | 1655 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:48:10 INFO - TEST-INFO | 1655: exit 0 20:48:10 INFO - Browser initialized. 20:48:10 INFO - Running cycle 1/1 for dromaeo_css test... 20:48:10 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpLgD8fQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:48:22 INFO - PROCESS | 1657 | RSS: Main: 238878720 20:48:22 INFO - PROCESS | 1657 | 20:49:13 INFO - PROCESS | 1657 | 1462938553593 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] 20:51:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 20:51:26 INFO - PROCESS | 1657 | RSS: Main: 338657280 20:51:26 INFO - PROCESS | 1657 | 20:54:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 20:54:30 INFO - PROCESS | 1657 | RSS: Main: 375918592 20:54:30 INFO - PROCESS | 1657 | 20:57:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 20:57:33 INFO - PROCESS | 1657 | RSS: Main: 369455104 20:57:33 INFO - PROCESS | 1657 | 21:00:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 21:00:35 INFO - PROCESS | 1657 | RSS: Main: 370962432 21:00:35 INFO - PROCESS | 1657 | 21:03:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 21:03:40 INFO - PROCESS | 1657 | RSS: Main: 337133568 21:03:40 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 21:06:43 INFO - PROCESS | 1657 | RSS: Main: 402960384 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | __start_tp_report 21:06:43 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 21:06:43 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 21:06:43 INFO - PROCESS | 1657 | |i|pagename|runs| 21:06:43 INFO - PROCESS | 1657 | |0;dojo.html;3222.4360736871045;3421.46967220081;3459.688628023478;3468.930621387574;3498.160441494042;4610.262358022717;4980.452150263468;5400.568849254951;5690.374058853528;5750.71246437678;5677.943220567793;5757.942420575793;5759.510441612458;5766.481016708495;5774.960507108719;5170.896582068356;5220.608454365919;5234.842954711365;5251.842444726645;5297.8940421191555;5279.076161671711;5283.709395983219;5307.150855863063;5333.333333333333;5335.039692855285;5209.453007434222;5225.03336882676;5227.634065615409;5240.764165612538;5315.920261196085;5312.203169524563;5331.306930099062;5348.358197016344;5353.705546194997;5509.256250406184;4673.205555055649;5123.052235336438;5123.359580052494;5247.737613119329;5281.287026251445;5070.416902056274;5581.5255703265275;5623.409541998072;5625.54995600351;5633.605647604629;6332.050192471166;6344.016677415008;6345;6345.841353966188;6348.904766428507;6217.844553886144;6287.2140982377205;6335.904961425582;6351.968240158816;6354.142190804228;5906.970465147647;5920.289565252154;5932.970335148297;5943.375945525744;5950.553708471882;6343.238811342669;6367.363263673688;6377.872442551123;6383.489320854321;6393.904091438632;6060.606060606029;6143.296949342275;6187.226596675416;6199.504039676815;6243.125962365272;6317.989121740499;6336.6831658416895;6343.429091381706;6347.873042539123;6356.555041147075;6254.937450625526;6283.7800676976085;6307.558470906993;6334.619922804646;6339.587926784744;6128.564234752123;6181.289151747516;6197.194364732556;6200.852842224203;6264.968675156595;4882.804687812519;4923.950760492349;4929.926051109236;4936.555709986119;4942.258661200849;4421.513633500313;4431.623312018514;4450.132224216311;4471.463424389094;4483.103379324149;5636.746346414421;5698.629589076697;5712.600117991784;5756.712164391847;5773;6311.211098612674;6319.3364696706185;6326.778562750282;6327.082573026885;6337.841553961188;5744.0522313817755;5752.712364381764;5761.107028410603;5789.334226563999;5846.766129354864;5279.630425870152;5375.139977603565;5400.810971615974;5428.158635411518;5442.564594832404;6398.0402939559435;6411.198600174978;6447.3552644735155;6452.709628066842;6470.126532917996;6299.244090709144;6302.936970630235;6331.986882098842;6335.049742538656;6337.429631333202;6266.905996409966;6275.937240627718;6343.555951083565;6364.840878978062;6459.644719540471;6261.123442718114;6267.811965641038;6279;6282.434580887742;6282.968585157045;6141.109539116893;6164.907526387197;6248.562600617913;6319.399657032574;6319.526035547445;6106.096658337741;6137.84655383619;6169.84575385619;6220;6221.09794079865;6172.938270617236;6196.535259855619;6209.0996805462055;6220.813375598739;6225.035119556295;5882.264716910387;5898.233229680286;6032.034874420072;6047.09293605945;6079.939200608114;6129.295131059986;6148.938510615016;6150.169727086965;6185.659788711664;6207.193064901534;6050.364711705208;6064.545159113172;6076.058210977306;6086.91760282864;6124.5712800103565 21:06:43 INFO - PROCESS | 1657 | |1;ext.html;509.368383204826;514.7966553211481;531.3623651618058;540.8512659018771;549.5658429840424;41120.79439602802;42636.78681606596;42775;42867;44586.33120503195;36923.4461483078;43115.922101947464;44282;44291;44326.55673443265;8605.698800541983;8801.603927823264;9641.758956026086;9845.261605379625;9855.310128291023;11613.18707690462;11932.105092118083;11939.701507462296;12013.639590812247;12031.458584363725;11824.581050273979;11892.286462812215;11896.988755955754;11909.523619055228;11913.404329783476;8765.94808622966;11761.647150585497;11791.29252244868;11981;11986.760264794742;11670.124740644485;11752.647420577396;11758;11774.587889423914;11777.057835373234;40580.79709601463;40903.38644920329;40917.977050573674;40940.590594094276;41031.58968410308;3176.3122476446747;3267.591551056118;3423.989922972729;3479.6868281854754;3503.5095086687884;27778.444431111468;28958;30436;30441;30479.847600761856;2761.0198379575363;2787.2613757354316;2815.085097343351;2824.5904343870025;2863.1696807925678;494169;497213;497837;497949;497960.9999999964;63768;64273;64280;64283.67858160679;64316.67841660762;66198;67082.66458667675;67223.3277667217;67370.66314668395;67391;17170.570735731708;17288.740668889975;17426.738598921027;17498.300067997392;17501;12178.330191839357;12333.136680432463;12335.93832030852;13005.284709340887;13221.206727596375;19512.899691204417;19642.90178549098;19666.311679091163;19742.01289935526;19755.50611234702;8187.26314631686;8259.669613215525;8293.95853020731;8348.4156109073;8379;915.7023967210683;1024.6055268721448;1084.2356138922041;1096.8793432722393;1145.1583086431633;24846.631896568935;26529.602055969175;29639.407211855643;29689.40621187564;30001.699983000326;1107.1530279336314;1159.907207423404;1190.315568548071;1204.9156559040957;1228.416502161466;15170.393184272505;15342;15381.312778321484;15654.45209417665;15732;652373;661680;662761;663582;664682;58424.41575584275;60129.699351503834;60270.383776973526;60369.69815150984;60481;1189.5182451107316;1360.115924648986;1362.0329566008047;1452.3174108169355;1477.3351991603606;23838.523229535658;29707.811687532256;30513.389732205676;30955.845220774652;31747.84126079355;53468.197977029595;53868.92262154813;54515.637109071;54547.45452545424;54553;567222;572841;573509;574123.1293843504;574133;23222;23705.64441533413;26020;26414.07550735715;26658.33354166084;42725.786371067945;43063.13873722571;45546;46229.53770462387;47045.294320585905;35843.64156358403;37731.43402849014;38042;38144.61855381411;38476.80761596286;36521.452178216816;37120.628793711716;37785.433218502294;37892.81053594824;38114.2377152461;37018.074548136516;37650.43524347082;37925;38393.4240986391;38481.807590961864;29026.854865725538;31242;32840.178995525304;33104;33265.667343326255;21617.567648646625;23081.192158274378;24288.57475270463;24735.50528989446;24753.62869556994 21:06:43 INFO - PROCESS | 1657 | |2;jquery.html;386.65587627011956;394.74341677909354;394.7927338147474;399.1179493319765;420.16597054846125;17596.560085997855;20298.391048268557;20405.979701014963;21410.46473838155;21486.462838429048;20028.998550072512;21081.051352689126;21464.93537637578;21624.918754062313;21696;5827.941720582803;6147.139400483936;6148.200733904586;6151.046587778901;6158.938410615904;5962.195103661015;6008.729607167688;6074.42292982166;6130.724117414727;6131.724072416752;5864.1203819427;6002.219711437507;6012.909806352908;6016.729247183887;6043.546733994946;5752.2234498342605;5978.8206353809455;6049.183360246355;6118.990366589552;6124.295705993779;5890.8821823563285;5903.085021821625;5917.082852157922;5922.58541902067;5929.3181284152015;22116.447088822748;24280;25831;26478.338041549116;26577.601335979976;1935.1678778125402;2148.5273239887356;2169.5986242545023;2741.232454912628;2781.749642532182;9899.851502227473;10189.54147063384;10230.181585473145;10529.684109476728;10566.418846963414;1524.8932574719774;1713.2804222226694;1766.0992893624216;1767.4962635648908;1833.8441232495254;124620;130798;131611;132673;133801.32397352095;11640.88359116398;11786.233894796811;11815.822762658567;11816.586419475278;12012.699682507833;15314.387424503118;15345.386184552715;15346.232688365537;15380;15412.61468463275;8789.208971192624;8855.867161992575;8874.822503549893;8909.465432074096;8969.551522423853;8699.173578510157;8715.215630593277;8728;8850.73447796567;8877.556122193864;4639.837605683785;4643.86068417948;4662.300654901724;4664.346991421204;4719.457262414798;1758.5339884930504;1825.435322767458;1839.1815642039164;1941.7864034956144;1996.3212507747435;915.5742579700471;929.3540989012706;935.1396715022196;936.1527817325332;956.2159029595804;14520.854791452162;19491.707624385646;20349.694754578693;20398.592028159354;20643.277485288243;985.5072463768116;987.5605355616825;998.0967224661692;999.7850462150673;1012.4229189361993;10530.36817790935;10704.571817127227;10738;10742.624008159833;10765.946170269099;612288;620276;622138;634407;634636;137841;139245.6075439253;140799;144035.2798236002;146646;996.9202463803024;1012.6405126180247;1026.7227848480868;1091.7598128411737;1092.7377429416883;13246.735135027506;16940.322387104377;18020.729689054937;18310.908445457688;18677.90661046686;36592;43728.627137286014;48038.5196148034;49223.261651075976;53450.39648810542;73733.631331843;73884.63057684677;75524.86712699205;77746.6112669433;81192.59403702944;11775.411229438323;12269.079819013432;12401;12522.18605790657;12643.494260229489;13380.531681391105;13393.732125357243;13645.795313070113;13689.86310136886;13696.794548081985;11677.357745324089;11829.928018956334;11847.52609895629;11970.46132924038;12108.757824843278;10829.84658298447;11296.548138074713;11655.475503602189;11794.115441342105;11895.762084758084;11643;11951.22317049389;12157.270563766202;12193.939030304791;12245.326507042199;12147.757044858876;12168.817467737814;12287.44706488228;12406.751864962831;12469.812952805896;11699.707507312385;11699.824502632297;11879.406029698308;12045.879541204476;12117.091218158497 21:06:43 INFO - PROCESS | 1657 | |3;mootools.html;1437.4322142753606;1603.3746838732893;1634.4933070748068;1745.7905051393827;1888.7817357804217;7924.603769811508;8639.956800215998;8853.690120845771;8887.777805554857;8997.145271199242;8142.592870356488;8823.117688231174;8823.823523529525;8839.425437346568;8844.425112367691;2422.80617550596;2467.469494058777;2468.0868078810863;2472.3819045238693;2476.3313905245573;2385.821063420242;2398.3764221302413;2399.7600239976036;2401.7838394544488;2408.0247499762622;2400.3639035655565;2407.9157229496973;2417.867017314047;2455.0057226823164;2463.0763463701114;2405.5910495215853;2434.306222726523;2440.377703685549;2441.694788151481;2453.055573604175;2345.976540234593;2416.299273210767;2423.1155628195756;2425.175440350272;2427.842190257645;8001.279884810395;8272.503649781032;8893.021767605624;8976.192142707188;8978.23684986777;5277.841664750025;5493.423190565013;5511.3937466878615;5547.029269877755;5547.362053363874;3538.929221415557;3617.7467577269604;3706.22169344438;3711.9443208351895;3724.3110024645525;1050.9316894401838;1060.8939106089406;1062.5059347403494;1062.760878802271;1064.5103252503857;11963.40182990847;12113.45489452968;12117.697057573543;12120.151589388746;12151.817722734078;4623.69945953512;4659.603933665677;4787.54518320759;4789.784459699322;4795.18481858078;9433.056694330511;9435.103665151799;9439.858402123973;9457;9464;5374.4625537445945;5398.514133727984;5399.946000539944;5404.91892621611;5409.377921538995;4834.830780922651;4891.608671306287;5014.166566902108;5049.990001999615;5059.2916991621205;3752.6434988676033;3772.2266935278203;3825.139343647657;3901.668358189529;3941.192055628589;1169.5030361137588;1175.1304035014027;1177.8292147638545;1187.8099504079305;1204.3978010994504;865.5455885660078;866.2593482572436;870.7910101575703;876.8684697295329;979.7844474215661;5804.622699524517;5996.970015149896;6087.691146403545;6105.389461053859;6205.348438413902;899.9550022498848;901.8376692195468;950.4012472142506;952.2286947572488;982.9508524573743;3796.183820478555;3927.509061367329;3964.6431821136234;3985.322495175827;3988.12261302513;10351.327163734333;10846.728831779268;10853.457327133612;10857.891421085846;10859.022687957964;10302.587896484056;10745;10757.892421075845;10888.29226100301;10894.237403381845;8015.959920200478;8052;8207.179282071746;8225.218604232588;8314.210150035737;5632.831015069554;5729.197912292199;5752.453516915886;5829.504492118217;5961.433663801932;9744;9775.511224438607;9979.301448898508;10004;10100;9963.800723985625;10224;10235.692929212135;10255.282130250811;10291.176705863663;7408.888866667112;7597.164311925794;7697.191794861459;7720.3051725344985;7759.107702614046;8230.83538329218;8286.337093032664;8330.625121869653;8336.583170841555;8373.706920257762;7366.7053317866685;7490.400767938443;7517.962410187914;7704.8073798153255;7750.728724494616;6072.605280656743;7244.855102898018;7288.599127048064;7441.888371674538;7593.278638529331;7063.187733410724;7109.075820143348;7144.214136444883;7160.069191005136;7165.462590305644;4726.172919738963;4972.0801651694555;4997.825076122318;5004.724740139327;5007.349044624176;4797.880052998564;4964.801407943786;5031.974840125776;5150.562202212854;5247 21:06:43 INFO - PROCESS | 1657 | |4;prototype.html;299.2503778036021;303.3054305640084;314.8724766469579;318.2124242499815;321.68796267620417;4362.432883725115;5222.764975576099;5268.683878967255;5306.575473962088;5309.99095682759;4391.319345501444;4895.926561101586;4929.334539837121;5217.243499692542;5255.605829562779;3313.5858017747782;4007.9198416031663;4012.8595499157536;4057.6956728245427;4065.918681626366;3622.510961020255;3811.8284677189595;4009.478767760187;4011.3381292086915;4028.47629808126;3324.634290228073;3620.4207326827714;3796.595259753895;3809.923801523968;4042.615951484619;3818.6563209311294;3953.6441720245316;3969.2260009298197;4017.799110044515;4039.7778122203267;2925.7513111385556;3063.7089476499696;3510.420780571228;3515.5605549306338;3590.0127464947086;4282.957170428318;4749.263864101035;4992.251162325644;5026.190571394295;5029.510392355807;4007.8196481158125;4503.347014682852;4515.4129963104915;4527.818887244506;4554.362389265505;4338.370936214231;5397.595180361449;5406.270153529263;5418.756155972991;5447.5641948644015;3820.2359528094216;3826.1199924017355;3829.7510661807173;3833.5591406987996;3840.596737342567;4843.910120222957;5072.67027643202;5288.947110528922;5688.658680479143;5748.7125643717645;3183.8089714616735;3401.3197360527993;3636.0364503406618;3783.9621603784162;3803.2583646188727;3479.3911065563425;3517.854108993512;3646.851241858806;3907.648311651908;3936.645701886787;4124.566920473367;4316.69783115179;4457.531959144308;4498.3927169832305;4558.675075711411;4474.351219073216;4792.568668819823;5477.4248703886315;5495.670259784426;5505.421930697219;1092.6776600902595;1298.6363818130937;1333.7999300104868;1336.3719052045524;1337.2979185927466;727.4689476682051;810.9862671660425;847.7965288330756;853.6158728572167;887.5163036145316;5032.691500209899;5068.341115654941;5069.654028341277;5077.416097148801;5106.11073902651;5513.594033521426;5524.226608274845;5570.777168913279;5586.597762961884;5603.467670571289;4065.6340929316502;5035.345405097314;5048.29915994941;5052.823151189764;5078.796848126033;2683.262102921712;2870.5550639650887;2886.321714397115;2893.7974341796294;2913.3539550154096;5059.291699162047;5234.790608375699;5492.725363731797;5628.296462942132;5731.140328950607;4965.553100220997;5037.219231019198;5376.46235376467;5420.322459692538;5880.882882162418;4920.089783390084;5641.689707066069;5651.491365777182;5698.686572238566;5934.525237980951;4578.862634120982;5350.678959262456;5686.4029276926985;5719.28508936383;5739.971300143473;4746.8101275948575;4900.436449808175;5276.657017293864;5654.40628733977;5656.8020119295625;5250.527452529291;5252.448492908343;5493.890122197454;5883.911741323798;5922.644641321534;3551.8756843510355;3701.463287823304;3874.457575939427;3877.6897848172644;3914.1388894443176;4994.575461085703;5582.637128586629;5618.803341883015;5688.175214593778;5696.629719068247;4827.517248275144;4877.097736918539;4923.606111511142;5572.442755724395;5583.804566840141;4650.604818554352;5269.5759950423235;5516.558675305886;5539.473749993744;5587.888242235052;4334.609885110229;5135.226977432294;5255.579553635624;5402.918956215739;5416.51251387377;4709.81160753566;4786.664933454625;5524.309461317335;5540.667559946416;5561.610687252015;5136.792853679457;5359.973200133974;5382;5404.459554044564;5445.673259604437 21:06:43 INFO - PROCESS | 1657 | |5;yui.html;572.3818276261637;678.9694463749131;691.6161530350656;702;707.7699747582037;15048.398064077437;15459.22703864808;15507.06957582543;15697.843021569795;15702.450414235518;13434.126781759178;13547.051706380556;13619.046666733333;13634.93182534086;13690.315484225774;709.3332267668401;729.1286912139994;892.3307519360479;903.2051794420901;922.6447817590209;901.9413738107035;907.863820426938;912.4114945859916;914.063085337531;917.334932174175;965.1410244882061;969.2585172343163;970.3401686852973;970.5050424283631;971.6307803034872;742.1761844352737;764;764.5947647746704;767.382257282887;963.78796664734;692.390696187357;709.9006139140524;709.9077119974422;714.7998560403096;715.2775696546473;14877.03299285543;14887.330070146869;14939.925300373428;14950.775738363933;14959.775603365959;12168.756624867454;12217.755644887053;12375.381230938507;12446.626601201888;12641;10823.114037915111;10876.728081797939;10883.945580272128;11220.887791122066;11354.772904541864;4015.19696060789;4023.8189281482405;4143.91712165755;4167.249895018896;4195.937060944088;81532;82242.9999999994;82721.58639206826;84075.15924840735;84087.15912840854;9090.40912340709;9313.627454901864;9336.859947100798;9568.330216884891;9580.281478888972;20329.898350508152;20924.89537552333;20929.16283348649;21077.578448430944;21349.46626334323;7550.622468876534;7557.773266802004;7571.091469023642;7750.14748377678;8017.037955445384;4031.5565287818317;4141.254574176677;4152.045029643199;4156.667466602665;4467.8212871484775;3381.1547113221695;3535.133892196377;3539.6991255743037;3541.11472131967;3542.8051457169586;637.5250438423404;646.1503123392723;651.0852252585125;655.6788072034853;657.2113463843407;521.6478876758173;592.644413351994;607.6809674920702;608.2883026858586;614.658864330301;8033.19668033192;8044.4368894178015;8108.310793582494;8233.46482478637;8263.586820658944;530.6391653675538;590.8138936235072;609.9878002439926;610.2006371653149;615.9353267906895;3629.310431018098;3708.147126160998;3747.2692824899427;3850.114473671071;3934.7245692801803;82986.17013829785;84082.5795871029;88498.23003539893;90437.54781226051;90454.54772726225;76541.6172919143;77227;77645.22354776492;77828;78858.84564617465;67555.66222168857;70280.64859675668;70958.64520677364;70987.64506177435;71403.92894106593;8215.632884514549;8295.460795048302;8300.667973281;8395.496270223806;8521;42383.3642495369;42470.93822654458;44326.77836610797;44961;46571.6028519145;56980.43019569751;60493.395066048775;60660;60802.17593472203;61639;11048.889511105108;11200.103991680484;11243;11496.827547586625;11624.360660163435;11916.52333906634;11935.343556104497;11970.401479925795;12469.563565275535;13045.478180872658;6237.812865614039;10101.444420556645;10467.685969420929;11623.476943537731;12397.93801030989;9375.578098985698;10074.899251007397;11811.822822657496;11927.284362938251;12138.968187704144;11264.493097810455;11306.886931130912;11469.369184694922;11503.252288601214;11712;9455.385399948982;9665.806683866143;9668.951655241679;9670.806583868143;9838.458884761407;9639.132478077006;9876.160526355054;9878.802423951625;9881.802363952824;9905.306628535933 21:06:43 INFO - PROCESS | 1657 | __end_tp_report 21:06:43 INFO - PROCESS | 1657 | __start_cc_report 21:06:43 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1260 21:06:43 INFO - PROCESS | 1657 | __end_cc_report 21:06:43 INFO - PROCESS | 1657 | __startTimestamp1462939603328__endTimestamp 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | ------- Summary: start ------- 21:06:43 INFO - PROCESS | 1657 | Number of tests: 6 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5786.81 Median:6076.06 stddev:668.47 (11.0%) stddev-sans-first:639.54 21:06:43 INFO - PROCESS | 1657 | Values: 3222.4 3421.5 3459.7 3468.9 3498.2 4610.3 4980.5 5400.6 5690.4 5750.7 5677.9 5757.9 5759.5 5766.5 5775.0 5170.9 5220.6 5234.8 5251.8 5297.9 5279.1 5283.7 5307.2 5333.3 5335.0 5209.5 5225.0 5227.6 5240.8 5315.9 5312.2 5331.3 5348.4 5353.7 5509.3 4673.2 5123.1 5123.4 5247.7 5281.3 5070.4 5581.5 5623.4 5625.5 5633.6 6332.1 6344.0 6345.0 6345.8 6348.9 6217.8 6287.2 6335.9 6352.0 6354.1 5907.0 5920.3 5933.0 5943.4 5950.6 6343.2 6367.4 6377.9 6383.5 6393.9 6060.6 6143.3 6187.2 6199.5 6243.1 6318.0 6336.7 6343.4 6347.9 6356.6 6254.9 6283.8 6307.6 6334.6 6339.6 6128.6 6181.3 6197.2 6200.9 6265.0 4882.8 4924.0 4929.9 4936.6 4942.3 4421.5 4431.6 4450.1 4471.5 4483.1 5636.7 5698.6 5712.6 5756.7 5773.0 6311.2 6319.3 6326.8 6327.1 6337.8 5744.1 5752.7 5761.1 5789.3 5846.8 5279.6 5375.1 5400.8 5428.2 5442.6 6398.0 6411.2 6447.4 6452.7 6470.1 6299.2 6302.9 6332.0 6335.0 6337.4 6266.9 6275.9 6343.6 6364.8 6459.6 6261.1 6267.8 6279.0 6282.4 6283.0 6141.1 6164.9 6248.6 6319.4 6319.5 6106.1 6137.8 6169.8 6220.0 6221.1 6172.9 6196.5 6209.1 6220.8 6225.0 5882.3 5898.2 6032.0 6047.1 6079.9 6129.3 6148.9 6150.2 6185.7 6207.2 6050.4 6064.5 6076.1 6086.9 6124.6 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:71326.39 Median:3451.84 stddev:155152.86 (4494.8%) stddev-sans-first:155497.00 21:06:43 INFO - PROCESS | 1657 | Values: 509.4 514.8 531.4 540.9 549.6 41120.8 42636.8 42775.0 42867.0 44586.3 36923.4 43115.9 44282.0 44291.0 44326.6 8605.7 8801.6 9641.8 9845.3 9855.3 11613.2 11932.1 11939.7 12013.6 12031.5 11824.6 11892.3 11897.0 11909.5 11913.4 8765.9 11761.6 11791.3 11981.0 11986.8 11670.1 11752.6 11758.0 11774.6 11777.1 40580.8 40903.4 40918.0 40940.6 41031.6 3176.3 3267.6 3424.0 3479.7 3503.5 27778.4 28958.0 30436.0 30441.0 30479.8 2761.0 2787.3 2815.1 2824.6 2863.2 494169.0 497213.0 497837.0 497949.0 497961.0 63768.0 64273.0 64280.0 64283.7 64316.7 66198.0 67082.7 67223.3 67370.7 67391.0 17170.6 17288.7 17426.7 17498.3 17501.0 12178.3 12333.1 12335.9 13005.3 13221.2 19512.9 19642.9 19666.3 19742.0 19755.5 8187.3 8259.7 8294.0 8348.4 8379.0 915.7 1024.6 1084.2 1096.9 1145.2 24846.6 26529.6 29639.4 29689.4 30001.7 1107.2 1159.9 1190.3 1204.9 1228.4 15170.4 15342.0 15381.3 15654.5 15732.0 652373.0 661680.0 662761.0 663582.0 664682.0 58424.4 60129.7 60270.4 60369.7 60481.0 1189.5 1360.1 1362.0 1452.3 1477.3 23838.5 29707.8 30513.4 30955.8 31747.8 53468.2 53868.9 54515.6 54547.5 54553.0 567222.0 572841.0 573509.0 574123.1 574133.0 23222.0 23705.6 26020.0 26414.1 26658.3 42725.8 43063.1 45546.0 46229.5 47045.3 35843.6 37731.4 38042.0 38144.6 38476.8 36521.5 37120.6 37785.4 37892.8 38114.2 37018.1 37650.4 37925.0 38393.4 38481.8 29026.9 31242.0 32840.2 33104.0 33265.7 21617.6 23081.2 24288.6 24735.5 24753.6 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:36603.30 Median:20374.14 stddev:104577.53 (513.3%) stddev-sans-first:104835.54 21:06:43 INFO - PROCESS | 1657 | Values: 386.7 394.7 394.8 399.1 420.2 17596.6 20298.4 20406.0 21410.5 21486.5 20029.0 21081.1 21464.9 21624.9 21696.0 5827.9 6147.1 6148.2 6151.0 6158.9 5962.2 6008.7 6074.4 6130.7 6131.7 5864.1 6002.2 6012.9 6016.7 6043.5 5752.2 5978.8 6049.2 6119.0 6124.3 5890.9 5903.1 5917.1 5922.6 5929.3 22116.4 24280.0 25831.0 26478.3 26577.6 1935.2 2148.5 2169.6 2741.2 2781.7 9899.9 10189.5 10230.2 10529.7 10566.4 1524.9 1713.3 1766.1 1767.5 1833.8 124620.0 130798.0 131611.0 132673.0 133801.3 11640.9 11786.2 11815.8 11816.6 12012.7 15314.4 15345.4 15346.2 15380.0 15412.6 8789.2 8855.9 8874.8 8909.5 8969.6 8699.2 8715.2 8728.0 8850.7 8877.6 4639.8 4643.9 4662.3 4664.3 4719.5 1758.5 1825.4 1839.2 1941.8 1996.3 915.6 929.4 935.1 936.2 956.2 14520.9 19491.7 20349.7 20398.6 20643.3 985.5 987.6 998.1 999.8 1012.4 10530.4 10704.6 10738.0 10742.6 10765.9 612288.0 620276.0 622138.0 634407.0 634636.0 137841.0 139245.6 140799.0 144035.3 146646.0 996.9 1012.6 1026.7 1091.8 1092.7 13246.7 16940.3 18020.7 18310.9 18677.9 36592.0 43728.6 48038.5 49223.3 53450.4 73733.6 73884.6 75524.9 77746.6 81192.6 11775.4 12269.1 12401.0 12522.2 12643.5 13380.5 13393.7 13645.8 13689.9 13696.8 11677.4 11829.9 11847.5 11970.5 12108.8 10829.8 11296.5 11655.5 11794.1 11895.8 11643.0 11951.2 12157.3 12193.9 12245.3 12147.8 12168.8 12287.4 12406.8 12469.8 11699.7 11699.8 11879.4 12045.9 12117.1 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5741.96 Median:5023.07 stddev:3198.16 (63.7%) stddev-sans-first:3190.77 21:06:43 INFO - PROCESS | 1657 | Values: 1437.4 1603.4 1634.5 1745.8 1888.8 7924.6 8640.0 8853.7 8887.8 8997.1 8142.6 8823.1 8823.8 8839.4 8844.4 2422.8 2467.5 2468.1 2472.4 2476.3 2385.8 2398.4 2399.8 2401.8 2408.0 2400.4 2407.9 2417.9 2455.0 2463.1 2405.6 2434.3 2440.4 2441.7 2453.1 2346.0 2416.3 2423.1 2425.2 2427.8 8001.3 8272.5 8893.0 8976.2 8978.2 5277.8 5493.4 5511.4 5547.0 5547.4 3538.9 3617.7 3706.2 3711.9 3724.3 1050.9 1060.9 1062.5 1062.8 1064.5 11963.4 12113.5 12117.7 12120.2 12151.8 4623.7 4659.6 4787.5 4789.8 4795.2 9433.1 9435.1 9439.9 9457.0 9464.0 5374.5 5398.5 5399.9 5404.9 5409.4 4834.8 4891.6 5014.2 5050.0 5059.3 3752.6 3772.2 3825.1 3901.7 3941.2 1169.5 1175.1 1177.8 1187.8 1204.4 865.5 866.3 870.8 876.9 979.8 5804.6 5997.0 6087.7 6105.4 6205.3 900.0 901.8 950.4 952.2 983.0 3796.2 3927.5 3964.6 3985.3 3988.1 10351.3 10846.7 10853.5 10857.9 10859.0 10302.6 10745.0 10757.9 10888.3 10894.2 8016.0 8052.0 8207.2 8225.2 8314.2 5632.8 5729.2 5752.5 5829.5 5961.4 9744.0 9775.5 9979.3 10004.0 10100.0 9963.8 10224.0 10235.7 10255.3 10291.2 7408.9 7597.2 7697.2 7720.3 7759.1 8230.8 8286.3 8330.6 8336.6 8373.7 7366.7 7490.4 7518.0 7704.8 7750.7 6072.6 7244.9 7288.6 7441.9 7593.3 7063.2 7109.1 7144.2 7160.1 7165.5 4726.2 4972.1 4997.8 5004.7 5007.3 4797.9 4964.8 5032.0 5150.6 5247.0 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4388.26 Median:4926.47 stddev:1344.93 (27.3%) stddev-sans-first:1313.22 21:06:43 INFO - PROCESS | 1657 | Values: 299.3 303.3 314.9 318.2 321.7 4362.4 5222.8 5268.7 5306.6 5310.0 4391.3 4895.9 4929.3 5217.2 5255.6 3313.6 4007.9 4012.9 4057.7 4065.9 3622.5 3811.8 4009.5 4011.3 4028.5 3324.6 3620.4 3796.6 3809.9 4042.6 3818.7 3953.6 3969.2 4017.8 4039.8 2925.8 3063.7 3510.4 3515.6 3590.0 4283.0 4749.3 4992.3 5026.2 5029.5 4007.8 4503.3 4515.4 4527.8 4554.4 4338.4 5397.6 5406.3 5418.8 5447.6 3820.2 3826.1 3829.8 3833.6 3840.6 4843.9 5072.7 5288.9 5688.7 5748.7 3183.8 3401.3 3636.0 3784.0 3803.3 3479.4 3517.9 3646.9 3907.6 3936.6 4124.6 4316.7 4457.5 4498.4 4558.7 4474.4 4792.6 5477.4 5495.7 5505.4 1092.7 1298.6 1333.8 1336.4 1337.3 727.5 811.0 847.8 853.6 887.5 5032.7 5068.3 5069.7 5077.4 5106.1 5513.6 5524.2 5570.8 5586.6 5603.5 4065.6 5035.3 5048.3 5052.8 5078.8 2683.3 2870.6 2886.3 2893.8 2913.4 5059.3 5234.8 5492.7 5628.3 5731.1 4965.6 5037.2 5376.5 5420.3 5880.9 4920.1 5641.7 5651.5 5698.7 5934.5 4578.9 5350.7 5686.4 5719.3 5740.0 4746.8 4900.4 5276.7 5654.4 5656.8 5250.5 5252.4 5493.9 5883.9 5922.6 3551.9 3701.5 3874.5 3877.7 3914.1 4994.6 5582.6 5618.8 5688.2 5696.6 4827.5 4877.1 4923.6 5572.4 5583.8 4650.6 5269.6 5516.6 5539.5 5587.9 4334.6 5135.2 5255.6 5402.9 5416.5 4709.8 4786.7 5524.3 5540.7 5561.6 5136.8 5360.0 5382.0 5404.5 5445.7 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:17840.94 Median:612.43 stddev:24856.39 (4058.7%) stddev-sans-first:24892.29 21:06:43 INFO - PROCESS | 1657 | Values: 572.4 679.0 691.6 702.0 707.8 15048.4 15459.2 15507.1 15697.8 15702.5 13434.1 13547.1 13619.0 13634.9 13690.3 709.3 729.1 892.3 903.2 922.6 901.9 907.9 912.4 914.1 917.3 965.1 969.3 970.3 970.5 971.6 742.2 764.0 764.6 767.4 963.8 692.4 709.9 709.9 714.8 715.3 14877.0 14887.3 14939.9 14950.8 14959.8 12168.8 12217.8 12375.4 12446.6 12641.0 10823.1 10876.7 10883.9 11220.9 11354.8 4015.2 4023.8 4143.9 4167.2 4195.9 81532.0 82243.0 82721.6 84075.2 84087.2 9090.4 9313.6 9336.9 9568.3 9580.3 20329.9 20924.9 20929.2 21077.6 21349.5 7550.6 7557.8 7571.1 7750.1 8017.0 4031.6 4141.3 4152.0 4156.7 4467.8 3381.2 3535.1 3539.7 3541.1 3542.8 637.5 646.2 651.1 655.7 657.2 521.6 592.6 607.7 608.3 614.7 8033.2 8044.4 8108.3 8233.5 8263.6 530.6 590.8 610.0 610.2 615.9 3629.3 3708.1 3747.3 3850.1 3934.7 82986.2 84082.6 88498.2 90437.5 90454.5 76541.6 77227.0 77645.2 77828.0 78858.8 67555.7 70280.6 70958.6 70987.6 71403.9 8215.6 8295.5 8300.7 8395.5 8521.0 42383.4 42470.9 44326.8 44961.0 46571.6 56980.4 60493.4 60660.0 60802.2 61639.0 11048.9 11200.1 11243.0 11496.8 11624.4 11916.5 11935.3 11970.4 12469.6 13045.5 6237.8 10101.4 10467.7 11623.5 12397.9 9375.6 10074.9 11811.8 11927.3 12139.0 11264.5 11306.9 11469.4 11503.3 11712.0 9455.4 9665.8 9669.0 9670.8 9838.5 9639.1 9876.2 9878.8 9881.8 9905.3 21:06:43 INFO - PROCESS | 1657 | -------- Summary: end -------- 21:06:43 INFO - PROCESS | 1657 | 21:06:43 INFO - TEST-INFO | 1657: exit 0 21:06:43 INFO - TEST-OK | dromaeo_css | took 1116467ms 21:06:43 INFO - TEST-START | kraken 21:06:43 INFO - operating with platform_type : mac_ 21:06:43 INFO - Initialising browser for kraken test... 21:06:43 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp_DVIxK/profile http://localhost:49276/getInfo.html) 21:06:46 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 21:06:46 INFO - PROCESS | 1666 | colorDepth:24 21:06:46 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 21:06:46 INFO - PROCESS | 1666 | __metrics 21:06:46 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:06:46 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:06:46 INFO - TEST-INFO | 1666: exit 0 21:06:46 INFO - Browser initialized. 21:06:46 INFO - Running cycle 1/1 for kraken test... 21:06:46 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp_DVIxK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:06:58 INFO - PROCESS | 1668 | RSS: Main: 238915584 21:06:58 INFO - PROCESS | 1668 | 21:07:01 INFO - PROCESS | 1668 | 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) 21:07:02 INFO - PROCESS | 1668 | RSS: Main: 287313920 21:07:02 INFO - PROCESS | 1668 | 21:07:05 INFO - PROCESS | 1668 | 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) 21:07:05 INFO - PROCESS | 1668 | RSS: Main: 296009728 21:07:05 INFO - PROCESS | 1668 | 21:07:09 INFO - PROCESS | 1668 | 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) 21:07:10 INFO - PROCESS | 1668 | RSS: Main: 384454656 21:07:10 INFO - PROCESS | 1668 | 21:07:12 INFO - PROCESS | 1668 | 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) 21:07:13 INFO - PROCESS | 1668 | RSS: Main: 282046464 21:07:13 INFO - PROCESS | 1668 | 21:07:15 INFO - PROCESS | 1668 | 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) 21:07:15 INFO - PROCESS | 1668 | RSS: Main: 285900800 21:07:15 INFO - PROCESS | 1668 | 21:07:18 INFO - PROCESS | 1668 | 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) 21:07:19 INFO - PROCESS | 1668 | RSS: Main: 324030464 21:07:19 INFO - PROCESS | 1668 | 21:07:22 INFO - PROCESS | 1668 | 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) 21:07:23 INFO - PROCESS | 1668 | RSS: Main: 325844992 21:07:23 INFO - PROCESS | 1668 | 21:07:26 INFO - PROCESS | 1668 | 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) 21:07:27 INFO - PROCESS | 1668 | RSS: Main: 326619136 21:07:27 INFO - PROCESS | 1668 | 21:07:29 INFO - PROCESS | 1668 | 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) 21:07:29 INFO - PROCESS | 1668 | RSS: Main: 316043264 21:07:29 INFO - PROCESS | 1668 | 21:07:31 INFO - PROCESS | 1668 | 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) 21:07:32 INFO - PROCESS | 1668 | RSS: Main: 322736128 21:07:32 INFO - PROCESS | 1668 | 21:07:34 INFO - PROCESS | 1668 | 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) 21:07:35 INFO - PROCESS | 1668 | RSS: Main: 324915200 21:07:35 INFO - PROCESS | 1668 | 21:07:38 INFO - PROCESS | 1668 | 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) 21:07:38 INFO - PROCESS | 1668 | RSS: Main: 329494528 21:07:38 INFO - PROCESS | 1668 | 21:07:41 INFO - PROCESS | 1668 | 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) 21:07:42 INFO - PROCESS | 1668 | RSS: Main: 327041024 21:07:42 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | 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) 21:07:44 INFO - PROCESS | 1668 | RSS: Main: 374001664 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | __start_tp_report 21:07:44 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 21:07:44 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 21:07:44 INFO - PROCESS | 1668 | |i|pagename|runs| 21:07:44 INFO - PROCESS | 1668 | |0;ai-astar;113;107;113;111;111;112;116;163;156;111 21:07:44 INFO - PROCESS | 1668 | |1;audio-beat-detection;155;116;142;203;120;140;143;120;153;133 21:07:44 INFO - PROCESS | 1668 | |2;audio-dft;184;188;185;187;184;185;183;184;183;183 21:07:44 INFO - PROCESS | 1668 | |3;audio-fft;78;73;58;147;80;84;59;59;112;59 21:07:44 INFO - PROCESS | 1668 | |4;audio-oscillator;92;95;134;79;80;77;103;81;78;102 21:07:44 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;85;135;131;74;114;136;88;90;86 21:07:44 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;130;148;135;96;95;95;95;95;95 21:07:44 INFO - PROCESS | 1668 | |7;imaging-desaturate;84;87;88;83;82;88;69;130;144;84 21:07:44 INFO - PROCESS | 1668 | |8;json-parse-financial;57;60;59;58;58;57;58;121;60;58 21:07:44 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;63;64;73;71;69;68;66;67;73;67 21:07:44 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;94;106;107;105;85;85;85;84;83;84 21:07:44 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;143;145;148;133;137;136;131;144;230;144 21:07:44 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;172;170;167;170;171;168;175;172;168;168 21:07:44 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;67;82;86;87;70;67;68;66;66;66 21:07:44 INFO - PROCESS | 1668 | __end_tp_report 21:07:44 INFO - PROCESS | 1668 | __start_cc_report 21:07:44 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2389 21:07:44 INFO - PROCESS | 1668 | __end_cc_report 21:07:44 INFO - PROCESS | 1668 | __startTimestamp1462939664639__endTimestamp 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | ------- Summary: start ------- 21:07:44 INFO - PROCESS | 1668 | Number of tests: 14 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:121.30 Median:113.00 stddev:20.33 (18.0%) stddev-sans-first:21.34 21:07:44 INFO - PROCESS | 1668 | Values: 113.0 107.0 113.0 111.0 111.0 112.0 116.0 163.0 156.0 111.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:142.50 stddev:25.25 (17.7%) stddev-sans-first:26.37 21:07:44 INFO - PROCESS | 1668 | Values: 155.0 116.0 142.0 203.0 120.0 140.0 143.0 120.0 153.0 133.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.50 stddev:1.71 (0.9%) stddev-sans-first:1.80 21:07:44 INFO - PROCESS | 1668 | Values: 184.0 188.0 185.0 187.0 184.0 185.0 183.0 184.0 183.0 183.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:80.90 Median:66.00 stddev:28.64 (43.4%) stddev-sans-first:30.36 21:07:44 INFO - PROCESS | 1668 | Values: 78.0 73.0 58.0 147.0 80.0 84.0 59.0 59.0 112.0 59.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:79.50 stddev:17.79 (22.4%) stddev-sans-first:18.87 21:07:44 INFO - PROCESS | 1668 | Values: 92.0 95.0 134.0 79.0 80.0 77.0 103.0 81.0 78.0 102.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:85.50 stddev:23.71 (27.7%) stddev-sans-first:24.62 21:07:44 INFO - PROCESS | 1668 | Values: 89.0 85.0 135.0 131.0 74.0 114.0 136.0 88.0 90.0 86.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:95.00 stddev:20.77 (21.9%) stddev-sans-first:21.75 21:07:44 INFO - PROCESS | 1668 | Values: 99.0 130.0 148.0 135.0 96.0 95.0 95.0 95.0 95.0 95.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:84.00 stddev:23.59 (28.1%) stddev-sans-first:24.74 21:07:44 INFO - PROCESS | 1668 | Values: 84.0 87.0 88.0 83.0 82.0 88.0 69.0 130.0 144.0 84.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:58.00 stddev:19.84 (34.2%) stddev-sans-first:20.86 21:07:44 INFO - PROCESS | 1668 | Values: 57.0 60.0 59.0 58.0 58.0 57.0 58.0 121.0 60.0 58.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.50 stddev:3.45 (5.0%) stddev-sans-first:3.12 21:07:44 INFO - PROCESS | 1668 | Values: 63.0 64.0 73.0 71.0 69.0 68.0 66.0 67.0 73.0 67.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:84.50 stddev:10.27 (12.2%) stddev-sans-first:10.86 21:07:44 INFO - PROCESS | 1668 | Values: 94.0 106.0 107.0 105.0 85.0 85.0 85.0 84.0 83.0 84.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:144.00 stddev:28.98 (20.1%) stddev-sans-first:30.65 21:07:44 INFO - PROCESS | 1668 | Values: 143.0 145.0 148.0 133.0 137.0 136.0 131.0 144.0 230.0 144.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:170.50 stddev:2.47 (1.4%) stddev-sans-first:2.52 21:07:44 INFO - PROCESS | 1668 | Values: 172.0 170.0 167.0 170.0 171.0 168.0 175.0 172.0 168.0 168.0 21:07:44 INFO - PROCESS | 1668 | 21:07:44 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:69.00 stddev:8.80 (12.7%) stddev-sans-first:9.10 21:07:44 INFO - PROCESS | 1668 | Values: 67.0 82.0 86.0 87.0 70.0 67.0 68.0 66.0 66.0 66.0 21:07:44 INFO - PROCESS | 1668 | -------- Summary: end -------- 21:07:44 INFO - PROCESS | 1668 | 21:07:45 INFO - TEST-INFO | 1668: exit 0 21:07:45 INFO - TEST-OK | kraken | took 61535ms 21:07:45 INFO - SUITE-END | took 1178s 21:07:45 INFO - Completed test suite (00:19:38) 21:07:45 INFO - javascript benchmark 21:07:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3222.4360736871045, 3421.46967220081, 3459.688628023478, 3468.930621387574, 3498.160441494042, 4610.262358022717, 4980.452150263468, 5400.568849254951, 5690.374058853528, 5750.71246437678, 5677.943220567793, 5757.942420575793, 5759.510441612458, 5766.481016708495, 5774.960507108719, 5170.896582068356, 5220.608454365919, 5234.842954711365, 5251.842444726645, 5297.8940421191555, 5279.076161671711, 5283.709395983219, 5307.150855863063, 5333.333333333333, 5335.039692855285, 5209.453007434222, 5225.03336882676, 5227.634065615409, 5240.764165612538, 5315.920261196085, 5312.203169524563, 5331.306930099062, 5348.358197016344, 5353.705546194997, 5509.256250406184, 4673.205555055649, 5123.052235336438, 5123.359580052494, 5247.737613119329, 5281.287026251445, 5070.416902056274, 5581.5255703265275, 5623.409541998072, 5625.54995600351, 5633.605647604629, 6332.050192471166, 6344.016677415008, 6345.0, 6345.841353966188, 6348.904766428507, 6217.844553886144, 6287.2140982377205, 6335.904961425582, 6351.968240158816, 6354.142190804228, 5906.970465147647, 5920.289565252154, 5932.970335148297, 5943.375945525744, 5950.553708471882, 6343.238811342669, 6367.363263673688, 6377.872442551123, 6383.489320854321, 6393.904091438632, 6060.606060606029, 6143.296949342275, 6187.226596675416, 6199.504039676815, 6243.125962365272, 6317.989121740499, 6336.6831658416895, 6343.429091381706, 6347.873042539123, 6356.555041147075, 6254.937450625526, 6283.7800676976085, 6307.558470906993, 6334.619922804646, 6339.587926784744, 6128.564234752123, 6181.289151747516, 6197.194364732556, 6200.852842224203, 6264.968675156595, 4882.804687812519, 4923.950760492349, 4929.926051109236, 4936.555709986119, 4942.258661200849, 4421.513633500313, 4431.623312018514, 4450.132224216311, 4471.463424389094, 4483.103379324149, 5636.746346414421, 5698.629589076697, 5712.600117991784, 5756.712164391847, 5773.0, 6311.211098612674, 6319.3364696706185, 6326.778562750282, 6327.082573026885, 6337.841553961188, 5744.0522313817755, 5752.712364381764, 5761.107028410603, 5789.334226563999, 5846.766129354864, 5279.630425870152, 5375.139977603565, 5400.810971615974, 5428.158635411518, 5442.564594832404, 6398.0402939559435, 6411.198600174978, 6447.3552644735155, 6452.709628066842, 6470.126532917996, 6299.244090709144, 6302.936970630235, 6331.986882098842, 6335.049742538656, 6337.429631333202, 6266.905996409966, 6275.937240627718, 6343.555951083565, 6364.840878978062, 6459.644719540471, 6261.123442718114, 6267.811965641038, 6279.0, 6282.434580887742, 6282.968585157045, 6141.109539116893, 6164.907526387197, 6248.562600617913, 6319.399657032574, 6319.526035547445, 6106.096658337741, 6137.84655383619, 6169.84575385619, 6220.0, 6221.09794079865, 6172.938270617236, 6196.535259855619, 6209.0996805462055, 6220.813375598739, 6225.035119556295, 5882.264716910387, 5898.233229680286, 6032.034874420072, 6047.09293605945, 6079.939200608114, 6129.295131059986, 6148.938510615016, 6150.169727086965, 6185.659788711664, 6207.193064901534, 6050.364711705208, 6064.545159113172, 6076.058210977306, 6086.91760282864, 6124.5712800103565], "value": 5742.788342775683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.368383204826, 514.7966553211481, 531.3623651618058, 540.8512659018771, 549.5658429840424, 41120.79439602802, 42636.78681606596, 42775.0, 42867.0, 44586.33120503195, 36923.4461483078, 43115.922101947464, 44282.0, 44291.0, 44326.55673443265, 8605.698800541983, 8801.603927823264, 9641.758956026086, 9845.261605379625, 9855.310128291023, 11613.18707690462, 11932.105092118083, 11939.701507462296, 12013.639590812247, 12031.458584363725, 11824.581050273979, 11892.286462812215, 11896.988755955754, 11909.523619055228, 11913.404329783476, 8765.94808622966, 11761.647150585497, 11791.29252244868, 11981.0, 11986.760264794742, 11670.124740644485, 11752.647420577396, 11758.0, 11774.587889423914, 11777.057835373234, 40580.79709601463, 40903.38644920329, 40917.977050573674, 40940.590594094276, 41031.58968410308, 3176.3122476446747, 3267.591551056118, 3423.989922972729, 3479.6868281854754, 3503.5095086687884, 27778.444431111468, 28958.0, 30436.0, 30441.0, 30479.847600761856, 2761.0198379575363, 2787.2613757354316, 2815.085097343351, 2824.5904343870025, 2863.1696807925678, 494169.0, 497213.0, 497837.0, 497949.0, 497960.9999999964, 63768.0, 64273.0, 64280.0, 64283.67858160679, 64316.67841660762, 66198.0, 67082.66458667675, 67223.3277667217, 67370.66314668395, 67391.0, 17170.570735731708, 17288.740668889975, 17426.738598921027, 17498.300067997392, 17501.0, 12178.330191839357, 12333.136680432463, 12335.93832030852, 13005.284709340887, 13221.206727596375, 19512.899691204417, 19642.90178549098, 19666.311679091163, 19742.01289935526, 19755.50611234702, 8187.26314631686, 8259.669613215525, 8293.95853020731, 8348.4156109073, 8379.0, 915.7023967210683, 1024.6055268721448, 1084.2356138922041, 1096.8793432722393, 1145.1583086431633, 24846.631896568935, 26529.602055969175, 29639.407211855643, 29689.40621187564, 30001.699983000326, 1107.1530279336314, 1159.907207423404, 1190.315568548071, 1204.9156559040957, 1228.416502161466, 15170.393184272505, 15342.0, 15381.312778321484, 15654.45209417665, 15732.0, 652373.0, 661680.0, 662761.0, 663582.0, 664682.0, 58424.41575584275, 60129.699351503834, 60270.383776973526, 60369.69815150984, 60481.0, 1189.5182451107316, 1360.115924648986, 1362.0329566008047, 1452.3174108169355, 1477.3351991603606, 23838.523229535658, 29707.811687532256, 30513.389732205676, 30955.845220774652, 31747.84126079355, 53468.197977029595, 53868.92262154813, 54515.637109071, 54547.45452545424, 54553.0, 567222.0, 572841.0, 573509.0, 574123.1293843504, 574133.0, 23222.0, 23705.64441533413, 26020.0, 26414.07550735715, 26658.33354166084, 42725.786371067945, 43063.13873722571, 45546.0, 46229.53770462387, 47045.294320585905, 35843.64156358403, 37731.43402849014, 38042.0, 38144.61855381411, 38476.80761596286, 36521.452178216816, 37120.628793711716, 37785.433218502294, 37892.81053594824, 38114.2377152461, 37018.074548136516, 37650.43524347082, 37925.0, 38393.4240986391, 38481.807590961864, 29026.854865725538, 31242.0, 32840.178995525304, 33104.0, 33265.667343326255, 21617.567648646625, 23081.192158274378, 24288.57475270463, 24735.50528989446, 24753.62869556994], "value": 20546.086065380874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.65587627011956, 394.74341677909354, 394.7927338147474, 399.1179493319765, 420.16597054846125, 17596.560085997855, 20298.391048268557, 20405.979701014963, 21410.46473838155, 21486.462838429048, 20028.998550072512, 21081.051352689126, 21464.93537637578, 21624.918754062313, 21696.0, 5827.941720582803, 6147.139400483936, 6148.200733904586, 6151.046587778901, 6158.938410615904, 5962.195103661015, 6008.729607167688, 6074.42292982166, 6130.724117414727, 6131.724072416752, 5864.1203819427, 6002.219711437507, 6012.909806352908, 6016.729247183887, 6043.546733994946, 5752.2234498342605, 5978.8206353809455, 6049.183360246355, 6118.990366589552, 6124.295705993779, 5890.8821823563285, 5903.085021821625, 5917.082852157922, 5922.58541902067, 5929.3181284152015, 22116.447088822748, 24280.0, 25831.0, 26478.338041549116, 26577.601335979976, 1935.1678778125402, 2148.5273239887356, 2169.5986242545023, 2741.232454912628, 2781.749642532182, 9899.851502227473, 10189.54147063384, 10230.181585473145, 10529.684109476728, 10566.418846963414, 1524.8932574719774, 1713.2804222226694, 1766.0992893624216, 1767.4962635648908, 1833.8441232495254, 124620.0, 130798.0, 131611.0, 132673.0, 133801.32397352095, 11640.88359116398, 11786.233894796811, 11815.822762658567, 11816.586419475278, 12012.699682507833, 15314.387424503118, 15345.386184552715, 15346.232688365537, 15380.0, 15412.61468463275, 8789.208971192624, 8855.867161992575, 8874.822503549893, 8909.465432074096, 8969.551522423853, 8699.173578510157, 8715.215630593277, 8728.0, 8850.73447796567, 8877.556122193864, 4639.837605683785, 4643.86068417948, 4662.300654901724, 4664.346991421204, 4719.457262414798, 1758.5339884930504, 1825.435322767458, 1839.1815642039164, 1941.7864034956144, 1996.3212507747435, 915.5742579700471, 929.3540989012706, 935.1396715022196, 936.1527817325332, 956.2159029595804, 14520.854791452162, 19491.707624385646, 20349.694754578693, 20398.592028159354, 20643.277485288243, 985.5072463768116, 987.5605355616825, 998.0967224661692, 999.7850462150673, 1012.4229189361993, 10530.36817790935, 10704.571817127227, 10738.0, 10742.624008159833, 10765.946170269099, 612288.0, 620276.0, 622138.0, 634407.0, 634636.0, 137841.0, 139245.6075439253, 140799.0, 144035.2798236002, 146646.0, 996.9202463803024, 1012.6405126180247, 1026.7227848480868, 1091.7598128411737, 1092.7377429416883, 13246.735135027506, 16940.322387104377, 18020.729689054937, 18310.908445457688, 18677.90661046686, 36592.0, 43728.627137286014, 48038.5196148034, 49223.261651075976, 53450.39648810542, 73733.631331843, 73884.63057684677, 75524.86712699205, 77746.6112669433, 81192.59403702944, 11775.411229438323, 12269.079819013432, 12401.0, 12522.18605790657, 12643.494260229489, 13380.531681391105, 13393.732125357243, 13645.795313070113, 13689.86310136886, 13696.794548081985, 11677.357745324089, 11829.928018956334, 11847.52609895629, 11970.46132924038, 12108.757824843278, 10829.84658298447, 11296.548138074713, 11655.475503602189, 11794.115441342105, 11895.762084758084, 11643.0, 11951.22317049389, 12157.270563766202, 12193.939030304791, 12245.326507042199, 12147.757044858876, 12168.817467737814, 12287.44706488228, 12406.751864962831, 12469.812952805896, 11699.707507312385, 11699.824502632297, 11879.406029698308, 12045.879541204476, 12117.091218158497], "value": 9990.62792693274, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.4322142753606, 1603.3746838732893, 1634.4933070748068, 1745.7905051393827, 1888.7817357804217, 7924.603769811508, 8639.956800215998, 8853.690120845771, 8887.777805554857, 8997.145271199242, 8142.592870356488, 8823.117688231174, 8823.823523529525, 8839.425437346568, 8844.425112367691, 2422.80617550596, 2467.469494058777, 2468.0868078810863, 2472.3819045238693, 2476.3313905245573, 2385.821063420242, 2398.3764221302413, 2399.7600239976036, 2401.7838394544488, 2408.0247499762622, 2400.3639035655565, 2407.9157229496973, 2417.867017314047, 2455.0057226823164, 2463.0763463701114, 2405.5910495215853, 2434.306222726523, 2440.377703685549, 2441.694788151481, 2453.055573604175, 2345.976540234593, 2416.299273210767, 2423.1155628195756, 2425.175440350272, 2427.842190257645, 8001.279884810395, 8272.503649781032, 8893.021767605624, 8976.192142707188, 8978.23684986777, 5277.841664750025, 5493.423190565013, 5511.3937466878615, 5547.029269877755, 5547.362053363874, 3538.929221415557, 3617.7467577269604, 3706.22169344438, 3711.9443208351895, 3724.3110024645525, 1050.9316894401838, 1060.8939106089406, 1062.5059347403494, 1062.760878802271, 1064.5103252503857, 11963.40182990847, 12113.45489452968, 12117.697057573543, 12120.151589388746, 12151.817722734078, 4623.69945953512, 4659.603933665677, 4787.54518320759, 4789.784459699322, 4795.18481858078, 9433.056694330511, 9435.103665151799, 9439.858402123973, 9457.0, 9464.0, 5374.4625537445945, 5398.514133727984, 5399.946000539944, 5404.91892621611, 5409.377921538995, 4834.830780922651, 4891.608671306287, 5014.166566902108, 5049.990001999615, 5059.2916991621205, 3752.6434988676033, 3772.2266935278203, 3825.139343647657, 3901.668358189529, 3941.192055628589, 1169.5030361137588, 1175.1304035014027, 1177.8292147638545, 1187.8099504079305, 1204.3978010994504, 865.5455885660078, 866.2593482572436, 870.7910101575703, 876.8684697295329, 979.7844474215661, 5804.622699524517, 5996.970015149896, 6087.691146403545, 6105.389461053859, 6205.348438413902, 899.9550022498848, 901.8376692195468, 950.4012472142506, 952.2286947572488, 982.9508524573743, 3796.183820478555, 3927.509061367329, 3964.6431821136234, 3985.322495175827, 3988.12261302513, 10351.327163734333, 10846.728831779268, 10853.457327133612, 10857.891421085846, 10859.022687957964, 10302.587896484056, 10745.0, 10757.892421075845, 10888.29226100301, 10894.237403381845, 8015.959920200478, 8052.0, 8207.179282071746, 8225.218604232588, 8314.210150035737, 5632.831015069554, 5729.197912292199, 5752.453516915886, 5829.504492118217, 5961.433663801932, 9744.0, 9775.511224438607, 9979.301448898508, 10004.0, 10100.0, 9963.800723985625, 10224.0, 10235.692929212135, 10255.282130250811, 10291.176705863663, 7408.888866667112, 7597.164311925794, 7697.191794861459, 7720.3051725344985, 7759.107702614046, 8230.83538329218, 8286.337093032664, 8330.625121869653, 8336.583170841555, 8373.706920257762, 7366.7053317866685, 7490.400767938443, 7517.962410187914, 7704.8073798153255, 7750.728724494616, 6072.605280656743, 7244.855102898018, 7288.599127048064, 7441.888371674538, 7593.278638529331, 7063.187733410724, 7109.075820143348, 7144.214136444883, 7160.069191005136, 7165.462590305644, 4726.172919738963, 4972.0801651694555, 4997.825076122318, 5004.724740139327, 5007.349044624176, 4797.880052998564, 4964.801407943786, 5031.974840125776, 5150.562202212854, 5247.0], "value": 4619.88656153952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.2503778036021, 303.3054305640084, 314.8724766469579, 318.2124242499815, 321.68796267620417, 4362.432883725115, 5222.764975576099, 5268.683878967255, 5306.575473962088, 5309.99095682759, 4391.319345501444, 4895.926561101586, 4929.334539837121, 5217.243499692542, 5255.605829562779, 3313.5858017747782, 4007.9198416031663, 4012.8595499157536, 4057.6956728245427, 4065.918681626366, 3622.510961020255, 3811.8284677189595, 4009.478767760187, 4011.3381292086915, 4028.47629808126, 3324.634290228073, 3620.4207326827714, 3796.595259753895, 3809.923801523968, 4042.615951484619, 3818.6563209311294, 3953.6441720245316, 3969.2260009298197, 4017.799110044515, 4039.7778122203267, 2925.7513111385556, 3063.7089476499696, 3510.420780571228, 3515.5605549306338, 3590.0127464947086, 4282.957170428318, 4749.263864101035, 4992.251162325644, 5026.190571394295, 5029.510392355807, 4007.8196481158125, 4503.347014682852, 4515.4129963104915, 4527.818887244506, 4554.362389265505, 4338.370936214231, 5397.595180361449, 5406.270153529263, 5418.756155972991, 5447.5641948644015, 3820.2359528094216, 3826.1199924017355, 3829.7510661807173, 3833.5591406987996, 3840.596737342567, 4843.910120222957, 5072.67027643202, 5288.947110528922, 5688.658680479143, 5748.7125643717645, 3183.8089714616735, 3401.3197360527993, 3636.0364503406618, 3783.9621603784162, 3803.2583646188727, 3479.3911065563425, 3517.854108993512, 3646.851241858806, 3907.648311651908, 3936.645701886787, 4124.566920473367, 4316.69783115179, 4457.531959144308, 4498.3927169832305, 4558.675075711411, 4474.351219073216, 4792.568668819823, 5477.4248703886315, 5495.670259784426, 5505.421930697219, 1092.6776600902595, 1298.6363818130937, 1333.7999300104868, 1336.3719052045524, 1337.2979185927466, 727.4689476682051, 810.9862671660425, 847.7965288330756, 853.6158728572167, 887.5163036145316, 5032.691500209899, 5068.341115654941, 5069.654028341277, 5077.416097148801, 5106.11073902651, 5513.594033521426, 5524.226608274845, 5570.777168913279, 5586.597762961884, 5603.467670571289, 4065.6340929316502, 5035.345405097314, 5048.29915994941, 5052.823151189764, 5078.796848126033, 2683.262102921712, 2870.5550639650887, 2886.321714397115, 2893.7974341796294, 2913.3539550154096, 5059.291699162047, 5234.790608375699, 5492.725363731797, 5628.296462942132, 5731.140328950607, 4965.553100220997, 5037.219231019198, 5376.46235376467, 5420.322459692538, 5880.882882162418, 4920.089783390084, 5641.689707066069, 5651.491365777182, 5698.686572238566, 5934.525237980951, 4578.862634120982, 5350.678959262456, 5686.4029276926985, 5719.28508936383, 5739.971300143473, 4746.8101275948575, 4900.436449808175, 5276.657017293864, 5654.40628733977, 5656.8020119295625, 5250.527452529291, 5252.448492908343, 5493.890122197454, 5883.911741323798, 5922.644641321534, 3551.8756843510355, 3701.463287823304, 3874.457575939427, 3877.6897848172644, 3914.1388894443176, 4994.575461085703, 5582.637128586629, 5618.803341883015, 5688.175214593778, 5696.629719068247, 4827.517248275144, 4877.097736918539, 4923.606111511142, 5572.442755724395, 5583.804566840141, 4650.604818554352, 5269.5759950423235, 5516.558675305886, 5539.473749993744, 5587.888242235052, 4334.609885110229, 5135.226977432294, 5255.579553635624, 5402.918956215739, 5416.51251387377, 4709.81160753566, 4786.664933454625, 5524.309461317335, 5540.667559946416, 5561.610687252015, 5136.792853679457, 5359.973200133974, 5382.0, 5404.459554044564, 5445.673259604437], "value": 3962.506672992374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.3818276261637, 678.9694463749131, 691.6161530350656, 702.0, 707.7699747582037, 15048.398064077437, 15459.22703864808, 15507.06957582543, 15697.843021569795, 15702.450414235518, 13434.126781759178, 13547.051706380556, 13619.046666733333, 13634.93182534086, 13690.315484225774, 709.3332267668401, 729.1286912139994, 892.3307519360479, 903.2051794420901, 922.6447817590209, 901.9413738107035, 907.863820426938, 912.4114945859916, 914.063085337531, 917.334932174175, 965.1410244882061, 969.2585172343163, 970.3401686852973, 970.5050424283631, 971.6307803034872, 742.1761844352737, 764.0, 764.5947647746704, 767.382257282887, 963.78796664734, 692.390696187357, 709.9006139140524, 709.9077119974422, 714.7998560403096, 715.2775696546473, 14877.03299285543, 14887.330070146869, 14939.925300373428, 14950.775738363933, 14959.775603365959, 12168.756624867454, 12217.755644887053, 12375.381230938507, 12446.626601201888, 12641.0, 10823.114037915111, 10876.728081797939, 10883.945580272128, 11220.887791122066, 11354.772904541864, 4015.19696060789, 4023.8189281482405, 4143.91712165755, 4167.249895018896, 4195.937060944088, 81532.0, 82242.9999999994, 82721.58639206826, 84075.15924840735, 84087.15912840854, 9090.40912340709, 9313.627454901864, 9336.859947100798, 9568.330216884891, 9580.281478888972, 20329.898350508152, 20924.89537552333, 20929.16283348649, 21077.578448430944, 21349.46626334323, 7550.622468876534, 7557.773266802004, 7571.091469023642, 7750.14748377678, 8017.037955445384, 4031.5565287818317, 4141.254574176677, 4152.045029643199, 4156.667466602665, 4467.8212871484775, 3381.1547113221695, 3535.133892196377, 3539.6991255743037, 3541.11472131967, 3542.8051457169586, 637.5250438423404, 646.1503123392723, 651.0852252585125, 655.6788072034853, 657.2113463843407, 521.6478876758173, 592.644413351994, 607.6809674920702, 608.2883026858586, 614.658864330301, 8033.19668033192, 8044.4368894178015, 8108.310793582494, 8233.46482478637, 8263.586820658944, 530.6391653675538, 590.8138936235072, 609.9878002439926, 610.2006371653149, 615.9353267906895, 3629.310431018098, 3708.147126160998, 3747.2692824899427, 3850.114473671071, 3934.7245692801803, 82986.17013829785, 84082.5795871029, 88498.23003539893, 90437.54781226051, 90454.54772726225, 76541.6172919143, 77227.0, 77645.22354776492, 77828.0, 78858.84564617465, 67555.66222168857, 70280.64859675668, 70958.64520677364, 70987.64506177435, 71403.92894106593, 8215.632884514549, 8295.460795048302, 8300.667973281, 8395.496270223806, 8521.0, 42383.3642495369, 42470.93822654458, 44326.77836610797, 44961.0, 46571.6028519145, 56980.43019569751, 60493.395066048775, 60660.0, 60802.17593472203, 61639.0, 11048.889511105108, 11200.103991680484, 11243.0, 11496.827547586625, 11624.360660163435, 11916.52333906634, 11935.343556104497, 11970.401479925795, 12469.563565275535, 13045.478180872658, 6237.812865614039, 10101.444420556645, 10467.685969420929, 11623.476943537731, 12397.93801030989, 9375.578098985698, 10074.899251007397, 11811.822822657496, 11927.284362938251, 12138.968187704144, 11264.493097810455, 11306.886931130912, 11469.369184694922, 11503.252288601214, 11712.0, 9455.385399948982, 9665.806683866143, 9668.951655241679, 9670.806583868143, 9838.458884761407, 9639.132478077006, 9876.160526355054, 9878.802423951625, 9881.802363952824, 9905.306628535933], "value": 6837.050951345052, "unit": "score"}], "name": "dromaeo_css", "value": 7269.3227762985925, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 111.0, 111.0, 112.0, 116.0, 163.0, 156.0, 111.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 142.0, 203.0, 120.0, 140.0, 143.0, 120.0, 153.0, 133.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 185.0, 187.0, 184.0, 185.0, 183.0, 184.0, 183.0, 183.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 147.0, 80.0, 84.0, 59.0, 59.0, 112.0, 59.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 134.0, 79.0, 80.0, 77.0, 103.0, 81.0, 78.0, 102.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 135.0, 131.0, 74.0, 114.0, 136.0, 88.0, 90.0, 86.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 130.0, 148.0, 135.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 88.0, 83.0, 82.0, 88.0, 69.0, 130.0, 144.0, 84.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 59.0, 58.0, 58.0, 57.0, 58.0, 121.0, 60.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 73.0, 71.0, 69.0, 68.0, 66.0, 67.0, 73.0, 67.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 106.0, 107.0, 105.0, 85.0, 85.0, 85.0, 84.0, 83.0, 84.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 145.0, 148.0, 133.0, 137.0, 136.0, 131.0, 144.0, 230.0, 144.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 167.0, 170.0, 171.0, 168.0, 175.0, 172.0, 168.0, 168.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 82.0, 86.0, 87.0, 70.0, 67.0, 68.0, 66.0, 66.0, 66.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1542.5999999999997, "alertThreshold": 2.0}]} 21:07:45 INFO - Return code: 0 21:07:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:07:45 ERROR - # TBPL SUCCESS # 21:07:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:07:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:07:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:07:45 INFO - 2016-05-10 21:07:45.924 screenresolution[1672:9543] starting screenresolution argv=screenresolution get 21:07:45 INFO - 2016-05-10 21:07:45.927 screenresolution[1672:9543] Display 0: 1600x1200x32@60 21:07:45 INFO - 2016-05-10 21:07:45.933 screenresolution[1673:9546] starting screenresolution argv=screenresolution list 21:07:45 INFO - Available Modes on Display 0 21:07:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:07:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:07:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:07:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:07:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:07:46 INFO - Intel Iris: 21:07:46 INFO - Chipset Model: Intel Iris 21:07:46 INFO - Type: GPU 21:07:46 INFO - Bus: Built-In 21:07:46 INFO - VRAM (Dynamic, Max): 1536 MB 21:07:46 INFO - Vendor: Intel (0x8086) 21:07:46 INFO - Device ID: 0x0a2e 21:07:46 INFO - Revision ID: 0x0009 21:07:46 INFO - Displays: 21:07:46 INFO - Display: 21:07:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:07:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:07:46 INFO - Display Serial Number: 1600x1200 60 21:07:46 INFO - Main Display: Yes 21:07:46 INFO - Mirror: Off 21:07:46 INFO - Online: Yes 21:07:46 INFO - Rotation: Supported 21:07:46 INFO - Return code: 0 21:07:46 INFO - Running post-action listener: _resource_record_post_action 21:07:46 INFO - ##### 21:07:46 INFO - ##### Finished run-tests step (success) 21:07:46 INFO - ##### 21:07:46 INFO - Running post-run listener: _resource_record_post_run 21:07:47 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 37721600; Write bytes: 692097536; Read time: 387; Write time: 1938 21:07:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394959360; Write bytes: 395995136; Read time: 29839; Write time: 742 21:07:47 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35898880; Write bytes: 295086592; Read time: 366; Write time: 1192 21:07:47 INFO - Running post-run listener: _upload_blobber_files 21:07:47 INFO - Blob upload gear active. 21:07:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:07:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:07:47 INFO - (blobuploader) - INFO - Open directory for files ... 21:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:48 INFO - (blobuploader) - INFO - Done attempting. 21:07:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:07:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:07:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:49 INFO - (blobuploader) - INFO - Done attempting. 21:07:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:07:49 INFO - Return code: 0 21:07:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:07:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:07:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c20314b29ba3038bdb9a35b8e7de3d041026673bd56805b3515285641db3af15de1fdb5231e77fd73f2ed61dd75ffd2adc1f4cb02e87d597c29f7394e88e34d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f3b746eb4b5b3d382e8b23a87784534bbfa15823420f981f5a8061719952aa13ea919799f27bfd2f5d60ec86406d364763bbfbe2e6dd54df6d1f1622ccf9a53"} 21:07:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:07:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:07:49 INFO - Contents: 21:07:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c20314b29ba3038bdb9a35b8e7de3d041026673bd56805b3515285641db3af15de1fdb5231e77fd73f2ed61dd75ffd2adc1f4cb02e87d597c29f7394e88e34d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f3b746eb4b5b3d382e8b23a87784534bbfa15823420f981f5a8061719952aa13ea919799f27bfd2f5d60ec86406d364763bbfbe2e6dd54df6d1f1622ccf9a53"} 21:07:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:07:49 INFO - Copying logs to upload dir... 21:07:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:07:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.417085 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-10 21:07:49.265422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 21:07:49.268518) ========= 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.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c20314b29ba3038bdb9a35b8e7de3d041026673bd56805b3515285641db3af15de1fdb5231e77fd73f2ed61dd75ffd2adc1f4cb02e87d597c29f7394e88e34d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f3b746eb4b5b3d382e8b23a87784534bbfa15823420f981f5a8061719952aa13ea919799f27bfd2f5d60ec86406d364763bbfbe2e6dd54df6d1f1622ccf9a53"} build_url:https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010933 build_url: 'https://queue.taskcluster.net/v1/task/BOBf8e8HRxe2VWUyyERMyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c20314b29ba3038bdb9a35b8e7de3d041026673bd56805b3515285641db3af15de1fdb5231e77fd73f2ed61dd75ffd2adc1f4cb02e87d597c29f7394e88e34d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f3b746eb4b5b3d382e8b23a87784534bbfa15823420f981f5a8061719952aa13ea919799f27bfd2f5d60ec86406d364763bbfbe2e6dd54df6d1f1622ccf9a53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 21:07:49.297045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:07:49.297361) ========= 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.ia4rxNgCVX/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.TB0yoGOylq/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005064 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:07:49.346753) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 21:07:49.347193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 21:07:49.347716) ========= ========= Total master_lag: 0.17 =========