builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0091 starttime: 1461329755.31 results: success (0) buildid: 20160419211331 builduid: 3b917d3ba8a04cb5a3589a514138e6b8 revision: bf3a35b355f0b48ac1020c47198053af9944a493 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.311514) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.311987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.312273) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.339139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.339538) ========= 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.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-22 05:55:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.7M=0s 2016-04-22 05:55:55 (39.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141495 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.498999) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.499431) ========= 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.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.026689 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:55.554130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 05:55:55.554497) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bf3a35b355f0b48ac1020c47198053af9944a493 --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 bf3a35b355f0b48ac1020c47198053af9944a493 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-22 05:55:55,629 truncating revision to first 12 chars 2016-04-22 05:55:55,629 Setting DEBUG logging. 2016-04-22 05:55:55,629 attempt 1/10 2016-04-22 05:55:55,629 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bf3a35b355f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:55:56,640 unpacking tar archive at: fx-team-bf3a35b355f0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.434126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 05:55:57.009069) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:57.009400) ========= script_repo_revision: bf3a35b355f0b48ac1020c47198053af9944a493 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:57.009820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:57.010085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:55:57.026488) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 05:55:57.026899) ========= /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.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:55:57 INFO - MultiFileLogger online at 20160422 05:55:57 in /builds/slave/test 05:55:57 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 05:55:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:55:57 INFO - {'append_to_log': False, 05:55:57 INFO - 'base_work_dir': '/builds/slave/test', 05:55:57 INFO - 'blob_upload_branch': 'Fx-Team', 05:55:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:55:57 INFO - 'branch': 'Fx-Team', 05:55:57 INFO - 'buildbot_json_path': 'buildprops.json', 05:55:57 INFO - 'config_files': ('talos/mac_config.py',), 05:55:57 INFO - 'default_actions': ('clobber', 05:55:57 INFO - 'read-buildbot-config', 05:55:57 INFO - 'download-and-extract', 05:55:57 INFO - 'populate-webroot', 05:55:57 INFO - 'create-virtualenv', 05:55:57 INFO - 'install', 05:55:57 INFO - 'run-tests'), 05:55:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:55:57 INFO - 'download_minidump_stackwalk': True, 05:55:57 INFO - 'download_symbols': 'ondemand', 05:55:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:55:57 INFO - 'tooltool.py': '/tools/tooltool.py', 05:55:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:55:57 INFO - '/tools/misc-python/virtualenv.py')}, 05:55:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:55:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:55:57 INFO - 'installer_path': 'installer.exe', 05:55:57 INFO - 'log_level': 'info', 05:55:57 INFO - 'log_name': 'talos', 05:55:57 INFO - 'log_to_console': True, 05:55:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:55:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:55:57 INFO - 'opt_config_files': (), 05:55:57 INFO - 'pip_index': False, 05:55:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:55:57 INFO - 'cmd': ('bash', 05:55:57 INFO - '-c', 05:55:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:55:57 INFO - 'enabled': True, 05:55:57 INFO - 'halt_on_failure': False, 05:55:57 INFO - 'name': 'check_screen_resolution'},), 05:55:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:55:57 INFO - 'cmd': ('bash', 05:55:57 INFO - '-c', 05:55:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:55:57 INFO - 'enabled': True, 05:55:57 INFO - 'halt_on_failure': False, 05:55:57 INFO - 'name': 'check_screen_resolution'},), 05:55:57 INFO - 'run_cmd_checks_enabled': True, 05:55:57 INFO - 'sps_profile': False, 05:55:57 INFO - 'sps_profile_interval': 0, 05:55:57 INFO - 'suite': 'dromaeojs', 05:55:57 INFO - 'system_bits': '32', 05:55:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:55:57 INFO - 'title': 't-yosemite-r7-0091', 05:55:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:55:57 INFO - 'use_talos_json': True, 05:55:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:55:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:55:57 INFO - 'work_dir': 'build'} 05:55:57 INFO - ##### 05:55:57 INFO - ##### Running clobber step. 05:55:57 INFO - ##### 05:55:57 INFO - Running pre-action listener: _resource_record_pre_action 05:55:57 INFO - Running main action method: clobber 05:55:57 INFO - rmtree: /builds/slave/test/build 05:55:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:55:59 INFO - Running post-action listener: _resource_record_post_action 05:55:59 INFO - ##### 05:55:59 INFO - ##### Running read-buildbot-config step. 05:55:59 INFO - ##### 05:55:59 INFO - Running pre-action listener: _resource_record_pre_action 05:55:59 INFO - Running main action method: read_buildbot_config 05:55:59 INFO - Using buildbot properties: 05:55:59 INFO - { 05:55:59 INFO - "project": "", 05:55:59 INFO - "product": "firefox", 05:55:59 INFO - "script_repo_revision": "production", 05:55:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:55:59 INFO - "repository": "", 05:55:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:55:59 INFO - "buildid": "20160419211331", 05:55:59 INFO - "pgo_build": "False", 05:55:59 INFO - "basedir": "/builds/slave/test", 05:55:59 INFO - "buildnumber": 757, 05:55:59 INFO - "slavename": "t-yosemite-r7-0091", 05:55:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:55:59 INFO - "platform": "yosemite_r7", 05:55:59 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:55:59 INFO - "branch": "fx-team", 05:55:59 INFO - "repo_path": "integration/fx-team", 05:55:59 INFO - "revision": "bf3a35b355f0b48ac1020c47198053af9944a493", 05:55:59 INFO - "stage_platform": "macosx64", 05:55:59 INFO - "builduid": "3b917d3ba8a04cb5a3589a514138e6b8", 05:55:59 INFO - "slavebuilddir": "test" 05:55:59 INFO - } 05:55:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:55:59 INFO - Running post-action listener: _resource_record_post_action 05:55:59 INFO - ##### 05:55:59 INFO - ##### Running download-and-extract step. 05:55:59 INFO - ##### 05:55:59 INFO - Running pre-action listener: _resource_record_pre_action 05:55:59 INFO - Running main action method: download_and_extract 05:55:59 INFO - mkdir: /builds/slave/test/build/tests 05:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:59 INFO - https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:55:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:55:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:55:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:56:01 INFO - Downloaded 1199 bytes. 05:56:01 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:56:01 INFO - Using the following test package requirements: 05:56:01 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:56:01 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:56:01 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:56:01 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'jsshell-mac.zip'], 05:56:01 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:56:01 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:56:01 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:56:01 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:56:01 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:56:01 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:56:01 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:56:01 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:56: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')]} 05:56:01 INFO - https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:56:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:56:02 INFO - Downloaded 18095501 bytes. 05:56:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:56:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:56:05 INFO - Return code: 0 05:56:05 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:05 INFO - https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:56:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:56:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:56:06 INFO - Downloaded 18095501 bytes. 05:56:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:56:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:56:10 INFO - Return code: 0 05:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:10 INFO - https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:56:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:56:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:56:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:56:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:56:11 INFO - Downloaded 10845952 bytes. 05:56:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:56:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:56:11 INFO - Return code: 0 05:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:11 INFO - https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:56:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:56:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:56:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:56:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:56:14 INFO - Downloaded 110002250 bytes. 05:56:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:56:14 INFO - mkdir: /builds/slave/test/properties 05:56:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:56:14 INFO - Writing to file /builds/slave/test/properties/build_url 05:56:14 INFO - Contents: 05:56:14 INFO - build_url:https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:56:15 INFO - Running post-action listener: _resource_record_post_action 05:56:15 INFO - Running post-action listener: set_extra_try_arguments 05:56:15 INFO - ##### 05:56:15 INFO - ##### Running populate-webroot step. 05:56:15 INFO - ##### 05:56:15 INFO - Running pre-action listener: _resource_record_pre_action 05:56:15 INFO - Running main action method: populate_webroot 05:56:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:56:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:56:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:56:15 INFO - '--tppagecycles', 05:56:15 INFO - '7'], 05:56:15 INFO - 'tests': ['tsvgm']}}, 05:56:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:56:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:56:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:56:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:56:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['damp', 'tps']}, 05:56:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'tests': ['damp', 'tps']}, 05:56:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['dromaeo_dom']}, 05:56:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:56:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'other-e10s': {'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['a11yr', 05:56:15 INFO - 'ts_paint', 05:56:15 INFO - 'tpaint', 05:56:15 INFO - 'sessionrestore', 05:56:15 INFO - 'sessionrestore_no_auto_restore', 05:56:15 INFO - 'tabpaint']}, 05:56:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['tsvgx', 05:56:15 INFO - 'tsvgr_opacity', 05:56:15 INFO - 'tart', 05:56:15 INFO - 'tscrollx', 05:56:15 INFO - 'cart']}, 05:56:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:56:15 INFO - 'tsvgr_opacity', 05:56:15 INFO - 'tart', 05:56:15 INFO - 'tscrollx', 05:56:15 INFO - 'cart']}, 05:56:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'talos_options': ['--disable-e10s'], 05:56:15 INFO - 'tests': ['tp5o']}, 05:56:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'tests': ['tp5o']}, 05:56:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'talos_options': ['--disable-e10s', 05:56:15 INFO - '--xperf_path', 05:56:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:15 INFO - 'tests': ['tp5n']}, 05:56:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:15 INFO - 'talos_options': ['--xperf_path', 05:56:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:15 INFO - 'tests': ['tp5n']}}, 05:56:15 INFO - 'talos.zip': {'path': '', 05:56:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:56:15 INFO - Running post-action listener: _resource_record_post_action 05:56:15 INFO - ##### 05:56:15 INFO - ##### Running create-virtualenv step. 05:56:15 INFO - ##### 05:56:15 INFO - Running pre-action listener: _resource_record_pre_action 05:56:15 INFO - Running main action method: create_virtualenv 05:56:15 INFO - Creating virtualenv /builds/slave/test/build/venv 05:56:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:56:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:56:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:56:16 INFO - Using real prefix '/tools/python27' 05:56:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:56:16 INFO - Installing distribute.............................................................................................................................................................................................done. 05:56:19 INFO - Installing pip.................done. 05:56:19 INFO - Return code: 0 05:56:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:19 INFO - 'HOME': '/Users/cltbld', 05:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:19 INFO - 'LOGNAME': 'cltbld', 05:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:19 INFO - 'MOZ_NO_REMOTE': '1', 05:56:19 INFO - 'NO_EM_RESTART': '1', 05:56:19 INFO - 'PAGER': '/bin/cat', 05:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:19 INFO - 'PWD': '/builds/slave/test', 05:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:19 INFO - 'SHELL': '/bin/bash', 05:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:19 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:19 INFO - 'USER': 'cltbld', 05:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:19 INFO - 'XPC_FLAGS': '0x0', 05:56:19 INFO - 'XPC_SERVICE_NAME': '0', 05:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:56:19 INFO - Cleaning up... 05:56:19 INFO - Return code: 0 05:56:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:19 INFO - 'HOME': '/Users/cltbld', 05:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:19 INFO - 'LOGNAME': 'cltbld', 05:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:19 INFO - 'MOZ_NO_REMOTE': '1', 05:56:19 INFO - 'NO_EM_RESTART': '1', 05:56:19 INFO - 'PAGER': '/bin/cat', 05:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:19 INFO - 'PWD': '/builds/slave/test', 05:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:19 INFO - 'SHELL': '/bin/bash', 05:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:19 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:19 INFO - 'USER': 'cltbld', 05:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:19 INFO - 'XPC_FLAGS': '0x0', 05:56:19 INFO - 'XPC_SERVICE_NAME': '0', 05:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:20 INFO - Downloading/unpacking psutil>=0.7.1 05:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:56:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:23 INFO - Installing collected packages: psutil 05:56:23 INFO - Running setup.py install for psutil 05:56:23 INFO - building 'psutil._psutil_osx' extension 05:56:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:56:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:56:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:56:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:56:24 INFO - building 'psutil._psutil_posix' extension 05:56:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:56:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:56:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:56:24 INFO - ^ 05:56:24 INFO - 1 warning generated. 05:56:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:56:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:24 INFO - Successfully installed psutil 05:56:24 INFO - Cleaning up... 05:56:24 INFO - Return code: 0 05:56:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:24 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:24 INFO - 'HOME': '/Users/cltbld', 05:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:24 INFO - 'LOGNAME': 'cltbld', 05:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:24 INFO - 'MOZ_NO_REMOTE': '1', 05:56:24 INFO - 'NO_EM_RESTART': '1', 05:56:24 INFO - 'PAGER': '/bin/cat', 05:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:24 INFO - 'PWD': '/builds/slave/test', 05:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:24 INFO - 'SHELL': '/bin/bash', 05:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:24 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:24 INFO - 'USER': 'cltbld', 05:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:24 INFO - 'XPC_FLAGS': '0x0', 05:56:24 INFO - 'XPC_SERVICE_NAME': '0', 05:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:56:27 INFO - Installing collected packages: mozsystemmonitor 05:56:27 INFO - Running setup.py install for mozsystemmonitor 05:56:27 INFO - Successfully installed mozsystemmonitor 05:56:27 INFO - Cleaning up... 05:56:27 INFO - Return code: 0 05:56:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:56: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')]} 05:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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', '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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:56: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 blobuploader==1.2.4 05:56:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:27 INFO - 'HOME': '/Users/cltbld', 05:56:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:27 INFO - 'LOGNAME': 'cltbld', 05:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:27 INFO - 'MOZ_NO_REMOTE': '1', 05:56:27 INFO - 'NO_EM_RESTART': '1', 05:56:27 INFO - 'PAGER': '/bin/cat', 05:56:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:27 INFO - 'PWD': '/builds/slave/test', 05:56:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:27 INFO - 'SHELL': '/bin/bash', 05:56:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:27 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:27 INFO - 'USER': 'cltbld', 05:56:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:27 INFO - 'XPC_FLAGS': '0x0', 05:56:27 INFO - 'XPC_SERVICE_NAME': '0', 05:56:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:27 INFO - Downloading/unpacking blobuploader==1.2.4 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56:29 INFO - Downloading blobuploader-1.2.4.tar.gz 05:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:56:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:56:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:31 INFO - Downloading docopt-0.6.1.tar.gz 05:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:56:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:56:31 INFO - Running setup.py install for blobuploader 05:56:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:56:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:56:31 INFO - Running setup.py install for requests 05:56:31 INFO - Running setup.py install for docopt 05:56:32 INFO - Successfully installed blobuploader requests docopt 05:56:32 INFO - Cleaning up... 05:56:32 INFO - Return code: 0 05:56:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:32 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:32 INFO - 'HOME': '/Users/cltbld', 05:56:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:32 INFO - 'LOGNAME': 'cltbld', 05:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:32 INFO - 'MOZ_NO_REMOTE': '1', 05:56:32 INFO - 'NO_EM_RESTART': '1', 05:56:32 INFO - 'PAGER': '/bin/cat', 05:56:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:32 INFO - 'PWD': '/builds/slave/test', 05:56:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:32 INFO - 'SHELL': '/bin/bash', 05:56:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:32 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:32 INFO - 'USER': 'cltbld', 05:56:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:32 INFO - 'XPC_FLAGS': '0x0', 05:56:32 INFO - 'XPC_SERVICE_NAME': '0', 05:56:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-2wBjZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-iiKBIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-7FN1tU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-fxqzwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-mQRMH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:56:32 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-wY3u7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-vhXwqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-PQr_Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-LTONm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-JkWuUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-yhwdA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-fps2ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-AayPj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Dd1wfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-SNqBWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:56:33 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-lZpHBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:56:34 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-46bTYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:56:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:56:34 INFO - Running setup.py install for manifestparser 05:56:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:56:34 INFO - Running setup.py install for mozcrash 05:56:34 INFO - Running setup.py install for mozdebug 05:56:34 INFO - Running setup.py install for mozdevice 05:56:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:56:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:56:34 INFO - Running setup.py install for mozfile 05:56:34 INFO - Running setup.py install for mozhttpd 05:56:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Running setup.py install for mozinfo 05:56:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Running setup.py install for mozInstall 05:56:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Running setup.py install for mozleak 05:56:35 INFO - Running setup.py install for mozlog 05:56:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Running setup.py install for moznetwork 05:56:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:56:35 INFO - Running setup.py install for mozprocess 05:56:35 INFO - Running setup.py install for mozprofile 05:56:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:56:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:56:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:56:36 INFO - Running setup.py install for mozrunner 05:56:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:56:36 INFO - Running setup.py install for mozscreenshot 05:56:36 INFO - Running setup.py install for moztest 05:56:36 INFO - Running setup.py install for mozversion 05:56:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:56:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:56:36 INFO - Cleaning up... 05:56:36 INFO - Return code: 0 05:56:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:36 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:36 INFO - 'HOME': '/Users/cltbld', 05:56:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:36 INFO - 'LOGNAME': 'cltbld', 05:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:36 INFO - 'MOZ_NO_REMOTE': '1', 05:56:36 INFO - 'NO_EM_RESTART': '1', 05:56:36 INFO - 'PAGER': '/bin/cat', 05:56:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:36 INFO - 'PWD': '/builds/slave/test', 05:56:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:36 INFO - 'SHELL': '/bin/bash', 05:56:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:36 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:36 INFO - 'USER': 'cltbld', 05:56:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:36 INFO - 'XPC_FLAGS': '0x0', 05:56:36 INFO - 'XPC_SERVICE_NAME': '0', 05:56:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-H7AMGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-OsLg53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-mmj3s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Rg8QyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-cw15MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qwwnqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-PsscDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-yTOdMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:56:37 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-DHPkr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-D9N87H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-HDVVe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-yAVeTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-p6CuZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Nfx5Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-B3tjgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-r2yHbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:56:38 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-FiJy7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:41 INFO - Downloading blessings-1.6.tar.gz 05:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:56:41 INFO - Installing collected packages: blessings 05:56:41 INFO - Running setup.py install for blessings 05:56:41 INFO - Successfully installed blessings 05:56:41 INFO - Cleaning up... 05:56:41 INFO - Return code: 0 05:56:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:56:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:56:41 INFO - Reading from file tmpfile_stdout 05:56:41 INFO - Current package versions: 05:56:41 INFO - blessings == 1.6 05:56:41 INFO - blobuploader == 1.2.4 05:56:41 INFO - docopt == 0.6.1 05:56:41 INFO - manifestparser == 1.1 05:56:41 INFO - mozInstall == 1.12 05:56:41 INFO - mozcrash == 0.17 05:56:41 INFO - mozdebug == 0.1 05:56:41 INFO - mozdevice == 0.48 05:56:41 INFO - mozfile == 1.2 05:56:41 INFO - mozhttpd == 0.7 05:56:41 INFO - mozinfo == 0.9 05:56:41 INFO - mozleak == 0.1 05:56:41 INFO - mozlog == 3.1 05:56:41 INFO - moznetwork == 0.27 05:56:41 INFO - mozprocess == 0.22 05:56:41 INFO - mozprofile == 0.28 05:56:41 INFO - mozrunner == 6.11 05:56:41 INFO - mozscreenshot == 0.1 05:56:41 INFO - mozsystemmonitor == 0.0 05:56:41 INFO - moztest == 0.7 05:56:41 INFO - mozversion == 1.4 05:56:41 INFO - psutil == 3.1.1 05:56:41 INFO - requests == 1.2.3 05:56:41 INFO - wsgiref == 0.1.2 05:56:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:42 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:42 INFO - 'HOME': '/Users/cltbld', 05:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:42 INFO - 'LOGNAME': 'cltbld', 05:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:42 INFO - 'MOZ_NO_REMOTE': '1', 05:56:42 INFO - 'NO_EM_RESTART': '1', 05:56:42 INFO - 'PAGER': '/bin/cat', 05:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:42 INFO - 'PWD': '/builds/slave/test', 05:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:42 INFO - 'SHELL': '/bin/bash', 05:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:42 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:42 INFO - 'USER': 'cltbld', 05:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:42 INFO - 'XPC_FLAGS': '0x0', 05:56:42 INFO - 'XPC_SERVICE_NAME': '0', 05:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:56:42 INFO - Cleaning up... 05:56:42 INFO - Return code: 0 05:56:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:42 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 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:42 INFO - 'HOME': '/Users/cltbld', 05:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:42 INFO - 'LOGNAME': 'cltbld', 05:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:42 INFO - 'MOZ_NO_REMOTE': '1', 05:56:42 INFO - 'NO_EM_RESTART': '1', 05:56:42 INFO - 'PAGER': '/bin/cat', 05:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:42 INFO - 'PWD': '/builds/slave/test', 05:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:42 INFO - 'SHELL': '/bin/bash', 05:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:42 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:42 INFO - 'USER': 'cltbld', 05:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:42 INFO - 'XPC_FLAGS': '0x0', 05:56:42 INFO - 'XPC_SERVICE_NAME': '0', 05:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:42 INFO - Downloading/unpacking jsonschema 05:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:56:44 INFO - Downloading/unpacking functools32 (from jsonschema) 05:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:45 INFO - Downloading functools32-3.2.3-2.tar.gz 05:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:56:45 INFO - warning: no files found matching '*.txt' 05:56:45 INFO - no previously-included directories found matching 'build' 05:56:45 INFO - no previously-included directories found matching 'dist' 05:56:45 INFO - no previously-included directories found matching '.git*' 05:56:45 INFO - Installing collected packages: jsonschema, functools32 05:56:45 INFO - Running setup.py install for functools32 05:56:45 INFO - warning: no files found matching '*.txt' 05:56:45 INFO - no previously-included directories found matching 'build' 05:56:45 INFO - no previously-included directories found matching 'dist' 05:56:45 INFO - no previously-included directories found matching '.git*' 05:56:45 INFO - Successfully installed jsonschema functools32 05:56:45 INFO - Cleaning up... 05:56:45 INFO - Return code: 0 05:56:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105106420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb20bc91de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, '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.FPunYZEXNO/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.1QYP9pvPk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:45 INFO - 'HOME': '/Users/cltbld', 05:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:45 INFO - 'LOGNAME': 'cltbld', 05:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:45 INFO - 'MOZ_NO_REMOTE': '1', 05:56:45 INFO - 'NO_EM_RESTART': '1', 05:56:45 INFO - 'PAGER': '/bin/cat', 05:56:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:45 INFO - 'PWD': '/builds/slave/test', 05:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:45 INFO - 'SHELL': '/bin/bash', 05:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:56:45 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:45 INFO - 'USER': 'cltbld', 05:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:45 INFO - 'XPC_FLAGS': '0x0', 05:56:45 INFO - 'XPC_SERVICE_NAME': '0', 05:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:46 INFO - Downloading/unpacking flake8 05:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:56:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:56:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:56:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:49 INFO - no previously-included directories found matching 'docs/_build' 05:56:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:56:49 INFO - Running setup.py install for pep8 05:56:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:49 INFO - no previously-included directories found matching 'docs/_build' 05:56:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:56:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:56:49 INFO - Cleaning up... 05:56:50 INFO - Return code: 0 05:56:50 INFO - Running post-action listener: _resource_record_post_action 05:56:50 INFO - Running post-action listener: _start_resource_monitoring 05:56:50 INFO - Starting resource monitoring. 05:56:50 INFO - ##### 05:56:50 INFO - ##### Running install step. 05:56:50 INFO - ##### 05:56:50 INFO - Running pre-action listener: _resource_record_pre_action 05:56:50 INFO - Running main action method: install 05:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:56:50 INFO - Reading from file tmpfile_stdout 05:56:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:56:50 INFO - Reading from file tmpfile_stdout 05:56:50 INFO - Output received: 05:56:50 INFO - Usage: mozinstall [options] installer 05:56:50 INFO - Options: 05:56:50 INFO - -h, --help show this help message and exit 05:56:50 INFO - -d DEST, --destination=DEST 05:56:50 INFO - Directory to install application into. [default: 05:56:50 INFO - "/builds/slave/test"] 05:56:50 INFO - --app=APP Application being installed. [default: firefox] 05:56:50 INFO - mkdir: /builds/slave/test/build/application 05:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:57:23 INFO - Reading from file tmpfile_stdout 05:57:23 INFO - Output received: 05:57:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:57:23 INFO - Running post-action listener: _resource_record_post_action 05:57:23 INFO - ##### 05:57:23 INFO - ##### Running run-tests step. 05:57:23 INFO - ##### 05:57:23 INFO - Running pre-action listener: _resource_record_pre_action 05:57:23 INFO - Running main action method: run_tests 05:57:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:57:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:57:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:57:23 INFO - 2016-04-22 05:57:23.801 screenresolution[1643:7681] starting screenresolution argv=screenresolution get 05:57:23 INFO - 2016-04-22 05:57:23.804 screenresolution[1643:7681] Display 0: 1600x1200x32@60 05:57:23 INFO - 2016-04-22 05:57:23.809 screenresolution[1644:7683] starting screenresolution argv=screenresolution list 05:57:23 INFO - Available Modes on Display 0 05:57:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:57:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:57:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:57:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:57:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:57:24 INFO - Intel Iris: 05:57:24 INFO - Chipset Model: Intel Iris 05:57:24 INFO - Type: GPU 05:57:24 INFO - Bus: Built-In 05:57:24 INFO - VRAM (Dynamic, Max): 1536 MB 05:57:24 INFO - Vendor: Intel (0x8086) 05:57:24 INFO - Device ID: 0x0a2e 05:57:24 INFO - Revision ID: 0x0009 05:57:24 INFO - Displays: 05:57:24 INFO - Display: 05:57:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:57:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:57:24 INFO - Display Serial Number: 1600x1200 60 05:57:24 INFO - Main Display: Yes 05:57:24 INFO - Mirror: Off 05:57:24 INFO - Online: Yes 05:57:24 INFO - Rotation: Supported 05:57:24 INFO - Return code: 0 05:57:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:57:24 INFO - Python 2.7.3 05:57:24 INFO - Return code: 0 05:57:24 INFO - grabbing minidump binary from tooltool 05:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f95e00>, '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 0x104dbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f90b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:57:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:57:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:57:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:57:24 INFO - Return code: 0 05:57:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:57:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:57:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:57:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:57:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:57:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:57:25 INFO - Return code: 0 05:57:25 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/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 05:57:25 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/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0091 --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 05:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FPunYZEXNO/Render', 05:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:25 INFO - 'HOME': '/Users/cltbld', 05:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:25 INFO - 'LOGNAME': 'cltbld', 05:57:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:57:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:25 INFO - 'MOZ_NO_REMOTE': '1', 05:57:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:57:25 INFO - 'NO_EM_RESTART': '1', 05:57:25 INFO - 'PAGER': '/bin/cat', 05:57:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:25 INFO - 'PWD': '/builds/slave/test', 05:57:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:25 INFO - 'SHELL': '/bin/bash', 05:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1QYP9pvPk1/Listeners', 05:57:25 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:25 INFO - 'USER': 'cltbld', 05:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:25 INFO - 'XPC_FLAGS': '0x0', 05:57:25 INFO - 'XPC_SERVICE_NAME': '0', 05:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:25 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/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 05:57:25 INFO - mozversion application_buildid: 20160419211331 05:57:25 INFO - mozversion application_changeset: bf3a35b355f0b48ac1020c47198053af9944a493 05:57:25 INFO - mozversion application_display_name: Nightly 05:57:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:25 INFO - mozversion application_name: Firefox 05:57:25 INFO - mozversion application_remotingname: firefox 05:57:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:57:25 INFO - mozversion application_vendor: Mozilla 05:57:25 INFO - mozversion application_version: 48.0a1 05:57:25 INFO - mozversion platform_buildid: 20160419211331 05:57:25 INFO - mozversion platform_changeset: bf3a35b355f0b48ac1020c47198053af9944a493 05:57:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:57:25 INFO - mozversion platform_version: 48.0a1 05:57:25 INFO - using testdate: 1461329845 05:57:25 INFO - actual date: 1461329845 05:57:25 INFO - starting webserver on 'localhost:49284' 05:57:25 INFO - SUITE-START | Running 2 tests 05:57:25 INFO - TEST-START | dromaeo_css 05:57:25 INFO - operating with platform_type : mac_ 05:57:25 INFO - Initialising browser for dromaeo_css test... 05:57:25 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpGiHLkA/profile http://localhost:49284/getInfo.html) 05:57:27 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:57:27 INFO - PROCESS | 1662 | colorDepth:24 05:57:27 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:57:27 INFO - PROCESS | 1662 | __metrics 05:57:27 INFO - PROCESS | 1662 | 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" 05:57:27 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:57:28 INFO - TEST-INFO | 1662: exit 0 05:57:28 INFO - Browser initialized. 05:57:28 INFO - Running cycle 1/1 for dromaeo_css test... 05:57:28 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpGiHLkA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:40 INFO - PROCESS | 1664 | RSS: Main: 237568000 05:57:40 INFO - PROCESS | 1664 | 05:58:31 INFO - PROCESS | 1664 | 1461329911927 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] 06:00:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 06:00:43 INFO - PROCESS | 1664 | RSS: Main: 413237248 06:00:43 INFO - PROCESS | 1664 | 06:03:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 06:03:47 INFO - PROCESS | 1664 | RSS: Main: 371240960 06:03:47 INFO - PROCESS | 1664 | 06:06:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 06:06:50 INFO - PROCESS | 1664 | RSS: Main: 374939648 06:06:50 INFO - PROCESS | 1664 | 06:09:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 06:09:53 INFO - PROCESS | 1664 | RSS: Main: 376295424 06:09:53 INFO - PROCESS | 1664 | 06:12:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 06:12:57 INFO - PROCESS | 1664 | RSS: Main: 346427392 06:12:57 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 06:16:00 INFO - PROCESS | 1664 | RSS: Main: 384479232 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | __start_tp_report 06:16:00 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 06:16:00 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 06:16:00 INFO - PROCESS | 1664 | |i|pagename|runs| 06:16:00 INFO - PROCESS | 1664 | |0;dojo.html;3141.1361875484245;3295.6045274567073;3326.3846188455136;3344.0803778960767;3358.697717205451;5376.354837419511;5381.569474442045;5429;5548.889022219553;5662.801801936933;5612.7754889804355;5716.971415142919;5721.799737009205;5772.134179873021;5849.093390524465;5056.14045612245;5169.224616307546;5171.715555644438;5201.115810312256;5204.193350030732;5065.746712664352;5184.429712731615;5200;5259.184826351921;5261.684298942076;5198;5218.1650935850275;5220.138677118271;5233.659812112201;5263.473652634725;4274.465691788527;5008.599312055063;5065.316182315377;5118.897622047538;5149.30484384607;5077.136886729228;5105.259737338065;5109.821156259513;5111.207762796773;5141.74291285438;5495.6427832191175;5532.889342213133;5564.888702225933;5587.525060369873;5610.410906854803;6081.817545473643;6137.140800287963;6146.569740118193;6150.108234306045;6163.383661633846;5854.707264636793;6084.168137413318;6171.25944886612;6203;6211.720472578745;5845.386234445407;5854.590178687494;5858.179854820329;5867.383924687846;5874.324452687995;6098.395027831184;6121.605402471149;6233.625982441068;6236.314005459396;6241.750329986749;6108.190302030417;6130.29501607312;6134.693265336716;6136.079588061737;6139.754409823557;6148.44663980235;6188.783392581238;6197.938020619827;6205.069239614004;6210.4410603045035;6023.0965355196195;6049.758009679564;6055.969720151371;6101.725422356005;6141.297828955246;6145.66198859067;6146.7399183167345;6221.06683997404;6221.160143380585;6224.004159334485;4894.706317620954;4904;4909.214525675946;4917.778699958439;4941.579965702884;4328.394024836525;4339.63113135387;4435.445569303837;4449.243628583149;4480.260756975128;5655.745491452894;5664.943350566442;5666.603337766399;5675.57433192504;5699.857503562361;6212.161358216673;6237.282712488031;6245.500359971192;6254.21822272216;6258.405451482102;5541.861453463615;5578.972105139448;5607.383187849333;5647.717614119278;5700.116481945305;5342.626016178908;5358.089124848785;5364.7317634118135;5383.192521121863;5385.649932754359;6233.688315584293;6237.937620623827;6243.906341404791;6251.156093927352;6266.780662676784;6103.85289575482;6113.263474096328;6221.06683997404;6247.094171345038;6309.242890853127;6229.750809967551;6232.594881332699;6251.281102673069;6264.906026409516;6272.529560282906;6150.138980542638;6153.969230153821;6154.476869466145;6158.04550294705;6164.106204600397;6085.361037091042;6142.1304578115505;6142.590862071594;6189.102580125947;6214.5960512566535;4724.527547245248;5988.790392336247;6000.249968753906;6009.158717779602;6026.337102918587;5961.910571341346;5985.700714964321;6078.3921607838865;6093.116498107838;6093.512518998558;5844.415558444121;5884.764609415746;5887.470127688529;5895.41045895407;5914.260717410324;6030.728617212324;6034.517238620988;6035.78874739382;6036.094585812163;6105.786297479567;5732.713364331683;5813.447722466359;5834.562407819346;5906.763729450774;5927.377625349276 06:16:00 INFO - PROCESS | 1664 | |1;ext.html;502.8893643398451;523.5706720489198;574.8246784730658;591.9758817246167;608.3703367015138;26195;38132.61867381325;39107.41338879919;39304;39385.40921886174;43240.35139472911;43742;43818;43884.12231755363;43908;8455.19675630817;9430.198433133175;9697.515124243759;9715.271354648394;9721.659741909034;8582.399232053758;8706.216440520351;8711.346649001318;11356.716082097932;11699.35653539055;11599.362035088066;11795.764084718301;11796;11801.29192248468;11812.173147879652;11576.826347604792;11704.180707350532;11711.941440292743;11716.297022178696;11718.06255499558;8483.388156250354;8798.472091674455;8810.955945220298;8813.647454101829;9030.774230644287;38374.61625383766;38860.80569597162;38944.22111557781;38990.830275091794;38998.025049373995;3223.6453990061073;3258.3971965186515;3315.6850099240773;3331.983340083284;3461.3077384522944;26609;27426.451470970467;27488;27680.031198907942;28066.859665701744;2616.2020583721956;2671.479061582992;2712.2676877243034;2783.262435454606;2790.972090279112;481591;484950.5752471215;485588;486868;486941;59513.404865951656;59811.700941495015;59931.40068599345;60024.39975600188;60073;61750.0737488938;61859.38140618536;62001.06998395028;62019;62114.68942655348;16015.279312430968;16686.666266674598;16687.415940442028;16706.498805035626;16711.331546737994;11743.295402275891;12958.157719748186;13015;13045.28250946188;13084.672883177805;14776.48282310114;15216.847831521765;15291.311890964933;15359.539213823604;15418.152001640017;5842.5618078644275;6527.694461107797;6790.864182716318;6873.934540146285;6944.618046007417;829.4484168028193;1012.7569383347943;1019.938803671782;1090.0407641275626;1127.8646562412564;28345.433091338058;28755.56866647002;29068;29348.559771603443;30220.546691799642;1101.9063379612662;1139.1627154041773;1164.9009834164021;1171.472837223253;1183.1008433590532;15164.696706065817;15236;15273.159976201416;15281.930264881352;15678.372865085268;629934;632093;632609.8369508123;632912;633374;58433.70783146057;60490.39509604935;60512.697436513416;60517.69741151354;60576.69711651414;1207.5652765004415;1313.7175507265792;1350.696093378982;1481.3259966715118;1490.6795039066653;24410.87794561016;31659;31839;32280.35439291248;32717;52286.73856630692;52694;52937.205941910135;52971.47028529665;52981.47018529919;431853;432626.67373327125;432831;432840;432846.2080723048;33035.33929321352;33802.661973380935;33866;36696.449553256185;37308.25383492369;33695.663043369255;38078.428823568225;44149;44331.55668443274;48764.51235487741;36131.27737445289;36862.8156859214;36959;37055.814720926224;37338.43992340171;35909.64090359063;35930.64069359273;36351.27297454089;36734;36763.63236367602;30358;36356.45465318075;36705.816470917474;37283.627163728015;37647.87056388313;31519;32088.358232834744;32150.83924580362;32254.032379028667;32518.187045324055;23238.72187029662;23672.28983130509;23713.762862371157;23745.881270593538;24162.912668929588 06:16:00 INFO - PROCESS | 1664 | |2;jquery.html;398.82252397683027;400.33544316434717;402.1855742122204;402.7724335750301;411.9237940980919;13659;19158.0420978951;20173.495662608424;20213.191472341106;20858;15524.243845486015;16827.915860420682;19839.504012399662;20156.488263380234;20574.382768516967;5813.883722325561;5985.940140598582;6031.939680603182;6040.214772079624;6043.305019922699;5048.469910659383;5527.198556209347;5922.31893332268;6082.787102451415;6108.81673549792;3814.4659747635355;4057.16828050249;5657.1514272858985;6022.187004754368;6022.939770602304;5943.920403152863;5959.135925290809;5997.070454079603;6005.4895333896675;6009.849753756147;5597.972010139964;5769.105788602773;5782.884342313172;5783.739731712083;5785.53715702747;10935.890641093647;13272.867271327259;13445.940212535548;13727.656808579766;13743.725125497534;2113.689512502243;2195.8133558647532;2211.1045026764186;2211.6461366181334;2321.872297023663;9416.387934784218;10040.748981275454;10095.545700443425;10226.284160108795;13742.725145497034;1674.4344038324084;1698.3546252424123;1715.3738885306898;1743.947681569555;1789.8926064436046;117768.41115794366;123656;123849;126396.73603264033;126815;11521.942390288163;11566.47950842214;11576.47905844239;11604.477798499087;11760.823587646191;14906.881983851255;14992.47526336573;14996.700065998619;15010.849891500946;15016.023958442665;8645.265152461985;8818;8841.98317193518;8926.776830579287;8927.821443571092;6966.3730264276455;8669.48885701292;8713.956430217808;8817.735467935972;8817.735467935972;4542.545745425431;4595.931061034087;4622.745748983838;4657.371254880548;4690.648201384842;1499.5801175670829;1873.157079314314;1937.602791427754;1948.7758907725793;1952;849.9235068843834;863.5897948474425;923.6028507741602;929.6839074714629;989.3272574649308;16660.16699165037;17500.824991750174;19401.902990484956;20389.694154587694;21081.894590527256;957.3537861943161;970.2286682087757;971.2327261463506;971.9708408747749;988.8071825993862;10830.461922596754;11174.553017879194;11243.988041076342;11271.943640281746;11383.975442210076;506525;515736.421317891;515764;587750;593028;134656;136296;143917;144093;145063;1028.5320179318392;1041.6041904076553;1059.4702648675661;1104.1222228328497;1111.6665000499818;18473.63052738911;18760.530986724894;18809.341673041377;18865.339713109974;18867.528311792314;51775;52208;53109.20336194883;53159.99999999845;53770.73114634402;76005;76204.61897690476;76832;77975.61012194902;78948.21051789638;7972.402069844668;8102.797430064295;8159.632816523152;8315.875261871197;8484;9757.853632195665;12016.579419720269;12899.161554499304;12967.546135885199;13373.933130334286;9281.582328795355;10975;11363.54545818158;11506.597269095542;11649.242799218024;11118.555257789598;11263.098952083978;11317.886821131684;11524.481398336928;11570.826437603275;9287;11445.141614378788;11620.709482263012;11647.30116193031;11848.940755296513;7961.164077771982;8105.878411823709;8124.83750325002;8386.496810191407;8386.66453341884;8189.79525511867;9353.438793672402;10339.431331276852;10542.209334299805;10658.987396197348 06:16:00 INFO - PROCESS | 1664 | |3;mootools.html;1424.0743516714128;1608.7426011838104;1627.9999999999998;1885.5097674604608;2008.7087372331011;7781.533108013523;8484.151584841513;8718.912810871889;8733.432326898763;8743.781405464866;8330.500169989806;8572.142785721424;8574.528400937947;8593.011803642583;8599.570021498916;2115.111653105699;2350.189184731265;2409.7831195192384;2433.707955045388;2438.3904023994;2442.8412153210074;2465.7287698353257;2467.864267465289;2468.333549941515;2471.88876500557;2390.5640823175295;2416.359664688859;2426.963595546068;2434.2453839309783;2443.6090225563826;1753.9123043847885;2326.7091613548305;2369.30105618842;2379.6549500322444;2411.288669842383;2400.97599024011;2414.1429792423614;2420.552197843387;2424.5029768897334;2424.733279339271;8412.242898139197;8527.18991695788;8534.487930724177;8537.701180458655;8539.57302134897;5053.292539044537;5260.105782017066;5267.157254839246;5268.157094864803;5282.603804714623;3364.814935178564;3436.1924947637535;3544.2734239480847;3550.343186510504;3551.449525323579;897.2956229359986;1052.7210289273348;1055.1347894726327;1055.5672174408533;1056.978860422795;9585.856212156823;11621.47703353351;11635.825462618068;11637.243579167327;11652.184347095706;4014.4781178446615;4539.273716205457;4666.95333046672;4806.8077276909235;4847.709137451764;9930.80138397228;9947.55236014381;9979.201663866874;9982.35114717541;9984.800303994025;5382.973085134628;5384.865378365494;5403.324584426947;5423.403425623178;5425.077736784756;3618.8552457901915;4289.357985728603;4381.561843815657;4512.774361281989;4914.72968986702;3712.1647629283193;3835.5013848199555;4064.085580744368;4069.1658210066867;4080.5511393746665;1321.4119716725966;1333.2467156056796;1344.6772774534238;1359.395069194199;1362.8160198373291;727.6398182899393;820.2330820682681;889.106448019736;902.4449963272672;933.1601558597316;5168.664036837594;5224.634275600672;5675.574331925122;5833.88332233353;5928.970355148197;948.4025064209504;955.8016561907724;956.2493442647489;1009.4750729620556;1023.8413045977885;3648.3797754381817;3756.2111956488898;3824.521934758155;3897.1621101462756;3920.255151521259;10087.394756314645;10518.947405263078;10524.473776311153;10624.840627390595;10645.467726613639;10290.588376464857;10555.84166237507;10644.361338319726;10757;10780.245382823126;8057.717979870794;8106.351491880635;8271.13153118926;8271.958640206882;8298.792530186794;4951.405831300267;5584.609077364546;5626.803061892814;5729.942700573108;5789.739461724317;9451.952740236255;9787.119159275415;9798.265130115127;9896.109350158233;9920.355176913477;8395.916040839758;9692;9835.311528192959;9864.358816676893;10006.799864002825;7178.712851485882;7376.594287314249;7449.962750186214;7450.143233527997;7452.478326517092;7546.773596792105;7799.727009554866;8089.352851771727;8325.167483251626;8337.791555211168;7120.252373500708;7196.856062878818;7219;7341.119065712149;7448.329650331496;7197.136343638587;7208.27917208275;7276.381507571916;7349.963250183715;7354.411647068328;6789.830254243683;6823.727050918106;6835.93164068353;6877.793666190022;6902.551334163263;4308.676849236257;4798.73606951621;4803.927941080957;4828.251620998751;4907.312976183266;4811.591014763784;4863.319135320987;4894.094592500399;4903.436104847988;5064 06:16:00 INFO - PROCESS | 1664 | |4;prototype.html;321.099316417449;324.5132301547678;330.55210190192287;336.2216468874555;339.6213222257184;5070.467600901908;5764.971175144124;5838.5260769841925;5843.941560584398;5848.853778655536;5221.216817477381;5327.360716714;5715.970535616119;5749.511291540213;5797.333306669734;3618.2401695643816;3841.442990766337;4092.3656833190753;4109.178164367131;4418.5802348776815;4310.892227694301;4377.934330985022;4380.167768124046;4383.780810959455;4405.603495685387;3632.309861126391;3668.119651283686;4350.086481838802;4383.430154079965;4391.27543955247;3495.580530336363;4099.483191219253;4353.477582690066;4362.236608593484;4373.518912919576;3752.249550089993;3758.8120593970193;3770.7926064066464;3779.546454425459;3793.3740932746064;5543.667379957215;5546.112621980464;5562.527185189264;5568.303962004749;5593.692346920918;4885.193942999401;5057.544820966131;5058.291839142523;5060.7722652480725;5120.436751957282;5387.4612538746205;5703.77184912603;6067.939320606782;6082.817515474543;6140.416660417253;3730.0674831292176;3883.922321553553;3943.9802800985813;4230.725002874803;4282.5075116361395;5951.49412299955;6440.516961227973;6443.613383196976;6448.16173897395;6492.9350706492805;4291.227579035741;4310.52584215736;4324.978375108104;4337.240982827992;4352.238358287287;3831.4635950966886;4379.299312110047;4391.451068616423;4403.801828917706;4421.867343979686;4172.996961458517;4175.645070169009;4234.7670878102;4974.179260422062;4983.8504844854715;5491.341039075336;6123.632582045091;6142.754289828356;6209.441150296495;6220.968895155495;1424.6936908564708;1448.217962300346;1467.530390275123;1469.2727100085312;1471.977920331196;895.4314010603314;942.9580313753204;960.6109525642127;965.5558443116105;967.1247520993512;5579.2746942897975;5637.9718101409235;5643.814798892278;5649.971750141223;5656.660600363992;5384.326959130109;5591.9720401397735;5813.941860581424;6329.461995730415;6459.773907913294;5562.666240025612;5782.421757824184;5826.970865145647;5860.648361098348;5861.267341582302;3242.1681749811282;3393.7963722176746;3405.4210784166753;3409.5567576214935;3413.7269018478464;5828;6454.290028096905;6769.492288078413;6781.830454238585;6783.389494945479;6327.203677793287;6555.573887697285;6603.636799975951;6644.634545100066;6661.600303981777;5077.644564880497;5533.059379905426;6292.087647291209;6739.157605299338;6775.93224067753;5444.727763611724;5871.766928944799;6433.131527243762;6436;6468.514861385321;5810.389909059657;6309.652969086561;6361.968190159019;6362.618242905441;6375.77684781049;5239.47605239473;5859.150423188699;6284.937150628435;6772.5936443813525;6904.344087311697;4162.313218318944;4176.749395036308;4187.560306167808;4219.780219780219;4229.746215227096;5409.269748583891;6460.967695161494;6481.092647029325;6490.772822951174;6558.573327868719;5216.843494695166;5873.089671100986;5880.529557635294;6244.313125556276;6277.5605707600025;6174.567780255339;6217.316095229431;6236.594621349779;6245.6252624842655;6268.874622507616;5748.396418376177;6094.329623741473;6112.266528016667;6124.744427392463;6178.69106544662;6194.690265486798;6216.844578885564;6239.313675495782;6240.881018527932;6277.403646653561;5901.29184497863;6055.333913269625;6098.146259523759;6103.816885493442;6134.895718770664 06:16:00 INFO - PROCESS | 1664 | |5;yui.html;585.1380886151401;746.8917007033976;749.8275396658769;751.4551949836367;763.1948294549248;14784.704305913889;15114;15116.319765610533;15120;16498.25757840897;15744.685106297868;15818.920905395456;15857.682846343067;15893.44372946947;15910.363585456596;727.4762171236728;754.8075240813585;930.9767255818591;939.8308304505186;942.194423767679;936.8266870628955;969.2294625772493;970.2819913264204;970.6651205334184;971.0241207586402;973.5375696544156;993.9900600994006;999.7200783780517;999.7900440907382;1000.304788172217;766.938644908406;793.8809178623195;794.0471434278888;996.0189213624574;1008.4604736465992;600.5285850607253;732.16533152206;752.003595236314;756.5952215564646;758.7799538133935;14994.70010599782;15032.549023529311;15046.398144074225;15051.623709407353;15094.245287735679;12940.352982350845;13099.2140471572;13106;13325.466981320833;13338.93330533341;9775.31572789905;9976.600935962553;10098.29311948164;10507.894921050769;10630.840537391945;3056.862441190152;3494.091536200581;3495.842687079062;3536.929261414757;3655.195856911502;83123;83911.58044209801;84083.5795821023;84100;85578.57210713968;7770;7908.248716371935;9541.045895410542;9558.761030974143;10105.848412273821;22586.435339116324;22613.09547618077;22641;22657.093716251497;22662.886685566467;7409.666565004588;7463.701451941862;7553.509021913558;7587.924120758832;7720.3051725344985;3894.201688653876;4036.4348991141264;4043.3732771420478;4063.3904914262507;4071.043304823364;3568.2863427314123;3671.4125739881492;3671.4859919611276;3672.59601443841;3677.9448308275396;640.2253273538977;640.8494003909078;641.2273210780997;645.2805122288614;650.4503694378243;535.8713908661894;545.3210752613022;591.470633782762;601.8826328865829;620.1968450856152;7692.346150577152;7870.173631768696;8051.677932882736;8110.9861267341585;8343.08226095129;507.3151245818116;558.7038869399225;591.5251125237564;600.3666132230452;622.5362105231541;3727.2731817295908;3729.0795240450648;3735;3772.8490860365278;3953;70019.2998070023;71958;72418.63790681119;94136.52931735298;94925.050749493;62535;63412;67640.6617966907;72321.55356892938;76434.61782691051;49287.63534928352;56559;58955.410445895846;67279.66360168168;69210;8116.634751436318;8131.430799844191;8211.17888211198;8294.336453083859;8403.033651129954;33314;44567.77716111398;44692.32961505515;44902;44986.77506612446;54293.94178756736;55404.61488462821;62634.06048909361;64002.35997639964;64152;10415.270931034755;10608.257421980386;10644.520996555018;10961.780764384508;11136.55453781872;11889;12038;12269.631911042683;12296.016318694661;12338.81491777606;11262.943685281522;11266.887331126913;11306;11432.599859004895;11599.942000290283;11110.277831940897;11113.277636953571;11297.43512824372;11483.942580287045;11700.70748231267;11030.724231894266;11066.833997489883;11401.600943966921;11530;11993.760124797629;9609.759756006155;9629.951850240985;9662.275329350186;9928.354656947275;10183.032611901857;9188.4839001564;9495.715128546155;9608.471534065693;9700.85448718284;9731.9026809731 06:16:00 INFO - PROCESS | 1664 | __end_tp_report 06:16:00 INFO - PROCESS | 1664 | __start_cc_report 06:16:00 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1648 06:16:00 INFO - PROCESS | 1664 | __end_cc_report 06:16:00 INFO - PROCESS | 1664 | __startTimestamp1461330960420__endTimestamp 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | ------- Summary: start ------- 06:16:00 INFO - PROCESS | 1664 | Number of tests: 6 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5677.11 Median:5887.47 stddev:646.63 (11.0%) stddev-sans-first:617.25 06:16:00 INFO - PROCESS | 1664 | Values: 3141.1 3295.6 3326.4 3344.1 3358.7 5376.4 5381.6 5429.0 5548.9 5662.8 5612.8 5717.0 5721.8 5772.1 5849.1 5056.1 5169.2 5171.7 5201.1 5204.2 5065.7 5184.4 5200.0 5259.2 5261.7 5198.0 5218.2 5220.1 5233.7 5263.5 4274.5 5008.6 5065.3 5118.9 5149.3 5077.1 5105.3 5109.8 5111.2 5141.7 5495.6 5532.9 5564.9 5587.5 5610.4 6081.8 6137.1 6146.6 6150.1 6163.4 5854.7 6084.2 6171.3 6203.0 6211.7 5845.4 5854.6 5858.2 5867.4 5874.3 6098.4 6121.6 6233.6 6236.3 6241.8 6108.2 6130.3 6134.7 6136.1 6139.8 6148.4 6188.8 6197.9 6205.1 6210.4 6023.1 6049.8 6056.0 6101.7 6141.3 6145.7 6146.7 6221.1 6221.2 6224.0 4894.7 4904.0 4909.2 4917.8 4941.6 4328.4 4339.6 4435.4 4449.2 4480.3 5655.7 5664.9 5666.6 5675.6 5699.9 6212.2 6237.3 6245.5 6254.2 6258.4 5541.9 5579.0 5607.4 5647.7 5700.1 5342.6 5358.1 5364.7 5383.2 5385.6 6233.7 6237.9 6243.9 6251.2 6266.8 6103.9 6113.3 6221.1 6247.1 6309.2 6229.8 6232.6 6251.3 6264.9 6272.5 6150.1 6154.0 6154.5 6158.0 6164.1 6085.4 6142.1 6142.6 6189.1 6214.6 4724.5 5988.8 6000.2 6009.2 6026.3 5961.9 5985.7 6078.4 6093.1 6093.5 5844.4 5884.8 5887.5 5895.4 5914.3 6030.7 6034.5 6035.8 6036.1 6105.8 5732.7 5813.4 5834.6 5906.8 5927.4 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:65564.25 Median:36353.86 stddev:139698.81 (384.3%) stddev-sans-first:140005.30 06:16:00 INFO - PROCESS | 1664 | Values: 502.9 523.6 574.8 592.0 608.4 26195.0 38132.6 39107.4 39304.0 39385.4 43240.4 43742.0 43818.0 43884.1 43908.0 8455.2 9430.2 9697.5 9715.3 9721.7 8582.4 8706.2 8711.3 11356.7 11699.4 11599.4 11795.8 11796.0 11801.3 11812.2 11576.8 11704.2 11711.9 11716.3 11718.1 8483.4 8798.5 8811.0 8813.6 9030.8 38374.6 38860.8 38944.2 38990.8 38998.0 3223.6 3258.4 3315.7 3332.0 3461.3 26609.0 27426.5 27488.0 27680.0 28066.9 2616.2 2671.5 2712.3 2783.3 2791.0 481591.0 484950.6 485588.0 486868.0 486941.0 59513.4 59811.7 59931.4 60024.4 60073.0 61750.1 61859.4 62001.1 62019.0 62114.7 16015.3 16686.7 16687.4 16706.5 16711.3 11743.3 12958.2 13015.0 13045.3 13084.7 14776.5 15216.8 15291.3 15359.5 15418.2 5842.6 6527.7 6790.9 6873.9 6944.6 829.4 1012.8 1019.9 1090.0 1127.9 28345.4 28755.6 29068.0 29348.6 30220.5 1101.9 1139.2 1164.9 1171.5 1183.1 15164.7 15236.0 15273.2 15281.9 15678.4 629934.0 632093.0 632609.8 632912.0 633374.0 58433.7 60490.4 60512.7 60517.7 60576.7 1207.6 1313.7 1350.7 1481.3 1490.7 24410.9 31659.0 31839.0 32280.4 32717.0 52286.7 52694.0 52937.2 52971.5 52981.5 431853.0 432626.7 432831.0 432840.0 432846.2 33035.3 33802.7 33866.0 36696.4 37308.3 33695.7 38078.4 44149.0 44331.6 48764.5 36131.3 36862.8 36959.0 37055.8 37338.4 35909.6 35930.6 36351.3 36734.0 36763.6 30358.0 36356.5 36705.8 37283.6 37647.9 31519.0 32088.4 32150.8 32254.0 32518.2 23238.7 23672.3 23713.8 23745.9 24162.9 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33510.30 Median:2203.46 stddev:92138.58 (4181.5%) stddev-sans-first:92363.51 06:16:00 INFO - PROCESS | 1664 | Values: 398.8 400.3 402.2 402.8 411.9 13659.0 19158.0 20173.5 20213.2 20858.0 15524.2 16827.9 19839.5 20156.5 20574.4 5813.9 5985.9 6031.9 6040.2 6043.3 5048.5 5527.2 5922.3 6082.8 6108.8 3814.5 4057.2 5657.2 6022.2 6022.9 5943.9 5959.1 5997.1 6005.5 6009.8 5598.0 5769.1 5782.9 5783.7 5785.5 10935.9 13272.9 13445.9 13727.7 13743.7 2113.7 2195.8 2211.1 2211.6 2321.9 9416.4 10040.7 10095.5 10226.3 13742.7 1674.4 1698.4 1715.4 1743.9 1789.9 117768.4 123656.0 123849.0 126396.7 126815.0 11521.9 11566.5 11576.5 11604.5 11760.8 14906.9 14992.5 14996.7 15010.8 15016.0 8645.3 8818.0 8842.0 8926.8 8927.8 6966.4 8669.5 8714.0 8817.7 8817.7 4542.5 4595.9 4622.7 4657.4 4690.6 1499.6 1873.2 1937.6 1948.8 1952.0 849.9 863.6 923.6 929.7 989.3 16660.2 17500.8 19401.9 20389.7 21081.9 957.4 970.2 971.2 972.0 988.8 10830.5 11174.6 11244.0 11271.9 11384.0 506525.0 515736.4 515764.0 587750.0 593028.0 134656.0 136296.0 143917.0 144093.0 145063.0 1028.5 1041.6 1059.5 1104.1 1111.7 18473.6 18760.5 18809.3 18865.3 18867.5 51775.0 52208.0 53109.2 53160.0 53770.7 76005.0 76204.6 76832.0 77975.6 78948.2 7972.4 8102.8 8159.6 8315.9 8484.0 9757.9 12016.6 12899.2 12967.5 13373.9 9281.6 10975.0 11363.5 11506.6 11649.2 11118.6 11263.1 11317.9 11524.5 11570.8 9287.0 11445.1 11620.7 11647.3 11848.9 7961.2 8105.9 8124.8 8386.5 8386.7 8189.8 9353.4 10339.4 10542.2 10659.0 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5596.83 Median:5002.35 stddev:3115.42 (62.3%) stddev-sans-first:3108.38 06:16:00 INFO - PROCESS | 1664 | Values: 1424.1 1608.7 1628.0 1885.5 2008.7 7781.5 8484.2 8718.9 8733.4 8743.8 8330.5 8572.1 8574.5 8593.0 8599.6 2115.1 2350.2 2409.8 2433.7 2438.4 2442.8 2465.7 2467.9 2468.3 2471.9 2390.6 2416.4 2427.0 2434.2 2443.6 1753.9 2326.7 2369.3 2379.7 2411.3 2401.0 2414.1 2420.6 2424.5 2424.7 8412.2 8527.2 8534.5 8537.7 8539.6 5053.3 5260.1 5267.2 5268.2 5282.6 3364.8 3436.2 3544.3 3550.3 3551.4 897.3 1052.7 1055.1 1055.6 1057.0 9585.9 11621.5 11635.8 11637.2 11652.2 4014.5 4539.3 4667.0 4806.8 4847.7 9930.8 9947.6 9979.2 9982.4 9984.8 5383.0 5384.9 5403.3 5423.4 5425.1 3618.9 4289.4 4381.6 4512.8 4914.7 3712.2 3835.5 4064.1 4069.2 4080.6 1321.4 1333.2 1344.7 1359.4 1362.8 727.6 820.2 889.1 902.4 933.2 5168.7 5224.6 5675.6 5833.9 5929.0 948.4 955.8 956.2 1009.5 1023.8 3648.4 3756.2 3824.5 3897.2 3920.3 10087.4 10518.9 10524.5 10624.8 10645.5 10290.6 10555.8 10644.4 10757.0 10780.2 8057.7 8106.4 8271.1 8272.0 8298.8 4951.4 5584.6 5626.8 5729.9 5789.7 9452.0 9787.1 9798.3 9896.1 9920.4 8395.9 9692.0 9835.3 9864.4 10006.8 7178.7 7376.6 7450.0 7450.1 7452.5 7546.8 7799.7 8089.4 8325.2 8337.8 7120.3 7196.9 7219.0 7341.1 7448.3 7197.1 7208.3 7276.4 7350.0 7354.4 6789.8 6823.7 6835.9 6877.8 6902.6 4308.7 4798.7 4803.9 4828.3 4907.3 4811.6 4863.3 4894.1 4903.4 5064.0 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4993.05 Median:5640.89 stddev:1567.26 (27.8%) stddev-sans-first:1531.93 06:16:00 INFO - PROCESS | 1664 | Values: 321.1 324.5 330.6 336.2 339.6 5070.5 5765.0 5838.5 5843.9 5848.9 5221.2 5327.4 5716.0 5749.5 5797.3 3618.2 3841.4 4092.4 4109.2 4418.6 4310.9 4377.9 4380.2 4383.8 4405.6 3632.3 3668.1 4350.1 4383.4 4391.3 3495.6 4099.5 4353.5 4362.2 4373.5 3752.2 3758.8 3770.8 3779.5 3793.4 5543.7 5546.1 5562.5 5568.3 5593.7 4885.2 5057.5 5058.3 5060.8 5120.4 5387.5 5703.8 6067.9 6082.8 6140.4 3730.1 3883.9 3944.0 4230.7 4282.5 5951.5 6440.5 6443.6 6448.2 6492.9 4291.2 4310.5 4325.0 4337.2 4352.2 3831.5 4379.3 4391.5 4403.8 4421.9 4173.0 4175.6 4234.8 4974.2 4983.9 5491.3 6123.6 6142.8 6209.4 6221.0 1424.7 1448.2 1467.5 1469.3 1472.0 895.4 943.0 960.6 965.6 967.1 5579.3 5638.0 5643.8 5650.0 5656.7 5384.3 5592.0 5813.9 6329.5 6459.8 5562.7 5782.4 5827.0 5860.6 5861.3 3242.2 3393.8 3405.4 3409.6 3413.7 5828.0 6454.3 6769.5 6781.8 6783.4 6327.2 6555.6 6603.6 6644.6 6661.6 5077.6 5533.1 6292.1 6739.2 6775.9 5444.7 5871.8 6433.1 6436.0 6468.5 5810.4 6309.7 6362.0 6362.6 6375.8 5239.5 5859.2 6284.9 6772.6 6904.3 4162.3 4176.7 4187.6 4219.8 4229.7 5409.3 6461.0 6481.1 6490.8 6558.6 5216.8 5873.1 5880.5 6244.3 6277.6 6174.6 6217.3 6236.6 6245.6 6268.9 5748.4 6094.3 6112.3 6124.7 6178.7 6194.7 6216.8 6239.3 6240.9 6277.4 5901.3 6055.3 6098.1 6103.8 6134.9 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17204.50 Median:29770.27 stddev:23397.01 (78.6%) stddev-sans-first:23429.37 06:16:00 INFO - PROCESS | 1664 | Values: 585.1 746.9 749.8 751.5 763.2 14784.7 15114.0 15116.3 15120.0 16498.3 15744.7 15818.9 15857.7 15893.4 15910.4 727.5 754.8 931.0 939.8 942.2 936.8 969.2 970.3 970.7 971.0 973.5 994.0 999.7 999.8 1000.3 766.9 793.9 794.0 996.0 1008.5 600.5 732.2 752.0 756.6 758.8 14994.7 15032.5 15046.4 15051.6 15094.2 12940.4 13099.2 13106.0 13325.5 13338.9 9775.3 9976.6 10098.3 10507.9 10630.8 3056.9 3494.1 3495.8 3536.9 3655.2 83123.0 83911.6 84083.6 84100.0 85578.6 7770.0 7908.2 9541.0 9558.8 10105.8 22586.4 22613.1 22641.0 22657.1 22662.9 7409.7 7463.7 7553.5 7587.9 7720.3 3894.2 4036.4 4043.4 4063.4 4071.0 3568.3 3671.4 3671.5 3672.6 3677.9 640.2 640.8 641.2 645.3 650.5 535.9 545.3 591.5 601.9 620.2 7692.3 7870.2 8051.7 8111.0 8343.1 507.3 558.7 591.5 600.4 622.5 3727.3 3729.1 3735.0 3772.8 3953.0 70019.3 71958.0 72418.6 94136.5 94925.1 62535.0 63412.0 67640.7 72321.6 76434.6 49287.6 56559.0 58955.4 67279.7 69210.0 8116.6 8131.4 8211.2 8294.3 8403.0 33314.0 44567.8 44692.3 44902.0 44986.8 54293.9 55404.6 62634.1 64002.4 64152.0 10415.3 10608.3 10644.5 10961.8 11136.6 11889.0 12038.0 12269.6 12296.0 12338.8 11262.9 11266.9 11306.0 11432.6 11599.9 11110.3 11113.3 11297.4 11483.9 11700.7 11030.7 11066.8 11401.6 11530.0 11993.8 9609.8 9630.0 9662.3 9928.4 10183.0 9188.5 9495.7 9608.5 9700.9 9731.9 06:16:00 INFO - PROCESS | 1664 | -------- Summary: end -------- 06:16:00 INFO - PROCESS | 1664 | 06:16:00 INFO - TEST-INFO | 1664: exit 0 06:16:01 INFO - TEST-OK | dromaeo_css | took 1115449ms 06:16:01 INFO - TEST-START | kraken 06:16:01 INFO - operating with platform_type : mac_ 06:16:01 INFO - Initialising browser for kraken test... 06:16:01 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp8y68T8/profile http://localhost:49284/getInfo.html) 06:16:03 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 06:16:03 INFO - PROCESS | 1673 | colorDepth:24 06:16:03 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 06:16:03 INFO - PROCESS | 1673 | __metrics 06:16:03 INFO - PROCESS | 1673 | 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" 06:16:03 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:16:03 INFO - TEST-INFO | 1673: exit 0 06:16:03 INFO - Browser initialized. 06:16:03 INFO - Running cycle 1/1 for kraken test... 06:16:03 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp8y68T8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:15 INFO - PROCESS | 1675 | RSS: Main: 237883392 06:16:15 INFO - PROCESS | 1675 | 06:16:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 06:16:19 INFO - PROCESS | 1675 | RSS: Main: 285847552 06:16:19 INFO - PROCESS | 1675 | 06:16:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 06:16:22 INFO - PROCESS | 1675 | RSS: Main: 299225088 06:16:22 INFO - PROCESS | 1675 | 06:16:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 06:16:26 INFO - PROCESS | 1675 | RSS: Main: 386166784 06:16:26 INFO - PROCESS | 1675 | 06:16:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 06:16:29 INFO - PROCESS | 1675 | RSS: Main: 285110272 06:16:29 INFO - PROCESS | 1675 | 06:16:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:16:32 INFO - PROCESS | 1675 | RSS: Main: 289533952 06:16:32 INFO - PROCESS | 1675 | 06:16:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 06:16:35 INFO - PROCESS | 1675 | RSS: Main: 329461760 06:16:35 INFO - PROCESS | 1675 | 06:16:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 06:16:38 INFO - PROCESS | 1675 | RSS: Main: 330633216 06:16:38 INFO - PROCESS | 1675 | 06:16:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 06:16:42 INFO - PROCESS | 1675 | RSS: Main: 327507968 06:16:42 INFO - PROCESS | 1675 | 06:16:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:16:44 INFO - PROCESS | 1675 | RSS: Main: 319926272 06:16:44 INFO - PROCESS | 1675 | 06:16:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:16:46 INFO - PROCESS | 1675 | RSS: Main: 326701056 06:16:46 INFO - PROCESS | 1675 | 06:16:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:16:49 INFO - PROCESS | 1675 | RSS: Main: 329637888 06:16:49 INFO - PROCESS | 1675 | 06:16:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:16:53 INFO - PROCESS | 1675 | RSS: Main: 334307328 06:16:53 INFO - PROCESS | 1675 | 06:16:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:16:56 INFO - PROCESS | 1675 | RSS: Main: 331890688 06:16:56 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 06:16:58 INFO - PROCESS | 1675 | RSS: Main: 396713984 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | __start_tp_report 06:16:58 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 06:16:58 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 06:16:58 INFO - PROCESS | 1675 | |i|pagename|runs| 06:16:58 INFO - PROCESS | 1675 | |0;ai-astar;109;109;112;111;113;113;116;112;113;113 06:16:58 INFO - PROCESS | 1675 | |1;audio-beat-detection;153;122;155;209;147;146;142;119;171;121 06:16:58 INFO - PROCESS | 1675 | |2;audio-dft;187;184;185;181;186;183;183;184;184;190 06:16:58 INFO - PROCESS | 1675 | |3;audio-fft;76;71;85;92;74;83;85;108;86;56 06:16:58 INFO - PROCESS | 1675 | |4;audio-oscillator;89;98;139;76;78;78;122;79;77;122 06:16:58 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;84;87;85;87;84;88;83;87;85 06:16:58 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;92;93;93;93;92;93;93;92;92 06:16:58 INFO - PROCESS | 1675 | |7;imaging-desaturate;84;83;84;86;84;84;87;86;86;85 06:16:58 INFO - PROCESS | 1675 | |8;json-parse-financial;82;83;58;61;61;58;60;78;59;55 06:16:58 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;63;73;89;104;100;87;67;68;68 06:16:58 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;82;83;83;83;83;80;83;80;83;83 06:16:58 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;134;124;156;152;149;136;134;136;160;160 06:16:58 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;172;175;185;173;166;167;170;171;171;165 06:16:58 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;68;69;68;68;69;67;69;69;68 06:16:58 INFO - PROCESS | 1675 | __end_tp_report 06:16:58 INFO - PROCESS | 1675 | __start_cc_report 06:16:58 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1936 06:16:58 INFO - PROCESS | 1675 | __end_cc_report 06:16:58 INFO - PROCESS | 1675 | __startTimestamp1461331018593__endTimestamp 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | ------- Summary: start ------- 06:16:58 INFO - PROCESS | 1675 | Number of tests: 14 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.08 (1.8%) stddev-sans-first:1.88 06:16:58 INFO - PROCESS | 1675 | Values: 109.0 109.0 112.0 111.0 113.0 113.0 116.0 112.0 113.0 113.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:150.00 stddev:27.10 (18.1%) stddev-sans-first:28.69 06:16:58 INFO - PROCESS | 1675 | Values: 153.0 122.0 155.0 209.0 147.0 146.0 142.0 119.0 171.0 121.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.50 stddev:2.50 (1.4%) stddev-sans-first:2.51 06:16:58 INFO - PROCESS | 1675 | Values: 187.0 184.0 185.0 181.0 186.0 183.0 183.0 184.0 184.0 190.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:81.60 Median:84.00 stddev:13.77 (16.4%) stddev-sans-first:14.45 06:16:58 INFO - PROCESS | 1675 | Values: 76.0 71.0 85.0 92.0 74.0 83.0 85.0 108.0 86.0 56.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:78.00 stddev:23.46 (30.1%) stddev-sans-first:24.75 06:16:58 INFO - PROCESS | 1675 | Values: 89.0 98.0 139.0 76.0 78.0 78.0 122.0 79.0 77.0 122.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.74 06:16:58 INFO - PROCESS | 1675 | Values: 88.0 84.0 87.0 85.0 87.0 84.0 88.0 83.0 87.0 85.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 06:16:58 INFO - PROCESS | 1675 | Values: 92.0 92.0 93.0 93.0 93.0 92.0 93.0 93.0 92.0 92.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.32 06:16:58 INFO - PROCESS | 1675 | Values: 84.0 83.0 84.0 86.0 84.0 84.0 87.0 86.0 86.0 85.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:61.00 stddev:10.91 (17.9%) stddev-sans-first:9.80 06:16:58 INFO - PROCESS | 1675 | Values: 82.0 83.0 58.0 61.0 61.0 58.0 60.0 78.0 59.0 55.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:68.00 stddev:15.48 (22.8%) stddev-sans-first:15.41 06:16:58 INFO - PROCESS | 1675 | Values: 63.0 63.0 73.0 89.0 104.0 100.0 87.0 67.0 68.0 68.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:1.25 (1.5%) stddev-sans-first:1.32 06:16:58 INFO - PROCESS | 1675 | Values: 82.0 83.0 83.0 83.0 83.0 80.0 83.0 80.0 83.0 83.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:150.50 stddev:12.79 (8.5%) stddev-sans-first:13.04 06:16:58 INFO - PROCESS | 1675 | Values: 134.0 124.0 156.0 152.0 149.0 136.0 134.0 136.0 160.0 160.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:171.50 stddev:5.70 (3.3%) stddev-sans-first:6.04 06:16:58 INFO - PROCESS | 1675 | Values: 172.0 175.0 185.0 173.0 166.0 167.0 170.0 171.0 171.0 165.0 06:16:58 INFO - PROCESS | 1675 | 06:16:58 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 06:16:58 INFO - PROCESS | 1675 | Values: 68.0 68.0 69.0 68.0 68.0 69.0 67.0 69.0 69.0 68.0 06:16:58 INFO - PROCESS | 1675 | -------- Summary: end -------- 06:16:58 INFO - PROCESS | 1675 | 06:16:59 INFO - TEST-INFO | 1675: exit 0 06:16:59 INFO - TEST-OK | kraken | took 58385ms 06:16:59 INFO - SUITE-END | took 1173s 06:16:59 INFO - Completed test suite (00:19:34) 06:16:59 INFO - javascript benchmark 06:16:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.1361875484245, 3295.6045274567073, 3326.3846188455136, 3344.0803778960767, 3358.697717205451, 5376.354837419511, 5381.569474442045, 5429.0, 5548.889022219553, 5662.801801936933, 5612.7754889804355, 5716.971415142919, 5721.799737009205, 5772.134179873021, 5849.093390524465, 5056.14045612245, 5169.224616307546, 5171.715555644438, 5201.115810312256, 5204.193350030732, 5065.746712664352, 5184.429712731615, 5200.0, 5259.184826351921, 5261.684298942076, 5198.0, 5218.1650935850275, 5220.138677118271, 5233.659812112201, 5263.473652634725, 4274.465691788527, 5008.599312055063, 5065.316182315377, 5118.897622047538, 5149.30484384607, 5077.136886729228, 5105.259737338065, 5109.821156259513, 5111.207762796773, 5141.74291285438, 5495.6427832191175, 5532.889342213133, 5564.888702225933, 5587.525060369873, 5610.410906854803, 6081.817545473643, 6137.140800287963, 6146.569740118193, 6150.108234306045, 6163.383661633846, 5854.707264636793, 6084.168137413318, 6171.25944886612, 6203.0, 6211.720472578745, 5845.386234445407, 5854.590178687494, 5858.179854820329, 5867.383924687846, 5874.324452687995, 6098.395027831184, 6121.605402471149, 6233.625982441068, 6236.314005459396, 6241.750329986749, 6108.190302030417, 6130.29501607312, 6134.693265336716, 6136.079588061737, 6139.754409823557, 6148.44663980235, 6188.783392581238, 6197.938020619827, 6205.069239614004, 6210.4410603045035, 6023.0965355196195, 6049.758009679564, 6055.969720151371, 6101.725422356005, 6141.297828955246, 6145.66198859067, 6146.7399183167345, 6221.06683997404, 6221.160143380585, 6224.004159334485, 4894.706317620954, 4904.0, 4909.214525675946, 4917.778699958439, 4941.579965702884, 4328.394024836525, 4339.63113135387, 4435.445569303837, 4449.243628583149, 4480.260756975128, 5655.745491452894, 5664.943350566442, 5666.603337766399, 5675.57433192504, 5699.857503562361, 6212.161358216673, 6237.282712488031, 6245.500359971192, 6254.21822272216, 6258.405451482102, 5541.861453463615, 5578.972105139448, 5607.383187849333, 5647.717614119278, 5700.116481945305, 5342.626016178908, 5358.089124848785, 5364.7317634118135, 5383.192521121863, 5385.649932754359, 6233.688315584293, 6237.937620623827, 6243.906341404791, 6251.156093927352, 6266.780662676784, 6103.85289575482, 6113.263474096328, 6221.06683997404, 6247.094171345038, 6309.242890853127, 6229.750809967551, 6232.594881332699, 6251.281102673069, 6264.906026409516, 6272.529560282906, 6150.138980542638, 6153.969230153821, 6154.476869466145, 6158.04550294705, 6164.106204600397, 6085.361037091042, 6142.1304578115505, 6142.590862071594, 6189.102580125947, 6214.5960512566535, 4724.527547245248, 5988.790392336247, 6000.249968753906, 6009.158717779602, 6026.337102918587, 5961.910571341346, 5985.700714964321, 6078.3921607838865, 6093.116498107838, 6093.512518998558, 5844.415558444121, 5884.764609415746, 5887.470127688529, 5895.41045895407, 5914.260717410324, 6030.728617212324, 6034.517238620988, 6035.78874739382, 6036.094585812163, 6105.786297479567, 5732.713364331683, 5813.447722466359, 5834.562407819346, 5906.763729450774, 5927.377625349276], "value": 5634.867803194741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.8893643398451, 523.5706720489198, 574.8246784730658, 591.9758817246167, 608.3703367015138, 26195.0, 38132.61867381325, 39107.41338879919, 39304.0, 39385.40921886174, 43240.35139472911, 43742.0, 43818.0, 43884.12231755363, 43908.0, 8455.19675630817, 9430.198433133175, 9697.515124243759, 9715.271354648394, 9721.659741909034, 8582.399232053758, 8706.216440520351, 8711.346649001318, 11356.716082097932, 11699.35653539055, 11599.362035088066, 11795.764084718301, 11796.0, 11801.29192248468, 11812.173147879652, 11576.826347604792, 11704.180707350532, 11711.941440292743, 11716.297022178696, 11718.06255499558, 8483.388156250354, 8798.472091674455, 8810.955945220298, 8813.647454101829, 9030.774230644287, 38374.61625383766, 38860.80569597162, 38944.22111557781, 38990.830275091794, 38998.025049373995, 3223.6453990061073, 3258.3971965186515, 3315.6850099240773, 3331.983340083284, 3461.3077384522944, 26609.0, 27426.451470970467, 27488.0, 27680.031198907942, 28066.859665701744, 2616.2020583721956, 2671.479061582992, 2712.2676877243034, 2783.262435454606, 2790.972090279112, 481591.0, 484950.5752471215, 485588.0, 486868.0, 486941.0, 59513.404865951656, 59811.700941495015, 59931.40068599345, 60024.39975600188, 60073.0, 61750.0737488938, 61859.38140618536, 62001.06998395028, 62019.0, 62114.68942655348, 16015.279312430968, 16686.666266674598, 16687.415940442028, 16706.498805035626, 16711.331546737994, 11743.295402275891, 12958.157719748186, 13015.0, 13045.28250946188, 13084.672883177805, 14776.48282310114, 15216.847831521765, 15291.311890964933, 15359.539213823604, 15418.152001640017, 5842.5618078644275, 6527.694461107797, 6790.864182716318, 6873.934540146285, 6944.618046007417, 829.4484168028193, 1012.7569383347943, 1019.938803671782, 1090.0407641275626, 1127.8646562412564, 28345.433091338058, 28755.56866647002, 29068.0, 29348.559771603443, 30220.546691799642, 1101.9063379612662, 1139.1627154041773, 1164.9009834164021, 1171.472837223253, 1183.1008433590532, 15164.696706065817, 15236.0, 15273.159976201416, 15281.930264881352, 15678.372865085268, 629934.0, 632093.0, 632609.8369508123, 632912.0, 633374.0, 58433.70783146057, 60490.39509604935, 60512.697436513416, 60517.69741151354, 60576.69711651414, 1207.5652765004415, 1313.7175507265792, 1350.696093378982, 1481.3259966715118, 1490.6795039066653, 24410.87794561016, 31659.0, 31839.0, 32280.35439291248, 32717.0, 52286.73856630692, 52694.0, 52937.205941910135, 52971.47028529665, 52981.47018529919, 431853.0, 432626.67373327125, 432831.0, 432840.0, 432846.2080723048, 33035.33929321352, 33802.661973380935, 33866.0, 36696.449553256185, 37308.25383492369, 33695.663043369255, 38078.428823568225, 44149.0, 44331.55668443274, 48764.51235487741, 36131.27737445289, 36862.8156859214, 36959.0, 37055.814720926224, 37338.43992340171, 35909.64090359063, 35930.64069359273, 36351.27297454089, 36734.0, 36763.63236367602, 30358.0, 36356.45465318075, 36705.816470917474, 37283.627163728015, 37647.87056388313, 31519.0, 32088.358232834744, 32150.83924580362, 32254.032379028667, 32518.187045324055, 23238.72187029662, 23672.28983130509, 23713.762862371157, 23745.881270593538, 24162.912668929588], "value": 19658.359610745712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.82252397683027, 400.33544316434717, 402.1855742122204, 402.7724335750301, 411.9237940980919, 13659.0, 19158.0420978951, 20173.495662608424, 20213.191472341106, 20858.0, 15524.243845486015, 16827.915860420682, 19839.504012399662, 20156.488263380234, 20574.382768516967, 5813.883722325561, 5985.940140598582, 6031.939680603182, 6040.214772079624, 6043.305019922699, 5048.469910659383, 5527.198556209347, 5922.31893332268, 6082.787102451415, 6108.81673549792, 3814.4659747635355, 4057.16828050249, 5657.1514272858985, 6022.187004754368, 6022.939770602304, 5943.920403152863, 5959.135925290809, 5997.070454079603, 6005.4895333896675, 6009.849753756147, 5597.972010139964, 5769.105788602773, 5782.884342313172, 5783.739731712083, 5785.53715702747, 10935.890641093647, 13272.867271327259, 13445.940212535548, 13727.656808579766, 13743.725125497534, 2113.689512502243, 2195.8133558647532, 2211.1045026764186, 2211.6461366181334, 2321.872297023663, 9416.387934784218, 10040.748981275454, 10095.545700443425, 10226.284160108795, 13742.725145497034, 1674.4344038324084, 1698.3546252424123, 1715.3738885306898, 1743.947681569555, 1789.8926064436046, 117768.41115794366, 123656.0, 123849.0, 126396.73603264033, 126815.0, 11521.942390288163, 11566.47950842214, 11576.47905844239, 11604.477798499087, 11760.823587646191, 14906.881983851255, 14992.47526336573, 14996.700065998619, 15010.849891500946, 15016.023958442665, 8645.265152461985, 8818.0, 8841.98317193518, 8926.776830579287, 8927.821443571092, 6966.3730264276455, 8669.48885701292, 8713.956430217808, 8817.735467935972, 8817.735467935972, 4542.545745425431, 4595.931061034087, 4622.745748983838, 4657.371254880548, 4690.648201384842, 1499.5801175670829, 1873.157079314314, 1937.602791427754, 1948.7758907725793, 1952.0, 849.9235068843834, 863.5897948474425, 923.6028507741602, 929.6839074714629, 989.3272574649308, 16660.16699165037, 17500.824991750174, 19401.902990484956, 20389.694154587694, 21081.894590527256, 957.3537861943161, 970.2286682087757, 971.2327261463506, 971.9708408747749, 988.8071825993862, 10830.461922596754, 11174.553017879194, 11243.988041076342, 11271.943640281746, 11383.975442210076, 506525.0, 515736.421317891, 515764.0, 587750.0, 593028.0, 134656.0, 136296.0, 143917.0, 144093.0, 145063.0, 1028.5320179318392, 1041.6041904076553, 1059.4702648675661, 1104.1222228328497, 1111.6665000499818, 18473.63052738911, 18760.530986724894, 18809.341673041377, 18865.339713109974, 18867.528311792314, 51775.0, 52208.0, 53109.20336194883, 53159.99999999845, 53770.73114634402, 76005.0, 76204.61897690476, 76832.0, 77975.61012194902, 78948.21051789638, 7972.402069844668, 8102.797430064295, 8159.632816523152, 8315.875261871197, 8484.0, 9757.853632195665, 12016.579419720269, 12899.161554499304, 12967.546135885199, 13373.933130334286, 9281.582328795355, 10975.0, 11363.54545818158, 11506.597269095542, 11649.242799218024, 11118.555257789598, 11263.098952083978, 11317.886821131684, 11524.481398336928, 11570.826437603275, 9287.0, 11445.141614378788, 11620.709482263012, 11647.30116193031, 11848.940755296513, 7961.164077771982, 8105.878411823709, 8124.83750325002, 8386.496810191407, 8386.66453341884, 8189.79525511867, 9353.438793672402, 10339.431331276852, 10542.209334299805, 10658.987396197348], "value": 9327.190458086481, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.0743516714128, 1608.7426011838104, 1627.9999999999998, 1885.5097674604608, 2008.7087372331011, 7781.533108013523, 8484.151584841513, 8718.912810871889, 8733.432326898763, 8743.781405464866, 8330.500169989806, 8572.142785721424, 8574.528400937947, 8593.011803642583, 8599.570021498916, 2115.111653105699, 2350.189184731265, 2409.7831195192384, 2433.707955045388, 2438.3904023994, 2442.8412153210074, 2465.7287698353257, 2467.864267465289, 2468.333549941515, 2471.88876500557, 2390.5640823175295, 2416.359664688859, 2426.963595546068, 2434.2453839309783, 2443.6090225563826, 1753.9123043847885, 2326.7091613548305, 2369.30105618842, 2379.6549500322444, 2411.288669842383, 2400.97599024011, 2414.1429792423614, 2420.552197843387, 2424.5029768897334, 2424.733279339271, 8412.242898139197, 8527.18991695788, 8534.487930724177, 8537.701180458655, 8539.57302134897, 5053.292539044537, 5260.105782017066, 5267.157254839246, 5268.157094864803, 5282.603804714623, 3364.814935178564, 3436.1924947637535, 3544.2734239480847, 3550.343186510504, 3551.449525323579, 897.2956229359986, 1052.7210289273348, 1055.1347894726327, 1055.5672174408533, 1056.978860422795, 9585.856212156823, 11621.47703353351, 11635.825462618068, 11637.243579167327, 11652.184347095706, 4014.4781178446615, 4539.273716205457, 4666.95333046672, 4806.8077276909235, 4847.709137451764, 9930.80138397228, 9947.55236014381, 9979.201663866874, 9982.35114717541, 9984.800303994025, 5382.973085134628, 5384.865378365494, 5403.324584426947, 5423.403425623178, 5425.077736784756, 3618.8552457901915, 4289.357985728603, 4381.561843815657, 4512.774361281989, 4914.72968986702, 3712.1647629283193, 3835.5013848199555, 4064.085580744368, 4069.1658210066867, 4080.5511393746665, 1321.4119716725966, 1333.2467156056796, 1344.6772774534238, 1359.395069194199, 1362.8160198373291, 727.6398182899393, 820.2330820682681, 889.106448019736, 902.4449963272672, 933.1601558597316, 5168.664036837594, 5224.634275600672, 5675.574331925122, 5833.88332233353, 5928.970355148197, 948.4025064209504, 955.8016561907724, 956.2493442647489, 1009.4750729620556, 1023.8413045977885, 3648.3797754381817, 3756.2111956488898, 3824.521934758155, 3897.1621101462756, 3920.255151521259, 10087.394756314645, 10518.947405263078, 10524.473776311153, 10624.840627390595, 10645.467726613639, 10290.588376464857, 10555.84166237507, 10644.361338319726, 10757.0, 10780.245382823126, 8057.717979870794, 8106.351491880635, 8271.13153118926, 8271.958640206882, 8298.792530186794, 4951.405831300267, 5584.609077364546, 5626.803061892814, 5729.942700573108, 5789.739461724317, 9451.952740236255, 9787.119159275415, 9798.265130115127, 9896.109350158233, 9920.355176913477, 8395.916040839758, 9692.0, 9835.311528192959, 9864.358816676893, 10006.799864002825, 7178.712851485882, 7376.594287314249, 7449.962750186214, 7450.143233527997, 7452.478326517092, 7546.773596792105, 7799.727009554866, 8089.352851771727, 8325.167483251626, 8337.791555211168, 7120.252373500708, 7196.856062878818, 7219.0, 7341.119065712149, 7448.329650331496, 7197.136343638587, 7208.27917208275, 7276.381507571916, 7349.963250183715, 7354.411647068328, 6789.830254243683, 6823.727050918106, 6835.93164068353, 6877.793666190022, 6902.551334163263, 4308.676849236257, 4798.73606951621, 4803.927941080957, 4828.251620998751, 4907.312976183266, 4811.591014763784, 4863.319135320987, 4894.094592500399, 4903.436104847988, 5064.0], "value": 4524.699143868788, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.099316417449, 324.5132301547678, 330.55210190192287, 336.2216468874555, 339.6213222257184, 5070.467600901908, 5764.971175144124, 5838.5260769841925, 5843.941560584398, 5848.853778655536, 5221.216817477381, 5327.360716714, 5715.970535616119, 5749.511291540213, 5797.333306669734, 3618.2401695643816, 3841.442990766337, 4092.3656833190753, 4109.178164367131, 4418.5802348776815, 4310.892227694301, 4377.934330985022, 4380.167768124046, 4383.780810959455, 4405.603495685387, 3632.309861126391, 3668.119651283686, 4350.086481838802, 4383.430154079965, 4391.27543955247, 3495.580530336363, 4099.483191219253, 4353.477582690066, 4362.236608593484, 4373.518912919576, 3752.249550089993, 3758.8120593970193, 3770.7926064066464, 3779.546454425459, 3793.3740932746064, 5543.667379957215, 5546.112621980464, 5562.527185189264, 5568.303962004749, 5593.692346920918, 4885.193942999401, 5057.544820966131, 5058.291839142523, 5060.7722652480725, 5120.436751957282, 5387.4612538746205, 5703.77184912603, 6067.939320606782, 6082.817515474543, 6140.416660417253, 3730.0674831292176, 3883.922321553553, 3943.9802800985813, 4230.725002874803, 4282.5075116361395, 5951.49412299955, 6440.516961227973, 6443.613383196976, 6448.16173897395, 6492.9350706492805, 4291.227579035741, 4310.52584215736, 4324.978375108104, 4337.240982827992, 4352.238358287287, 3831.4635950966886, 4379.299312110047, 4391.451068616423, 4403.801828917706, 4421.867343979686, 4172.996961458517, 4175.645070169009, 4234.7670878102, 4974.179260422062, 4983.8504844854715, 5491.341039075336, 6123.632582045091, 6142.754289828356, 6209.441150296495, 6220.968895155495, 1424.6936908564708, 1448.217962300346, 1467.530390275123, 1469.2727100085312, 1471.977920331196, 895.4314010603314, 942.9580313753204, 960.6109525642127, 965.5558443116105, 967.1247520993512, 5579.2746942897975, 5637.9718101409235, 5643.814798892278, 5649.971750141223, 5656.660600363992, 5384.326959130109, 5591.9720401397735, 5813.941860581424, 6329.461995730415, 6459.773907913294, 5562.666240025612, 5782.421757824184, 5826.970865145647, 5860.648361098348, 5861.267341582302, 3242.1681749811282, 3393.7963722176746, 3405.4210784166753, 3409.5567576214935, 3413.7269018478464, 5828.0, 6454.290028096905, 6769.492288078413, 6781.830454238585, 6783.389494945479, 6327.203677793287, 6555.573887697285, 6603.636799975951, 6644.634545100066, 6661.600303981777, 5077.644564880497, 5533.059379905426, 6292.087647291209, 6739.157605299338, 6775.93224067753, 5444.727763611724, 5871.766928944799, 6433.131527243762, 6436.0, 6468.514861385321, 5810.389909059657, 6309.652969086561, 6361.968190159019, 6362.618242905441, 6375.77684781049, 5239.47605239473, 5859.150423188699, 6284.937150628435, 6772.5936443813525, 6904.344087311697, 4162.313218318944, 4176.749395036308, 4187.560306167808, 4219.780219780219, 4229.746215227096, 5409.269748583891, 6460.967695161494, 6481.092647029325, 6490.772822951174, 6558.573327868719, 5216.843494695166, 5873.089671100986, 5880.529557635294, 6244.313125556276, 6277.5605707600025, 6174.567780255339, 6217.316095229431, 6236.594621349779, 6245.6252624842655, 6268.874622507616, 5748.396418376177, 6094.329623741473, 6112.266528016667, 6124.744427392463, 6178.69106544662, 6194.690265486798, 6216.844578885564, 6239.313675495782, 6240.881018527932, 6277.403646653561, 5901.29184497863, 6055.333913269625, 6098.146259523759, 6103.816885493442, 6134.895718770664], "value": 4490.530027672069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.1380886151401, 746.8917007033976, 749.8275396658769, 751.4551949836367, 763.1948294549248, 14784.704305913889, 15114.0, 15116.319765610533, 15120.0, 16498.25757840897, 15744.685106297868, 15818.920905395456, 15857.682846343067, 15893.44372946947, 15910.363585456596, 727.4762171236728, 754.8075240813585, 930.9767255818591, 939.8308304505186, 942.194423767679, 936.8266870628955, 969.2294625772493, 970.2819913264204, 970.6651205334184, 971.0241207586402, 973.5375696544156, 993.9900600994006, 999.7200783780517, 999.7900440907382, 1000.304788172217, 766.938644908406, 793.8809178623195, 794.0471434278888, 996.0189213624574, 1008.4604736465992, 600.5285850607253, 732.16533152206, 752.003595236314, 756.5952215564646, 758.7799538133935, 14994.70010599782, 15032.549023529311, 15046.398144074225, 15051.623709407353, 15094.245287735679, 12940.352982350845, 13099.2140471572, 13106.0, 13325.466981320833, 13338.93330533341, 9775.31572789905, 9976.600935962553, 10098.29311948164, 10507.894921050769, 10630.840537391945, 3056.862441190152, 3494.091536200581, 3495.842687079062, 3536.929261414757, 3655.195856911502, 83123.0, 83911.58044209801, 84083.5795821023, 84100.0, 85578.57210713968, 7770.0, 7908.248716371935, 9541.045895410542, 9558.761030974143, 10105.848412273821, 22586.435339116324, 22613.09547618077, 22641.0, 22657.093716251497, 22662.886685566467, 7409.666565004588, 7463.701451941862, 7553.509021913558, 7587.924120758832, 7720.3051725344985, 3894.201688653876, 4036.4348991141264, 4043.3732771420478, 4063.3904914262507, 4071.043304823364, 3568.2863427314123, 3671.4125739881492, 3671.4859919611276, 3672.59601443841, 3677.9448308275396, 640.2253273538977, 640.8494003909078, 641.2273210780997, 645.2805122288614, 650.4503694378243, 535.8713908661894, 545.3210752613022, 591.470633782762, 601.8826328865829, 620.1968450856152, 7692.346150577152, 7870.173631768696, 8051.677932882736, 8110.9861267341585, 8343.08226095129, 507.3151245818116, 558.7038869399225, 591.5251125237564, 600.3666132230452, 622.5362105231541, 3727.2731817295908, 3729.0795240450648, 3735.0, 3772.8490860365278, 3953.0, 70019.2998070023, 71958.0, 72418.63790681119, 94136.52931735298, 94925.050749493, 62535.0, 63412.0, 67640.6617966907, 72321.55356892938, 76434.61782691051, 49287.63534928352, 56559.0, 58955.410445895846, 67279.66360168168, 69210.0, 8116.634751436318, 8131.430799844191, 8211.17888211198, 8294.336453083859, 8403.033651129954, 33314.0, 44567.77716111398, 44692.32961505515, 44902.0, 44986.77506612446, 54293.94178756736, 55404.61488462821, 62634.06048909361, 64002.35997639964, 64152.0, 10415.270931034755, 10608.257421980386, 10644.520996555018, 10961.780764384508, 11136.55453781872, 11889.0, 12038.0, 12269.631911042683, 12296.016318694661, 12338.81491777606, 11262.943685281522, 11266.887331126913, 11306.0, 11432.599859004895, 11599.942000290283, 11110.277831940897, 11113.277636953571, 11297.43512824372, 11483.942580287045, 11700.70748231267, 11030.724231894266, 11066.833997489883, 11401.600943966921, 11530.0, 11993.760124797629, 9609.759756006155, 9629.951850240985, 9662.275329350186, 9928.354656947275, 10183.032611901857, 9188.4839001564, 9495.715128546155, 9608.471534065693, 9700.85448718284, 9731.9026809731], "value": 6810.138735053877, "unit": "score"}], "name": "dromaeo_css", "value": 7231.213091948751, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 112.0, 111.0, 113.0, 113.0, 116.0, 112.0, 113.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 155.0, 209.0, 147.0, 146.0, 142.0, 119.0, 171.0, 121.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 181.0, 186.0, 183.0, 183.0, 184.0, 184.0, 190.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 85.0, 92.0, 74.0, 83.0, 85.0, 108.0, 86.0, 56.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 98.0, 139.0, 76.0, 78.0, 78.0, 122.0, 79.0, 77.0, 122.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 83.0, 87.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 86.0, 84.0, 84.0, 87.0, 86.0, 86.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 58.0, 61.0, 61.0, 58.0, 60.0, 78.0, 59.0, 55.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 73.0, 89.0, 104.0, 100.0, 87.0, 67.0, 68.0, 68.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 83.0, 83.0, 80.0, 83.0, 80.0, 83.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 124.0, 156.0, 152.0, 149.0, 136.0, 134.0, 136.0, 160.0, 160.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 185.0, 173.0, 166.0, 167.0, 170.0, 171.0, 171.0, 165.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 69.0, 67.0, 69.0, 69.0, 68.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1495.7999999999997, "alertThreshold": 2.0}]} 06:16:59 INFO - Return code: 0 06:16:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:16:59 ERROR - # TBPL SUCCESS # 06:16:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:16:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:16:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:16:59 INFO - 2016-04-22 06:16:59.896 screenresolution[1679:9772] starting screenresolution argv=screenresolution get 06:16:59 INFO - 2016-04-22 06:16:59.899 screenresolution[1679:9772] Display 0: 1600x1200x32@60 06:16:59 INFO - 2016-04-22 06:16:59.905 screenresolution[1680:9775] starting screenresolution argv=screenresolution list 06:16:59 INFO - Available Modes on Display 0 06:16:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:16:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:16:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:16:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:00 INFO - Intel Iris: 06:17:00 INFO - Chipset Model: Intel Iris 06:17:00 INFO - Type: GPU 06:17:00 INFO - Bus: Built-In 06:17:00 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:00 INFO - Vendor: Intel (0x8086) 06:17:00 INFO - Device ID: 0x0a2e 06:17:00 INFO - Revision ID: 0x0009 06:17:00 INFO - Displays: 06:17:00 INFO - Display: 06:17:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:00 INFO - Display Serial Number: 1600x1200 60 06:17:00 INFO - Main Display: Yes 06:17:00 INFO - Mirror: Off 06:17:00 INFO - Online: Yes 06:17:00 INFO - Rotation: Supported 06:17:00 INFO - Return code: 0 06:17:00 INFO - Running post-action listener: _resource_record_post_action 06:17:00 INFO - Running post-run listener: _resource_record_post_run 06:17:01 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37807616; Write bytes: 692343808; Read time: 392; Write time: 1906 06:17:01 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379724288; Write bytes: 373822976; Read time: 28135; Write time: 710 06:17:01 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 36853248; Write bytes: 295423488; Read time: 378; Write time: 1149 06:17:01 INFO - Running post-run listener: _upload_blobber_files 06:17:01 INFO - Blob upload gear active. 06:17:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:01 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 06:17:01 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'] 06:17:01 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 06:17:01 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:17:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:17:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:03 INFO - (blobuploader) - INFO - Done attempting. 06:17:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:17:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:17:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:04 INFO - (blobuploader) - INFO - Done attempting. 06:17:04 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:04 INFO - Return code: 0 06:17:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f6069be2b2844282faa2113bab4e02bb0c5c859a9038a7c237688510c79e426bb2b0911bccfcaf48e9fbd5e945e2947a87d715627bc43efc1be09891272fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc72463ebc49724659af4135bf73ff33b0805de3394c2b46928bdd905c96891745a995bf823a45967cc70cf8f6fced719eaf2760734a2a79cf94ac2254c45509"} 06:17:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:04 INFO - Contents: 06:17:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f6069be2b2844282faa2113bab4e02bb0c5c859a9038a7c237688510c79e426bb2b0911bccfcaf48e9fbd5e945e2947a87d715627bc43efc1be09891272fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc72463ebc49724659af4135bf73ff33b0805de3394c2b46928bdd905c96891745a995bf823a45967cc70cf8f6fced719eaf2760734a2a79cf94ac2254c45509"} 06:17:04 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:04 INFO - Copying logs to upload dir... 06:17:04 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.056228 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 06:17:04.420352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:04.425096) ========= 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.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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/94f6069be2b2844282faa2113bab4e02bb0c5c859a9038a7c237688510c79e426bb2b0911bccfcaf48e9fbd5e945e2947a87d715627bc43efc1be09891272fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc72463ebc49724659af4135bf73ff33b0805de3394c2b46928bdd905c96891745a995bf823a45967cc70cf8f6fced719eaf2760734a2a79cf94ac2254c45509"} build_url:https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010529 build_url: 'https://queue.taskcluster.net/v1/task/bVymaqpcTomf9pB5UcmAjA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f6069be2b2844282faa2113bab4e02bb0c5c859a9038a7c237688510c79e426bb2b0911bccfcaf48e9fbd5e945e2947a87d715627bc43efc1be09891272fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc72463ebc49724659af4135bf73ff33b0805de3394c2b46928bdd905c96891745a995bf823a45967cc70cf8f6fced719eaf2760734a2a79cf94ac2254c45509"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:04.481143) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:04.481462) ========= 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.FPunYZEXNO/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.1QYP9pvPk1/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005107 ========= master_lag: 0.25 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:04.736375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:17:04.736807) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:17:04.737416) ========= ========= Total master_lag: 0.70 =========