builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0091 starttime: 1461363498.06 results: success (0) buildid: 20160422131145 builduid: 87190c818e864d288903c10b07af8d60 revision: ab85f19b67b1d093e060bfe52a672a3a9995e252 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:18.063874) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:18.064289) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-22 15:18:18.064561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-22 15:18:20.485958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-22 15:18:20.486225) ========= 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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-22 15:18:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.1M=0s 2016-04-22 15:18:21 (53.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.545688 ========= master_lag: 5.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-22 15:18:26.258531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:26.261836) ========= 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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027159 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:26.357445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:26.360762) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ab85f19b67b1d093e060bfe52a672a3a9995e252 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev ab85f19b67b1d093e060bfe52a672a3a9995e252 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-22 15:18:26,441 truncating revision to first 12 chars 2016-04-22 15:18:26,442 Setting DEBUG logging. 2016-04-22 15:18:26,442 attempt 1/10 2016-04-22 15:18:26,442 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ab85f19b67b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 15:18:27,079 unpacking tar archive at: fx-team-ab85f19b67b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.881779 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:27.308625) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:27.309010) ========= script_repo_revision: ab85f19b67b1d093e060bfe52a672a3a9995e252 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:18:27.309367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-04-22 15:18:27.309631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-04-22 15:18:30.164219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-22 15:18:30.164673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:18:30 INFO - MultiFileLogger online at 20160422 15:18:30 in /builds/slave/test 15:18:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:18:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:18:30 INFO - {'append_to_log': False, 15:18:30 INFO - 'base_work_dir': '/builds/slave/test', 15:18:30 INFO - 'blob_upload_branch': 'Fx-Team', 15:18:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:18:30 INFO - 'branch': 'Fx-Team', 15:18:30 INFO - 'buildbot_json_path': 'buildprops.json', 15:18:30 INFO - 'config_files': ('talos/mac_config.py',), 15:18:30 INFO - 'default_actions': ('clobber', 15:18:30 INFO - 'read-buildbot-config', 15:18:30 INFO - 'download-and-extract', 15:18:30 INFO - 'populate-webroot', 15:18:30 INFO - 'create-virtualenv', 15:18:30 INFO - 'install', 15:18:30 INFO - 'run-tests'), 15:18:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:18:30 INFO - 'download_minidump_stackwalk': True, 15:18:30 INFO - 'download_symbols': 'ondemand', 15:18:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:18:30 INFO - 'tooltool.py': '/tools/tooltool.py', 15:18:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:18:30 INFO - '/tools/misc-python/virtualenv.py')}, 15:18:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:18:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:18:30 INFO - 'installer_path': 'installer.exe', 15:18:30 INFO - 'log_level': 'info', 15:18:30 INFO - 'log_name': 'talos', 15:18:30 INFO - 'log_to_console': True, 15:18:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:18:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:18:30 INFO - 'opt_config_files': (), 15:18:30 INFO - 'pip_index': False, 15:18:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:30 INFO - 'cmd': ('bash', 15:18:30 INFO - '-c', 15:18:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:30 INFO - 'enabled': True, 15:18:30 INFO - 'halt_on_failure': False, 15:18:30 INFO - 'name': 'check_screen_resolution'},), 15:18:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:30 INFO - 'cmd': ('bash', 15:18:30 INFO - '-c', 15:18:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:30 INFO - 'enabled': True, 15:18:30 INFO - 'halt_on_failure': False, 15:18:30 INFO - 'name': 'check_screen_resolution'},), 15:18:30 INFO - 'run_cmd_checks_enabled': True, 15:18:30 INFO - 'sps_profile': False, 15:18:30 INFO - 'sps_profile_interval': 0, 15:18:30 INFO - 'suite': 'dromaeojs', 15:18:30 INFO - 'system_bits': '32', 15:18:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:18:30 INFO - 'title': 't-yosemite-r7-0091', 15:18:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:18:30 INFO - 'use_talos_json': True, 15:18:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:18:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:18:30 INFO - 'work_dir': 'build'} 15:18:30 INFO - ##### 15:18:30 INFO - ##### Running clobber step. 15:18:30 INFO - ##### 15:18:30 INFO - Running pre-action listener: _resource_record_pre_action 15:18:30 INFO - Running main action method: clobber 15:18:30 INFO - rmtree: /builds/slave/test/build 15:18:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:18:31 INFO - Running post-action listener: _resource_record_post_action 15:18:31 INFO - ##### 15:18:31 INFO - ##### Running read-buildbot-config step. 15:18:31 INFO - ##### 15:18:31 INFO - Running pre-action listener: _resource_record_pre_action 15:18:31 INFO - Running main action method: read_buildbot_config 15:18:31 INFO - Using buildbot properties: 15:18:31 INFO - { 15:18:31 INFO - "project": "", 15:18:31 INFO - "product": "firefox", 15:18:31 INFO - "script_repo_revision": "production", 15:18:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:18:31 INFO - "repository": "", 15:18:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:18:31 INFO - "buildid": "20160422131145", 15:18:31 INFO - "pgo_build": "False", 15:18:31 INFO - "basedir": "/builds/slave/test", 15:18:31 INFO - "buildnumber": 761, 15:18:31 INFO - "slavename": "t-yosemite-r7-0091", 15:18:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:18:31 INFO - "platform": "yosemite_r7", 15:18:31 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:18:31 INFO - "branch": "fx-team", 15:18:31 INFO - "repo_path": "integration/fx-team", 15:18:31 INFO - "revision": "ab85f19b67b1d093e060bfe52a672a3a9995e252", 15:18:31 INFO - "stage_platform": "macosx64", 15:18:31 INFO - "builduid": "87190c818e864d288903c10b07af8d60", 15:18:31 INFO - "slavebuilddir": "test" 15:18:31 INFO - } 15:18:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 15:18:31 INFO - Running post-action listener: _resource_record_post_action 15:18:31 INFO - ##### 15:18:31 INFO - ##### Running download-and-extract step. 15:18:31 INFO - ##### 15:18:31 INFO - Running pre-action listener: _resource_record_pre_action 15:18:31 INFO - Running main action method: download_and_extract 15:18:31 INFO - mkdir: /builds/slave/test/build/tests 15:18:31 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')]} 15:18:31 INFO - https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:18:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 15:18:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 15:18:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 15:18:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 15:18:34 INFO - Downloaded 1199 bytes. 15:18:34 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 15:18:34 INFO - Using the following test package requirements: 15:18:34 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 15:18:34 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 15:18:34 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 15:18:34 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'jsshell-mac.zip'], 15:18:34 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 15:18:34 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 15:18:34 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 15:18:34 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 15:18:34 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 15:18:34 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:18:34 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 15:18:34 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:18:34 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')]} 15:18:34 INFO - https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:18:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:37 INFO - Downloaded 18109591 bytes. 15:18:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:18:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:18:40 INFO - Return code: 0 15:18:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:18:40 INFO - https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:18:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:41 INFO - Downloaded 18109591 bytes. 15:18:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:18:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:18:45 INFO - Return code: 0 15:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:18:45 INFO - https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:18:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:18:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:18:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:18:46 INFO - Downloaded 10840145 bytes. 15:18:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:18:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:18:46 INFO - Return code: 0 15:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:18:46 INFO - https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:18:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:18:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:18:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 15:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 15:18:51 INFO - Downloaded 110124729 bytes. 15:18:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:18:51 INFO - mkdir: /builds/slave/test/properties 15:18:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:18:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:18:51 INFO - Contents: 15:18:51 INFO - build_url:https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - Running post-action listener: set_extra_try_arguments 15:18:52 INFO - ##### 15:18:52 INFO - ##### Running populate-webroot step. 15:18:52 INFO - ##### 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: populate_webroot 15:18:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:18:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:18:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:18:52 INFO - '--tppagecycles', 15:18:52 INFO - '7'], 15:18:52 INFO - 'tests': ['tsvgm']}}, 15:18:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:18:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:18:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:18:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:18:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['damp', 'tps']}, 15:18:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'tests': ['damp', 'tps']}, 15:18:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['dromaeo_dom']}, 15:18:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:18:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tsvgx', 15:18:52 INFO - 'tsvgr_opacity', 15:18:52 INFO - 'tart', 15:18:52 INFO - 'tscrollx', 15:18:52 INFO - 'cart']}, 15:18:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:18:52 INFO - 'tsvgr_opacity', 15:18:52 INFO - 'tart', 15:18:52 INFO - 'tscrollx', 15:18:52 INFO - 'cart']}, 15:18:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tp5o']}, 15:18:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'tests': ['tp5o']}, 15:18:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s', 15:18:52 INFO - '--xperf_path', 15:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:52 INFO - 'tests': ['tp5n']}, 15:18:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:18:52 INFO - 'talos_options': ['--xperf_path', 15:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:52 INFO - 'tests': ['tp5n']}}, 15:18:52 INFO - 'talos.zip': {'path': '', 15:18:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - ##### 15:18:52 INFO - ##### Running create-virtualenv step. 15:18:52 INFO - ##### 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: create_virtualenv 15:18:52 INFO - Creating virtualenv /builds/slave/test/build/venv 15:18:52 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 15:18:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:18:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:18:52 INFO - Using real prefix '/tools/python27' 15:18:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:18:53 INFO - Installing distribute.............................................................................................................................................................................................done. 15:18:56 INFO - Installing pip.................done. 15:18:56 INFO - Return code: 0 15:18:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:18: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')]} 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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')]} 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:18:56 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 15:18:56 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 15:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:56 INFO - 'HOME': '/Users/cltbld', 15:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:56 INFO - 'LOGNAME': 'cltbld', 15:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:56 INFO - 'MOZ_NO_REMOTE': '1', 15:18:56 INFO - 'NO_EM_RESTART': '1', 15:18:56 INFO - 'PAGER': '/bin/cat', 15:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:56 INFO - 'PWD': '/builds/slave/test', 15:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:56 INFO - 'SHELL': '/bin/bash', 15:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:18:56 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:56 INFO - 'USER': 'cltbld', 15:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:56 INFO - 'XPC_FLAGS': '0x0', 15:18:56 INFO - 'XPC_SERVICE_NAME': '0', 15:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:56 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 15:18:56 INFO - Cleaning up... 15:18:56 INFO - Return code: 0 15:18:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:18: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')]} 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18: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')]} 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:18:56 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 15:18:56 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 15:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:56 INFO - 'HOME': '/Users/cltbld', 15:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:56 INFO - 'LOGNAME': 'cltbld', 15:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:56 INFO - 'MOZ_NO_REMOTE': '1', 15:18:56 INFO - 'NO_EM_RESTART': '1', 15:18:56 INFO - 'PAGER': '/bin/cat', 15:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:56 INFO - 'PWD': '/builds/slave/test', 15:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:56 INFO - 'SHELL': '/bin/bash', 15:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:18:56 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:56 INFO - 'USER': 'cltbld', 15:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:56 INFO - 'XPC_FLAGS': '0x0', 15:18:56 INFO - 'XPC_SERVICE_NAME': '0', 15:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:56 INFO - Downloading/unpacking psutil>=0.7.1 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:18:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:18:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:18:59 INFO - Installing collected packages: psutil 15:18:59 INFO - Running setup.py install for psutil 15:18:59 INFO - building 'psutil._psutil_osx' extension 15:18:59 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 15:19:00 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 15:19:00 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 15:19:00 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 15:19:00 INFO - building 'psutil._psutil_posix' extension 15:19:00 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 15:19:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:19:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:19:00 INFO - ^ 15:19:00 INFO - 1 warning generated. 15:19:00 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 15:19:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:00 INFO - Successfully installed psutil 15:19:00 INFO - Cleaning up... 15:19:00 INFO - Return code: 0 15:19:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:19:00 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')]} 15:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:00 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')]} 15:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:00 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:00 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 15:19:00 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 15:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:00 INFO - 'HOME': '/Users/cltbld', 15:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:00 INFO - 'LOGNAME': 'cltbld', 15:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:00 INFO - 'MOZ_NO_REMOTE': '1', 15:19:00 INFO - 'NO_EM_RESTART': '1', 15:19:00 INFO - 'PAGER': '/bin/cat', 15:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:00 INFO - 'PWD': '/builds/slave/test', 15:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:00 INFO - 'SHELL': '/bin/bash', 15:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:00 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:00 INFO - 'USER': 'cltbld', 15:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:00 INFO - 'XPC_FLAGS': '0x0', 15:19:00 INFO - 'XPC_SERVICE_NAME': '0', 15:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:19:03 INFO - Installing collected packages: mozsystemmonitor 15:19:03 INFO - Running setup.py install for mozsystemmonitor 15:19:03 INFO - Successfully installed mozsystemmonitor 15:19:03 INFO - Cleaning up... 15:19:03 INFO - Return code: 0 15:19:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:03 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:03 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 15:19:03 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 15:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:03 INFO - 'HOME': '/Users/cltbld', 15:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:03 INFO - 'LOGNAME': 'cltbld', 15:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:03 INFO - 'MOZ_NO_REMOTE': '1', 15:19:03 INFO - 'NO_EM_RESTART': '1', 15:19:03 INFO - 'PAGER': '/bin/cat', 15:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:03 INFO - 'PWD': '/builds/slave/test', 15:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:03 INFO - 'SHELL': '/bin/bash', 15:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:03 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:03 INFO - 'USER': 'cltbld', 15:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:03 INFO - 'XPC_FLAGS': '0x0', 15:19:03 INFO - 'XPC_SERVICE_NAME': '0', 15:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:04 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 15:19:06 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:07 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 15:19:07 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 15:19:07 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 15:19:07 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 15:19:07 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 15:19:07 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 15:19:07 INFO - Downloading docopt-0.6.1.tar.gz 15:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:07 INFO - Running setup.py install for blobuploader 15:19:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:07 INFO - Running setup.py install for requests 15:19:08 INFO - Running setup.py install for docopt 15:19:08 INFO - Successfully installed blobuploader requests docopt 15:19:08 INFO - Cleaning up... 15:19:08 INFO - Return code: 0 15:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:08 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 15:19:08 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 15:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:08 INFO - 'HOME': '/Users/cltbld', 15:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:08 INFO - 'LOGNAME': 'cltbld', 15:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:08 INFO - 'MOZ_NO_REMOTE': '1', 15:19:08 INFO - 'NO_EM_RESTART': '1', 15:19:08 INFO - 'PAGER': '/bin/cat', 15:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:08 INFO - 'PWD': '/builds/slave/test', 15:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:08 INFO - 'SHELL': '/bin/bash', 15:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:08 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:08 INFO - 'USER': 'cltbld', 15:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:08 INFO - 'XPC_FLAGS': '0x0', 15:19:08 INFO - 'XPC_SERVICE_NAME': '0', 15:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:08 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qcVbe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-sFzqQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-4O3cEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Qp0b1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-AfpnFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-9_b0PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-y3u_Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-A2egnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-vvrC8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-k2q06M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:09 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-gGDUfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-JgebYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-EQWjUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-236ipd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-lhdIO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-06PZG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:10 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qLU5bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:10 INFO - Running setup.py install for manifestparser 15:19:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:10 INFO - Running setup.py install for mozcrash 15:19:10 INFO - Running setup.py install for mozdebug 15:19:11 INFO - Running setup.py install for mozdevice 15:19:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Running setup.py install for mozfile 15:19:11 INFO - Running setup.py install for mozhttpd 15:19:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Running setup.py install for mozinfo 15:19:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Running setup.py install for mozInstall 15:19:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:11 INFO - Running setup.py install for mozleak 15:19:11 INFO - Running setup.py install for mozlog 15:19:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Running setup.py install for moznetwork 15:19:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Running setup.py install for mozprocess 15:19:12 INFO - Running setup.py install for mozprofile 15:19:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Running setup.py install for mozrunner 15:19:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:12 INFO - Running setup.py install for mozscreenshot 15:19:12 INFO - Running setup.py install for moztest 15:19:13 INFO - Running setup.py install for mozversion 15:19:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:13 INFO - Cleaning up... 15:19:13 INFO - Return code: 0 15:19:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:13 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:13 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 15:19:13 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 15:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:13 INFO - 'HOME': '/Users/cltbld', 15:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:13 INFO - 'LOGNAME': 'cltbld', 15:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:13 INFO - 'MOZ_NO_REMOTE': '1', 15:19:13 INFO - 'NO_EM_RESTART': '1', 15:19:13 INFO - 'PAGER': '/bin/cat', 15:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:13 INFO - 'PWD': '/builds/slave/test', 15:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:13 INFO - 'SHELL': '/bin/bash', 15:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:13 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:13 INFO - 'USER': 'cltbld', 15:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:13 INFO - 'XPC_FLAGS': '0x0', 15:19:13 INFO - 'XPC_SERVICE_NAME': '0', 15:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:13 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-ZFqEJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:13 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)) 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:13 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-EBZfjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:13 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)) 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:13 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-KslwWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:13 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)) 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:13 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Mi8jE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:13 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)) 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:13 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-JkNAgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:13 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)) 15:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-5m21za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Z4peid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-q71WJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-bUjIgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-WCWvsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-1u8EUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-GYP9Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-ut_H7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-4jjqqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:14 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)) 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:14 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-gSRTbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:15 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)) 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:15 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-yYCVhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:15 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)) 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:15 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-CtMgGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:15 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)) 15:19:15 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)) 15:19:15 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)) 15:19:15 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)) 15:19:15 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)) 15:19:15 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)) 15:19:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:17 INFO - Downloading blessings-1.6.tar.gz 15:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:17 INFO - Installing collected packages: blessings 15:19:17 INFO - Running setup.py install for blessings 15:19:17 INFO - Successfully installed blessings 15:19:17 INFO - Cleaning up... 15:19:18 INFO - Return code: 0 15:19:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:18 INFO - Reading from file tmpfile_stdout 15:19:18 INFO - Current package versions: 15:19:18 INFO - blessings == 1.6 15:19:18 INFO - blobuploader == 1.2.4 15:19:18 INFO - docopt == 0.6.1 15:19:18 INFO - manifestparser == 1.1 15:19:18 INFO - mozInstall == 1.12 15:19:18 INFO - mozcrash == 0.17 15:19:18 INFO - mozdebug == 0.1 15:19:18 INFO - mozdevice == 0.48 15:19:18 INFO - mozfile == 1.2 15:19:18 INFO - mozhttpd == 0.7 15:19:18 INFO - mozinfo == 0.9 15:19:18 INFO - mozleak == 0.1 15:19:18 INFO - mozlog == 3.1 15:19:18 INFO - moznetwork == 0.27 15:19:18 INFO - mozprocess == 0.22 15:19:18 INFO - mozprofile == 0.28 15:19:18 INFO - mozrunner == 6.11 15:19:18 INFO - mozscreenshot == 0.1 15:19:18 INFO - mozsystemmonitor == 0.0 15:19:18 INFO - moztest == 0.7 15:19:18 INFO - mozversion == 1.4 15:19:18 INFO - psutil == 3.1.1 15:19:18 INFO - requests == 1.2.3 15:19:18 INFO - wsgiref == 0.1.2 15:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:18 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 15:19:18 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 15:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:18 INFO - 'HOME': '/Users/cltbld', 15:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:18 INFO - 'LOGNAME': 'cltbld', 15:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:18 INFO - 'MOZ_NO_REMOTE': '1', 15:19:18 INFO - 'NO_EM_RESTART': '1', 15:19:18 INFO - 'PAGER': '/bin/cat', 15:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:18 INFO - 'PWD': '/builds/slave/test', 15:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:18 INFO - 'SHELL': '/bin/bash', 15:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:18 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:18 INFO - 'USER': 'cltbld', 15:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:18 INFO - 'XPC_FLAGS': '0x0', 15:19:18 INFO - 'XPC_SERVICE_NAME': '0', 15:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 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)) 15:19:18 INFO - Cleaning up... 15:19:18 INFO - Return code: 0 15:19:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:18 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 15:19:18 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 15:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:18 INFO - 'HOME': '/Users/cltbld', 15:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:18 INFO - 'LOGNAME': 'cltbld', 15:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:18 INFO - 'MOZ_NO_REMOTE': '1', 15:19:18 INFO - 'NO_EM_RESTART': '1', 15:19:18 INFO - 'PAGER': '/bin/cat', 15:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:18 INFO - 'PWD': '/builds/slave/test', 15:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:18 INFO - 'SHELL': '/bin/bash', 15:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:18 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:18 INFO - 'USER': 'cltbld', 15:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:18 INFO - 'XPC_FLAGS': '0x0', 15:19:18 INFO - 'XPC_SERVICE_NAME': '0', 15:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:18 INFO - Downloading/unpacking jsonschema 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:21 INFO - Downloading/unpacking functools32 (from jsonschema) 15:19:21 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 15:19:21 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 15:19:21 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 15:19:21 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 15:19:21 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 15:19:21 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 15:19:21 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:21 INFO - warning: no files found matching '*.txt' 15:19:21 INFO - no previously-included directories found matching 'build' 15:19:21 INFO - no previously-included directories found matching 'dist' 15:19:21 INFO - no previously-included directories found matching '.git*' 15:19:21 INFO - Installing collected packages: jsonschema, functools32 15:19:21 INFO - Running setup.py install for functools32 15:19:21 INFO - warning: no files found matching '*.txt' 15:19:21 INFO - no previously-included directories found matching 'build' 15:19:21 INFO - no previously-included directories found matching 'dist' 15:19:21 INFO - no previously-included directories found matching '.git*' 15:19:21 INFO - Successfully installed jsonschema functools32 15:19:21 INFO - Cleaning up... 15:19:21 INFO - Return code: 0 15:19:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:19:21 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')]} 15:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:21 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')]} 15:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:21 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 0x102caaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff46ada2030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, '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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:21 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 15:19:21 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 15:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:21 INFO - 'HOME': '/Users/cltbld', 15:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:21 INFO - 'LOGNAME': 'cltbld', 15:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:21 INFO - 'MOZ_NO_REMOTE': '1', 15:19:21 INFO - 'NO_EM_RESTART': '1', 15:19:21 INFO - 'PAGER': '/bin/cat', 15:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:21 INFO - 'PWD': '/builds/slave/test', 15:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:21 INFO - 'SHELL': '/bin/bash', 15:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:19:21 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:21 INFO - 'USER': 'cltbld', 15:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:21 INFO - 'XPC_FLAGS': '0x0', 15:19:21 INFO - 'XPC_SERVICE_NAME': '0', 15:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:22 INFO - Downloading/unpacking flake8 15:19:22 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 15:19:22 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 15:19:22 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 15:19:22 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 15:19:22 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 15:19:22 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 15:19:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:19:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:19:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:19:25 INFO - no previously-included directories found matching 'docs/_build' 15:19:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:19:26 INFO - Running setup.py install for pep8 15:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:19:26 INFO - no previously-included directories found matching 'docs/_build' 15:19:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:19:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:19:26 INFO - Cleaning up... 15:19:26 INFO - Return code: 0 15:19:26 INFO - Running post-action listener: _resource_record_post_action 15:19:26 INFO - Running post-action listener: _start_resource_monitoring 15:19:26 INFO - Starting resource monitoring. 15:19:26 INFO - ##### 15:19:26 INFO - ##### Running install step. 15:19:26 INFO - ##### 15:19:26 INFO - Running pre-action listener: _resource_record_pre_action 15:19:26 INFO - Running main action method: install 15:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:26 INFO - Reading from file tmpfile_stdout 15:19:26 INFO - Detecting whether we're running mozinstall >=1.0... 15:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:19:26 INFO - Reading from file tmpfile_stdout 15:19:26 INFO - Output received: 15:19:26 INFO - Usage: mozinstall [options] installer 15:19:26 INFO - Options: 15:19:26 INFO - -h, --help show this help message and exit 15:19:26 INFO - -d DEST, --destination=DEST 15:19:26 INFO - Directory to install application into. [default: 15:19:26 INFO - "/builds/slave/test"] 15:19:26 INFO - --app=APP Application being installed. [default: firefox] 15:19:26 INFO - mkdir: /builds/slave/test/build/application 15:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:20:00 INFO - Reading from file tmpfile_stdout 15:20:00 INFO - Output received: 15:20:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:20:00 INFO - Running post-action listener: _resource_record_post_action 15:20:00 INFO - ##### 15:20:00 INFO - ##### Running run-tests step. 15:20:00 INFO - ##### 15:20:00 INFO - Running pre-action listener: _resource_record_pre_action 15:20:00 INFO - Running main action method: run_tests 15:20:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:20:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:20:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:20:00 INFO - 2016-04-22 15:20:00.453 screenresolution[1644:7861] starting screenresolution argv=screenresolution get 15:20:00 INFO - 2016-04-22 15:20:00.455 screenresolution[1644:7861] Display 0: 1600x1200x32@60 15:20:00 INFO - 2016-04-22 15:20:00.460 screenresolution[1645:7863] starting screenresolution argv=screenresolution list 15:20:00 INFO - Available Modes on Display 0 15:20:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:20:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:20:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:20:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:20:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:20:00 INFO - Intel Iris: 15:20:00 INFO - Chipset Model: Intel Iris 15:20:00 INFO - Type: GPU 15:20:00 INFO - Bus: Built-In 15:20:00 INFO - VRAM (Dynamic, Max): 1536 MB 15:20:00 INFO - Vendor: Intel (0x8086) 15:20:00 INFO - Device ID: 0x0a2e 15:20:00 INFO - Revision ID: 0x0009 15:20:00 INFO - Displays: 15:20:00 INFO - Display: 15:20:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:20:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:20:00 INFO - Display Serial Number: 1600x1200 60 15:20:00 INFO - Main Display: Yes 15:20:00 INFO - Mirror: Off 15:20:00 INFO - Online: Yes 15:20:00 INFO - Rotation: Supported 15:20:00 INFO - Return code: 0 15:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:00 INFO - Python 2.7.3 15:20:00 INFO - Return code: 0 15:20:00 INFO - grabbing minidump binary from tooltool 15:20:00 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')]} 15:20:00 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 0x102b49e00>, '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 0x102972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b44b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:00 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 15:20:00 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 15:20:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:00 INFO - Return code: 0 15:20:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:20:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:20:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:20:01 INFO - Return code: 0 15:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0091 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:20:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktcr5NFCmh/Render', 15:20:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:01 INFO - 'HOME': '/Users/cltbld', 15:20:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:01 INFO - 'LOGNAME': 'cltbld', 15:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:01 INFO - 'MOZ_NO_REMOTE': '1', 15:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:01 INFO - 'NO_EM_RESTART': '1', 15:20:01 INFO - 'PAGER': '/bin/cat', 15:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:01 INFO - 'PWD': '/builds/slave/test', 15:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:01 INFO - 'SHELL': '/bin/bash', 15:20:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFtBwzkI9I/Listeners', 15:20:01 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 15:20:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:01 INFO - 'USER': 'cltbld', 15:20:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:01 INFO - 'XPC_FLAGS': '0x0', 15:20:01 INFO - 'XPC_SERVICE_NAME': '0', 15:20:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:20:02 INFO - mozversion application_buildid: 20160422131145 15:20:02 INFO - mozversion application_changeset: ab85f19b67b1d093e060bfe52a672a3a9995e252 15:20:02 INFO - mozversion application_display_name: Nightly 15:20:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:02 INFO - mozversion application_name: Firefox 15:20:02 INFO - mozversion application_remotingname: firefox 15:20:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:20:02 INFO - mozversion application_vendor: Mozilla 15:20:02 INFO - mozversion application_version: 48.0a1 15:20:02 INFO - mozversion platform_buildid: 20160422131145 15:20:02 INFO - mozversion platform_changeset: ab85f19b67b1d093e060bfe52a672a3a9995e252 15:20:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:20:02 INFO - mozversion platform_version: 48.0a1 15:20:02 INFO - using testdate: 1461363602 15:20:02 INFO - actual date: 1461363602 15:20:02 INFO - starting webserver on 'localhost:49284' 15:20:02 INFO - SUITE-START | Running 2 tests 15:20:02 INFO - TEST-START | dromaeo_css 15:20:02 INFO - operating with platform_type : mac_ 15:20:02 INFO - Initialising browser for dromaeo_css test... 15:20:02 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp7eeKXA/profile http://localhost:49284/getInfo.html) 15:20:04 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:20:04 INFO - PROCESS | 1663 | colorDepth:24 15:20:04 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:20:04 INFO - PROCESS | 1663 | __metrics 15:20:04 INFO - PROCESS | 1663 | 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" 15:20:04 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:20:04 INFO - TEST-INFO | 1663: exit 0 15:20:04 INFO - Browser initialized. 15:20:04 INFO - Running cycle 1/1 for dromaeo_css test... 15:20:04 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp7eeKXA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:16 INFO - PROCESS | 1666 | RSS: Main: 238874624 15:20:16 INFO - PROCESS | 1666 | 15:21:08 INFO - PROCESS | 1666 | 1461363668078 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] 15:23:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 15:23:19 INFO - PROCESS | 1666 | RSS: Main: 358567936 15:23:19 INFO - PROCESS | 1666 | 15:26:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 15:26:23 INFO - PROCESS | 1666 | RSS: Main: 379379712 15:26:23 INFO - PROCESS | 1666 | 15:29:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 15:29:26 INFO - PROCESS | 1666 | RSS: Main: 375767040 15:29:26 INFO - PROCESS | 1666 | 15:32:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 15:32:29 INFO - PROCESS | 1666 | RSS: Main: 378384384 15:32:29 INFO - PROCESS | 1666 | 15:35:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 15:35:33 INFO - PROCESS | 1666 | RSS: Main: 349646848 15:35:33 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 15:38:36 INFO - PROCESS | 1666 | RSS: Main: 382382080 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | __start_tp_report 15:38:36 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:38:36 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:38:36 INFO - PROCESS | 1666 | |i|pagename|runs| 15:38:36 INFO - PROCESS | 1666 | |0;dojo.html;3381.2054167270685;3438.0545350028756;3458.6541345865407;3487.5291835602216;3496.038589387918;5616.382197958222;5643.294588176478;5652.943470565293;5662.660240385575;5675.914861277079;5695.45893140152;5719.656820590757;5748.568857335696;5847.210626565412;5851.7074146292625;5095.566876815456;5165.251038599401;5197.766100525486;5211.921821172686;5289.841304760844;5257.815976440826;5258.55302299305;5279.313689220415;5304.045271851065;5356.9732151339185;5103.106956282654;5129.204973229118;5185.740712964337;5215.009148261818;5220;5168.5089916458255;5196.948030519684;5213.687178769286;5221.39953905302;5267.920981185285;5077.64456488046;5170.508801663836;5197.58419326453;5212.843614691566;5224.425313215544;5557.722113894289;5591.329040515124;5653.660780353192;5658.264425624684;5716.79991200306;6195.473384762301;6221.564490485668;6225.035119556431;6226.968865155645;6243.157173781527;6160.84597885052;6253.843653908689;6273.56085074045;6291.622502649856;6307.18006659136;5701.629394089371;5728.856778580527;5757.107648314518;5820.563457740602;5820.738066787005;6245.156903818017;6248.062790581449;6256.74973001084;6270.843228919222;6279.843003924939;5945.078512830518;6026.939730602726;6074.605150665193;6113.510919126459;6118.541109416811;6111.04482360895;6155.815325540241;6171.444569988633;6172.351903050205;6183.907241391383;6109.009944784285;6115.575014303891;6120.846978825476;6225.6264624121795;6236.34518375564;6215.471684906744;6219.626822390581;6224.906626400608;6227.221597300338;6239.344868788802;4826.686265392738;4886.438059623118;4901.387326584177;4904.362432883702;4944.55499005084;4284.357346398003;4288.614024737726;4291.69958102936;4441.644668426518;4445.711028783118;5645.887082258414;5653.434656534396;5655.557832752622;5665.461781130786;5669.461401166883;6134;6240.687965601702;6268.811935641938;6270.498360131179;6291.087792270096;5622.437756224344;5628.549716022791;5645.294338207724;5649.293838270216;5651.717414129277;5309.309789727311;5327.547158491571;5336.092864213093;5337.092694241988;5337.332833395825;5799.971000144972;5863.765449382062;6242.344553821965;6254.34329395416;6264.686765661699;6095.891769653442;6096.420840020191;6097.237845269341;6111.257501106225;6114.388610881663;6287.779927702692;6359.459445947146;6368.840778980562;6371.980483122678;6379.362063793584;6009.939900601113;6075.544334175043;6084.9959756639655;6087;6094.299155597163;4863.659543832039;6122.825106970096;6152.64641778808;6265.4361107500545;6273.341299163522;6026.246719160105;6052.092186172109;6053.606515576474;6062.454379105901;6068.4538391544975;5858.297004359504;5992.700364981821;6027.578069535266;6060.969695151496;6064.4541991221;5795.449432303924;5907.291125064848;5951.1370851225465;5976.163337132718;5990.6705131218205;6037.939620603738;6083.695815209133;6103.69481525913;6127.050307202391;6132.478739307209;5748.48263656273;5865.120231965068;5866.67733274674;5876.6180198286975;5877.35349111606 15:38:36 INFO - PROCESS | 1666 | |1;ext.html;511.70321213696053;518.4400847085151;529.864884454464;541.8320320700582;596.5376832954457;30856.691433085663;38451;39456.21087578247;39539.2092158157;39562.20875582487;43699.781501092526;44482.777586112104;44514;44559.55440445594;44650.10699786002;8621.224089831914;9511.191548718367;9766.853497197548;9775.853362199572;9777.364471309378;8795.120487951217;11562.07503399726;11869.703257418547;11869.703257418547;11880.524779008872;11512.13658975576;11601.709957251094;11607.593734219305;11619.360935148563;11643.476043578059;8714.34642401816;8974.326925480615;8985.550722463917;11577.131715121357;11759.47082381295;8668.133186681345;11511.597094101666;11800.291982481078;11831.763364732744;11854.170208085437;40848.59151408478;41572.584274157176;41581;41637.791811041054;41826.790866045776;3382.695557399821;3734.6825519830845;3771.283456143324;3820.8280627371837;3897.824597893102;28706.42587148266;29727.70272297271;30589;30753;30831;2750.2711781377952;2763.613094166798;2805.0603047978952;2816.887324507017;2839.8580070996572;347237;380340;380488;381237;382234;62345;63021;63432;63452;63508.36491635024;71965;73700.63149684218;73851.26148738444;73894;74060;14191.574252772434;17028.57428564271;17207.225674844663;17256.22346994363;17331.913340433217;11863.46614402354;12153.69615759613;12877.227366358049;13107.279099649611;13200.801987970188;17855;18985.810141898404;19486.836990001848;20103.798962010485;20199.899000505196;7897.407694422941;8032.638531266107;8083.6766529338165;8086.676532938734;8121.593920304079;953.6948176583566;960.6349587156975;1001.1790331927895;1083.2417307884512;1127.6786115956913;29569.408611827643;29731;30436.847815761223;30460;30561.541576876363;1020.7648745018462;1068.4657671164418;1100.5212732461405;1129.170060005895;1194.3371428856894;11629.18595698293;14290;15185.544433667008;15339.463118791013;15704.371825127095;657412;661243;670515;670894.6455267692;671174;60675.69662151661;61492.999999999105;61662.383376165664;61710.69144654248;61780.38219617836;1338.5315139701222;1474.9410023598937;1512.4857548433586;1523.634327761307;1523.9466618668293;24311.878440607685;29416;30420.695793042672;30440.41256493223;30632.387352253278;46844.53155468538;53948.73025634847;54052.72973635264;54064.72967635294;54083.72958135184;562572;593819;594056;595014;596766;34109.14727131742;36088.4586731194;37030.629693702715;37052.25895482129;43395;43375;44296.335554966055;44804.55195448004;44827.327590086825;46161.30758038565;35890;37733.622663773014;38247;38335.23329533338;38632.034199145244;37632.623673762915;38198.23603527858;38397.23205535818;39432.6056739429;39500.80249598734;37606.435903462014;38298.80850595729;39053.2189356217;39056.41415378828;39077.41383879187;30990.69009309968;33204.66795332016;34123;34132;34249.48625770665;21334;23524;24748.257552273462;25187.496250075263;25217.621735674347 15:38:36 INFO - PROCESS | 1666 | |2;jquery.html;396.95831937646545;399.32115403813503;400.797597213407;401.2917201139987;414.6247645880479;14006.929965350158;19430.514237144078;20455.795442045594;20488.795112048894;20770.480737981557;15434.845651543481;15931.601709957256;18201.362952296673;19972.60054798903;20696.965151742428;5844.123381492789;6022.789202377918;6072.969635151818;6078.696065196745;6083.422074902877;6004.309504406983;6013.278406591216;6015.398460153993;6048.274207095154;6123.173371594822;5791.333996590404;5902.232709747748;5940.970295148518;5947.940520594803;5954.4640982311585;5899.115132730082;5928.170056192136;6000.489958353502;6000.699965001732;6010;5048.14181589127;5203.349581302337;5226;5228.529432351107;5239.449857764918;26199.821008054492;26307.605385919225;26337.73662263372;26758.33104172392;26932.326691832666;2139.8716077035274;2172.652375619909;2190.8466407351493;2354.564405584972;2648;12473.93763031188;14326.570202893825;14808.703825923529;14898.957073004894;15017.924910375488;1565.1939251285596;1714.7599336092958;1716.9570760730958;1743.2155530011546;1816.5277027972822;91200;119451;120374.39812800968;120677.79322206753;121321.39339303336;9295.674651387304;10762.3542587445;11318.603848865416;11537.596184133516;11539.307641541534;14189.148651080968;14270;14281.642958925902;14295;14301.356438960272;6453;7633.770986870402;8143.42995990275;8735.956320218358;8767.780805479913;7689.231076892266;7985.722284434462;8601.397902146915;8686.86969695455;8770.166834150745;5691.658500489983;6662.933370666231;6762.797116086525;6774.932250677529;6784.134363050102;1883.0584707646176;1887.4715079777684;1899.5725961658516;1936.014761987887;1939.679952807799;950.3110245072268;968.4915419404728;975.8097171051577;1041.271110222847;1050.9421981791074;19878.60242795165;20453.07961141752;20871;21549.922503874743;21773.34679959604;938.2415697848304;964.3249725192394;972.6692924405736;1003.7653685966156;1057.582255009267;10452.92374076514;10493.527791249413;10910.68720568274;10980.780384392427;11055.336679799238;581655;594447;605527;608654;631227;104894;127730.36134819266;133954;134208;137392;1007.7782887764827;1011.1758916483043;1051.3481641382239;1057.5293994172519;1060.4591658254346;17412.042337671548;17796.110194490484;17890.731639025686;17954.820451795316;18338.266469341077;47384;49031.774205645146;55553.44446555483;55725.72137139289;56617.43382566122;73460;75979;77385.06537336612;80479;84901;5572.414896435978;8230.21812927771;8339.749807505785;8401.873971890549;8701.608427620646;12134.211276267015;12446;12690.619281421572;12993.870061299265;13031.152975056591;10983.670489885317;11110.61112861046;11132.499037543168;11261.436928153396;11273.661790146309;10861.782764344827;10899.128069754559;11006.229563930448;11272.9436352821;11315.660530184108;10967.50646220938;11319.037881779812;11619.0704743622;11725.706857328294;11754.353510557;8117.675292988451;8190.836183276182;8364.539950302791;8385.161483851565;8485.787855303666;10307.381557106597;11150.275232109887;11150.776984460428;11410.258333208647;11492.367919764492 15:38:36 INFO - PROCESS | 1666 | |3;mootools.html;1417.6314158318837;1436.7126574685064;1629.3238306102967;1860.135037207699;2023.7267968824212;7846.686132554698;8367.91632083679;8638.524881131536;8645.69740059098;8660.004099528556;8289.461185022985;8667.046624871275;8674.783130421743;8675;8682.305415566756;1861.7021276595747;2147.860389074713;2386.9761302387014;2468.9013389041793;2475.0718480569785;2474.2206205045445;2495.862727549984;2495.900163993438;2498.725140234573;2499.3501689560753;2262.592733308004;2269.2965180793926;2417.395651087228;2463.9014439422403;2467.3214865911837;2122.5118222808687;2361.303415492434;2393.628987506939;2445.425325048612;2459.8893049812623;2436.84160529565;2451.019592163131;2468.8642124683133;2471.1721573272976;2473.0849585653245;8652.783680407978;8716.694915677921;8720.825583488358;8721.084286149926;8722.38943273971;5009.974950125226;5230.764615592308;5233.528982391603;5259.789608415659;5266.947330526722;3484.181217413906;3565.4651802229614;3582.2344150024214;3642.4718415829557;3657.750483452684;1001.7295330260789;1021.0606242257142;1022.2792930983638;1031.4842578710645;1031.8142734307821;11490.138239631977;11597.4201289936;11613.535458581646;11613.767724645544;11643.708907277301;4730.59789917854;4800.567948884617;4833.617556542219;4906.362172917497;4918;8415.326773858076;10096.444695541815;10129.544170512345;10144.797104058025;10155.289129760846;5414.214938833847;5431.4840090191365;5437.130059190589;5445.237666726662;5451.918221226685;4378.408914796461;4500.774961251925;4554.476235232991;4755.38180036593;4937.432195297515;3948.0919388540797;3985.123272879962;4061.6750659947133;4090.1001779608437;4102.856400026045;1315.8092076648832;1355.3968484024515;1361.5370773936909;1371.8010888421322;1373.203541945689;922.9030951749969;925.2690374603987;939.5537119868145;954.355809828363;964.71058682395;5846.736896839652;5905.498032667186;5934.762609495659;5972.552058595623;6055.757769689163;459.6299978517259;470.15372329806894;478.33750255895137;482.8382491865194;484.2494134092143;3505.1938054247667;3911.6088391161225;3950.2297052075146;4000.5399379071196;4062;10240.48797560119;10675.466226688635;10761.354318740901;10763.892361076445;10779.407132607781;9938.900610993798;10732.892671073188;10733.409662468639;10735.946320268506;10740.838887416696;6668.86662266752;7911.604419779103;8180.9590952044855;8191.426600138052;8201.95899020501;4632.86101416958;5915.911261330997;6005.099235114768;6153.784617538365;6178.104174894705;9769.462679552693;9855.95072024664;9887;9896.208303335572;9898.307118501636;9473.763155921157;10155.492225388849;10242.283040187116;10257.179425645782;10280.22898282617;6847.041414201916;7065.22282548909;7343.889841652486;7434.442416818652;7634.656440460304;8208.179182081743;8383.413161078666;8383.496990180609;8383.706570270011;8384.916150838413;7214.675339609835;7417.666205020895;7442.888356674546;7513.624318784148;7601.353884919844;6118.20463339763;7208.4593655475;7212.4590655699985;7280.854382912206;7286.781396558112;6978.162620485574;7053.612051337226;7142.392896603639;7154.284571542804;7157.105361829771;4877.073356062337;4915.287283343967;4985.227289770091;5022.949770502249;5183.844484665466;4942.75286235694;5053.090443720019;5103.642745007814;5144.742762861917;5270.947290527046 15:38:36 INFO - PROCESS | 1666 | |4;prototype.html;319.86086052567134;321.1905996887842;329.84167599552217;329.9406106900757;334.6736931491794;5073.2643766653855;5797.855053623662;5880.2355693759855;5890.882182356356;5895.469407753301;4722.976385118078;5813.62211456255;5858.886811505811;5862.306746749542;5863.413658634135;4031.9920019995;4220.810063547147;4273.465816772903;4480.484744254403;4515.074409746011;4413.161499315136;4459.821607135711;4483.5964763171305;4488;4490;3441.6902478776906;4392.704152275087;4481.641468682497;4490.326451032355;4509.210888094571;3670.871519496805;4168;4214.557471465498;4464.575865292792;4482.439695038121;3059.908202753921;3231.8222497762613;3512.6838584527513;3577.801436518769;3874.849458550503;5638.520725738317;5658.321001479808;5667.546596272288;5675.659460432387;5702.429757024306;4962.865967633952;5094.439611642716;5102.795888164469;5141.38303403594;5163.199704045879;5078.454782969222;5381.623286369956;5863.355030946593;6170.2053237259215;6175.66033868137;3563.394222982099;3793.7670257166533;4228.4080228768;4275.038116423811;4289.656827453796;6222.284437289724;6525.9884717868335;6530.706118224643;6535.607863528204;6581.473482121419;3696.464012718141;3908.6286802753693;4295.871123866289;4328.696991210585;4328.826846926151;3859.054531639766;4077.0215148364177;4395.384646149541;4408.669349798778;4433.091216300651;4937.851864444073;5108.182690769533;5111.386633603991;5138.820141295037;5143.8199663012365;5083.771230294646;6279.874402512016;6286.717097730613;6291.465225455796;6306.590071645328;1267.315649549233;1292.9030322725832;1294.8057791331187;1531.1196062264244;1537.8077740282465;839.6515446089804;948.3551185193996;975.673149494913;1029.2383636109225;1032.7056788815153;4879.804807807719;5667.943320566742;5976.61152025117;5981.192539007178;5983.252093488314;5374.865628359323;6328.6835658216905;6511.609303441809;6563.606183628997;6569.540132190701;5103.974480127576;5300.833816560351;5930.555208359304;5942.286925568959;5971.342352964694;2571.5756900111273;3283.1122104789843;3451.87918422854;3454.602720687153;3457.4640930655787;6067.605605635619;6240.720652266275;6415.326390728999;6770.119884415093;6830.21452532965;5493;6372.904406433907;6666.866512785128;6701.396874281338;6702.631355275506;6560.573562718408;6777.932220677829;6809.693563789542;6857.588544687334;6869.278725733726;6461.483081353575;6533.542652014504;6537.313582073813;6554.442872355903;6566.409023187936;5146.305248791366;5507.586930980273;5602.3837377887585;5841.299044114733;6070.42169036045;5145.639805213599;5984.581079324405;6179.918514260076;6592.604443733391;6814.8637027260165;4125.257453658371;4337.674674399495;4383.693141480066;4385.276429389115;4394.231009573376;5298.284731561344;6197.411245931629;6465.288818229897;6511.153550038464;6524.67376631176;6038.245219347582;6286.528510361833;6310.558260921692;6344.397282258178;6378.799314236581;4998.6750861193905;5141.897162056813;6216.627002379872;6309.328028072473;6316.789123806815;4809.495003024632;5083.415407228216;5939.732712028055;6138.570300078952;6162.22972128484;5390.056740070394;6082.817515474543;6290.119383286252;6313.55805093639;6320.494360451061;5765.509931655856;6160.691965401622;6166.753329866934;6172.4136207060255;6191.504679625529 15:38:36 INFO - PROCESS | 1666 | |5;yui.html;744.5830335012394;746.7386414754833;748.8689479341114;755.697720911635;815.2214635023552;14915.552533423988;14925.402983880631;15392.538223853287;15420.537383878474;15701.371945122182;14164.71670566588;14323.212223327739;14364;14374.784378234308;14384.496542621011;722.2957616324069;740.6222826358542;903.7333986473973;923.501309292981;940.4827344960245;934.103260869566;936.7985883035146;942.8302905477011;944.5371767833747;945.6642891773424;988.6440881282732;994.0506815990711;994.2443742755522;994.8656931314289;995.3281534963907;755.4334249313015;936.513013233115;988.574912787501;990.2276224544867;990.593856518831;733.8165458635341;733.9449541284372;734.0347443112344;734.8273155808381;736.2306389822648;15114.773278400833;15183.924080379638;15207.163606001666;15224.619384515476;15228.847711522854;12454.626361209088;12531;12625.179363341353;12663.810042849273;12903;10049.648262310857;10086.344387614781;10131.544080516394;10472.842907356397;10476.895231047669;3451.0682115828586;3484.3205574912904;3496.4930085137767;3737.009692431508;3757.511523501955;78935.42129157443;79097.81353279705;79182;79301.60349198275;79551.20448795496;9213.401128926598;9524.095210954949;9858.359206651689;9957.302988790716;9968.40189588627;22236.332910012727;22263.332100037023;22282.665760013613;22312.442188945406;22333.664995025087;7455.478116531899;7516.5114267572435;7604.5437273763755;7653.272939070779;7663.923360766433;4018.4374187613757;4081.1225586499045;4089.4683691119963;4152.5432202457705;4420.9115817684105;3006.7293943545187;3642.1440961374055;3643.799591022519;3649.5073165122367;3657.7256705747177;527.5416558835807;627.9905801412982;645.803030075822;648.970796314167;655.9048937903978;426.7994042799879;494.52278551198265;575.4159528079003;575.4676923845426;602.2682440834408;7451.925480745232;7727.459077864495;7928.3260922821055;7986.201379861967;8058.988161459134;489.8554926296751;574.5489790514426;575.10571061999;583.6002338398126;584.8070136854827;3661.1213308805695;3839.9424008639894;3863.9613603864163;3964.8810535683974;3997.420374045805;93257.533712331;93573.5321323389;94848.52575737078;95033.57449638261;95199.52400237955;76195.47609047788;76785.23214767892;77205;77256;77870.61064694753;68832.6558367215;68863.31136688669;71340.28659713545;71434;71573.64213179005;7472.140703818913;7475.401967842451;7691.2308769124875;7715.614219289126;7894.881576776468;43376.69869903908;43486.56513434825;44333.77833110814;44466;44683.77658111689;59962.40037599568;62835.05747413884;63426.60873825777;64036.67981660062;65910;10250.179985600986;10470.476476176009;10495.947520262349;10535.157187424835;10875.945620272165;10768.569257229936;10829.945850270698;10937.398443085389;11062.059724923472;11494.712632184262;10342.948285258526;10422;10500.789984200426;10759.623413180501;10949.83575246356;10271.435071071162;10462.006109419593;10533.42066186367;10700.41147736882;11410.372429516456;9748.658796942073;9777.804443911224;10002.24983126254;10056.64801731936;10173.643922462677;8545.145485451405;8673.089325620718;8714.346424018351;8920.55397230123;9152.993170751346;8624.655013799378;9056.275497960016;9377.812443751223;9704.078112579293;9795 15:38:36 INFO - PROCESS | 1666 | __end_tp_report 15:38:36 INFO - PROCESS | 1666 | __start_cc_report 15:38:36 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1155 15:38:36 INFO - PROCESS | 1666 | __end_cc_report 15:38:36 INFO - PROCESS | 1666 | __startTimestamp1461364716426__endTimestamp 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:38:36 INFO - PROCESS | 1666 | Number of tests: 6 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5704.30 Median:5866.68 stddev:621.90 (10.6%) stddev-sans-first:596.51 15:38:36 INFO - PROCESS | 1666 | Values: 3381.2 3438.1 3458.7 3487.5 3496.0 5616.4 5643.3 5652.9 5662.7 5675.9 5695.5 5719.7 5748.6 5847.2 5851.7 5095.6 5165.3 5197.8 5211.9 5289.8 5257.8 5258.6 5279.3 5304.0 5357.0 5103.1 5129.2 5185.7 5215.0 5220.0 5168.5 5196.9 5213.7 5221.4 5267.9 5077.6 5170.5 5197.6 5212.8 5224.4 5557.7 5591.3 5653.7 5658.3 5716.8 6195.5 6221.6 6225.0 6227.0 6243.2 6160.8 6253.8 6273.6 6291.6 6307.2 5701.6 5728.9 5757.1 5820.6 5820.7 6245.2 6248.1 6256.7 6270.8 6279.8 5945.1 6026.9 6074.6 6113.5 6118.5 6111.0 6155.8 6171.4 6172.4 6183.9 6109.0 6115.6 6120.8 6225.6 6236.3 6215.5 6219.6 6224.9 6227.2 6239.3 4826.7 4886.4 4901.4 4904.4 4944.6 4284.4 4288.6 4291.7 4441.6 4445.7 5645.9 5653.4 5655.6 5665.5 5669.5 6134.0 6240.7 6268.8 6270.5 6291.1 5622.4 5628.5 5645.3 5649.3 5651.7 5309.3 5327.5 5336.1 5337.1 5337.3 5800.0 5863.8 6242.3 6254.3 6264.7 6095.9 6096.4 6097.2 6111.3 6114.4 6287.8 6359.5 6368.8 6372.0 6379.4 6009.9 6075.5 6085.0 6087.0 6094.3 4863.7 6122.8 6152.6 6265.4 6273.3 6026.2 6052.1 6053.6 6062.5 6068.5 5858.3 5992.7 6027.6 6061.0 6064.5 5795.4 5907.3 5951.1 5976.2 5990.7 6037.9 6083.7 6103.7 6127.1 6132.5 5748.5 5865.1 5866.7 5876.6 5877.4 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:68949.56 Median:209036.81 stddev:148966.35 (71.3%) stddev-sans-first:149295.62 15:38:36 INFO - PROCESS | 1666 | Values: 511.7 518.4 529.9 541.8 596.5 30856.7 38451.0 39456.2 39539.2 39562.2 43699.8 44482.8 44514.0 44559.6 44650.1 8621.2 9511.2 9766.9 9775.9 9777.4 8795.1 11562.1 11869.7 11869.7 11880.5 11512.1 11601.7 11607.6 11619.4 11643.5 8714.3 8974.3 8985.6 11577.1 11759.5 8668.1 11511.6 11800.3 11831.8 11854.2 40848.6 41572.6 41581.0 41637.8 41826.8 3382.7 3734.7 3771.3 3820.8 3897.8 28706.4 29727.7 30589.0 30753.0 30831.0 2750.3 2763.6 2805.1 2816.9 2839.9 347237.0 380340.0 380488.0 381237.0 382234.0 62345.0 63021.0 63432.0 63452.0 63508.4 71965.0 73700.6 73851.3 73894.0 74060.0 14191.6 17028.6 17207.2 17256.2 17331.9 11863.5 12153.7 12877.2 13107.3 13200.8 17855.0 18985.8 19486.8 20103.8 20199.9 7897.4 8032.6 8083.7 8086.7 8121.6 953.7 960.6 1001.2 1083.2 1127.7 29569.4 29731.0 30436.8 30460.0 30561.5 1020.8 1068.5 1100.5 1129.2 1194.3 11629.2 14290.0 15185.5 15339.5 15704.4 657412.0 661243.0 670515.0 670894.6 671174.0 60675.7 61493.0 61662.4 61710.7 61780.4 1338.5 1474.9 1512.5 1523.6 1523.9 24311.9 29416.0 30420.7 30440.4 30632.4 46844.5 53948.7 54052.7 54064.7 54083.7 562572.0 593819.0 594056.0 595014.0 596766.0 34109.1 36088.5 37030.6 37052.3 43395.0 43375.0 44296.3 44804.6 44827.3 46161.3 35890.0 37733.6 38247.0 38335.2 38632.0 37632.6 38198.2 38397.2 39432.6 39500.8 37606.4 38298.8 39053.2 39056.4 39077.4 30990.7 33204.7 34123.0 34132.0 34249.5 21334.0 23524.0 24748.3 25187.5 25217.6 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35149.37 Median:11844.90 stddev:100781.04 (850.8%) stddev-sans-first:101029.98 15:38:36 INFO - PROCESS | 1666 | Values: 397.0 399.3 400.8 401.3 414.6 14006.9 19430.5 20455.8 20488.8 20770.5 15434.8 15931.6 18201.4 19972.6 20697.0 5844.1 6022.8 6073.0 6078.7 6083.4 6004.3 6013.3 6015.4 6048.3 6123.2 5791.3 5902.2 5941.0 5947.9 5954.5 5899.1 5928.2 6000.5 6000.7 6010.0 5048.1 5203.3 5226.0 5228.5 5239.4 26199.8 26307.6 26337.7 26758.3 26932.3 2139.9 2172.7 2190.8 2354.6 2648.0 12473.9 14326.6 14808.7 14899.0 15017.9 1565.2 1714.8 1717.0 1743.2 1816.5 91200.0 119451.0 120374.4 120677.8 121321.4 9295.7 10762.4 11318.6 11537.6 11539.3 14189.1 14270.0 14281.6 14295.0 14301.4 6453.0 7633.8 8143.4 8736.0 8767.8 7689.2 7985.7 8601.4 8686.9 8770.2 5691.7 6662.9 6762.8 6774.9 6784.1 1883.1 1887.5 1899.6 1936.0 1939.7 950.3 968.5 975.8 1041.3 1050.9 19878.6 20453.1 20871.0 21549.9 21773.3 938.2 964.3 972.7 1003.8 1057.6 10452.9 10493.5 10910.7 10980.8 11055.3 581655.0 594447.0 605527.0 608654.0 631227.0 104894.0 127730.4 133954.0 134208.0 137392.0 1007.8 1011.2 1051.3 1057.5 1060.5 17412.0 17796.1 17890.7 17954.8 18338.3 47384.0 49031.8 55553.4 55725.7 56617.4 73460.0 75979.0 77385.1 80479.0 84901.0 5572.4 8230.2 8339.7 8401.9 8701.6 12134.2 12446.0 12690.6 12993.9 13031.2 10983.7 11110.6 11132.5 11261.4 11273.7 10861.8 10899.1 11006.2 11272.9 11315.7 10967.5 11319.0 11619.1 11725.7 11754.4 8117.7 8190.8 8364.5 8385.2 8485.8 10307.4 11150.3 11150.8 11410.3 11492.4 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5670.32 Median:4891.72 stddev:3167.65 (64.8%) stddev-sans-first:3160.41 15:38:36 INFO - PROCESS | 1666 | Values: 1417.6 1436.7 1629.3 1860.1 2023.7 7846.7 8367.9 8638.5 8645.7 8660.0 8289.5 8667.0 8674.8 8675.0 8682.3 1861.7 2147.9 2387.0 2468.9 2475.1 2474.2 2495.9 2495.9 2498.7 2499.4 2262.6 2269.3 2417.4 2463.9 2467.3 2122.5 2361.3 2393.6 2445.4 2459.9 2436.8 2451.0 2468.9 2471.2 2473.1 8652.8 8716.7 8720.8 8721.1 8722.4 5010.0 5230.8 5233.5 5259.8 5266.9 3484.2 3565.5 3582.2 3642.5 3657.8 1001.7 1021.1 1022.3 1031.5 1031.8 11490.1 11597.4 11613.5 11613.8 11643.7 4730.6 4800.6 4833.6 4906.4 4918.0 8415.3 10096.4 10129.5 10144.8 10155.3 5414.2 5431.5 5437.1 5445.2 5451.9 4378.4 4500.8 4554.5 4755.4 4937.4 3948.1 3985.1 4061.7 4090.1 4102.9 1315.8 1355.4 1361.5 1371.8 1373.2 922.9 925.3 939.6 954.4 964.7 5846.7 5905.5 5934.8 5972.6 6055.8 459.6 470.2 478.3 482.8 484.2 3505.2 3911.6 3950.2 4000.5 4062.0 10240.5 10675.5 10761.4 10763.9 10779.4 9938.9 10732.9 10733.4 10735.9 10740.8 6668.9 7911.6 8181.0 8191.4 8202.0 4632.9 5915.9 6005.1 6153.8 6178.1 9769.5 9856.0 9887.0 9896.2 9898.3 9473.8 10155.5 10242.3 10257.2 10280.2 6847.0 7065.2 7343.9 7434.4 7634.7 8208.2 8383.4 8383.5 8383.7 8384.9 7214.7 7417.7 7442.9 7513.6 7601.4 6118.2 7208.5 7212.5 7280.9 7286.8 6978.2 7053.6 7142.4 7154.3 7157.1 4877.1 4915.3 4985.2 5022.9 5183.8 4942.8 5053.1 5103.6 5144.7 5270.9 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5010.27 Median:5554.99 stddev:1591.03 (28.6%) stddev-sans-first:1556.06 15:38:36 INFO - PROCESS | 1666 | Values: 319.9 321.2 329.8 329.9 334.7 5073.3 5797.9 5880.2 5890.9 5895.5 4723.0 5813.6 5858.9 5862.3 5863.4 4032.0 4220.8 4273.5 4480.5 4515.1 4413.2 4459.8 4483.6 4488.0 4490.0 3441.7 4392.7 4481.6 4490.3 4509.2 3670.9 4168.0 4214.6 4464.6 4482.4 3059.9 3231.8 3512.7 3577.8 3874.8 5638.5 5658.3 5667.5 5675.7 5702.4 4962.9 5094.4 5102.8 5141.4 5163.2 5078.5 5381.6 5863.4 6170.2 6175.7 3563.4 3793.8 4228.4 4275.0 4289.7 6222.3 6526.0 6530.7 6535.6 6581.5 3696.5 3908.6 4295.9 4328.7 4328.8 3859.1 4077.0 4395.4 4408.7 4433.1 4937.9 5108.2 5111.4 5138.8 5143.8 5083.8 6279.9 6286.7 6291.5 6306.6 1267.3 1292.9 1294.8 1531.1 1537.8 839.7 948.4 975.7 1029.2 1032.7 4879.8 5667.9 5976.6 5981.2 5983.3 5374.9 6328.7 6511.6 6563.6 6569.5 5104.0 5300.8 5930.6 5942.3 5971.3 2571.6 3283.1 3451.9 3454.6 3457.5 6067.6 6240.7 6415.3 6770.1 6830.2 5493.0 6372.9 6666.9 6701.4 6702.6 6560.6 6777.9 6809.7 6857.6 6869.3 6461.5 6533.5 6537.3 6554.4 6566.4 5146.3 5507.6 5602.4 5841.3 6070.4 5145.6 5984.6 6179.9 6592.6 6814.9 4125.3 4337.7 4383.7 4385.3 4394.2 5298.3 6197.4 6465.3 6511.2 6524.7 6038.2 6286.5 6310.6 6344.4 6378.8 4998.7 5141.9 6216.6 6309.3 6316.8 4809.5 5083.4 5939.7 6138.6 6162.2 5390.1 6082.8 6290.1 6313.6 6320.5 5765.5 6160.7 6166.8 6172.4 6191.5 15:38:36 INFO - PROCESS | 1666 | 15:38:36 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17889.27 Median:31731.52 stddev:25342.75 (79.9%) stddev-sans-first:25381.15 15:38:36 INFO - PROCESS | 1666 | Values: 744.6 746.7 748.9 755.7 815.2 14915.6 14925.4 15392.5 15420.5 15701.4 14164.7 14323.2 14364.0 14374.8 14384.5 722.3 740.6 903.7 923.5 940.5 934.1 936.8 942.8 944.5 945.7 988.6 994.1 994.2 994.9 995.3 755.4 936.5 988.6 990.2 990.6 733.8 733.9 734.0 734.8 736.2 15114.8 15183.9 15207.2 15224.6 15228.8 12454.6 12531.0 12625.2 12663.8 12903.0 10049.6 10086.3 10131.5 10472.8 10476.9 3451.1 3484.3 3496.5 3737.0 3757.5 78935.4 79097.8 79182.0 79301.6 79551.2 9213.4 9524.1 9858.4 9957.3 9968.4 22236.3 22263.3 22282.7 22312.4 22333.7 7455.5 7516.5 7604.5 7653.3 7663.9 4018.4 4081.1 4089.5 4152.5 4420.9 3006.7 3642.1 3643.8 3649.5 3657.7 527.5 628.0 645.8 649.0 655.9 426.8 494.5 575.4 575.5 602.3 7451.9 7727.5 7928.3 7986.2 8059.0 489.9 574.5 575.1 583.6 584.8 3661.1 3839.9 3864.0 3964.9 3997.4 93257.5 93573.5 94848.5 95033.6 95199.5 76195.5 76785.2 77205.0 77256.0 77870.6 68832.7 68863.3 71340.3 71434.0 71573.6 7472.1 7475.4 7691.2 7715.6 7894.9 43376.7 43486.6 44333.8 44466.0 44683.8 59962.4 62835.1 63426.6 64036.7 65910.0 10250.2 10470.5 10495.9 10535.2 10875.9 10768.6 10829.9 10937.4 11062.1 11494.7 10342.9 10422.0 10500.8 10759.6 10949.8 10271.4 10462.0 10533.4 10700.4 11410.4 9748.7 9777.8 10002.2 10056.6 10173.6 8545.1 8673.1 8714.3 8920.6 9153.0 8624.7 9056.3 9377.8 9704.1 9795.0 15:38:36 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:38:36 INFO - PROCESS | 1666 | 15:38:37 INFO - TEST-INFO | 1666: exit 0 15:38:37 INFO - TEST-OK | dromaeo_css | took 1114932ms 15:38:37 INFO - TEST-START | kraken 15:38:37 INFO - operating with platform_type : mac_ 15:38:37 INFO - Initialising browser for kraken test... 15:38:37 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpK4Rozc/profile http://localhost:49284/getInfo.html) 15:38:39 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 15:38:39 INFO - PROCESS | 1672 | colorDepth:24 15:38:39 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 15:38:39 INFO - PROCESS | 1672 | __metrics 15:38:39 INFO - PROCESS | 1672 | 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" 15:38:39 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:38:39 INFO - TEST-INFO | 1672: exit 0 15:38:39 INFO - Browser initialized. 15:38:39 INFO - Running cycle 1/1 for kraken test... 15:38:39 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpK4Rozc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:51 INFO - PROCESS | 1674 | RSS: Main: 238952448 15:38:51 INFO - PROCESS | 1674 | 15:38:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 15:38:55 INFO - PROCESS | 1674 | RSS: Main: 286523392 15:38:55 INFO - PROCESS | 1674 | 15:38:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 15:38:58 INFO - PROCESS | 1674 | RSS: Main: 300064768 15:38:58 INFO - PROCESS | 1674 | 15:39:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 15:39:03 INFO - PROCESS | 1674 | RSS: Main: 384778240 15:39:03 INFO - PROCESS | 1674 | 15:39:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 15:39:05 INFO - PROCESS | 1674 | RSS: Main: 288169984 15:39:05 INFO - PROCESS | 1674 | 15:39:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:08 INFO - PROCESS | 1674 | RSS: Main: 293330944 15:39:08 INFO - PROCESS | 1674 | 15:39:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:12 INFO - PROCESS | 1674 | RSS: Main: 331452416 15:39:12 INFO - PROCESS | 1674 | 15:39:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:15 INFO - PROCESS | 1674 | RSS: Main: 328859648 15:39:15 INFO - PROCESS | 1674 | 15:39:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 15:39:19 INFO - PROCESS | 1674 | RSS: Main: 327847936 15:39:19 INFO - PROCESS | 1674 | 15:39:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:21 INFO - PROCESS | 1674 | RSS: Main: 321769472 15:39:21 INFO - PROCESS | 1674 | 15:39:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:23 INFO - PROCESS | 1674 | RSS: Main: 328736768 15:39:23 INFO - PROCESS | 1674 | 15:39:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:26 INFO - PROCESS | 1674 | RSS: Main: 330342400 15:39:26 INFO - PROCESS | 1674 | 15:39:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:30 INFO - PROCESS | 1674 | RSS: Main: 335200256 15:39:30 INFO - PROCESS | 1674 | 15:39:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:33 INFO - PROCESS | 1674 | RSS: Main: 332890112 15:39:33 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 15:39:35 INFO - PROCESS | 1674 | RSS: Main: 397107200 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | __start_tp_report 15:39:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 15:39:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 15:39:35 INFO - PROCESS | 1674 | |i|pagename|runs| 15:39:35 INFO - PROCESS | 1674 | |0;ai-astar;110;111;108;112;110;112;111;110;104;112 15:39:35 INFO - PROCESS | 1674 | |1;audio-beat-detection;188;151;143;169;123;144;149;119;172;121 15:39:35 INFO - PROCESS | 1674 | |2;audio-dft;185;188;186;184;184;183;233;180;184;186 15:39:35 INFO - PROCESS | 1674 | |3;audio-fft;78;73;87;93;55;59;57;82;86;56 15:39:35 INFO - PROCESS | 1674 | |4;audio-oscillator;94;102;142;93;117;117;135;81;77;126 15:39:35 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;86;84;90;127;122;83;87;83;86;84 15:39:35 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;93;93;93;93;93;94;94;119;146 15:39:35 INFO - PROCESS | 1674 | |7;imaging-desaturate;107;139;75;130;138;125;86;85;86;83 15:39:35 INFO - PROCESS | 1674 | |8;json-parse-financial;59;59;58;58;57;58;58;73;60;58 15:39:35 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;67;71;77;71;70;66;66;65;70;98 15:39:35 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;83;82;82;83;82;82;83;96;107;107 15:39:35 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;146;131;157;155;148;145;141;132;180;140 15:39:35 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;174;168;183;171;167;175;178;171;171;170 15:39:35 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;71;71;69;68;68;66;67;70;67;66 15:39:35 INFO - PROCESS | 1674 | __end_tp_report 15:39:35 INFO - PROCESS | 1674 | __start_cc_report 15:39:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1992 15:39:35 INFO - PROCESS | 1674 | __end_cc_report 15:39:35 INFO - PROCESS | 1674 | __startTimestamp1461364775863__endTimestamp 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 15:39:35 INFO - PROCESS | 1674 | Number of tests: 14 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.45 (2.2%) stddev-sans-first:2.60 15:39:35 INFO - PROCESS | 1674 | Values: 110.0 111.0 108.0 112.0 110.0 112.0 111.0 110.0 104.0 112.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:150.00 stddev:23.24 (15.5%) stddev-sans-first:19.61 15:39:35 INFO - PROCESS | 1674 | Values: 188.0 151.0 143.0 169.0 123.0 144.0 149.0 119.0 172.0 121.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:189.30 Median:185.50 stddev:15.50 (8.4%) stddev-sans-first:16.36 15:39:35 INFO - PROCESS | 1674 | Values: 185.0 188.0 186.0 184.0 184.0 183.0 233.0 180.0 184.0 186.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:72.60 Median:80.00 stddev:14.66 (18.3%) stddev-sans-first:15.42 15:39:35 INFO - PROCESS | 1674 | Values: 78.0 73.0 87.0 93.0 55.0 59.0 57.0 82.0 86.0 56.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:108.40 Median:109.50 stddev:22.40 (20.5%) stddev-sans-first:23.15 15:39:35 INFO - PROCESS | 1674 | Values: 94.0 102.0 142.0 93.0 117.0 117.0 135.0 81.0 77.0 126.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:85.00 stddev:16.67 (19.6%) stddev-sans-first:17.48 15:39:35 INFO - PROCESS | 1674 | Values: 86.0 84.0 90.0 127.0 122.0 83.0 87.0 83.0 86.0 84.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:93.00 stddev:17.69 (19.0%) stddev-sans-first:18.57 15:39:35 INFO - PROCESS | 1674 | Values: 94.0 93.0 93.0 93.0 93.0 93.0 94.0 94.0 119.0 146.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:79.00 stddev:25.34 (32.1%) stddev-sans-first:26.87 15:39:35 INFO - PROCESS | 1674 | Values: 107.0 139.0 75.0 130.0 138.0 125.0 86.0 85.0 86.0 83.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:58.50 stddev:4.71 (8.1%) stddev-sans-first:4.99 15:39:35 INFO - PROCESS | 1674 | Values: 59.0 59.0 58.0 58.0 57.0 58.0 58.0 73.0 60.0 58.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:70.50 stddev:9.76 (13.8%) stddev-sans-first:10.17 15:39:35 INFO - PROCESS | 1674 | Values: 67.0 71.0 77.0 71.0 70.0 66.0 66.0 65.0 70.0 98.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:82.50 stddev:10.54 (12.8%) stddev-sans-first:10.98 15:39:35 INFO - PROCESS | 1674 | Values: 83.0 82.0 82.0 83.0 82.0 82.0 83.0 96.0 107.0 107.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:147.00 stddev:14.23 (9.7%) stddev-sans-first:15.08 15:39:35 INFO - PROCESS | 1674 | Values: 146.0 131.0 157.0 155.0 148.0 145.0 141.0 132.0 180.0 140.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:172.50 stddev:4.85 (2.8%) stddev-sans-first:5.12 15:39:35 INFO - PROCESS | 1674 | Values: 174.0 168.0 183.0 171.0 167.0 175.0 178.0 171.0 171.0 170.0 15:39:35 INFO - PROCESS | 1674 | 15:39:35 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.89 (2.8%) stddev-sans-first:1.73 15:39:35 INFO - PROCESS | 1674 | Values: 71.0 71.0 69.0 68.0 68.0 66.0 67.0 70.0 67.0 66.0 15:39:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 15:39:35 INFO - PROCESS | 1674 | 15:39:36 INFO - TEST-INFO | 1674: exit 0 15:39:36 INFO - TEST-OK | kraken | took 59638ms 15:39:36 INFO - SUITE-END | took 1174s 15:39:37 INFO - Completed test suite (00:19:34) 15:39:37 INFO - javascript benchmark 15:39:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.2054167270685, 3438.0545350028756, 3458.6541345865407, 3487.5291835602216, 3496.038589387918, 5616.382197958222, 5643.294588176478, 5652.943470565293, 5662.660240385575, 5675.914861277079, 5695.45893140152, 5719.656820590757, 5748.568857335696, 5847.210626565412, 5851.7074146292625, 5095.566876815456, 5165.251038599401, 5197.766100525486, 5211.921821172686, 5289.841304760844, 5257.815976440826, 5258.55302299305, 5279.313689220415, 5304.045271851065, 5356.9732151339185, 5103.106956282654, 5129.204973229118, 5185.740712964337, 5215.009148261818, 5220.0, 5168.5089916458255, 5196.948030519684, 5213.687178769286, 5221.39953905302, 5267.920981185285, 5077.64456488046, 5170.508801663836, 5197.58419326453, 5212.843614691566, 5224.425313215544, 5557.722113894289, 5591.329040515124, 5653.660780353192, 5658.264425624684, 5716.79991200306, 6195.473384762301, 6221.564490485668, 6225.035119556431, 6226.968865155645, 6243.157173781527, 6160.84597885052, 6253.843653908689, 6273.56085074045, 6291.622502649856, 6307.18006659136, 5701.629394089371, 5728.856778580527, 5757.107648314518, 5820.563457740602, 5820.738066787005, 6245.156903818017, 6248.062790581449, 6256.74973001084, 6270.843228919222, 6279.843003924939, 5945.078512830518, 6026.939730602726, 6074.605150665193, 6113.510919126459, 6118.541109416811, 6111.04482360895, 6155.815325540241, 6171.444569988633, 6172.351903050205, 6183.907241391383, 6109.009944784285, 6115.575014303891, 6120.846978825476, 6225.6264624121795, 6236.34518375564, 6215.471684906744, 6219.626822390581, 6224.906626400608, 6227.221597300338, 6239.344868788802, 4826.686265392738, 4886.438059623118, 4901.387326584177, 4904.362432883702, 4944.55499005084, 4284.357346398003, 4288.614024737726, 4291.69958102936, 4441.644668426518, 4445.711028783118, 5645.887082258414, 5653.434656534396, 5655.557832752622, 5665.461781130786, 5669.461401166883, 6134.0, 6240.687965601702, 6268.811935641938, 6270.498360131179, 6291.087792270096, 5622.437756224344, 5628.549716022791, 5645.294338207724, 5649.293838270216, 5651.717414129277, 5309.309789727311, 5327.547158491571, 5336.092864213093, 5337.092694241988, 5337.332833395825, 5799.971000144972, 5863.765449382062, 6242.344553821965, 6254.34329395416, 6264.686765661699, 6095.891769653442, 6096.420840020191, 6097.237845269341, 6111.257501106225, 6114.388610881663, 6287.779927702692, 6359.459445947146, 6368.840778980562, 6371.980483122678, 6379.362063793584, 6009.939900601113, 6075.544334175043, 6084.9959756639655, 6087.0, 6094.299155597163, 4863.659543832039, 6122.825106970096, 6152.64641778808, 6265.4361107500545, 6273.341299163522, 6026.246719160105, 6052.092186172109, 6053.606515576474, 6062.454379105901, 6068.4538391544975, 5858.297004359504, 5992.700364981821, 6027.578069535266, 6060.969695151496, 6064.4541991221, 5795.449432303924, 5907.291125064848, 5951.1370851225465, 5976.163337132718, 5990.6705131218205, 6037.939620603738, 6083.695815209133, 6103.69481525913, 6127.050307202391, 6132.478739307209, 5748.48263656273, 5865.120231965068, 5866.67733274674, 5876.6180198286975, 5877.35349111606], "value": 5665.820521422993, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.70321213696053, 518.4400847085151, 529.864884454464, 541.8320320700582, 596.5376832954457, 30856.691433085663, 38451.0, 39456.21087578247, 39539.2092158157, 39562.20875582487, 43699.781501092526, 44482.777586112104, 44514.0, 44559.55440445594, 44650.10699786002, 8621.224089831914, 9511.191548718367, 9766.853497197548, 9775.853362199572, 9777.364471309378, 8795.120487951217, 11562.07503399726, 11869.703257418547, 11869.703257418547, 11880.524779008872, 11512.13658975576, 11601.709957251094, 11607.593734219305, 11619.360935148563, 11643.476043578059, 8714.34642401816, 8974.326925480615, 8985.550722463917, 11577.131715121357, 11759.47082381295, 8668.133186681345, 11511.597094101666, 11800.291982481078, 11831.763364732744, 11854.170208085437, 40848.59151408478, 41572.584274157176, 41581.0, 41637.791811041054, 41826.790866045776, 3382.695557399821, 3734.6825519830845, 3771.283456143324, 3820.8280627371837, 3897.824597893102, 28706.42587148266, 29727.70272297271, 30589.0, 30753.0, 30831.0, 2750.2711781377952, 2763.613094166798, 2805.0603047978952, 2816.887324507017, 2839.8580070996572, 347237.0, 380340.0, 380488.0, 381237.0, 382234.0, 62345.0, 63021.0, 63432.0, 63452.0, 63508.36491635024, 71965.0, 73700.63149684218, 73851.26148738444, 73894.0, 74060.0, 14191.574252772434, 17028.57428564271, 17207.225674844663, 17256.22346994363, 17331.913340433217, 11863.46614402354, 12153.69615759613, 12877.227366358049, 13107.279099649611, 13200.801987970188, 17855.0, 18985.810141898404, 19486.836990001848, 20103.798962010485, 20199.899000505196, 7897.407694422941, 8032.638531266107, 8083.6766529338165, 8086.676532938734, 8121.593920304079, 953.6948176583566, 960.6349587156975, 1001.1790331927895, 1083.2417307884512, 1127.6786115956913, 29569.408611827643, 29731.0, 30436.847815761223, 30460.0, 30561.541576876363, 1020.7648745018462, 1068.4657671164418, 1100.5212732461405, 1129.170060005895, 1194.3371428856894, 11629.18595698293, 14290.0, 15185.544433667008, 15339.463118791013, 15704.371825127095, 657412.0, 661243.0, 670515.0, 670894.6455267692, 671174.0, 60675.69662151661, 61492.999999999105, 61662.383376165664, 61710.69144654248, 61780.38219617836, 1338.5315139701222, 1474.9410023598937, 1512.4857548433586, 1523.634327761307, 1523.9466618668293, 24311.878440607685, 29416.0, 30420.695793042672, 30440.41256493223, 30632.387352253278, 46844.53155468538, 53948.73025634847, 54052.72973635264, 54064.72967635294, 54083.72958135184, 562572.0, 593819.0, 594056.0, 595014.0, 596766.0, 34109.14727131742, 36088.4586731194, 37030.629693702715, 37052.25895482129, 43395.0, 43375.0, 44296.335554966055, 44804.55195448004, 44827.327590086825, 46161.30758038565, 35890.0, 37733.622663773014, 38247.0, 38335.23329533338, 38632.034199145244, 37632.623673762915, 38198.23603527858, 38397.23205535818, 39432.6056739429, 39500.80249598734, 37606.435903462014, 38298.80850595729, 39053.2189356217, 39056.41415378828, 39077.41383879187, 30990.69009309968, 33204.66795332016, 34123.0, 34132.0, 34249.48625770665, 21334.0, 23524.0, 24748.257552273462, 25187.496250075263, 25217.621735674347], "value": 20556.718321105567, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.95831937646545, 399.32115403813503, 400.797597213407, 401.2917201139987, 414.6247645880479, 14006.929965350158, 19430.514237144078, 20455.795442045594, 20488.795112048894, 20770.480737981557, 15434.845651543481, 15931.601709957256, 18201.362952296673, 19972.60054798903, 20696.965151742428, 5844.123381492789, 6022.789202377918, 6072.969635151818, 6078.696065196745, 6083.422074902877, 6004.309504406983, 6013.278406591216, 6015.398460153993, 6048.274207095154, 6123.173371594822, 5791.333996590404, 5902.232709747748, 5940.970295148518, 5947.940520594803, 5954.4640982311585, 5899.115132730082, 5928.170056192136, 6000.489958353502, 6000.699965001732, 6010.0, 5048.14181589127, 5203.349581302337, 5226.0, 5228.529432351107, 5239.449857764918, 26199.821008054492, 26307.605385919225, 26337.73662263372, 26758.33104172392, 26932.326691832666, 2139.8716077035274, 2172.652375619909, 2190.8466407351493, 2354.564405584972, 2648.0, 12473.93763031188, 14326.570202893825, 14808.703825923529, 14898.957073004894, 15017.924910375488, 1565.1939251285596, 1714.7599336092958, 1716.9570760730958, 1743.2155530011546, 1816.5277027972822, 91200.0, 119451.0, 120374.39812800968, 120677.79322206753, 121321.39339303336, 9295.674651387304, 10762.3542587445, 11318.603848865416, 11537.596184133516, 11539.307641541534, 14189.148651080968, 14270.0, 14281.642958925902, 14295.0, 14301.356438960272, 6453.0, 7633.770986870402, 8143.42995990275, 8735.956320218358, 8767.780805479913, 7689.231076892266, 7985.722284434462, 8601.397902146915, 8686.86969695455, 8770.166834150745, 5691.658500489983, 6662.933370666231, 6762.797116086525, 6774.932250677529, 6784.134363050102, 1883.0584707646176, 1887.4715079777684, 1899.5725961658516, 1936.014761987887, 1939.679952807799, 950.3110245072268, 968.4915419404728, 975.8097171051577, 1041.271110222847, 1050.9421981791074, 19878.60242795165, 20453.07961141752, 20871.0, 21549.922503874743, 21773.34679959604, 938.2415697848304, 964.3249725192394, 972.6692924405736, 1003.7653685966156, 1057.582255009267, 10452.92374076514, 10493.527791249413, 10910.68720568274, 10980.780384392427, 11055.336679799238, 581655.0, 594447.0, 605527.0, 608654.0, 631227.0, 104894.0, 127730.36134819266, 133954.0, 134208.0, 137392.0, 1007.7782887764827, 1011.1758916483043, 1051.3481641382239, 1057.5293994172519, 1060.4591658254346, 17412.042337671548, 17796.110194490484, 17890.731639025686, 17954.820451795316, 18338.266469341077, 47384.0, 49031.774205645146, 55553.44446555483, 55725.72137139289, 56617.43382566122, 73460.0, 75979.0, 77385.06537336612, 80479.0, 84901.0, 5572.414896435978, 8230.21812927771, 8339.749807505785, 8401.873971890549, 8701.608427620646, 12134.211276267015, 12446.0, 12690.619281421572, 12993.870061299265, 13031.152975056591, 10983.670489885317, 11110.61112861046, 11132.499037543168, 11261.436928153396, 11273.661790146309, 10861.782764344827, 10899.128069754559, 11006.229563930448, 11272.9436352821, 11315.660530184108, 10967.50646220938, 11319.037881779812, 11619.0704743622, 11725.706857328294, 11754.353510557, 8117.675292988451, 8190.836183276182, 8364.539950302791, 8385.161483851565, 8485.787855303666, 10307.381557106597, 11150.275232109887, 11150.776984460428, 11410.258333208647, 11492.367919764492], "value": 9711.224540679332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.6314158318837, 1436.7126574685064, 1629.3238306102967, 1860.135037207699, 2023.7267968824212, 7846.686132554698, 8367.91632083679, 8638.524881131536, 8645.69740059098, 8660.004099528556, 8289.461185022985, 8667.046624871275, 8674.783130421743, 8675.0, 8682.305415566756, 1861.7021276595747, 2147.860389074713, 2386.9761302387014, 2468.9013389041793, 2475.0718480569785, 2474.2206205045445, 2495.862727549984, 2495.900163993438, 2498.725140234573, 2499.3501689560753, 2262.592733308004, 2269.2965180793926, 2417.395651087228, 2463.9014439422403, 2467.3214865911837, 2122.5118222808687, 2361.303415492434, 2393.628987506939, 2445.425325048612, 2459.8893049812623, 2436.84160529565, 2451.019592163131, 2468.8642124683133, 2471.1721573272976, 2473.0849585653245, 8652.783680407978, 8716.694915677921, 8720.825583488358, 8721.084286149926, 8722.38943273971, 5009.974950125226, 5230.764615592308, 5233.528982391603, 5259.789608415659, 5266.947330526722, 3484.181217413906, 3565.4651802229614, 3582.2344150024214, 3642.4718415829557, 3657.750483452684, 1001.7295330260789, 1021.0606242257142, 1022.2792930983638, 1031.4842578710645, 1031.8142734307821, 11490.138239631977, 11597.4201289936, 11613.535458581646, 11613.767724645544, 11643.708907277301, 4730.59789917854, 4800.567948884617, 4833.617556542219, 4906.362172917497, 4918.0, 8415.326773858076, 10096.444695541815, 10129.544170512345, 10144.797104058025, 10155.289129760846, 5414.214938833847, 5431.4840090191365, 5437.130059190589, 5445.237666726662, 5451.918221226685, 4378.408914796461, 4500.774961251925, 4554.476235232991, 4755.38180036593, 4937.432195297515, 3948.0919388540797, 3985.123272879962, 4061.6750659947133, 4090.1001779608437, 4102.856400026045, 1315.8092076648832, 1355.3968484024515, 1361.5370773936909, 1371.8010888421322, 1373.203541945689, 922.9030951749969, 925.2690374603987, 939.5537119868145, 954.355809828363, 964.71058682395, 5846.736896839652, 5905.498032667186, 5934.762609495659, 5972.552058595623, 6055.757769689163, 459.6299978517259, 470.15372329806894, 478.33750255895137, 482.8382491865194, 484.2494134092143, 3505.1938054247667, 3911.6088391161225, 3950.2297052075146, 4000.5399379071196, 4062.0, 10240.48797560119, 10675.466226688635, 10761.354318740901, 10763.892361076445, 10779.407132607781, 9938.900610993798, 10732.892671073188, 10733.409662468639, 10735.946320268506, 10740.838887416696, 6668.86662266752, 7911.604419779103, 8180.9590952044855, 8191.426600138052, 8201.95899020501, 4632.86101416958, 5915.911261330997, 6005.099235114768, 6153.784617538365, 6178.104174894705, 9769.462679552693, 9855.95072024664, 9887.0, 9896.208303335572, 9898.307118501636, 9473.763155921157, 10155.492225388849, 10242.283040187116, 10257.179425645782, 10280.22898282617, 6847.041414201916, 7065.22282548909, 7343.889841652486, 7434.442416818652, 7634.656440460304, 8208.179182081743, 8383.413161078666, 8383.496990180609, 8383.706570270011, 8384.916150838413, 7214.675339609835, 7417.666205020895, 7442.888356674546, 7513.624318784148, 7601.353884919844, 6118.20463339763, 7208.4593655475, 7212.4590655699985, 7280.854382912206, 7286.781396558112, 6978.162620485574, 7053.612051337226, 7142.392896603639, 7154.284571542804, 7157.105361829771, 4877.073356062337, 4915.287283343967, 4985.227289770091, 5022.949770502249, 5183.844484665466, 4942.75286235694, 5053.090443720019, 5103.642745007814, 5144.742762861917, 5270.947290527046], "value": 4510.408868496684, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.86086052567134, 321.1905996887842, 329.84167599552217, 329.9406106900757, 334.6736931491794, 5073.2643766653855, 5797.855053623662, 5880.2355693759855, 5890.882182356356, 5895.469407753301, 4722.976385118078, 5813.62211456255, 5858.886811505811, 5862.306746749542, 5863.413658634135, 4031.9920019995, 4220.810063547147, 4273.465816772903, 4480.484744254403, 4515.074409746011, 4413.161499315136, 4459.821607135711, 4483.5964763171305, 4488.0, 4490.0, 3441.6902478776906, 4392.704152275087, 4481.641468682497, 4490.326451032355, 4509.210888094571, 3670.871519496805, 4168.0, 4214.557471465498, 4464.575865292792, 4482.439695038121, 3059.908202753921, 3231.8222497762613, 3512.6838584527513, 3577.801436518769, 3874.849458550503, 5638.520725738317, 5658.321001479808, 5667.546596272288, 5675.659460432387, 5702.429757024306, 4962.865967633952, 5094.439611642716, 5102.795888164469, 5141.38303403594, 5163.199704045879, 5078.454782969222, 5381.623286369956, 5863.355030946593, 6170.2053237259215, 6175.66033868137, 3563.394222982099, 3793.7670257166533, 4228.4080228768, 4275.038116423811, 4289.656827453796, 6222.284437289724, 6525.9884717868335, 6530.706118224643, 6535.607863528204, 6581.473482121419, 3696.464012718141, 3908.6286802753693, 4295.871123866289, 4328.696991210585, 4328.826846926151, 3859.054531639766, 4077.0215148364177, 4395.384646149541, 4408.669349798778, 4433.091216300651, 4937.851864444073, 5108.182690769533, 5111.386633603991, 5138.820141295037, 5143.8199663012365, 5083.771230294646, 6279.874402512016, 6286.717097730613, 6291.465225455796, 6306.590071645328, 1267.315649549233, 1292.9030322725832, 1294.8057791331187, 1531.1196062264244, 1537.8077740282465, 839.6515446089804, 948.3551185193996, 975.673149494913, 1029.2383636109225, 1032.7056788815153, 4879.804807807719, 5667.943320566742, 5976.61152025117, 5981.192539007178, 5983.252093488314, 5374.865628359323, 6328.6835658216905, 6511.609303441809, 6563.606183628997, 6569.540132190701, 5103.974480127576, 5300.833816560351, 5930.555208359304, 5942.286925568959, 5971.342352964694, 2571.5756900111273, 3283.1122104789843, 3451.87918422854, 3454.602720687153, 3457.4640930655787, 6067.605605635619, 6240.720652266275, 6415.326390728999, 6770.119884415093, 6830.21452532965, 5493.0, 6372.904406433907, 6666.866512785128, 6701.396874281338, 6702.631355275506, 6560.573562718408, 6777.932220677829, 6809.693563789542, 6857.588544687334, 6869.278725733726, 6461.483081353575, 6533.542652014504, 6537.313582073813, 6554.442872355903, 6566.409023187936, 5146.305248791366, 5507.586930980273, 5602.3837377887585, 5841.299044114733, 6070.42169036045, 5145.639805213599, 5984.581079324405, 6179.918514260076, 6592.604443733391, 6814.8637027260165, 4125.257453658371, 4337.674674399495, 4383.693141480066, 4385.276429389115, 4394.231009573376, 5298.284731561344, 6197.411245931629, 6465.288818229897, 6511.153550038464, 6524.67376631176, 6038.245219347582, 6286.528510361833, 6310.558260921692, 6344.397282258178, 6378.799314236581, 4998.6750861193905, 5141.897162056813, 6216.627002379872, 6309.328028072473, 6316.789123806815, 4809.495003024632, 5083.415407228216, 5939.732712028055, 6138.570300078952, 6162.22972128484, 5390.056740070394, 6082.817515474543, 6290.119383286252, 6313.55805093639, 6320.494360451061, 5765.509931655856, 6160.691965401622, 6166.753329866934, 6172.4136207060255, 6191.504679625529], "value": 4499.236105872343, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.5830335012394, 746.7386414754833, 748.8689479341114, 755.697720911635, 815.2214635023552, 14915.552533423988, 14925.402983880631, 15392.538223853287, 15420.537383878474, 15701.371945122182, 14164.71670566588, 14323.212223327739, 14364.0, 14374.784378234308, 14384.496542621011, 722.2957616324069, 740.6222826358542, 903.7333986473973, 923.501309292981, 940.4827344960245, 934.103260869566, 936.7985883035146, 942.8302905477011, 944.5371767833747, 945.6642891773424, 988.6440881282732, 994.0506815990711, 994.2443742755522, 994.8656931314289, 995.3281534963907, 755.4334249313015, 936.513013233115, 988.574912787501, 990.2276224544867, 990.593856518831, 733.8165458635341, 733.9449541284372, 734.0347443112344, 734.8273155808381, 736.2306389822648, 15114.773278400833, 15183.924080379638, 15207.163606001666, 15224.619384515476, 15228.847711522854, 12454.626361209088, 12531.0, 12625.179363341353, 12663.810042849273, 12903.0, 10049.648262310857, 10086.344387614781, 10131.544080516394, 10472.842907356397, 10476.895231047669, 3451.0682115828586, 3484.3205574912904, 3496.4930085137767, 3737.009692431508, 3757.511523501955, 78935.42129157443, 79097.81353279705, 79182.0, 79301.60349198275, 79551.20448795496, 9213.401128926598, 9524.095210954949, 9858.359206651689, 9957.302988790716, 9968.40189588627, 22236.332910012727, 22263.332100037023, 22282.665760013613, 22312.442188945406, 22333.664995025087, 7455.478116531899, 7516.5114267572435, 7604.5437273763755, 7653.272939070779, 7663.923360766433, 4018.4374187613757, 4081.1225586499045, 4089.4683691119963, 4152.5432202457705, 4420.9115817684105, 3006.7293943545187, 3642.1440961374055, 3643.799591022519, 3649.5073165122367, 3657.7256705747177, 527.5416558835807, 627.9905801412982, 645.803030075822, 648.970796314167, 655.9048937903978, 426.7994042799879, 494.52278551198265, 575.4159528079003, 575.4676923845426, 602.2682440834408, 7451.925480745232, 7727.459077864495, 7928.3260922821055, 7986.201379861967, 8058.988161459134, 489.8554926296751, 574.5489790514426, 575.10571061999, 583.6002338398126, 584.8070136854827, 3661.1213308805695, 3839.9424008639894, 3863.9613603864163, 3964.8810535683974, 3997.420374045805, 93257.533712331, 93573.5321323389, 94848.52575737078, 95033.57449638261, 95199.52400237955, 76195.47609047788, 76785.23214767892, 77205.0, 77256.0, 77870.61064694753, 68832.6558367215, 68863.31136688669, 71340.28659713545, 71434.0, 71573.64213179005, 7472.140703818913, 7475.401967842451, 7691.2308769124875, 7715.614219289126, 7894.881576776468, 43376.69869903908, 43486.56513434825, 44333.77833110814, 44466.0, 44683.77658111689, 59962.40037599568, 62835.05747413884, 63426.60873825777, 64036.67981660062, 65910.0, 10250.179985600986, 10470.476476176009, 10495.947520262349, 10535.157187424835, 10875.945620272165, 10768.569257229936, 10829.945850270698, 10937.398443085389, 11062.059724923472, 11494.712632184262, 10342.948285258526, 10422.0, 10500.789984200426, 10759.623413180501, 10949.83575246356, 10271.435071071162, 10462.006109419593, 10533.42066186367, 10700.41147736882, 11410.372429516456, 9748.658796942073, 9777.804443911224, 10002.24983126254, 10056.64801731936, 10173.643922462677, 8545.145485451405, 8673.089325620718, 8714.346424018351, 8920.55397230123, 9152.993170751346, 8624.655013799378, 9056.275497960016, 9377.812443751223, 9704.078112579293, 9795.0], "value": 6771.905473330697, "unit": "score"}], "name": "dromaeo_css", "value": 7332.747127184317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 108.0, 112.0, 110.0, 112.0, 111.0, 110.0, 104.0, 112.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 151.0, 143.0, 169.0, 123.0, 144.0, 149.0, 119.0, 172.0, 121.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 186.0, 184.0, 184.0, 183.0, 233.0, 180.0, 184.0, 186.0], "value": 189.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 87.0, 93.0, 55.0, 59.0, 57.0, 82.0, 86.0, 56.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 142.0, 93.0, 117.0, 117.0, 135.0, 81.0, 77.0, 126.0], "value": 108.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 90.0, 127.0, 122.0, 83.0, 87.0, 83.0, 86.0, 84.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 119.0, 146.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 139.0, 75.0, 130.0, 138.0, 125.0, 86.0, 85.0, 86.0, 83.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 57.0, 58.0, 58.0, 73.0, 60.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 77.0, 71.0, 70.0, 66.0, 66.0, 65.0, 70.0, 98.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 83.0, 82.0, 82.0, 83.0, 96.0, 107.0, 107.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 131.0, 157.0, 155.0, 148.0, 145.0, 141.0, 132.0, 180.0, 140.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 168.0, 183.0, 171.0, 167.0, 175.0, 178.0, 171.0, 171.0, 170.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 68.0, 68.0, 66.0, 67.0, 70.0, 67.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1537.1999999999998, "alertThreshold": 2.0}]} 15:39:37 INFO - Return code: 0 15:39:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:39:37 ERROR - # TBPL SUCCESS # 15:39:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:39:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:39:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:39:37 INFO - 2016-04-22 15:39:37.170 screenresolution[1678:9834] starting screenresolution argv=screenresolution get 15:39:37 INFO - 2016-04-22 15:39:37.172 screenresolution[1678:9834] Display 0: 1600x1200x32@60 15:39:37 INFO - 2016-04-22 15:39:37.178 screenresolution[1679:9837] starting screenresolution argv=screenresolution list 15:39:37 INFO - Available Modes on Display 0 15:39:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:39:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:39:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:39:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:39:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:39:37 INFO - Intel Iris: 15:39:37 INFO - Chipset Model: Intel Iris 15:39:37 INFO - Type: GPU 15:39:37 INFO - Bus: Built-In 15:39:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:39:37 INFO - Vendor: Intel (0x8086) 15:39:37 INFO - Device ID: 0x0a2e 15:39:37 INFO - Revision ID: 0x0009 15:39:37 INFO - Displays: 15:39:37 INFO - Display: 15:39:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:39:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:39:37 INFO - Display Serial Number: 1600x1200 60 15:39:37 INFO - Main Display: Yes 15:39:37 INFO - Mirror: Off 15:39:37 INFO - Online: Yes 15:39:37 INFO - Rotation: Supported 15:39:37 INFO - Return code: 0 15:39:37 INFO - Running post-action listener: _resource_record_post_action 15:39:37 INFO - Running post-run listener: _resource_record_post_run 15:39:38 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 32386560; Write bytes: 697017344; Read time: 326; Write time: 1913 15:39:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395909632; Write bytes: 398616576; Read time: 30272; Write time: 800 15:39:38 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30592512; Write bytes: 297393152; Read time: 306; Write time: 1109 15:39:38 INFO - Running post-run listener: _upload_blobber_files 15:39:38 INFO - Blob upload gear active. 15:39:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:38 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 15:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:39:38 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:39:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:39:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:39 INFO - (blobuploader) - INFO - Done attempting. 15:39:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:39:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:40 INFO - (blobuploader) - INFO - Done attempting. 15:39:40 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:40 INFO - Return code: 0 15:39:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c59e0ee343a993ac4c81a195c84afd9f7b25f0d0f4a03ed8fd73a512968114ebb20b8ef71c8b8f012fd5c651a09f4723290be94f250f85c34b276faa57f6e33e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd42e1a8af63c51af1c5b036088edc38dcefd06be449a5420897fd32cab4a799953e7bd3dfa96ccfae945f915c10234174e4c302ec1206b3a13bebe192f2060c"} 15:39:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:40 INFO - Contents: 15:39:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c59e0ee343a993ac4c81a195c84afd9f7b25f0d0f4a03ed8fd73a512968114ebb20b8ef71c8b8f012fd5c651a09f4723290be94f250f85c34b276faa57f6e33e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd42e1a8af63c51af1c5b036088edc38dcefd06be449a5420897fd32cab4a799953e7bd3dfa96ccfae945f915c10234174e4c302ec1206b3a13bebe192f2060c"} 15:39:40 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:40 INFO - Copying logs to upload dir... 15:39:40 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.797746 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-22 15:39:41.069899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 15:39:41.074119) ========= 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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c59e0ee343a993ac4c81a195c84afd9f7b25f0d0f4a03ed8fd73a512968114ebb20b8ef71c8b8f012fd5c651a09f4723290be94f250f85c34b276faa57f6e33e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd42e1a8af63c51af1c5b036088edc38dcefd06be449a5420897fd32cab4a799953e7bd3dfa96ccfae945f915c10234174e4c302ec1206b3a13bebe192f2060c"} build_url:https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/GKDB0Ct5QBehRJJFxneF-g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c59e0ee343a993ac4c81a195c84afd9f7b25f0d0f4a03ed8fd73a512968114ebb20b8ef71c8b8f012fd5c651a09f4723290be94f250f85c34b276faa57f6e33e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd42e1a8af63c51af1c5b036088edc38dcefd06be449a5420897fd32cab4a799953e7bd3dfa96ccfae945f915c10234174e4c302ec1206b3a13bebe192f2060c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 15:39:41.108965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:39:41.109259) ========= 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.ktcr5NFCmh/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.lFtBwzkI9I/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005051 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:39:41.157071) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 15:39:41.157379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 15:39:41.157710) ========= ========= Total master_lag: 5.53 =========