builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0205 starttime: 1463653246.22 results: success (0) buildid: 20160519020753 builduid: 645d9f387ec84ae0b97b9b613d199be1 revision: ca41d290daa081865b50f2434aabe15c9fd7ae66 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.219027) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.219641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.219979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.238839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.239169) ========= 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.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 03:20:46-- 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% 145M=0s 2016-05-19 03:20:46 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.378046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.378415) ========= 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.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.020195 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.427179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.427554) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ca41d290daa081865b50f2434aabe15c9fd7ae66 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ca41d290daa081865b50f2434aabe15c9fd7ae66 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 03:20:46,464 truncating revision to first 12 chars 2016-05-19 03:20:46,464 Setting DEBUG logging. 2016-05-19 03:20:46,464 attempt 1/10 2016-05-19 03:20:46,465 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ca41d290daa0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 03:20:46,721 unpacking tar archive at: mozilla-aurora-ca41d290daa0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.513325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.958777) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.959128) ========= script_repo_revision: ca41d290daa081865b50f2434aabe15c9fd7ae66 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.959579) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.959993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:20:46.974276) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-19 03:20:46.974601) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:20:47 INFO - MultiFileLogger online at 20160519 03:20:47 in /builds/slave/test 03:20:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:20:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:47 INFO - {'append_to_log': False, 03:20:47 INFO - 'base_work_dir': '/builds/slave/test', 03:20:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:20:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:47 INFO - 'branch': 'Mozilla-Aurora', 03:20:47 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:47 INFO - 'config_files': ('talos/mac_config.py',), 03:20:47 INFO - 'default_actions': ('clobber', 03:20:47 INFO - 'read-buildbot-config', 03:20:47 INFO - 'download-and-extract', 03:20:47 INFO - 'populate-webroot', 03:20:47 INFO - 'create-virtualenv', 03:20:47 INFO - 'install', 03:20:47 INFO - 'run-tests'), 03:20:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:47 INFO - 'download_minidump_stackwalk': True, 03:20:47 INFO - 'download_symbols': 'ondemand', 03:20:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:47 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:47 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:47 INFO - 'installer_path': 'installer.exe', 03:20:47 INFO - 'log_level': 'info', 03:20:47 INFO - 'log_name': 'talos', 03:20:47 INFO - 'log_to_console': True, 03:20:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:47 INFO - 'opt_config_files': (), 03:20:47 INFO - 'pip_index': False, 03:20:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:47 INFO - 'cmd': ('bash', 03:20:47 INFO - '-c', 03:20:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:47 INFO - 'enabled': True, 03:20:47 INFO - 'halt_on_failure': False, 03:20:47 INFO - 'name': 'check_screen_resolution'},), 03:20:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:47 INFO - 'cmd': ('bash', 03:20:47 INFO - '-c', 03:20:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:47 INFO - 'enabled': True, 03:20:47 INFO - 'halt_on_failure': False, 03:20:47 INFO - 'name': 'check_screen_resolution'},), 03:20:47 INFO - 'run_cmd_checks_enabled': True, 03:20:47 INFO - 'sps_profile': False, 03:20:47 INFO - 'sps_profile_interval': 0, 03:20:47 INFO - 'suite': 'dromaeojs', 03:20:47 INFO - 'system_bits': '32', 03:20:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:47 INFO - 'title': 't-yosemite-r7-0205', 03:20:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:47 INFO - 'use_talos_json': True, 03:20:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:47 INFO - 'work_dir': 'build'} 03:20:47 INFO - ##### 03:20:47 INFO - ##### Running clobber step. 03:20:47 INFO - ##### 03:20:47 INFO - Running pre-action listener: _resource_record_pre_action 03:20:47 INFO - Running main action method: clobber 03:20:47 INFO - rmtree: /builds/slave/test/build 03:20:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:48 INFO - Running post-action listener: _resource_record_post_action 03:20:48 INFO - ##### 03:20:48 INFO - ##### Running read-buildbot-config step. 03:20:48 INFO - ##### 03:20:48 INFO - Running pre-action listener: _resource_record_pre_action 03:20:48 INFO - Running main action method: read_buildbot_config 03:20:48 INFO - Using buildbot properties: 03:20:48 INFO - { 03:20:48 INFO - "project": "", 03:20:48 INFO - "product": "firefox", 03:20:48 INFO - "script_repo_revision": "production", 03:20:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:20:48 INFO - "repository": "", 03:20:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:20:48 INFO - "buildid": "20160519020753", 03:20:48 INFO - "pgo_build": "False", 03:20:48 INFO - "basedir": "/builds/slave/test", 03:20:48 INFO - "buildnumber": 242, 03:20:48 INFO - "slavename": "t-yosemite-r7-0205", 03:20:48 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:20:48 INFO - "platform": "yosemite_r7", 03:20:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:20:48 INFO - "branch": "mozilla-aurora", 03:20:48 INFO - "repo_path": "releases/mozilla-aurora", 03:20:48 INFO - "revision": "ca41d290daa081865b50f2434aabe15c9fd7ae66", 03:20:48 INFO - "stage_platform": "macosx64", 03:20:48 INFO - "builduid": "645d9f387ec84ae0b97b9b613d199be1", 03:20:48 INFO - "slavebuilddir": "test" 03:20:48 INFO - } 03:20:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:20:48 INFO - Running post-action listener: _resource_record_post_action 03:20:48 INFO - ##### 03:20:48 INFO - ##### Running download-and-extract step. 03:20:48 INFO - ##### 03:20:48 INFO - Running pre-action listener: _resource_record_pre_action 03:20:48 INFO - Running main action method: download_and_extract 03:20:48 INFO - mkdir: /builds/slave/test/build/tests 03:20:48 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')]} 03:20:48 INFO - https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:20:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:20:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:20:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:20:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:20:50 INFO - Downloaded 1199 bytes. 03:20:50 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:20:50 INFO - Using the following test package requirements: 03:20:50 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:20:50 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:20:51 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:20:51 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'jsshell-mac.zip'], 03:20:51 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:20:51 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:20:51 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:20:51 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:20:51 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:20:51 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:20:51 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:20:51 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:20:51 INFO - https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:20:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:20:53 INFO - Downloaded 18102408 bytes. 03:20:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:20:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:20:56 INFO - Return code: 0 03:20:56 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:20:56 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')]} 03:20:56 INFO - https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:20:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:20:58 INFO - Downloaded 18102408 bytes. 03:20:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:20:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:21:02 INFO - Return code: 0 03:21:02 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')]} 03:21:02 INFO - https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:21:03 INFO - Downloaded 10840145 bytes. 03:21:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:21:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:21:04 INFO - Return code: 0 03:21:04 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')]} 03:21:04 INFO - https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:21:08 INFO - Downloaded 114096531 bytes. 03:21:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:08 INFO - mkdir: /builds/slave/test/properties 03:21:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:08 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:08 INFO - Contents: 03:21:08 INFO - build_url:https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:09 INFO - Running post-action listener: _resource_record_post_action 03:21:09 INFO - Running post-action listener: set_extra_try_arguments 03:21:09 INFO - ##### 03:21:09 INFO - ##### Running populate-webroot step. 03:21:09 INFO - ##### 03:21:09 INFO - Running pre-action listener: _resource_record_pre_action 03:21:09 INFO - Running main action method: populate_webroot 03:21:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:09 INFO - '--tppagecycles', 03:21:09 INFO - '7'], 03:21:09 INFO - 'tests': ['tsvgm']}}, 03:21:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['damp', 'tps']}, 03:21:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'tests': ['damp', 'tps']}, 03:21:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['dromaeo_dom']}, 03:21:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['a11yr', 03:21:09 INFO - 'ts_paint', 03:21:09 INFO - 'tpaint', 03:21:09 INFO - 'sessionrestore', 03:21:09 INFO - 'sessionrestore_no_auto_restore', 03:21:09 INFO - 'tabpaint']}, 03:21:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['tsvgx', 03:21:09 INFO - 'tsvgr_opacity', 03:21:09 INFO - 'tart', 03:21:09 INFO - 'tscrollx', 03:21:09 INFO - 'cart']}, 03:21:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:09 INFO - 'tsvgr_opacity', 03:21:09 INFO - 'tart', 03:21:09 INFO - 'tscrollx', 03:21:09 INFO - 'cart']}, 03:21:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'talos_options': ['--disable-e10s'], 03:21:09 INFO - 'tests': ['tp5o']}, 03:21:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'tests': ['tp5o']}, 03:21:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'talos_options': ['--disable-e10s', 03:21:09 INFO - '--xperf_path', 03:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:09 INFO - 'tests': ['tp5n']}, 03:21:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:09 INFO - 'talos_options': ['--xperf_path', 03:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:09 INFO - 'tests': ['tp5n']}}, 03:21:09 INFO - 'talos.zip': {'path': '', 03:21:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:09 INFO - Running post-action listener: _resource_record_post_action 03:21:09 INFO - ##### 03:21:09 INFO - ##### Running create-virtualenv step. 03:21:09 INFO - ##### 03:21:09 INFO - Running pre-action listener: _resource_record_pre_action 03:21:09 INFO - Running main action method: create_virtualenv 03:21:09 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:09 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 03:21:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:09 INFO - Using real prefix '/tools/python27' 03:21:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:10 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:13 INFO - Installing pip.................done. 03:21:13 INFO - Return code: 0 03:21:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:13 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:13 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 03:21:13 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 03:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:13 INFO - 'HOME': '/Users/cltbld', 03:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:13 INFO - 'LOGNAME': 'cltbld', 03:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:13 INFO - 'MOZ_NO_REMOTE': '1', 03:21:13 INFO - 'NO_EM_RESTART': '1', 03:21:13 INFO - 'PAGER': '/bin/cat', 03:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:13 INFO - 'PWD': '/builds/slave/test', 03:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:13 INFO - 'SHELL': '/bin/bash', 03:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:13 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:13 INFO - 'USER': 'cltbld', 03:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:13 INFO - 'XPC_FLAGS': '0x0', 03:21:13 INFO - 'XPC_SERVICE_NAME': '0', 03:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:13 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 03:21:13 INFO - Cleaning up... 03:21:13 INFO - Return code: 0 03:21:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:13 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:13 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 03:21:13 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 03:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:13 INFO - 'HOME': '/Users/cltbld', 03:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:13 INFO - 'LOGNAME': 'cltbld', 03:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:13 INFO - 'MOZ_NO_REMOTE': '1', 03:21:13 INFO - 'NO_EM_RESTART': '1', 03:21:13 INFO - 'PAGER': '/bin/cat', 03:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:13 INFO - 'PWD': '/builds/slave/test', 03:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:13 INFO - 'SHELL': '/bin/bash', 03:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:13 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:13 INFO - 'USER': 'cltbld', 03:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:13 INFO - 'XPC_FLAGS': '0x0', 03:21:13 INFO - 'XPC_SERVICE_NAME': '0', 03:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:13 INFO - Downloading/unpacking psutil>=0.7.1 03:21:13 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 03:21:13 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 03:21:13 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 03:21:13 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 03:21:13 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 03:21:13 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 03:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:16 INFO - Installing collected packages: psutil 03:21:16 INFO - Running setup.py install for psutil 03:21:16 INFO - building 'psutil._psutil_osx' extension 03:21:16 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 03:21:17 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 03:21:17 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 03:21:17 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 03:21:17 INFO - building 'psutil._psutil_posix' extension 03:21:17 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 03:21:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:17 INFO - ^ 03:21:17 INFO - 1 warning generated. 03:21:17 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 03:21:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:17 INFO - Successfully installed psutil 03:21:17 INFO - Cleaning up... 03:21:17 INFO - Return code: 0 03:21:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:17 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:17 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 03:21:17 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 03:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:17 INFO - 'HOME': '/Users/cltbld', 03:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:17 INFO - 'LOGNAME': 'cltbld', 03:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:17 INFO - 'MOZ_NO_REMOTE': '1', 03:21:17 INFO - 'NO_EM_RESTART': '1', 03:21:17 INFO - 'PAGER': '/bin/cat', 03:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:17 INFO - 'PWD': '/builds/slave/test', 03:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:17 INFO - 'SHELL': '/bin/bash', 03:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:17 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:17 INFO - 'USER': 'cltbld', 03:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:17 INFO - 'XPC_FLAGS': '0x0', 03:21:17 INFO - 'XPC_SERVICE_NAME': '0', 03:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:21:20 INFO - Installing collected packages: mozsystemmonitor 03:21:20 INFO - Running setup.py install for mozsystemmonitor 03:21:20 INFO - Successfully installed mozsystemmonitor 03:21:20 INFO - Cleaning up... 03:21:20 INFO - Return code: 0 03:21:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:20 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')]} 03:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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')]} 03:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:20 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 03:21:20 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 03:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:20 INFO - 'HOME': '/Users/cltbld', 03:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:20 INFO - 'LOGNAME': 'cltbld', 03:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:20 INFO - 'MOZ_NO_REMOTE': '1', 03:21:20 INFO - 'NO_EM_RESTART': '1', 03:21:20 INFO - 'PAGER': '/bin/cat', 03:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:20 INFO - 'PWD': '/builds/slave/test', 03:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:20 INFO - 'SHELL': '/bin/bash', 03:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:20 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:20 INFO - 'USER': 'cltbld', 03:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:20 INFO - 'XPC_FLAGS': '0x0', 03:21:20 INFO - 'XPC_SERVICE_NAME': '0', 03:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:20 INFO - Downloading/unpacking blobuploader==1.2.4 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:24 INFO - Downloading docopt-0.6.1.tar.gz 03:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:24 INFO - Running setup.py install for blobuploader 03:21:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:24 INFO - Running setup.py install for requests 03:21:25 INFO - Running setup.py install for docopt 03:21:25 INFO - Successfully installed blobuploader requests docopt 03:21:25 INFO - Cleaning up... 03:21:25 INFO - Return code: 0 03:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:25 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:25 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 03:21:25 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 03:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:25 INFO - 'HOME': '/Users/cltbld', 03:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:25 INFO - 'LOGNAME': 'cltbld', 03:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:25 INFO - 'MOZ_NO_REMOTE': '1', 03:21:25 INFO - 'NO_EM_RESTART': '1', 03:21:25 INFO - 'PAGER': '/bin/cat', 03:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:25 INFO - 'PWD': '/builds/slave/test', 03:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:25 INFO - 'SHELL': '/bin/bash', 03:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:25 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:25 INFO - 'USER': 'cltbld', 03:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:25 INFO - 'XPC_FLAGS': '0x0', 03:21:25 INFO - 'XPC_SERVICE_NAME': '0', 03:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ri220d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-z6LuOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:25 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-EyINfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-2akyXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ok2m8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-GvPVv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-8ymKuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-i8gfb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Dpa9o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-mFh749-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Hfs1YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:26 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-10cY44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-_Wvie7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-whgGe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-bZFOjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-GeJ8lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-LxvVpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:27 INFO - Running setup.py install for manifestparser 03:21:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:27 INFO - Running setup.py install for mozcrash 03:21:27 INFO - Running setup.py install for mozdebug 03:21:27 INFO - Running setup.py install for mozdevice 03:21:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Running setup.py install for mozfile 03:21:28 INFO - Running setup.py install for mozhttpd 03:21:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Running setup.py install for mozinfo 03:21:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Running setup.py install for mozInstall 03:21:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:28 INFO - Running setup.py install for mozleak 03:21:28 INFO - Running setup.py install for mozlog 03:21:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Running setup.py install for moznetwork 03:21:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Running setup.py install for mozprocess 03:21:29 INFO - Running setup.py install for mozprofile 03:21:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Running setup.py install for mozrunner 03:21:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:29 INFO - Running setup.py install for mozscreenshot 03:21:29 INFO - Running setup.py install for moztest 03:21:29 INFO - Running setup.py install for mozversion 03:21:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:30 INFO - Cleaning up... 03:21:30 INFO - Return code: 0 03:21:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:30 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 03:21:30 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 03:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:30 INFO - 'HOME': '/Users/cltbld', 03:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:30 INFO - 'LOGNAME': 'cltbld', 03:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:30 INFO - 'MOZ_NO_REMOTE': '1', 03:21:30 INFO - 'NO_EM_RESTART': '1', 03:21:30 INFO - 'PAGER': '/bin/cat', 03:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:30 INFO - 'PWD': '/builds/slave/test', 03:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:30 INFO - 'SHELL': '/bin/bash', 03:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:30 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:30 INFO - 'USER': 'cltbld', 03:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:30 INFO - 'XPC_FLAGS': '0x0', 03:21:30 INFO - 'XPC_SERVICE_NAME': '0', 03:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-zuTRE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:30 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)) 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-0u9JhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:30 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)) 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-MMyfPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:30 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)) 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-wF6o6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:30 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)) 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-GRAH_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:30 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)) 03:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1vewvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-peY6Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-3yJJ74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-oQHV9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-mzcWZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZXOVwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-JJUVZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-brYhGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-MyMH_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:31 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)) 03:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1KzqQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:32 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)) 03:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-HgXxzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:32 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)) 03:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-2F4fRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:32 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)) 03:21:32 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)) 03:21:32 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)) 03:21:32 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)) 03:21:32 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)) 03:21:32 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)) 03:21:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:34 INFO - Downloading blessings-1.6.tar.gz 03:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:34 INFO - Installing collected packages: blessings 03:21:34 INFO - Running setup.py install for blessings 03:21:34 INFO - Successfully installed blessings 03:21:34 INFO - Cleaning up... 03:21:35 INFO - Return code: 0 03:21:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:35 INFO - Reading from file tmpfile_stdout 03:21:35 INFO - Current package versions: 03:21:35 INFO - blessings == 1.6 03:21:35 INFO - blobuploader == 1.2.4 03:21:35 INFO - docopt == 0.6.1 03:21:35 INFO - manifestparser == 1.1 03:21:35 INFO - mozInstall == 1.12 03:21:35 INFO - mozcrash == 0.17 03:21:35 INFO - mozdebug == 0.1 03:21:35 INFO - mozdevice == 0.48 03:21:35 INFO - mozfile == 1.2 03:21:35 INFO - mozhttpd == 0.7 03:21:35 INFO - mozinfo == 0.9 03:21:35 INFO - mozleak == 0.1 03:21:35 INFO - mozlog == 3.1 03:21:35 INFO - moznetwork == 0.27 03:21:35 INFO - mozprocess == 0.22 03:21:35 INFO - mozprofile == 0.28 03:21:35 INFO - mozrunner == 6.11 03:21:35 INFO - mozscreenshot == 0.1 03:21:35 INFO - mozsystemmonitor == 0.0 03:21:35 INFO - moztest == 0.7 03:21:35 INFO - mozversion == 1.4 03:21:35 INFO - psutil == 3.1.1 03:21:35 INFO - requests == 1.2.3 03:21:35 INFO - wsgiref == 0.1.2 03:21:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:35 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')]} 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 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')]} 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:35 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 03:21:35 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 03:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:35 INFO - 'HOME': '/Users/cltbld', 03:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PAGER': '/bin/cat', 03:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:35 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - 'XPC_FLAGS': '0x0', 03:21:35 INFO - 'XPC_SERVICE_NAME': '0', 03:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 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)) 03:21:35 INFO - Cleaning up... 03:21:35 INFO - Return code: 0 03:21:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:35 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')]} 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 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')]} 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:35 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 03:21:35 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 03:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:35 INFO - 'HOME': '/Users/cltbld', 03:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PAGER': '/bin/cat', 03:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:35 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - 'XPC_FLAGS': '0x0', 03:21:35 INFO - 'XPC_SERVICE_NAME': '0', 03:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:35 INFO - Downloading/unpacking jsonschema 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:38 INFO - Downloading/unpacking functools32 (from jsonschema) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:38 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:38 INFO - warning: no files found matching '*.txt' 03:21:38 INFO - no previously-included directories found matching 'build' 03:21:38 INFO - no previously-included directories found matching 'dist' 03:21:38 INFO - no previously-included directories found matching '.git*' 03:21:38 INFO - Installing collected packages: jsonschema, functools32 03:21:38 INFO - Running setup.py install for functools32 03:21:39 INFO - warning: no files found matching '*.txt' 03:21:39 INFO - no previously-included directories found matching 'build' 03:21:39 INFO - no previously-included directories found matching 'dist' 03:21:39 INFO - no previously-included directories found matching '.git*' 03:21:39 INFO - Successfully installed jsonschema functools32 03:21:39 INFO - Cleaning up... 03:21:39 INFO - Return code: 0 03:21:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:39 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 0x102695a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd3d81580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, '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.bQ94ejlhrk/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.ODwcHnaU19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 03:21:39 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 03:21:39 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 03:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:39 INFO - 'HOME': '/Users/cltbld', 03:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:39 INFO - 'LOGNAME': 'cltbld', 03:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:39 INFO - 'MOZ_NO_REMOTE': '1', 03:21:39 INFO - 'NO_EM_RESTART': '1', 03:21:39 INFO - 'PAGER': '/bin/cat', 03:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:39 INFO - 'PWD': '/builds/slave/test', 03:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:39 INFO - 'SHELL': '/bin/bash', 03:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:21:39 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:39 INFO - 'USER': 'cltbld', 03:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:39 INFO - 'XPC_FLAGS': '0x0', 03:21:39 INFO - 'XPC_SERVICE_NAME': '0', 03:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:39 INFO - Downloading/unpacking flake8 03:21:39 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 03:21:39 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 03:21:39 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 03:21:39 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 03:21:39 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 03:21:39 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 03:21:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:21:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:21:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:21:43 INFO - no previously-included directories found matching 'docs/_build' 03:21:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:21:43 INFO - Running setup.py install for pep8 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:21:43 INFO - no previously-included directories found matching 'docs/_build' 03:21:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:21:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:21:43 INFO - Cleaning up... 03:21:43 INFO - Return code: 0 03:21:43 INFO - Running post-action listener: _resource_record_post_action 03:21:43 INFO - Running post-action listener: _start_resource_monitoring 03:21:43 INFO - Starting resource monitoring. 03:21:43 INFO - ##### 03:21:43 INFO - ##### Running install step. 03:21:43 INFO - ##### 03:21:43 INFO - Running pre-action listener: _resource_record_pre_action 03:21:43 INFO - Running main action method: install 03:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:43 INFO - Reading from file tmpfile_stdout 03:21:43 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:43 INFO - Reading from file tmpfile_stdout 03:21:43 INFO - Output received: 03:21:43 INFO - Usage: mozinstall [options] installer 03:21:43 INFO - Options: 03:21:43 INFO - -h, --help show this help message and exit 03:21:43 INFO - -d DEST, --destination=DEST 03:21:43 INFO - Directory to install application into. [default: 03:21:43 INFO - "/builds/slave/test"] 03:21:43 INFO - --app=APP Application being installed. [default: firefox] 03:21:43 INFO - mkdir: /builds/slave/test/build/application 03:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:22:15 INFO - Reading from file tmpfile_stdout 03:22:15 INFO - Output received: 03:22:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:15 INFO - Running post-action listener: _resource_record_post_action 03:22:15 INFO - ##### 03:22:15 INFO - ##### Running run-tests step. 03:22:15 INFO - ##### 03:22:15 INFO - Running pre-action listener: _resource_record_pre_action 03:22:15 INFO - Running main action method: run_tests 03:22:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:15 INFO - 2016-05-19 03:22:15.889 screenresolution[2456:23910] starting screenresolution argv=screenresolution get 03:22:15 INFO - 2016-05-19 03:22:15.891 screenresolution[2456:23910] Display 0: 1600x1200x32@60 03:22:15 INFO - 2016-05-19 03:22:15.897 screenresolution[2457:23912] starting screenresolution argv=screenresolution list 03:22:15 INFO - Available Modes on Display 0 03:22:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:16 INFO - Intel Iris: 03:22:16 INFO - Chipset Model: Intel Iris 03:22:16 INFO - Type: GPU 03:22:16 INFO - Bus: Built-In 03:22:16 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:16 INFO - Vendor: Intel (0x8086) 03:22:16 INFO - Device ID: 0x0a2e 03:22:16 INFO - Revision ID: 0x0009 03:22:16 INFO - Displays: 03:22:16 INFO - Display: 03:22:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:16 INFO - Display Serial Number: 1600x1200 60 03:22:16 INFO - Main Display: Yes 03:22:16 INFO - Mirror: Off 03:22:16 INFO - Online: Yes 03:22:16 INFO - Rotation: Supported 03:22:16 INFO - Return code: 0 03:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:16 INFO - Python 2.7.3 03:22:16 INFO - Return code: 0 03:22:16 INFO - grabbing minidump binary from tooltool 03:22:16 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')]} 03:22:16 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 0x102534e00>, '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 0x10239ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:16 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 03:22:16 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 03:22:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:16 INFO - Return code: 0 03:22:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:22:17 INFO - Return code: 0 03:22:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 03:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --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 03:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bQ94ejlhrk/Render', 03:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:17 INFO - 'HOME': '/Users/cltbld', 03:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:17 INFO - 'LOGNAME': 'cltbld', 03:22:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:17 INFO - 'MOZ_NO_REMOTE': '1', 03:22:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:17 INFO - 'NO_EM_RESTART': '1', 03:22:17 INFO - 'PAGER': '/bin/cat', 03:22:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:17 INFO - 'PWD': '/builds/slave/test', 03:22:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:17 INFO - 'SHELL': '/bin/bash', 03:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ODwcHnaU19/Listeners', 03:22:17 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 03:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:17 INFO - 'USER': 'cltbld', 03:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:17 INFO - 'XPC_FLAGS': '0x0', 03:22:17 INFO - 'XPC_SERVICE_NAME': '0', 03:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 03:22:17 INFO - mozversion application_buildid: 20160519020753 03:22:17 INFO - mozversion application_changeset: ca41d290daa081865b50f2434aabe15c9fd7ae66 03:22:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:17 INFO - mozversion application_name: Firefox 03:22:17 INFO - mozversion application_remotingname: firefox-dev 03:22:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:17 INFO - mozversion application_vendor: Mozilla 03:22:17 INFO - mozversion application_version: 48.0a2 03:22:17 INFO - mozversion platform_buildid: 20160519020753 03:22:17 INFO - mozversion platform_changeset: ca41d290daa081865b50f2434aabe15c9fd7ae66 03:22:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:17 INFO - mozversion platform_version: 48.0a2 03:22:17 INFO - using testdate: 1463653337 03:22:17 INFO - actual date: 1463653337 03:22:17 INFO - starting webserver on 'localhost:56807' 03:22:17 INFO - SUITE-START | Running 2 tests 03:22:17 INFO - TEST-START | dromaeo_css 03:22:17 INFO - operating with platform_type : mac_ 03:22:17 INFO - Initialising browser for dromaeo_css test... 03:22:17 INFO - TEST-INFO | started process 2475 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmprgSxoR/profile http://localhost:56807/getInfo.html) 03:22:19 INFO - PROCESS | 2475 | __metrics Screen width/height:1600/1200 03:22:19 INFO - PROCESS | 2475 | colorDepth:24 03:22:19 INFO - PROCESS | 2475 | Browser inner width/height: 1024/689 03:22:19 INFO - PROCESS | 2475 | __metrics 03:22:19 INFO - PROCESS | 2475 | 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" 03:22:19 ERROR - PROCESS | 2475 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:22:20 INFO - TEST-INFO | 2475: exit 0 03:22:20 INFO - Browser initialized. 03:22:20 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:20 INFO - TEST-INFO | started process 2477 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmprgSxoR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:32 INFO - PROCESS | 2477 | RSS: Main: 211058688 03:22:32 INFO - PROCESS | 2477 | 03:23:24 INFO - PROCESS | 2477 | 1463653404065 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] 03:25:34 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56807/tests/dromaeo/cssquery-ext.html) 03:25:35 INFO - PROCESS | 2477 | RSS: Main: 359940096 03:25:35 INFO - PROCESS | 2477 | 03:28:38 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-ext.html (next: http://localhost:56807/tests/dromaeo/cssquery-jquery.html) 03:28:39 INFO - PROCESS | 2477 | RSS: Main: 351309824 03:28:39 INFO - PROCESS | 2477 | 03:31:41 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56807/tests/dromaeo/cssquery-mootools.html) 03:31:42 INFO - PROCESS | 2477 | RSS: Main: 355831808 03:31:42 INFO - PROCESS | 2477 | 03:34:44 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56807/tests/dromaeo/cssquery-prototype.html) 03:34:45 INFO - PROCESS | 2477 | RSS: Main: 356294656 03:34:45 INFO - PROCESS | 2477 | 03:37:48 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56807/tests/dromaeo/cssquery-yui.html) 03:37:48 INFO - PROCESS | 2477 | RSS: Main: 331485184 03:37:48 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | Cycle 1(1): loaded http://localhost:56807/tests/dromaeo/cssquery-yui.html (next: http://localhost:56807/tests/dromaeo/cssquery-dojo.html) 03:40:51 INFO - PROCESS | 2477 | RSS: Main: 363646976 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | __start_tp_report 03:40:51 INFO - PROCESS | 2477 | _x_x_mozilla_page_load 03:40:51 INFO - PROCESS | 2477 | _x_x_mozilla_page_load_details 03:40:51 INFO - PROCESS | 2477 | |i|pagename|runs| 03:40:51 INFO - PROCESS | 2477 | |0;dojo.html;3350.2294472271383;3403.710684591808;3438.776479528829;3443.741719371047;3473.4442489201733;5700.9714951425185;5715.799947001856;5717.313922329316;5725.312962444502;5729.942700572993;5723.942760572393;5736.8852622947625;5748.913766293509;5753.367129615739;5759.798407055754;5034.24486327052;5084.3644544431945;5126.410462796789;5245.527902488775;5254.81608143715;5208.817691380802;5209.036328279261;5216.400113986902;5218.1650935850275;5221.817236396727;5126.102931986906;5167.974160129194;5199.038177937094;5213.6089793265655;5215.582753379739;5158.896822063556;5227.634065615409;5229.111051121318;5233.058049551079;5277.076511610453;5066.961272939038;5071.087204303224;5095.261187127846;5157.922631160535;5163.096458119851;5548.667079975215;5590.91613625796;5592.105263157875;5593.440655934414;5600.5799565032785;6194.070889366586;6197.977333739927;6211.16149319845;6225.097360882647;6236.501079913642;6080.55132462734;6151.446369826737;6224.09750586167;6227.688615569204;6235.251769787609;5764.798232061941;5782.392848750821;5796.768129274782;5812.680302583314;5836.620619659709;6225.501959843202;6227.09707092469;6232.875342493216;6243.531735119794;6246.937530624727;5955.047192449274;6001.549883758736;6104.664243466652;6121.663308517986;6122.703114648804;6069.544784141207;6071.332153463116;6081.787137450168;6086.2087928569;6132.5707200495535;5948.524118070544;5961.493273071837;5969.3732158122775;5973.940260597338;5978.103284507359;5963.075723262901;6107.267127944586;6132.50939924805;6150.892839288881;6167.321594624587;4834.20235661112;4869.220924652039;4875.366202393666;4902.289168070681;4910.582600479;4359.978200108979;4387.363832244307;4392.3411488277015;4406.140802543473;4419.712718673276;5562.471565201299;5675.318961724537;5686.289213848269;5686.601937864392;5698.0598201296325;6143.788236234952;6195.566310358232;6197.194364732556;6211.875762484725;6216.564840461125;5742.31092268922;5743.425657434307;5757.13642953552;5758.992176369119;5759.740811663485;4469;5359.892802143935;5382.2464854920345;5388.703621300788;5393.298871146804;6211.534134939898;6287.434130928238;6292.370762923671;6302.936970630327;6307.558470906993;6166.105914642441;6182.350853160354;6191.16419283409;6197.5351848610635;6198.969005154945;6213.813585592439;6314.55798094129;6321.1782468278825;6330.177076979963;6333.303336633059;4954.702717836942;6074.878502430015;6103.11504831788;6160.876782464416;6178.196834411498;6151.815445536641;6196.225471816023;6196.628202307876;6223.097650840512;6225.533085018732;5944.9405505946115;6063.484603808549;6082.9354862897935;6085.57400981927;6107.236595425572;5896.705164741831;5977.820665380045;6117.522930757332;6119.776756597957;6142.222533142175;5879.441453061952;5950.077737950624;5959.523238140851;5964.254468191476;5967.522598192221;5965.2245208124405;6063.363346848694;6115.510759139169;6141.723622436912;6144.893919094606;5752;5884.499817515559;5914.852128696817;5916.497097746567;5917.142014407973 03:40:51 INFO - PROCESS | 2477 | |1;ext.html;499.8250612285703;518.2252532463966;549.3901769036366;588.7497813429292;614.8647297594531;31049.379012419737;31171.532427013557;37531.62468375315;38893.61106388935;39010;41800.37299440511;42176.94557636061;42301.942451438656;42437;42444.78777606115;8562.87155692662;9521.6191352346;9651.276154288422;9669.419834809898;9686.564104615309;8626.482411055356;11492;11699.824502632424;11716.707082322924;11723.531058757639;11453.770924581504;11501.539938402453;11533.192676512648;11534.365609891453;11590.59432919848;8721.30229581632;8919.68781092665;8928.33037522182;11416.657500274963;11512.65462036136;8768.693095741619;11444;11692.415379231004;11696.23974441667;11708.414579271002;38557.61442385568;39030.21939561193;39071.21857562861;39117.60882391196;39271.41092883609;3244.0916543367894;3260.5924259467565;3289.1612638777196;3339.4990751387245;3495.930081398383;27565.75954082051;27930.441391172062;28099;28491.572626410412;29099;2710.4037111835364;2776.0422654184276;2792.4833905727505;2907.752841008517;2995.8352290624007;496840;497860;498617;498659;500166;30085.849570752005;36606.45090323647;58005.96979105711;58134.12798808021;58866.70566647225;35776;35902.820485897406;35919.102022449646;35970;36018.27963440769;15180.696386072217;15422.46021389246;15433.38266469351;15447.922760386125;15452.38190472391;11790.705232369259;12007.399630018464;12021.699457513458;12027.977621902237;12861.099723019486;8105.432619716681;8189.22202390772;8196.467229630054;8288.544130072903;8292.460990035628;2934.3544420227518;2948.513495273256;3063.417950589381;3299.703026727606;3436.60479044913;811.3590263691735;928.9767755806024;950.2493030505962;989.4904124375952;1000.9690019280154;28001.439971200463;29583.85208073989;29700.811967521488;29912;30609.69390306113;792.9960350198212;1083.653230966099;1183.5384200161773;1225.2587184753097;1263.77252094622;13428.798568021488;14283.42866285337;14616.269186540843;14693.897957653218;15057.849421505645;625821;632341;633693;633940;636009;57336;58582.70708646515;58644.7067764667;58666.41333586695;58792.412075878696;1347.1176379471462;1381.5855243426731;1383.5019393018706;1383.5710929611853;1499.9850001499844;22731.772682272967;28943.842246309912;29029.854850725613;29384.55923161197;29741.107766767032;47989.5201047985;48291;48781.5121848777;48810.755946220044;49012.26481602708;427889;428700;430332;430834;430970;23168.18911338095;23608.881955590114;26508.8674556626;26687;27230.455390892468;41528.37707434451;42909.57090429056;42916.78541607272;43177.78411107924;43398.56601433945;35580.64419355773;36037.27925441529;36166.63833361633;36596.63403365932;36958;36573.08567285733;36628.08429789277;36670.633293667786;36898.27576753045;37366;35434.11414714653;35597.46603800997;35942;36074.78504299203;36101.458478122324;26917.596236056866;32065.198370040936;32191;32686.019419417455;33040;24725;24767;24872.502549949262;24914.75085249197;25061.74938250667 03:40:51 INFO - PROCESS | 2477 | |2;jquery.html;351.9120219945014;395.19972056585414;397.1203783619284;402.33815373710263;414.93775933609953;19934.302299419523;21488.140474381005;22237.332880013626;22261.554768904654;22295.99668014939;21644.8917755411;21675.891620541875;21981.010854511544;22017.33947981559;22068.33794986149;5722.113072473763;6050.606710563821;6054.6367217966845;6071.939280607204;6680.699368528428;6526.902096468533;6585.967070164643;6622.503312251576;6630.900536491957;6633.071370008179;6297.622142671455;6544.345565443466;6573.474122070223;6577.046328282396;6588.5175835436885;5130.948690513103;6120.571559990803;6154.5076393888385;6158.753649854045;6159.7536098556;5171.22431635254;5769.942300576982;5778.27771528559;5781.682007489587;5790.536757059425;26327.868360658267;26522;26689.466210675677;27042.5943610846;27104.45791084187;1869.44851268876;2196.736391632998;2201.460642142685;2350.576896158691;3063.540468929656;14665.26673666323;15115.16866572338;15378.538643840702;15615.765763513557;15733.44932927342;1375.1542801177204;1721.9139043047799;1771.4419957713408;1793.5695433096093;1824.8905065696101;78142;98993.5150972736;101609;111241.44379278133;114056;10965.616203433001;11372.829407558893;11373.090152787758;11500.079993600491;11554.76890462203;12630.494780208688;13033.804492932613;13089.869101309056;14409.42362305517;14436.927815361067;8339.249467547828;8703.651853925772;8818.382713210141;8888.422252553562;8948.955255223813;8953.910460895308;9071.410358326688;9079.228265597367;9148.817023659489;9166.26669866409;5910.172575839386;6722.193336799615;6791.694373753193;6926.61903595297;6955.6869940852785;1890.6785846406144;1916.9808301916803;1950.405126436422;1962.6172896285088;1963.1754663041556;876.7019213467452;932.440535678601;1027.1474676018886;1058.1587637827945;1061.474570087796;16342.591435214215;17969.550761231072;20908.954552272324;22295;22387.88806055959;940.9743379716142;945.7210123013728;954.5084281594984;957.6025949230991;969.709087273815;10784.299020563638;11178.608748693756;11240.550377984953;11294.096472282197;11314.886851131549;568892.3110768922;569340;588167;659224;660999.3400395992;123442;137280.31359843136;138296;139928;149565.25217373844;396.738152819138;476.3759475087646;482.24047125776343;482.8720389096892;483.5430518160375;9298.163165314883;10405.479726013518;10441.373517588969;11552.6534203974;11656;53595.6601084976;54419.18371224514;54676;54699;55246.44753552413;80019;80074.59962700149;81541.77687334576;82178.76731849146;84216;8272.420930535045;8313.33493320545;8421.578921054046;8429.199226073513;8662.82674346497;10028.69913902584;12475.5009799607;12691.682707932376;12930.482780688668;13346.799798003232;9775.80448391014;9879;10591.89408105909;10677.93220677926;11318.207725459139;10353.896461035294;10778.08386287143;11097.445127743742;11335.376554289593;11620.012298954345;11423.657290281304;11439.885601143882;11684.591039313584;11813.586524471602;11898.762024759284;8118.959405202936;8179.754607361789;8296.128906464975;8340.332773378237;8500.234978851933;10101.69694909154;10910.672679819618;11191.496382662963;11257.774844503228;11308.49111789955 03:40:51 INFO - PROCESS | 2477 | |3;mootools.html;1420.5667271482207;1560.758082497213;1578.9763153552694;1587.7935868337115;1984.861059725818;7828.9608551957235;8532.23209911108;8674.91325086749;8675.262602678764;8679.566021698922;8441.746747597566;8460.830783384328;8587.441816281937;8620.439671421353;8626.525541095238;2135.177956486756;2280.498290376123;2416.3113512648897;2437.731849496554;2440.707115146176;2343.121329501437;2346.7887890089887;2349.0368948731025;2349.4713689419827;2353.576356255873;1961.6763234066361;2019.3134334326326;2278.1798552521163;2293.0713061210236;2388.9880550597222;2112.7464704235435;2174.2172817785586;2176.4014895903597;2393.114547617375;2411.40920474484;2168.262790651186;2357.1749887539395;2413.5655581995234;2416.0215112879314;2430.052279610953;8566.18621230982;8663.35024873137;8667.39328247023;8670.002949660806;8679.132086791333;5208.479152084799;5315.0432922074015;5352.170413585901;5358.062339090644;5365.382980957201;3116.875324986998;3482.3035392921515;3538.1331573764337;3620.8913732588067;3667.5965643779164;1021.8875923648393;1047.3087762077068;1054.8101341758481;1058.7035630023606;1081.2215205052428;10221;11999.400029998553;12012.63962081139;12020.338881361522;12034;4659.883502912454;4679.157751604744;4767.356406885096;4795.160846851787;4838.491958344393;10101.898981010243;10119.392836429837;10124.949375253223;10131.594736210616;10135.594576217016;5469.453054694499;5471.480209380103;5477.589180811455;5478;5478.972605137029;4360.389545463638;4522.276435770326;4729.337892695025;4838.685485443435;4959.677620954626;3474.2356681530023;3927.9607203928167;3943.8028098594955;4058.1072164123843;4080.163566468867;1317.9324746954996;1343.0934119469318;1343.8656134386483;1358.877701006914;1359.3882752761297;820.8112134209165;864.4294765454896;902.4449963272672;928.5496534180917;935.3265648733037;5761.39505351932;5766.913496297559;5839.532837373;5879.265091863517;6031.517478601701;456.3474231848489;460.9861704148881;462.7732411118595;874.2699845628971;1001.3090967232587;3789.4505296732373;3867.3812190049457;3928.724989250782;3929.410588411704;3940.940885886714;10305.381677099398;10738.624148154777;10802.51388687511;10875.673729788119;10876.129909607213;10399.740006499898;10481.213908956859;10526.526306316235;10720.981506756845;10736.30214036085;7646.311831935152;7769.494982826098;7822.882656760153;7831.530108193527;7861.960690196627;4819.493953135011;5278.9472105278455;5301.363836339509;5392.5416339611575;5480.506754392124;8795.384323097322;8948.328875334506;9058.501782401767;9062.184403403462;9063.592138353659;8942.597583108645;9170.128837760401;9187.678431254873;9401.85897211556;9433.669821556212;6747.797566073025;6916.827079322856;7217.206107328085;7332.30343117403;7469.1037075551285;8137.471064380797;8137.715179968673;8212.17878212174;8226.465279756796;8234.505929644241;7203.8559228816175;7282.817929551804;7321.341079302888;7439.293267139613;7446.73936412223;7036.401905837856;7167.641617919187;7172.605506697182;7203.459740519586;7216.96391518039;6824.727010919508;6903.1716194057035;6909;6914.896276555956;6915.757948471779;3799.7530160539477;4691.108689349013;4724.929126063043;4857.028594281087;4936.555709986119;4257.515671608377;4540.273556230987;4810.687305325283;4895.314655948241;4948.109340318777 03:40:51 INFO - PROCESS | 2477 | |4;prototype.html;316.61689355879383;319.59411547334884;326.43363763869684;328.02739876266867;331.8539842469315;6681.732730690766;6704.061431399602;6704.664766761667;6710.36251556102;6723.092382528357;6568.113304703864;6653.234877989033;6714.462842972563;6722.991551267312;6754.392104710575;3986;4695.5539223773685;4714.175019371613;4802.255650374198;4814.711117332954;3969.0672691917375;4376.234159022174;4727.267273572602;4806.4232292125;4833.347498087766;4054.3918412238104;4733.66864319496;4830.9275360869615;4838.346823178861;4840.830570930018;4876.097921884445;4893.608511319104;4896.265560165968;4905.23968784839;4906.141425250603;3992.8203230854683;4031.8588849390426;4059.6346328830546;4067.715259931806;4074.449949256842;6188.350223226541;6324.33594472583;6432.324605916405;6437.388448097423;6450.580712253735;5796.304443466769;5797.449242321973;5807.767689292423;5830.387809280007;5835.941640583582;7038.542494737826;7057.717691292342;7061.611611361373;7077.292270772881;7081.92918070823;3843.98078009611;4378.912421751579;4672.229082201433;4678.719276843401;4679.15775160471;7401.666924988334;7471.066116735408;7495.550266983998;7511.248875112499;7539.982102416158;4644.489106198383;4648.140094082605;4651.348811166439;4653.348531205634;4653.604443622262;3920.313945059603;4737.976310118427;4738.957429365534;4743.217369134123;4755.881102972384;5612.775488980476;5623.072193088176;5629.521490673257;5630.071038278638;5630.971845140748;6882.414994725505;7157.57054576727;7163.10461192351;7172.748953786592;7202.49582529218;1622.399712106528;1624.1473226556152;1634.3707672546154;1642.4169419856019;1653.0329757092236;1019.1846522781744;1019.7399663086014;1036.2435422141878;1040.2094408249784;1044.9007344302279;6705.664716764142;6710.496712746563;6717.193936727624;6720.395164435225;6721.294264102199;7144.249853765384;7229.674664640103;7288.344049035612;7358.264173582599;7372.299631534996;6602.933970660328;6653.534252602271;6653.933460665428;6656.400923916871;6778.712044711489;3239.7084262416497;3900.5124359455067;3906.199229158016;3930.567637559866;3949.960500395017;7708.075030996316;7720.691172353156;7740.6129693515095;7752.496087754278;7762.7671169865;7340;7614.771556853303;7617.162112167657;7629.389648828081;7630.969819074465;6157.753689852445;7673.53958762476;7687.461877668509;7705.306522412786;7715.151333353327;7376.7786966391095;7383.150937642025;7387.150477695134;7389.667464964197;7394.482386233127;6482.189726284215;6856.251655827732;7010;7288.927110728825;7297.306755858185;6474.805755827333;6553.541252112306;7479.17729049815;7689.269519395649;7719.652615632423;4527.411436513232;4645.163870503342;4645.372874661877;4646.372739680235;4653.418322709661;7382.815429614087;7394.704211831681;7396.482246242711;7400.222976587596;7415;5876.970615146897;6908.730797562788;7109.964450177716;7129.251428599922;7144.714211431485;7003.774339490466;7125.287471252833;7138.14342278909;7143.535670181422;7154.427645788429;6409.2949775525585;6902.413294869792;6957.686904089227;6969.059177011039;6969.825754356181;6180.845478862884;6787.762428314985;7122.608256545733;7128.073350464407;7130.679119439534;6450.967845144754;6738.427233685192;6969.825754356181;6986.231514533499;6991.2659170788365 03:40:51 INFO - PROCESS | 2477 | |5;yui.html;672.4553111979297;697.5919087333909;700.3591713582073;702.725936884615;754.9698012079516;15030.17334046627;15096.396144154234;15216.69566608667;15233.390664373426;15254.542363729079;13490.325483725823;13548.119372240797;13892.652683682887;15947.20263986799;15962.76055859163;748.6967778049899;908.8909330880304;929.948852813095;930.4231376546551;931.5621657820814;715.3883429667637;736.9410447164214;737.2590546500759;737.3437640499959;737.4948160510068;955.2405837359316;981.102291403365;981.4356744871692;981.504340308147;982.6708052802284;967.7000129959665;969.6121551379434;970.587500312368;971.3055165556625;971.4365667912593;558.6815515156362;709.1242315740056;715.152544235083;716.3159182980241;717.042747931512;15188.924055379763;15279.465218717403;15293.082415055133;15301;15301.923490382476;12238.938805306005;12416.317102559356;12535.31055791931;12609.810852837214;12857.549985750547;9787.168090712297;9926.007399260088;9977.401355918668;10255.384676919408;10476.0571548561;3378.6621337866263;3419.3674170278578;3428.862845486178;3465.8960231193105;3511.7015053720465;77586.61206693987;77989.61005194938;78747;80119;80391.59804200941;8398.958005210057;9293.628254869729;9326.673566425143;9387;9388.624455021723;21071.157153713986;21276.255331063338;21284.574308514053;21308.36074917755;21315.46711332198;7576.772696819105;7605.809854753565;7701.845963080819;7920.168382319889;7939.726463104312;4073.918521629551;4147.52303485097;4154.667626589926;4273;4336.980809509762;3630.237650093457;3696.8706095286534;3697.519322488113;3704.666580007812;3706.258748250361;642.2966851297822;659.1924892007253;663.0849427789619;665.8302132956065;685.2633419074415;608.8934436473688;613.6440864298696;615.4338009031702;616.6546733828981;628.0673200297555;6741.325867413219;7869.095054068741;8198.713045043394;8287.58562071906;8576.699815506427;581.3261378516762;588.2176704982363;593.6942474625571;596.3053043204618;606.805822136912;3397.8301084946147;3789.147441825567;3898.1229223424502;3909.100906791397;3931.1548017175874;88771;90980.72548186348;91347;92933.60599591011;93791;77292;77670.6116469414;77803.61098194473;78039;78680;68739.81341679273;69205.30794692089;69249.65375173192;69847.3015269851;69910;7593.468457207942;7828.725994590162;7841.333486653698;7860.978072850721;7969.880451793112;49447.75276123596;49485.75257123692;49764.75117624389;50047.499525004285;50203.49796502134;63385.612222961565;64850;67697;67714.66142669255;70562;9780.755481112745;9790.46152461593;9958.751031224278;10014;10169.711490466088;8719.040905500264;10613.787724245627;10753.89246107529;10783.406912619881;11110.666679999613;10356.533955971849;10386.169106471616;10425.739356516147;10553.947230264106;10704;10291.022352876465;10298.22763292741;10305.845412318971;10308.96910308963;10322.948385258325;10177.440240786827;10204.081632653097;10239.436830974368;10246.38521688701;10246.743831404274;8574.571271436278;8854.911450885666;8886;8937.910620893968;9324.254059675346;9425;9626.566804493954;9639.132478077006;9646.710598682052;9908.603655853685 03:40:51 INFO - PROCESS | 2477 | __end_tp_report 03:40:51 INFO - PROCESS | 2477 | __start_cc_report 03:40:51 INFO - PROCESS | 2477 | _x_x_mozilla_cycle_collect,1429 03:40:51 INFO - PROCESS | 2477 | __end_cc_report 03:40:51 INFO - PROCESS | 2477 | __startTimestamp1463654451764__endTimestamp 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | ------- Summary: start ------- 03:40:51 INFO - PROCESS | 2477 | Number of tests: 6 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#0] dojo.html Cycles:165 Average:5695.75 Median:5917.14 stddev:627.18 (10.6%) stddev-sans-first:601.51 03:40:51 INFO - PROCESS | 2477 | Values: 3350.2 3403.7 3438.8 3443.7 3473.4 5701.0 5715.8 5717.3 5725.3 5729.9 5723.9 5736.9 5748.9 5753.4 5759.8 5034.2 5084.4 5126.4 5245.5 5254.8 5208.8 5209.0 5216.4 5218.2 5221.8 5126.1 5168.0 5199.0 5213.6 5215.6 5158.9 5227.6 5229.1 5233.1 5277.1 5067.0 5071.1 5095.3 5157.9 5163.1 5548.7 5590.9 5592.1 5593.4 5600.6 6194.1 6198.0 6211.2 6225.1 6236.5 6080.6 6151.4 6224.1 6227.7 6235.3 5764.8 5782.4 5796.8 5812.7 5836.6 6225.5 6227.1 6232.9 6243.5 6246.9 5955.0 6001.5 6104.7 6121.7 6122.7 6069.5 6071.3 6081.8 6086.2 6132.6 5948.5 5961.5 5969.4 5973.9 5978.1 5963.1 6107.3 6132.5 6150.9 6167.3 4834.2 4869.2 4875.4 4902.3 4910.6 4360.0 4387.4 4392.3 4406.1 4419.7 5562.5 5675.3 5686.3 5686.6 5698.1 6143.8 6195.6 6197.2 6211.9 6216.6 5742.3 5743.4 5757.1 5759.0 5759.7 4469.0 5359.9 5382.2 5388.7 5393.3 6211.5 6287.4 6292.4 6302.9 6307.6 6166.1 6182.4 6191.2 6197.5 6199.0 6213.8 6314.6 6321.2 6330.2 6333.3 4954.7 6074.9 6103.1 6160.9 6178.2 6151.8 6196.2 6196.6 6223.1 6225.5 5944.9 6063.5 6082.9 6085.6 6107.2 5896.7 5977.8 6117.5 6119.8 6142.2 5879.4 5950.1 5959.5 5964.3 5967.5 5965.2 6063.4 6115.5 6141.7 6144.9 5752.0 5884.5 5914.9 5916.5 5917.1 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#1] ext.html Cycles:180 Average:64003.42 Median:35686.73 stddev:140969.98 (395.0%) stddev-sans-first:141284.80 03:40:51 INFO - PROCESS | 2477 | Values: 499.8 518.2 549.4 588.7 614.9 31049.4 31171.5 37531.6 38893.6 39010.0 41800.4 42176.9 42301.9 42437.0 42444.8 8562.9 9521.6 9651.3 9669.4 9686.6 8626.5 11492.0 11699.8 11716.7 11723.5 11453.8 11501.5 11533.2 11534.4 11590.6 8721.3 8919.7 8928.3 11416.7 11512.7 8768.7 11444.0 11692.4 11696.2 11708.4 38557.6 39030.2 39071.2 39117.6 39271.4 3244.1 3260.6 3289.2 3339.5 3495.9 27565.8 27930.4 28099.0 28491.6 29099.0 2710.4 2776.0 2792.5 2907.8 2995.8 496840.0 497860.0 498617.0 498659.0 500166.0 30085.8 36606.5 58006.0 58134.1 58866.7 35776.0 35902.8 35919.1 35970.0 36018.3 15180.7 15422.5 15433.4 15447.9 15452.4 11790.7 12007.4 12021.7 12028.0 12861.1 8105.4 8189.2 8196.5 8288.5 8292.5 2934.4 2948.5 3063.4 3299.7 3436.6 811.4 929.0 950.2 989.5 1001.0 28001.4 29583.9 29700.8 29912.0 30609.7 793.0 1083.7 1183.5 1225.3 1263.8 13428.8 14283.4 14616.3 14693.9 15057.8 625821.0 632341.0 633693.0 633940.0 636009.0 57336.0 58582.7 58644.7 58666.4 58792.4 1347.1 1381.6 1383.5 1383.6 1500.0 22731.8 28943.8 29029.9 29384.6 29741.1 47989.5 48291.0 48781.5 48810.8 49012.3 427889.0 428700.0 430332.0 430834.0 430970.0 23168.2 23608.9 26508.9 26687.0 27230.5 41528.4 42909.6 42916.8 43177.8 43398.6 35580.6 36037.3 36166.6 36596.6 36958.0 36573.1 36628.1 36670.6 36898.3 37366.0 35434.1 35597.5 35942.0 36074.8 36101.5 26917.6 32065.2 32191.0 32686.0 33040.0 24725.0 24767.0 24872.5 24914.8 25061.7 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#2] jquery.html Cycles:180 Average:35372.26 Median:27073.53 stddev:101804.15 (376.0%) stddev-sans-first:102055.77 03:40:51 INFO - PROCESS | 2477 | Values: 351.9 395.2 397.1 402.3 414.9 19934.3 21488.1 22237.3 22261.6 22296.0 21644.9 21675.9 21981.0 22017.3 22068.3 5722.1 6050.6 6054.6 6071.9 6680.7 6526.9 6586.0 6622.5 6630.9 6633.1 6297.6 6544.3 6573.5 6577.0 6588.5 5130.9 6120.6 6154.5 6158.8 6159.8 5171.2 5769.9 5778.3 5781.7 5790.5 26327.9 26522.0 26689.5 27042.6 27104.5 1869.4 2196.7 2201.5 2350.6 3063.5 14665.3 15115.2 15378.5 15615.8 15733.4 1375.2 1721.9 1771.4 1793.6 1824.9 78142.0 98993.5 101609.0 111241.4 114056.0 10965.6 11372.8 11373.1 11500.1 11554.8 12630.5 13033.8 13089.9 14409.4 14436.9 8339.2 8703.7 8818.4 8888.4 8949.0 8953.9 9071.4 9079.2 9148.8 9166.3 5910.2 6722.2 6791.7 6926.6 6955.7 1890.7 1917.0 1950.4 1962.6 1963.2 876.7 932.4 1027.1 1058.2 1061.5 16342.6 17969.6 20909.0 22295.0 22387.9 941.0 945.7 954.5 957.6 969.7 10784.3 11178.6 11240.6 11294.1 11314.9 568892.3 569340.0 588167.0 659224.0 660999.3 123442.0 137280.3 138296.0 139928.0 149565.3 396.7 476.4 482.2 482.9 483.5 9298.2 10405.5 10441.4 11552.7 11656.0 53595.7 54419.2 54676.0 54699.0 55246.4 80019.0 80074.6 81541.8 82178.8 84216.0 8272.4 8313.3 8421.6 8429.2 8662.8 10028.7 12475.5 12691.7 12930.5 13346.8 9775.8 9879.0 10591.9 10677.9 11318.2 10353.9 10778.1 11097.4 11335.4 11620.0 11423.7 11439.9 11684.6 11813.6 11898.8 8119.0 8179.8 8296.1 8340.3 8500.2 10101.7 10910.7 11191.5 11257.8 11308.5 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#3] mootools.html Cycles:180 Average:5567.23 Median:4802.92 stddev:3131.66 (65.2%) stddev-sans-first:3124.94 03:40:51 INFO - PROCESS | 2477 | Values: 1420.6 1560.8 1579.0 1587.8 1984.9 7829.0 8532.2 8674.9 8675.3 8679.6 8441.7 8460.8 8587.4 8620.4 8626.5 2135.2 2280.5 2416.3 2437.7 2440.7 2343.1 2346.8 2349.0 2349.5 2353.6 1961.7 2019.3 2278.2 2293.1 2389.0 2112.7 2174.2 2176.4 2393.1 2411.4 2168.3 2357.2 2413.6 2416.0 2430.1 8566.2 8663.4 8667.4 8670.0 8679.1 5208.5 5315.0 5352.2 5358.1 5365.4 3116.9 3482.3 3538.1 3620.9 3667.6 1021.9 1047.3 1054.8 1058.7 1081.2 10221.0 11999.4 12012.6 12020.3 12034.0 4659.9 4679.2 4767.4 4795.2 4838.5 10101.9 10119.4 10124.9 10131.6 10135.6 5469.5 5471.5 5477.6 5478.0 5479.0 4360.4 4522.3 4729.3 4838.7 4959.7 3474.2 3928.0 3943.8 4058.1 4080.2 1317.9 1343.1 1343.9 1358.9 1359.4 820.8 864.4 902.4 928.5 935.3 5761.4 5766.9 5839.5 5879.3 6031.5 456.3 461.0 462.8 874.3 1001.3 3789.5 3867.4 3928.7 3929.4 3940.9 10305.4 10738.6 10802.5 10875.7 10876.1 10399.7 10481.2 10526.5 10721.0 10736.3 7646.3 7769.5 7822.9 7831.5 7862.0 4819.5 5278.9 5301.4 5392.5 5480.5 8795.4 8948.3 9058.5 9062.2 9063.6 8942.6 9170.1 9187.7 9401.9 9433.7 6747.8 6916.8 7217.2 7332.3 7469.1 8137.5 8137.7 8212.2 8226.5 8234.5 7203.9 7282.8 7321.3 7439.3 7446.7 7036.4 7167.6 7172.6 7203.5 7217.0 6824.7 6903.2 6909.0 6914.9 6915.8 3799.8 4691.1 4724.9 4857.0 4936.6 4257.5 4540.3 4810.7 4895.3 4948.1 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#4] prototype.html Cycles:180 Average:5763.23 Median:6653.38 stddev:1875.63 (28.2%) stddev-sans-first:1835.80 03:40:51 INFO - PROCESS | 2477 | Values: 316.6 319.6 326.4 328.0 331.9 6681.7 6704.1 6704.7 6710.4 6723.1 6568.1 6653.2 6714.5 6723.0 6754.4 3986.0 4695.6 4714.2 4802.3 4814.7 3969.1 4376.2 4727.3 4806.4 4833.3 4054.4 4733.7 4830.9 4838.3 4840.8 4876.1 4893.6 4896.3 4905.2 4906.1 3992.8 4031.9 4059.6 4067.7 4074.4 6188.4 6324.3 6432.3 6437.4 6450.6 5796.3 5797.4 5807.8 5830.4 5835.9 7038.5 7057.7 7061.6 7077.3 7081.9 3844.0 4378.9 4672.2 4678.7 4679.2 7401.7 7471.1 7495.6 7511.2 7540.0 4644.5 4648.1 4651.3 4653.3 4653.6 3920.3 4738.0 4739.0 4743.2 4755.9 5612.8 5623.1 5629.5 5630.1 5631.0 6882.4 7157.6 7163.1 7172.7 7202.5 1622.4 1624.1 1634.4 1642.4 1653.0 1019.2 1019.7 1036.2 1040.2 1044.9 6705.7 6710.5 6717.2 6720.4 6721.3 7144.2 7229.7 7288.3 7358.3 7372.3 6602.9 6653.5 6653.9 6656.4 6778.7 3239.7 3900.5 3906.2 3930.6 3950.0 7708.1 7720.7 7740.6 7752.5 7762.8 7340.0 7614.8 7617.2 7629.4 7631.0 6157.8 7673.5 7687.5 7705.3 7715.2 7376.8 7383.2 7387.2 7389.7 7394.5 6482.2 6856.3 7010.0 7288.9 7297.3 6474.8 6553.5 7479.2 7689.3 7719.7 4527.4 4645.2 4645.4 4646.4 4653.4 7382.8 7394.7 7396.5 7400.2 7415.0 5877.0 6908.7 7110.0 7129.3 7144.7 7003.8 7125.3 7138.1 7143.5 7154.4 6409.3 6902.4 6957.7 6969.1 6969.8 6180.8 6787.8 7122.6 7128.1 7130.7 6451.0 6738.4 6969.8 6986.2 6991.3 03:40:51 INFO - PROCESS | 2477 | 03:40:51 INFO - PROCESS | 2477 | [#5] yui.html Cycles:180 Average:17995.70 Median:3706.89 stddev:25468.78 (687.1%) stddev-sans-first:25507.01 03:40:51 INFO - PROCESS | 2477 | Values: 672.5 697.6 700.4 702.7 755.0 15030.2 15096.4 15216.7 15233.4 15254.5 13490.3 13548.1 13892.7 15947.2 15962.8 748.7 908.9 929.9 930.4 931.6 715.4 736.9 737.3 737.3 737.5 955.2 981.1 981.4 981.5 982.7 967.7 969.6 970.6 971.3 971.4 558.7 709.1 715.2 716.3 717.0 15188.9 15279.5 15293.1 15301.0 15301.9 12238.9 12416.3 12535.3 12609.8 12857.5 9787.2 9926.0 9977.4 10255.4 10476.1 3378.7 3419.4 3428.9 3465.9 3511.7 77586.6 77989.6 78747.0 80119.0 80391.6 8399.0 9293.6 9326.7 9387.0 9388.6 21071.2 21276.3 21284.6 21308.4 21315.5 7576.8 7605.8 7701.8 7920.2 7939.7 4073.9 4147.5 4154.7 4273.0 4337.0 3630.2 3696.9 3697.5 3704.7 3706.3 642.3 659.2 663.1 665.8 685.3 608.9 613.6 615.4 616.7 628.1 6741.3 7869.1 8198.7 8287.6 8576.7 581.3 588.2 593.7 596.3 606.8 3397.8 3789.1 3898.1 3909.1 3931.2 88771.0 90980.7 91347.0 92933.6 93791.0 77292.0 77670.6 77803.6 78039.0 78680.0 68739.8 69205.3 69249.7 69847.3 69910.0 7593.5 7828.7 7841.3 7861.0 7969.9 49447.8 49485.8 49764.8 50047.5 50203.5 63385.6 64850.0 67697.0 67714.7 70562.0 9780.8 9790.5 9958.8 10014.0 10169.7 8719.0 10613.8 10753.9 10783.4 11110.7 10356.5 10386.2 10425.7 10553.9 10704.0 10291.0 10298.2 10305.8 10309.0 10322.9 10177.4 10204.1 10239.4 10246.4 10246.7 8574.6 8854.9 8886.0 8937.9 9324.3 9425.0 9626.6 9639.1 9646.7 9908.6 03:40:51 INFO - PROCESS | 2477 | -------- Summary: end -------- 03:40:51 INFO - PROCESS | 2477 | 03:40:52 INFO - TEST-INFO | 2477: exit 0 03:40:52 INFO - TEST-OK | dromaeo_css | took 1114852ms 03:40:52 INFO - TEST-START | kraken 03:40:52 INFO - operating with platform_type : mac_ 03:40:52 INFO - Initialising browser for kraken test... 03:40:52 INFO - TEST-INFO | started process 2503 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp_AXyco/profile http://localhost:56807/getInfo.html) 03:40:54 INFO - PROCESS | 2503 | __metrics Screen width/height:1600/1200 03:40:54 INFO - PROCESS | 2503 | colorDepth:24 03:40:54 INFO - PROCESS | 2503 | Browser inner width/height: 1024/689 03:40:54 INFO - PROCESS | 2503 | __metrics 03:40:54 INFO - PROCESS | 2503 | 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" 03:40:54 ERROR - PROCESS | 2503 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:40:55 INFO - TEST-INFO | 2503: exit 0 03:40:55 INFO - Browser initialized. 03:40:55 INFO - Running cycle 1/1 for kraken test... 03:40:55 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp_AXyco/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:07 INFO - PROCESS | 2505 | RSS: Main: 210853888 03:41:07 INFO - PROCESS | 2505 | 03:41:09 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56807/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:10 INFO - PROCESS | 2505 | RSS: Main: 271527936 03:41:10 INFO - PROCESS | 2505 | 03:41:13 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56807/tests/kraken/driver.html?testName=audio-dft) 03:41:14 INFO - PROCESS | 2505 | RSS: Main: 285937664 03:41:14 INFO - PROCESS | 2505 | 03:41:17 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56807/tests/kraken/driver.html?testName=audio-fft) 03:41:17 INFO - PROCESS | 2505 | RSS: Main: 373981184 03:41:17 INFO - PROCESS | 2505 | 03:41:19 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56807/tests/kraken/driver.html?testName=audio-oscillator) 03:41:20 INFO - PROCESS | 2505 | RSS: Main: 278519808 03:41:20 INFO - PROCESS | 2505 | 03:41:22 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56807/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:23 INFO - PROCESS | 2505 | RSS: Main: 280571904 03:41:23 INFO - PROCESS | 2505 | 03:41:26 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56807/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:26 INFO - PROCESS | 2505 | RSS: Main: 319361024 03:41:26 INFO - PROCESS | 2505 | 03:41:29 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56807/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:30 INFO - PROCESS | 2505 | RSS: Main: 319197184 03:41:30 INFO - PROCESS | 2505 | 03:41:32 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56807/tests/kraken/driver.html?testName=json-parse-financial) 03:41:33 INFO - PROCESS | 2505 | RSS: Main: 316850176 03:41:33 INFO - PROCESS | 2505 | 03:41:35 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56807/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:35 INFO - PROCESS | 2505 | RSS: Main: 309280768 03:41:35 INFO - PROCESS | 2505 | 03:41:37 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:38 INFO - PROCESS | 2505 | RSS: Main: 315604992 03:41:38 INFO - PROCESS | 2505 | 03:41:40 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:41 INFO - PROCESS | 2505 | RSS: Main: 319164416 03:41:41 INFO - PROCESS | 2505 | 03:41:44 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:44 INFO - PROCESS | 2505 | RSS: Main: 320643072 03:41:44 INFO - PROCESS | 2505 | 03:41:47 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:48 INFO - PROCESS | 2505 | RSS: Main: 318562304 03:41:48 INFO - PROCESS | 2505 | 03:41:49 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:56807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56807/tests/kraken/driver.html?testName=ai-astar) 03:41:50 INFO - PROCESS | 2505 | RSS: Main: 382656512 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | __start_tp_report 03:41:50 INFO - PROCESS | 2505 | _x_x_mozilla_page_load 03:41:50 INFO - PROCESS | 2505 | _x_x_mozilla_page_load_details 03:41:50 INFO - PROCESS | 2505 | |i|pagename|runs| 03:41:50 INFO - PROCESS | 2505 | |0;ai-astar;114;115;122;119;163;166;115;116;115;136 03:41:50 INFO - PROCESS | 2505 | |1;audio-beat-detection;156;120;150;194;149;138;140;138;153;138 03:41:50 INFO - PROCESS | 2505 | |2;audio-dft;186;184;187;184;185;186;185;185;185;186 03:41:50 INFO - PROCESS | 2505 | |3;audio-fft;70;77;76;153;56;55;57;67;136;56 03:41:50 INFO - PROCESS | 2505 | |4;audio-oscillator;91;88;139;77;80;90;123;81;80;118 03:41:50 INFO - PROCESS | 2505 | |5;imaging-gaussian-blur;117;130;98;84;87;83;96;85;88;84 03:41:50 INFO - PROCESS | 2505 | |6;imaging-darkroom;93;94;94;93;94;97;94;94;93;92 03:41:50 INFO - PROCESS | 2505 | |7;imaging-desaturate;84;86;85;86;106;133;132;84;84;86 03:41:50 INFO - PROCESS | 2505 | |8;json-parse-financial;61;64;65;63;65;65;65;81;58;61 03:41:50 INFO - PROCESS | 2505 | |9;json-stringify-tinderbox;63;66;71;84;75;71;71;69;71;71 03:41:50 INFO - PROCESS | 2505 | |10;stanford-crypto-aes;82;80;81;82;81;80;82;80;80;85 03:41:50 INFO - PROCESS | 2505 | |11;stanford-crypto-ccm;144;151;139;142;126;119;134;145;164;183 03:41:50 INFO - PROCESS | 2505 | |12;stanford-crypto-pbkdf2;176;164;166;165;168;166;166;166;166;165 03:41:50 INFO - PROCESS | 2505 | |13;stanford-crypto-sha256-iterative;59;67;98;67;68;65;68;68;67;70 03:41:50 INFO - PROCESS | 2505 | __end_tp_report 03:41:50 INFO - PROCESS | 2505 | __start_cc_report 03:41:50 INFO - PROCESS | 2505 | _x_x_mozilla_cycle_collect,2029 03:41:50 INFO - PROCESS | 2505 | __end_cc_report 03:41:50 INFO - PROCESS | 2505 | __startTimestamp1463654510108__endTimestamp 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | ------- Summary: start ------- 03:41:50 INFO - PROCESS | 2505 | Number of tests: 14 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#0] ai-astar Cycles:10 Average:128.10 Median:120.50 stddev:20.27 (16.8%) stddev-sans-first:20.84 03:41:50 INFO - PROCESS | 2505 | Values: 114.0 115.0 122.0 119.0 163.0 166.0 115.0 116.0 115.0 136.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:149.50 stddev:19.31 (12.9%) stddev-sans-first:20.24 03:41:50 INFO - PROCESS | 2505 | Values: 156.0 120.0 150.0 194.0 149.0 138.0 140.0 138.0 153.0 138.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.50 stddev:0.95 (0.5%) stddev-sans-first:0.97 03:41:50 INFO - PROCESS | 2505 | Values: 186.0 184.0 187.0 184.0 185.0 186.0 185.0 185.0 185.0 186.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#3] audio-fft Cycles:10 Average:80.30 Median:62.00 stddev:35.06 (56.6%) stddev-sans-first:36.99 03:41:50 INFO - PROCESS | 2505 | Values: 70.0 77.0 76.0 153.0 56.0 55.0 57.0 67.0 136.0 56.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.50 stddev:21.81 (27.1%) stddev-sans-first:23.03 03:41:50 INFO - PROCESS | 2505 | Values: 91.0 88.0 139.0 77.0 80.0 90.0 123.0 81.0 80.0 118.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:86.00 stddev:16.05 (18.7%) stddev-sans-first:14.96 03:41:50 INFO - PROCESS | 2505 | Values: 117.0 130.0 98.0 84.0 87.0 83.0 96.0 85.0 88.0 84.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.32 (1.4%) stddev-sans-first:1.36 03:41:50 INFO - PROCESS | 2505 | Values: 93.0 94.0 94.0 93.0 94.0 97.0 94.0 94.0 93.0 92.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:84.50 stddev:20.04 (23.7%) stddev-sans-first:20.73 03:41:50 INFO - PROCESS | 2505 | Values: 84.0 86.0 85.0 86.0 106.0 133.0 132.0 84.0 84.0 86.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:6.16 (9.5%) stddev-sans-first:6.38 03:41:50 INFO - PROCESS | 2505 | Values: 61.0 64.0 65.0 63.0 65.0 65.0 65.0 81.0 58.0 61.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:5.55 (7.8%) stddev-sans-first:5.04 03:41:50 INFO - PROCESS | 2505 | Values: 63.0 66.0 71.0 84.0 75.0 71.0 71.0 69.0 71.0 71.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.57 (1.9%) stddev-sans-first:1.64 03:41:50 INFO - PROCESS | 2505 | Values: 82.0 80.0 81.0 82.0 81.0 80.0 82.0 80.0 80.0 85.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:144.50 stddev:18.39 (12.7%) stddev-sans-first:19.50 03:41:50 INFO - PROCESS | 2505 | Values: 144.0 151.0 139.0 142.0 126.0 119.0 134.0 145.0 164.0 183.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:166.00 stddev:3.39 (2.0%) stddev-sans-first:1.09 03:41:50 INFO - PROCESS | 2505 | Values: 176.0 164.0 166.0 165.0 168.0 166.0 166.0 166.0 166.0 165.0 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - PROCESS | 2505 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.00 stddev:10.37 (15.3%) stddev-sans-first:10.25 03:41:50 INFO - PROCESS | 2505 | Values: 59.0 67.0 98.0 67.0 68.0 65.0 68.0 68.0 67.0 70.0 03:41:50 INFO - PROCESS | 2505 | -------- Summary: end -------- 03:41:50 INFO - PROCESS | 2505 | 03:41:50 INFO - TEST-INFO | 2505: exit 0 03:41:50 INFO - TEST-OK | kraken | took 58480ms 03:41:50 INFO - SUITE-END | took 1173s 03:41:51 INFO - Completed test suite (00:19:33) 03:41:51 INFO - javascript benchmark 03:41:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.2294472271383, 3403.710684591808, 3438.776479528829, 3443.741719371047, 3473.4442489201733, 5700.9714951425185, 5715.799947001856, 5717.313922329316, 5725.312962444502, 5729.942700572993, 5723.942760572393, 5736.8852622947625, 5748.913766293509, 5753.367129615739, 5759.798407055754, 5034.24486327052, 5084.3644544431945, 5126.410462796789, 5245.527902488775, 5254.81608143715, 5208.817691380802, 5209.036328279261, 5216.400113986902, 5218.1650935850275, 5221.817236396727, 5126.102931986906, 5167.974160129194, 5199.038177937094, 5213.6089793265655, 5215.582753379739, 5158.896822063556, 5227.634065615409, 5229.111051121318, 5233.058049551079, 5277.076511610453, 5066.961272939038, 5071.087204303224, 5095.261187127846, 5157.922631160535, 5163.096458119851, 5548.667079975215, 5590.91613625796, 5592.105263157875, 5593.440655934414, 5600.5799565032785, 6194.070889366586, 6197.977333739927, 6211.16149319845, 6225.097360882647, 6236.501079913642, 6080.55132462734, 6151.446369826737, 6224.09750586167, 6227.688615569204, 6235.251769787609, 5764.798232061941, 5782.392848750821, 5796.768129274782, 5812.680302583314, 5836.620619659709, 6225.501959843202, 6227.09707092469, 6232.875342493216, 6243.531735119794, 6246.937530624727, 5955.047192449274, 6001.549883758736, 6104.664243466652, 6121.663308517986, 6122.703114648804, 6069.544784141207, 6071.332153463116, 6081.787137450168, 6086.2087928569, 6132.5707200495535, 5948.524118070544, 5961.493273071837, 5969.3732158122775, 5973.940260597338, 5978.103284507359, 5963.075723262901, 6107.267127944586, 6132.50939924805, 6150.892839288881, 6167.321594624587, 4834.20235661112, 4869.220924652039, 4875.366202393666, 4902.289168070681, 4910.582600479, 4359.978200108979, 4387.363832244307, 4392.3411488277015, 4406.140802543473, 4419.712718673276, 5562.471565201299, 5675.318961724537, 5686.289213848269, 5686.601937864392, 5698.0598201296325, 6143.788236234952, 6195.566310358232, 6197.194364732556, 6211.875762484725, 6216.564840461125, 5742.31092268922, 5743.425657434307, 5757.13642953552, 5758.992176369119, 5759.740811663485, 4469.0, 5359.892802143935, 5382.2464854920345, 5388.703621300788, 5393.298871146804, 6211.534134939898, 6287.434130928238, 6292.370762923671, 6302.936970630327, 6307.558470906993, 6166.105914642441, 6182.350853160354, 6191.16419283409, 6197.5351848610635, 6198.969005154945, 6213.813585592439, 6314.55798094129, 6321.1782468278825, 6330.177076979963, 6333.303336633059, 4954.702717836942, 6074.878502430015, 6103.11504831788, 6160.876782464416, 6178.196834411498, 6151.815445536641, 6196.225471816023, 6196.628202307876, 6223.097650840512, 6225.533085018732, 5944.9405505946115, 6063.484603808549, 6082.9354862897935, 6085.57400981927, 6107.236595425572, 5896.705164741831, 5977.820665380045, 6117.522930757332, 6119.776756597957, 6142.222533142175, 5879.441453061952, 5950.077737950624, 5959.523238140851, 5964.254468191476, 5967.522598192221, 5965.2245208124405, 6063.363346848694, 6115.510759139169, 6141.723622436912, 6144.893919094606, 5752.0, 5884.499817515559, 5914.852128696817, 5916.497097746567, 5917.142014407973], "value": 5656.554974360601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.8250612285703, 518.2252532463966, 549.3901769036366, 588.7497813429292, 614.8647297594531, 31049.379012419737, 31171.532427013557, 37531.62468375315, 38893.61106388935, 39010.0, 41800.37299440511, 42176.94557636061, 42301.942451438656, 42437.0, 42444.78777606115, 8562.87155692662, 9521.6191352346, 9651.276154288422, 9669.419834809898, 9686.564104615309, 8626.482411055356, 11492.0, 11699.824502632424, 11716.707082322924, 11723.531058757639, 11453.770924581504, 11501.539938402453, 11533.192676512648, 11534.365609891453, 11590.59432919848, 8721.30229581632, 8919.68781092665, 8928.33037522182, 11416.657500274963, 11512.65462036136, 8768.693095741619, 11444.0, 11692.415379231004, 11696.23974441667, 11708.414579271002, 38557.61442385568, 39030.21939561193, 39071.21857562861, 39117.60882391196, 39271.41092883609, 3244.0916543367894, 3260.5924259467565, 3289.1612638777196, 3339.4990751387245, 3495.930081398383, 27565.75954082051, 27930.441391172062, 28099.0, 28491.572626410412, 29099.0, 2710.4037111835364, 2776.0422654184276, 2792.4833905727505, 2907.752841008517, 2995.8352290624007, 496840.0, 497860.0, 498617.0, 498659.0, 500166.0, 30085.849570752005, 36606.45090323647, 58005.96979105711, 58134.12798808021, 58866.70566647225, 35776.0, 35902.820485897406, 35919.102022449646, 35970.0, 36018.27963440769, 15180.696386072217, 15422.46021389246, 15433.38266469351, 15447.922760386125, 15452.38190472391, 11790.705232369259, 12007.399630018464, 12021.699457513458, 12027.977621902237, 12861.099723019486, 8105.432619716681, 8189.22202390772, 8196.467229630054, 8288.544130072903, 8292.460990035628, 2934.3544420227518, 2948.513495273256, 3063.417950589381, 3299.703026727606, 3436.60479044913, 811.3590263691735, 928.9767755806024, 950.2493030505962, 989.4904124375952, 1000.9690019280154, 28001.439971200463, 29583.85208073989, 29700.811967521488, 29912.0, 30609.69390306113, 792.9960350198212, 1083.653230966099, 1183.5384200161773, 1225.2587184753097, 1263.77252094622, 13428.798568021488, 14283.42866285337, 14616.269186540843, 14693.897957653218, 15057.849421505645, 625821.0, 632341.0, 633693.0, 633940.0, 636009.0, 57336.0, 58582.70708646515, 58644.7067764667, 58666.41333586695, 58792.412075878696, 1347.1176379471462, 1381.5855243426731, 1383.5019393018706, 1383.5710929611853, 1499.9850001499844, 22731.772682272967, 28943.842246309912, 29029.854850725613, 29384.55923161197, 29741.107766767032, 47989.5201047985, 48291.0, 48781.5121848777, 48810.755946220044, 49012.26481602708, 427889.0, 428700.0, 430332.0, 430834.0, 430970.0, 23168.18911338095, 23608.881955590114, 26508.8674556626, 26687.0, 27230.455390892468, 41528.37707434451, 42909.57090429056, 42916.78541607272, 43177.78411107924, 43398.56601433945, 35580.64419355773, 36037.27925441529, 36166.63833361633, 36596.63403365932, 36958.0, 36573.08567285733, 36628.08429789277, 36670.633293667786, 36898.27576753045, 37366.0, 35434.11414714653, 35597.46603800997, 35942.0, 36074.78504299203, 36101.458478122324, 26917.596236056866, 32065.198370040936, 32191.0, 32686.019419417455, 33040.0, 24725.0, 24767.0, 24872.502549949262, 24914.75085249197, 25061.74938250667], "value": 18257.177014676432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9120219945014, 395.19972056585414, 397.1203783619284, 402.33815373710263, 414.93775933609953, 19934.302299419523, 21488.140474381005, 22237.332880013626, 22261.554768904654, 22295.99668014939, 21644.8917755411, 21675.891620541875, 21981.010854511544, 22017.33947981559, 22068.33794986149, 5722.113072473763, 6050.606710563821, 6054.6367217966845, 6071.939280607204, 6680.699368528428, 6526.902096468533, 6585.967070164643, 6622.503312251576, 6630.900536491957, 6633.071370008179, 6297.622142671455, 6544.345565443466, 6573.474122070223, 6577.046328282396, 6588.5175835436885, 5130.948690513103, 6120.571559990803, 6154.5076393888385, 6158.753649854045, 6159.7536098556, 5171.22431635254, 5769.942300576982, 5778.27771528559, 5781.682007489587, 5790.536757059425, 26327.868360658267, 26522.0, 26689.466210675677, 27042.5943610846, 27104.45791084187, 1869.44851268876, 2196.736391632998, 2201.460642142685, 2350.576896158691, 3063.540468929656, 14665.26673666323, 15115.16866572338, 15378.538643840702, 15615.765763513557, 15733.44932927342, 1375.1542801177204, 1721.9139043047799, 1771.4419957713408, 1793.5695433096093, 1824.8905065696101, 78142.0, 98993.5150972736, 101609.0, 111241.44379278133, 114056.0, 10965.616203433001, 11372.829407558893, 11373.090152787758, 11500.079993600491, 11554.76890462203, 12630.494780208688, 13033.804492932613, 13089.869101309056, 14409.42362305517, 14436.927815361067, 8339.249467547828, 8703.651853925772, 8818.382713210141, 8888.422252553562, 8948.955255223813, 8953.910460895308, 9071.410358326688, 9079.228265597367, 9148.817023659489, 9166.26669866409, 5910.172575839386, 6722.193336799615, 6791.694373753193, 6926.61903595297, 6955.6869940852785, 1890.6785846406144, 1916.9808301916803, 1950.405126436422, 1962.6172896285088, 1963.1754663041556, 876.7019213467452, 932.440535678601, 1027.1474676018886, 1058.1587637827945, 1061.474570087796, 16342.591435214215, 17969.550761231072, 20908.954552272324, 22295.0, 22387.88806055959, 940.9743379716142, 945.7210123013728, 954.5084281594984, 957.6025949230991, 969.709087273815, 10784.299020563638, 11178.608748693756, 11240.550377984953, 11294.096472282197, 11314.886851131549, 568892.3110768922, 569340.0, 588167.0, 659224.0, 660999.3400395992, 123442.0, 137280.31359843136, 138296.0, 139928.0, 149565.25217373844, 396.738152819138, 476.3759475087646, 482.24047125776343, 482.8720389096892, 483.5430518160375, 9298.163165314883, 10405.479726013518, 10441.373517588969, 11552.6534203974, 11656.0, 53595.6601084976, 54419.18371224514, 54676.0, 54699.0, 55246.44753552413, 80019.0, 80074.59962700149, 81541.77687334576, 82178.76731849146, 84216.0, 8272.420930535045, 8313.33493320545, 8421.578921054046, 8429.199226073513, 8662.82674346497, 10028.69913902584, 12475.5009799607, 12691.682707932376, 12930.482780688668, 13346.799798003232, 9775.80448391014, 9879.0, 10591.89408105909, 10677.93220677926, 11318.207725459139, 10353.896461035294, 10778.08386287143, 11097.445127743742, 11335.376554289593, 11620.012298954345, 11423.657290281304, 11439.885601143882, 11684.591039313584, 11813.586524471602, 11898.762024759284, 8118.959405202936, 8179.754607361789, 8296.128906464975, 8340.332773378237, 8500.234978851933, 10101.69694909154, 10910.672679819618, 11191.496382662963, 11257.774844503228, 11308.49111789955], "value": 9575.266432570703, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.5667271482207, 1560.758082497213, 1578.9763153552694, 1587.7935868337115, 1984.861059725818, 7828.9608551957235, 8532.23209911108, 8674.91325086749, 8675.262602678764, 8679.566021698922, 8441.746747597566, 8460.830783384328, 8587.441816281937, 8620.439671421353, 8626.525541095238, 2135.177956486756, 2280.498290376123, 2416.3113512648897, 2437.731849496554, 2440.707115146176, 2343.121329501437, 2346.7887890089887, 2349.0368948731025, 2349.4713689419827, 2353.576356255873, 1961.6763234066361, 2019.3134334326326, 2278.1798552521163, 2293.0713061210236, 2388.9880550597222, 2112.7464704235435, 2174.2172817785586, 2176.4014895903597, 2393.114547617375, 2411.40920474484, 2168.262790651186, 2357.1749887539395, 2413.5655581995234, 2416.0215112879314, 2430.052279610953, 8566.18621230982, 8663.35024873137, 8667.39328247023, 8670.002949660806, 8679.132086791333, 5208.479152084799, 5315.0432922074015, 5352.170413585901, 5358.062339090644, 5365.382980957201, 3116.875324986998, 3482.3035392921515, 3538.1331573764337, 3620.8913732588067, 3667.5965643779164, 1021.8875923648393, 1047.3087762077068, 1054.8101341758481, 1058.7035630023606, 1081.2215205052428, 10221.0, 11999.400029998553, 12012.63962081139, 12020.338881361522, 12034.0, 4659.883502912454, 4679.157751604744, 4767.356406885096, 4795.160846851787, 4838.491958344393, 10101.898981010243, 10119.392836429837, 10124.949375253223, 10131.594736210616, 10135.594576217016, 5469.453054694499, 5471.480209380103, 5477.589180811455, 5478.0, 5478.972605137029, 4360.389545463638, 4522.276435770326, 4729.337892695025, 4838.685485443435, 4959.677620954626, 3474.2356681530023, 3927.9607203928167, 3943.8028098594955, 4058.1072164123843, 4080.163566468867, 1317.9324746954996, 1343.0934119469318, 1343.8656134386483, 1358.877701006914, 1359.3882752761297, 820.8112134209165, 864.4294765454896, 902.4449963272672, 928.5496534180917, 935.3265648733037, 5761.39505351932, 5766.913496297559, 5839.532837373, 5879.265091863517, 6031.517478601701, 456.3474231848489, 460.9861704148881, 462.7732411118595, 874.2699845628971, 1001.3090967232587, 3789.4505296732373, 3867.3812190049457, 3928.724989250782, 3929.410588411704, 3940.940885886714, 10305.381677099398, 10738.624148154777, 10802.51388687511, 10875.673729788119, 10876.129909607213, 10399.740006499898, 10481.213908956859, 10526.526306316235, 10720.981506756845, 10736.30214036085, 7646.311831935152, 7769.494982826098, 7822.882656760153, 7831.530108193527, 7861.960690196627, 4819.493953135011, 5278.9472105278455, 5301.363836339509, 5392.5416339611575, 5480.506754392124, 8795.384323097322, 8948.328875334506, 9058.501782401767, 9062.184403403462, 9063.592138353659, 8942.597583108645, 9170.128837760401, 9187.678431254873, 9401.85897211556, 9433.669821556212, 6747.797566073025, 6916.827079322856, 7217.206107328085, 7332.30343117403, 7469.1037075551285, 8137.471064380797, 8137.715179968673, 8212.17878212174, 8226.465279756796, 8234.505929644241, 7203.8559228816175, 7282.817929551804, 7321.341079302888, 7439.293267139613, 7446.73936412223, 7036.401905837856, 7167.641617919187, 7172.605506697182, 7203.459740519586, 7216.96391518039, 6824.727010919508, 6903.1716194057035, 6909.0, 6914.896276555956, 6915.757948471779, 3799.7530160539477, 4691.108689349013, 4724.929126063043, 4857.028594281087, 4936.555709986119, 4257.515671608377, 4540.273556230987, 4810.687305325283, 4895.314655948241, 4948.109340318777], "value": 4452.0325754803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.61689355879383, 319.59411547334884, 326.43363763869684, 328.02739876266867, 331.8539842469315, 6681.732730690766, 6704.061431399602, 6704.664766761667, 6710.36251556102, 6723.092382528357, 6568.113304703864, 6653.234877989033, 6714.462842972563, 6722.991551267312, 6754.392104710575, 3986.0, 4695.5539223773685, 4714.175019371613, 4802.255650374198, 4814.711117332954, 3969.0672691917375, 4376.234159022174, 4727.267273572602, 4806.4232292125, 4833.347498087766, 4054.3918412238104, 4733.66864319496, 4830.9275360869615, 4838.346823178861, 4840.830570930018, 4876.097921884445, 4893.608511319104, 4896.265560165968, 4905.23968784839, 4906.141425250603, 3992.8203230854683, 4031.8588849390426, 4059.6346328830546, 4067.715259931806, 4074.449949256842, 6188.350223226541, 6324.33594472583, 6432.324605916405, 6437.388448097423, 6450.580712253735, 5796.304443466769, 5797.449242321973, 5807.767689292423, 5830.387809280007, 5835.941640583582, 7038.542494737826, 7057.717691292342, 7061.611611361373, 7077.292270772881, 7081.92918070823, 3843.98078009611, 4378.912421751579, 4672.229082201433, 4678.719276843401, 4679.15775160471, 7401.666924988334, 7471.066116735408, 7495.550266983998, 7511.248875112499, 7539.982102416158, 4644.489106198383, 4648.140094082605, 4651.348811166439, 4653.348531205634, 4653.604443622262, 3920.313945059603, 4737.976310118427, 4738.957429365534, 4743.217369134123, 4755.881102972384, 5612.775488980476, 5623.072193088176, 5629.521490673257, 5630.071038278638, 5630.971845140748, 6882.414994725505, 7157.57054576727, 7163.10461192351, 7172.748953786592, 7202.49582529218, 1622.399712106528, 1624.1473226556152, 1634.3707672546154, 1642.4169419856019, 1653.0329757092236, 1019.1846522781744, 1019.7399663086014, 1036.2435422141878, 1040.2094408249784, 1044.9007344302279, 6705.664716764142, 6710.496712746563, 6717.193936727624, 6720.395164435225, 6721.294264102199, 7144.249853765384, 7229.674664640103, 7288.344049035612, 7358.264173582599, 7372.299631534996, 6602.933970660328, 6653.534252602271, 6653.933460665428, 6656.400923916871, 6778.712044711489, 3239.7084262416497, 3900.5124359455067, 3906.199229158016, 3930.567637559866, 3949.960500395017, 7708.075030996316, 7720.691172353156, 7740.6129693515095, 7752.496087754278, 7762.7671169865, 7340.0, 7614.771556853303, 7617.162112167657, 7629.389648828081, 7630.969819074465, 6157.753689852445, 7673.53958762476, 7687.461877668509, 7705.306522412786, 7715.151333353327, 7376.7786966391095, 7383.150937642025, 7387.150477695134, 7389.667464964197, 7394.482386233127, 6482.189726284215, 6856.251655827732, 7010.0, 7288.927110728825, 7297.306755858185, 6474.805755827333, 6553.541252112306, 7479.17729049815, 7689.269519395649, 7719.652615632423, 4527.411436513232, 4645.163870503342, 4645.372874661877, 4646.372739680235, 4653.418322709661, 7382.815429614087, 7394.704211831681, 7396.482246242711, 7400.222976587596, 7415.0, 5876.970615146897, 6908.730797562788, 7109.964450177716, 7129.251428599922, 7144.714211431485, 7003.774339490466, 7125.287471252833, 7138.14342278909, 7143.535670181422, 7154.427645788429, 6409.2949775525585, 6902.413294869792, 6957.686904089227, 6969.059177011039, 6969.825754356181, 6180.845478862884, 6787.762428314985, 7122.608256545733, 7128.073350464407, 7130.679119439534, 6450.967845144754, 6738.427233685192, 6969.825754356181, 6986.231514533499, 6991.2659170788365], "value": 5132.101081976191, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.4553111979297, 697.5919087333909, 700.3591713582073, 702.725936884615, 754.9698012079516, 15030.17334046627, 15096.396144154234, 15216.69566608667, 15233.390664373426, 15254.542363729079, 13490.325483725823, 13548.119372240797, 13892.652683682887, 15947.20263986799, 15962.76055859163, 748.6967778049899, 908.8909330880304, 929.948852813095, 930.4231376546551, 931.5621657820814, 715.3883429667637, 736.9410447164214, 737.2590546500759, 737.3437640499959, 737.4948160510068, 955.2405837359316, 981.102291403365, 981.4356744871692, 981.504340308147, 982.6708052802284, 967.7000129959665, 969.6121551379434, 970.587500312368, 971.3055165556625, 971.4365667912593, 558.6815515156362, 709.1242315740056, 715.152544235083, 716.3159182980241, 717.042747931512, 15188.924055379763, 15279.465218717403, 15293.082415055133, 15301.0, 15301.923490382476, 12238.938805306005, 12416.317102559356, 12535.31055791931, 12609.810852837214, 12857.549985750547, 9787.168090712297, 9926.007399260088, 9977.401355918668, 10255.384676919408, 10476.0571548561, 3378.6621337866263, 3419.3674170278578, 3428.862845486178, 3465.8960231193105, 3511.7015053720465, 77586.61206693987, 77989.61005194938, 78747.0, 80119.0, 80391.59804200941, 8398.958005210057, 9293.628254869729, 9326.673566425143, 9387.0, 9388.624455021723, 21071.157153713986, 21276.255331063338, 21284.574308514053, 21308.36074917755, 21315.46711332198, 7576.772696819105, 7605.809854753565, 7701.845963080819, 7920.168382319889, 7939.726463104312, 4073.918521629551, 4147.52303485097, 4154.667626589926, 4273.0, 4336.980809509762, 3630.237650093457, 3696.8706095286534, 3697.519322488113, 3704.666580007812, 3706.258748250361, 642.2966851297822, 659.1924892007253, 663.0849427789619, 665.8302132956065, 685.2633419074415, 608.8934436473688, 613.6440864298696, 615.4338009031702, 616.6546733828981, 628.0673200297555, 6741.325867413219, 7869.095054068741, 8198.713045043394, 8287.58562071906, 8576.699815506427, 581.3261378516762, 588.2176704982363, 593.6942474625571, 596.3053043204618, 606.805822136912, 3397.8301084946147, 3789.147441825567, 3898.1229223424502, 3909.100906791397, 3931.1548017175874, 88771.0, 90980.72548186348, 91347.0, 92933.60599591011, 93791.0, 77292.0, 77670.6116469414, 77803.61098194473, 78039.0, 78680.0, 68739.81341679273, 69205.30794692089, 69249.65375173192, 69847.3015269851, 69910.0, 7593.468457207942, 7828.725994590162, 7841.333486653698, 7860.978072850721, 7969.880451793112, 49447.75276123596, 49485.75257123692, 49764.75117624389, 50047.499525004285, 50203.49796502134, 63385.612222961565, 64850.0, 67697.0, 67714.66142669255, 70562.0, 9780.755481112745, 9790.46152461593, 9958.751031224278, 10014.0, 10169.711490466088, 8719.040905500264, 10613.787724245627, 10753.89246107529, 10783.406912619881, 11110.666679999613, 10356.533955971849, 10386.169106471616, 10425.739356516147, 10553.947230264106, 10704.0, 10291.022352876465, 10298.22763292741, 10305.845412318971, 10308.96910308963, 10322.948385258325, 10177.440240786827, 10204.081632653097, 10239.436830974368, 10246.38521688701, 10246.743831404274, 8574.571271436278, 8854.911450885666, 8886.0, 8937.910620893968, 9324.254059675346, 9425.0, 9626.566804493954, 9639.132478077006, 9646.710598682052, 9908.603655853685], "value": 6758.589640163452, "unit": "score"}], "name": "dromaeo_css", "value": 7311.059883707118, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 122.0, 119.0, 163.0, 166.0, 115.0, 116.0, 115.0, 136.0], "value": 128.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 150.0, 194.0, 149.0, 138.0, 140.0, 138.0, 153.0, 138.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 187.0, 184.0, 185.0, 186.0, 185.0, 185.0, 185.0, 186.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 76.0, 153.0, 56.0, 55.0, 57.0, 67.0, 136.0, 56.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 139.0, 77.0, 80.0, 90.0, 123.0, 81.0, 80.0, 118.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 98.0, 84.0, 87.0, 83.0, 96.0, 85.0, 88.0, 84.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 94.0, 97.0, 94.0, 94.0, 93.0, 92.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 86.0, 106.0, 133.0, 132.0, 84.0, 84.0, 86.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 63.0, 65.0, 65.0, 65.0, 81.0, 58.0, 61.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 71.0, 84.0, 75.0, 71.0, 71.0, 69.0, 71.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 82.0, 81.0, 80.0, 82.0, 80.0, 80.0, 85.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 139.0, 142.0, 126.0, 119.0, 134.0, 145.0, 164.0, 183.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 164.0, 166.0, 165.0, 168.0, 166.0, 166.0, 166.0, 166.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 98.0, 67.0, 68.0, 65.0, 68.0, 68.0, 67.0, 70.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1522.1, "alertThreshold": 2.0}]} 03:41:51 INFO - Return code: 0 03:41:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:41:51 ERROR - # TBPL SUCCESS # 03:41:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:51 INFO - 2016-05-19 03:41:51.400 screenresolution[2509:25818] starting screenresolution argv=screenresolution get 03:41:51 INFO - 2016-05-19 03:41:51.402 screenresolution[2509:25818] Display 0: 1600x1200x32@60 03:41:51 INFO - 2016-05-19 03:41:51.408 screenresolution[2510:25821] starting screenresolution argv=screenresolution list 03:41:51 INFO - Available Modes on Display 0 03:41:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:51 INFO - Intel Iris: 03:41:51 INFO - Chipset Model: Intel Iris 03:41:51 INFO - Type: GPU 03:41:51 INFO - Bus: Built-In 03:41:51 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:51 INFO - Vendor: Intel (0x8086) 03:41:51 INFO - Device ID: 0x0a2e 03:41:51 INFO - Revision ID: 0x0009 03:41:51 INFO - Displays: 03:41:51 INFO - Display: 03:41:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:51 INFO - Display Serial Number: 1600x1200 60 03:41:51 INFO - Main Display: Yes 03:41:51 INFO - Mirror: Off 03:41:51 INFO - Online: Yes 03:41:51 INFO - Rotation: Supported 03:41:51 INFO - Return code: 0 03:41:51 INFO - Running post-action listener: _resource_record_post_action 03:41:51 INFO - Running post-run listener: _resource_record_post_run 03:41:52 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 659456; Write bytes: 651492864; Read time: 5; Write time: 1806 03:41:52 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 352875008; Write bytes: 346938368; Read time: 27725; Write time: 702 03:41:52 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 659456; Write bytes: 294810112; Read time: 5; Write time: 1068 03:41:52 INFO - Running post-run listener: _upload_blobber_files 03:41:52 INFO - Blob upload gear active. 03:41:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:52 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 03:41:52 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:41:52 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:41:53 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:54 INFO - (blobuploader) - INFO - Done attempting. 03:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:54 INFO - (blobuploader) - INFO - Done attempting. 03:41:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:41:54 INFO - Return code: 0 03:41:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:41:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:41:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76bb7741207500c1f802847a2aafba35d97c3c6ac45ba4decd96b9b3a33ad5bd69843205e353435e4c8b20916abb53de82060269c61c59c27f8acaa227a684c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62ab083696096deb8db55f224e6c4c6bfc318e337bccca7208586ea0d9369b860f1b206d1795d8403728950b47ee6ee646a307060a1578a0e52032c78dc955e9"} 03:41:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:41:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:41:54 INFO - Contents: 03:41:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76bb7741207500c1f802847a2aafba35d97c3c6ac45ba4decd96b9b3a33ad5bd69843205e353435e4c8b20916abb53de82060269c61c59c27f8acaa227a684c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62ab083696096deb8db55f224e6c4c6bfc318e337bccca7208586ea0d9369b860f1b206d1795d8403728950b47ee6ee646a307060a1578a0e52032c78dc955e9"} 03:41:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:41:54 INFO - Copying logs to upload dir... 03:41:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:41:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.072399 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-19 03:41:55.262901) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:41:55.266989) ========= 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.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/Mozilla-Aurora/sha512/76bb7741207500c1f802847a2aafba35d97c3c6ac45ba4decd96b9b3a33ad5bd69843205e353435e4c8b20916abb53de82060269c61c59c27f8acaa227a684c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62ab083696096deb8db55f224e6c4c6bfc318e337bccca7208586ea0d9369b860f1b206d1795d8403728950b47ee6ee646a307060a1578a0e52032c78dc955e9"} build_url:https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/MFGenDxoSIOTLh8X8TnLDA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76bb7741207500c1f802847a2aafba35d97c3c6ac45ba4decd96b9b3a33ad5bd69843205e353435e4c8b20916abb53de82060269c61c59c27f8acaa227a684c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62ab083696096deb8db55f224e6c4c6bfc318e337bccca7208586ea0d9369b860f1b206d1795d8403728950b47ee6ee646a307060a1578a0e52032c78dc955e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:41:55.294563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:41:55.294874) ========= 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.bQ94ejlhrk/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.ODwcHnaU19/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:41:55.343159) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:41:55.343506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:41:55.343830) ========= ========= Total master_lag: 0.34 =========