builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1463105759.3 results: success (0) buildid: 20160512162545 builduid: eae3da6f4091412e9452478e549d0e00 revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.297052) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.297501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.297768) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.315228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.315845) ========= 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.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-12 19:15:59-- 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% 64.3M=0s 2016-05-12 19:15:59 (64.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.459267) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.459572) ========= 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.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.019882 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.508919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:15:59.509284) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bec48bfd05a8c6703aab63181441869a3dcaa4cc --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 bec48bfd05a8c6703aab63181441869a3dcaa4cc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-12 19:15:59,584 truncating revision to first 12 chars 2016-05-12 19:15:59,584 Setting DEBUG logging. 2016-05-12 19:15:59,584 attempt 1/10 2016-05-12 19:15:59,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bec48bfd05a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 19:16:00,280 unpacking tar archive at: fx-team-bec48bfd05a8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:16:00.462832) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 19:16:00.463377) ========= script_repo_revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 19:16:00.463985) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 19:16:00.464455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 19:16:00.478167) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-12 19:16:00.478485) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:00 INFO - MultiFileLogger online at 20160512 19:16:00 in /builds/slave/test 19:16:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:16:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:16:00 INFO - {'append_to_log': False, 19:16:00 INFO - 'base_work_dir': '/builds/slave/test', 19:16:00 INFO - 'blob_upload_branch': 'Fx-Team', 19:16:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:16:00 INFO - 'branch': 'Fx-Team', 19:16:00 INFO - 'buildbot_json_path': 'buildprops.json', 19:16:00 INFO - 'config_files': ('talos/mac_config.py',), 19:16:00 INFO - 'default_actions': ('clobber', 19:16:00 INFO - 'read-buildbot-config', 19:16:00 INFO - 'download-and-extract', 19:16:00 INFO - 'populate-webroot', 19:16:00 INFO - 'create-virtualenv', 19:16:00 INFO - 'install', 19:16:00 INFO - 'run-tests'), 19:16:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:16:00 INFO - 'download_minidump_stackwalk': True, 19:16:00 INFO - 'download_symbols': 'ondemand', 19:16:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:16:00 INFO - 'tooltool.py': '/tools/tooltool.py', 19:16:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:16:00 INFO - '/tools/misc-python/virtualenv.py')}, 19:16:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:16:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:16:00 INFO - 'installer_path': 'installer.exe', 19:16:00 INFO - 'log_level': 'info', 19:16:00 INFO - 'log_name': 'talos', 19:16:00 INFO - 'log_to_console': True, 19:16:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:16:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:16:00 INFO - 'opt_config_files': (), 19:16:00 INFO - 'pip_index': False, 19:16:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:00 INFO - 'cmd': ('bash', 19:16:00 INFO - '-c', 19:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:00 INFO - 'enabled': True, 19:16:00 INFO - 'halt_on_failure': False, 19:16:00 INFO - 'name': 'check_screen_resolution'},), 19:16:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:00 INFO - 'cmd': ('bash', 19:16:00 INFO - '-c', 19:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:00 INFO - 'enabled': True, 19:16:00 INFO - 'halt_on_failure': False, 19:16:00 INFO - 'name': 'check_screen_resolution'},), 19:16:00 INFO - 'run_cmd_checks_enabled': True, 19:16:00 INFO - 'sps_profile': False, 19:16:00 INFO - 'sps_profile_interval': 0, 19:16:00 INFO - 'suite': 'dromaeojs-e10s', 19:16:00 INFO - 'system_bits': '32', 19:16:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:16:00 INFO - 'title': 't-yosemite-r7-0005', 19:16:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:16:00 INFO - 'use_talos_json': True, 19:16:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:16:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:16:00 INFO - 'work_dir': 'build'} 19:16:00 INFO - ##### 19:16:00 INFO - ##### Running clobber step. 19:16:00 INFO - ##### 19:16:00 INFO - Running pre-action listener: _resource_record_pre_action 19:16:00 INFO - Running main action method: clobber 19:16:00 INFO - rmtree: /builds/slave/test/build 19:16:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:16:00 INFO - Running post-action listener: _resource_record_post_action 19:16:00 INFO - ##### 19:16:00 INFO - ##### Finished clobber step (success) 19:16:00 INFO - ##### 19:16:00 INFO - ##### 19:16:00 INFO - ##### Running read-buildbot-config step. 19:16:00 INFO - ##### 19:16:00 INFO - Running pre-action listener: _resource_record_pre_action 19:16:00 INFO - Running main action method: read_buildbot_config 19:16:00 INFO - Using buildbot properties: 19:16:00 INFO - { 19:16:00 INFO - "project": "", 19:16:00 INFO - "product": "firefox", 19:16:00 INFO - "script_repo_revision": "production", 19:16:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 19:16:00 INFO - "repository": "", 19:16:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 19:16:00 INFO - "buildid": "20160512162545", 19:16:00 INFO - "pgo_build": "False", 19:16:00 INFO - "basedir": "/builds/slave/test", 19:16:00 INFO - "buildnumber": 8, 19:16:00 INFO - "slavename": "t-yosemite-r7-0005", 19:16:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:16:00 INFO - "platform": "yosemite_r7", 19:16:00 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:16:00 INFO - "branch": "fx-team", 19:16:00 INFO - "repo_path": "integration/fx-team", 19:16:00 INFO - "revision": "bec48bfd05a8c6703aab63181441869a3dcaa4cc", 19:16:00 INFO - "stage_platform": "macosx64", 19:16:00 INFO - "builduid": "eae3da6f4091412e9452478e549d0e00", 19:16:00 INFO - "slavebuilddir": "test" 19:16:00 INFO - } 19:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:16:00 INFO - Running post-action listener: _resource_record_post_action 19:16:00 INFO - ##### 19:16:00 INFO - ##### Finished read-buildbot-config step (success) 19:16:00 INFO - ##### 19:16:00 INFO - ##### 19:16:00 INFO - ##### Running download-and-extract step. 19:16:00 INFO - ##### 19:16:00 INFO - Running pre-action listener: _resource_record_pre_action 19:16:00 INFO - Running main action method: download_and_extract 19:16:00 INFO - mkdir: /builds/slave/test/build/tests 19:16: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')]} 19:16:00 INFO - https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:16:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:16:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:16:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:16:02 INFO - Downloaded 1199 bytes. 19:16:02 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:16:02 INFO - Using the following test package requirements: 19:16:02 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:16:02 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:16:02 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:16:02 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'jsshell-mac.zip'], 19:16:02 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:16:02 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:16:02 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:16:02 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:16:02 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:16:02 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:16:02 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:16:02 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:02 INFO - https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:16:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:16:03 INFO - Downloaded 18151135 bytes. 19:16:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:16:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:16:06 INFO - Return code: 0 19:16:06 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:06 INFO - https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:16:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:16:07 INFO - Downloaded 18151135 bytes. 19:16:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:16:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:16:11 INFO - Return code: 0 19:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:11 INFO - https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:16:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:16:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:16:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:16:12 INFO - Downloaded 10840239 bytes. 19:16:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:16:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:16:12 INFO - Return code: 0 19:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:12 INFO - https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:16:15 INFO - Downloaded 109839707 bytes. 19:16:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:16:15 INFO - mkdir: /builds/slave/test/properties 19:16:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:16:15 INFO - Writing to file /builds/slave/test/properties/build_url 19:16:15 INFO - Contents: 19:16:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:16:17 INFO - Running post-action listener: _resource_record_post_action 19:16:17 INFO - Running post-action listener: set_extra_try_arguments 19:16:17 INFO - ##### 19:16:17 INFO - ##### Finished download-and-extract step (success) 19:16:17 INFO - ##### 19:16:17 INFO - ##### 19:16:17 INFO - ##### Running populate-webroot step. 19:16:17 INFO - ##### 19:16:17 INFO - Running pre-action listener: _resource_record_pre_action 19:16:17 INFO - Running main action method: populate_webroot 19:16:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:16:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:16:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:16:17 INFO - '--tppagecycles', 19:16:17 INFO - '7'], 19:16:17 INFO - 'tests': ['tsvgm']}}, 19:16:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:16:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:16:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:16:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:16:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['damp', 'tps']}, 19:16:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'tests': ['damp', 'tps']}, 19:16:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['dromaeo_dom']}, 19:16:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:16:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'other-e10s': {'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['a11yr', 19:16:17 INFO - 'ts_paint', 19:16:17 INFO - 'tpaint', 19:16:17 INFO - 'sessionrestore', 19:16:17 INFO - 'sessionrestore_no_auto_restore', 19:16:17 INFO - 'tabpaint']}, 19:16:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['tsvgx', 19:16:17 INFO - 'tsvgr_opacity', 19:16:17 INFO - 'tart', 19:16:17 INFO - 'tscrollx', 19:16:17 INFO - 'cart']}, 19:16:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:16:17 INFO - 'tsvgr_opacity', 19:16:17 INFO - 'tart', 19:16:17 INFO - 'tscrollx', 19:16:17 INFO - 'cart']}, 19:16:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'talos_options': ['--disable-e10s'], 19:16:17 INFO - 'tests': ['tp5o']}, 19:16:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'tests': ['tp5o']}, 19:16:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'talos_options': ['--disable-e10s', 19:16:17 INFO - '--xperf_path', 19:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:17 INFO - 'tests': ['tp5n']}, 19:16:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:17 INFO - 'talos_options': ['--xperf_path', 19:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:17 INFO - 'tests': ['tp5n']}}, 19:16:17 INFO - 'talos.zip': {'path': '', 19:16:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:16:17 INFO - Running post-action listener: _resource_record_post_action 19:16:17 INFO - ##### 19:16:17 INFO - ##### Finished populate-webroot step (success) 19:16:17 INFO - ##### 19:16:17 INFO - ##### 19:16:17 INFO - ##### Running create-virtualenv step. 19:16:17 INFO - ##### 19:16:17 INFO - Running pre-action listener: _resource_record_pre_action 19:16:17 INFO - Running main action method: create_virtualenv 19:16:17 INFO - Creating virtualenv /builds/slave/test/build/venv 19:16:17 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 19:16:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:16:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:16:17 INFO - Using real prefix '/tools/python27' 19:16:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:16:18 INFO - Installing distribute.............................................................................................................................................................................................done. 19:16:21 INFO - Installing pip.................done. 19:16:21 INFO - Return code: 0 19:16:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:16: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')]} 19:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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')]} 19:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16: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', 'pip>=1.5'] in /builds/slave/test/build 19:16: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 pip>=1.5 19:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:21 INFO - 'HOME': '/Users/cltbld', 19:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:21 INFO - 'LOGNAME': 'cltbld', 19:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:21 INFO - 'MOZ_NO_REMOTE': '1', 19:16:21 INFO - 'NO_EM_RESTART': '1', 19:16:21 INFO - 'PAGER': '/bin/cat', 19:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:21 INFO - 'PWD': '/builds/slave/test', 19:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:21 INFO - 'SHELL': '/bin/bash', 19:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:21 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:21 INFO - 'USER': 'cltbld', 19:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:21 INFO - 'XPC_FLAGS': '0x0', 19:16:21 INFO - 'XPC_SERVICE_NAME': '0', 19:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:21 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 19:16:21 INFO - Cleaning up... 19:16:21 INFO - Return code: 0 19:16:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:16: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')]} 19:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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')]} 19:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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', '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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16: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', 'psutil>=0.7.1'] in /builds/slave/test/build 19:16: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 psutil>=0.7.1 19:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:21 INFO - 'HOME': '/Users/cltbld', 19:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:21 INFO - 'LOGNAME': 'cltbld', 19:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:21 INFO - 'MOZ_NO_REMOTE': '1', 19:16:21 INFO - 'NO_EM_RESTART': '1', 19:16:21 INFO - 'PAGER': '/bin/cat', 19:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:21 INFO - 'PWD': '/builds/slave/test', 19:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:21 INFO - 'SHELL': '/bin/bash', 19:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:21 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:21 INFO - 'USER': 'cltbld', 19:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:21 INFO - 'XPC_FLAGS': '0x0', 19:16:21 INFO - 'XPC_SERVICE_NAME': '0', 19:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:21 INFO - Downloading/unpacking psutil>=0.7.1 19:16: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 19:16: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 19:16: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 19:16: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 19:16: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 19:16: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 19:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:24 INFO - Installing collected packages: psutil 19:16:24 INFO - Running setup.py install for psutil 19:16:24 INFO - building 'psutil._psutil_osx' extension 19:16:24 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 19:16:25 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 19:16:25 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 19:16:25 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 19:16:25 INFO - building 'psutil._psutil_posix' extension 19:16:25 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 19:16:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:16:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:16:25 INFO - ^ 19:16:25 INFO - 1 warning generated. 19:16:25 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 19:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:25 INFO - Successfully installed psutil 19:16:25 INFO - Cleaning up... 19:16:25 INFO - Return code: 0 19:16:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:25 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:25 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 19:16:25 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 19:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:25 INFO - 'HOME': '/Users/cltbld', 19:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:25 INFO - 'LOGNAME': 'cltbld', 19:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:25 INFO - 'MOZ_NO_REMOTE': '1', 19:16:25 INFO - 'NO_EM_RESTART': '1', 19:16:25 INFO - 'PAGER': '/bin/cat', 19:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:25 INFO - 'PWD': '/builds/slave/test', 19:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:25 INFO - 'SHELL': '/bin/bash', 19:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:25 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:25 INFO - 'USER': 'cltbld', 19:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:25 INFO - 'XPC_FLAGS': '0x0', 19:16:25 INFO - 'XPC_SERVICE_NAME': '0', 19:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:16:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:16:28 INFO - Installing collected packages: mozsystemmonitor 19:16:28 INFO - Running setup.py install for mozsystemmonitor 19:16:28 INFO - Successfully installed mozsystemmonitor 19:16:28 INFO - Cleaning up... 19:16:28 INFO - Return code: 0 19:16:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:16:28 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')]} 19:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:28 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')]} 19:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:28 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:28 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 19:16:28 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 19:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:28 INFO - 'HOME': '/Users/cltbld', 19:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:28 INFO - 'LOGNAME': 'cltbld', 19:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:28 INFO - 'MOZ_NO_REMOTE': '1', 19:16:28 INFO - 'NO_EM_RESTART': '1', 19:16:28 INFO - 'PAGER': '/bin/cat', 19:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:28 INFO - 'PWD': '/builds/slave/test', 19:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:28 INFO - 'SHELL': '/bin/bash', 19:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:28 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:28 INFO - 'USER': 'cltbld', 19:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:28 INFO - 'XPC_FLAGS': '0x0', 19:16:28 INFO - 'XPC_SERVICE_NAME': '0', 19:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:29 INFO - Downloading/unpacking blobuploader==1.2.4 19:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:31 INFO - Downloading blobuploader-1.2.4.tar.gz 19:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:16:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:16:31 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 19:16:31 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 19:16:31 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 19:16:31 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 19:16:31 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 19:16:31 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 19:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:16:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:32 INFO - Downloading docopt-0.6.1.tar.gz 19:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:16:32 INFO - Installing collected packages: blobuploader, requests, docopt 19:16:32 INFO - Running setup.py install for blobuploader 19:16:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:16:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:16:32 INFO - Running setup.py install for requests 19:16:33 INFO - Running setup.py install for docopt 19:16:33 INFO - Successfully installed blobuploader requests docopt 19:16:33 INFO - Cleaning up... 19:16:33 INFO - Return code: 0 19:16:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:33 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')]} 19:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:33 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')]} 19:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:33 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:33 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 19:16:33 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 19:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:33 INFO - 'HOME': '/Users/cltbld', 19:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:33 INFO - 'LOGNAME': 'cltbld', 19:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:33 INFO - 'MOZ_NO_REMOTE': '1', 19:16:33 INFO - 'NO_EM_RESTART': '1', 19:16:33 INFO - 'PAGER': '/bin/cat', 19:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:33 INFO - 'PWD': '/builds/slave/test', 19:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:33 INFO - 'SHELL': '/bin/bash', 19:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:33 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:33 INFO - 'USER': 'cltbld', 19:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:33 INFO - 'XPC_FLAGS': '0x0', 19:16:33 INFO - 'XPC_SERVICE_NAME': '0', 19:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-R8MwYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:33 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-B8ACOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-eK6dlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-EBI37T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ZVAnHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DZaUcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0JPRzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gqqqjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DauHV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-SBfmlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-2h8JbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:34 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-lwDAlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:35 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-b_FtDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:35 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gULh9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:35 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LjBzUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:35 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yQKP8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:35 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-9bV2xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:16:35 INFO - Running setup.py install for manifestparser 19:16:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:16:35 INFO - Running setup.py install for mozcrash 19:16:35 INFO - Running setup.py install for mozdebug 19:16:35 INFO - Running setup.py install for mozdevice 19:16:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Running setup.py install for mozfile 19:16:36 INFO - Running setup.py install for mozhttpd 19:16:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Running setup.py install for mozinfo 19:16:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Running setup.py install for mozInstall 19:16:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Running setup.py install for mozleak 19:16:36 INFO - Running setup.py install for mozlog 19:16:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for moznetwork 19:16:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozprocess 19:16:37 INFO - Running setup.py install for mozprofile 19:16:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozrunner 19:16:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozscreenshot 19:16:37 INFO - Running setup.py install for moztest 19:16:37 INFO - Running setup.py install for mozversion 19:16:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:16:38 INFO - Cleaning up... 19:16:38 INFO - Return code: 0 19:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:38 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:38 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 19:16:38 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 19:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:38 INFO - 'HOME': '/Users/cltbld', 19:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:38 INFO - 'LOGNAME': 'cltbld', 19:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:38 INFO - 'MOZ_NO_REMOTE': '1', 19:16:38 INFO - 'NO_EM_RESTART': '1', 19:16:38 INFO - 'PAGER': '/bin/cat', 19:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:38 INFO - 'PWD': '/builds/slave/test', 19:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:38 INFO - 'SHELL': '/bin/bash', 19:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:38 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:38 INFO - 'USER': 'cltbld', 19:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:38 INFO - 'XPC_FLAGS': '0x0', 19:16:38 INFO - 'XPC_SERVICE_NAME': '0', 19:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-oG7esM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-B24rc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yjKYmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-QSa1qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qI2rO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-toEK7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:38 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-zsx3Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yEHFi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-NyEd9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-tp3fa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-lHuTBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UaeHL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-tVaFYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Moh2kT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-c_GxVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:39 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)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:39 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-8Ay8wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:40 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)) 19:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:40 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UPRbHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:40 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)) 19:16:40 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)) 19:16:40 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)) 19:16:40 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)) 19:16:40 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)) 19:16:40 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)) 19:16:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:16:40 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 19:16:40 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 19:16:40 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 19:16:40 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 19:16:40 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 19:16:40 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 19:16:42 INFO - Downloading blessings-1.6.tar.gz 19:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:16:42 INFO - Installing collected packages: blessings 19:16:42 INFO - Running setup.py install for blessings 19:16:42 INFO - Successfully installed blessings 19:16:42 INFO - Cleaning up... 19:16:42 INFO - Return code: 0 19:16:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:16:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:16:43 INFO - Reading from file tmpfile_stdout 19:16:43 INFO - Current package versions: 19:16:43 INFO - blessings == 1.6 19:16:43 INFO - blobuploader == 1.2.4 19:16:43 INFO - docopt == 0.6.1 19:16:43 INFO - manifestparser == 1.1 19:16:43 INFO - mozInstall == 1.12 19:16:43 INFO - mozcrash == 0.17 19:16:43 INFO - mozdebug == 0.1 19:16:43 INFO - mozdevice == 0.48 19:16:43 INFO - mozfile == 1.2 19:16:43 INFO - mozhttpd == 0.7 19:16:43 INFO - mozinfo == 0.9 19:16:43 INFO - mozleak == 0.1 19:16:43 INFO - mozlog == 3.2 19:16:43 INFO - moznetwork == 0.27 19:16:43 INFO - mozprocess == 0.22 19:16:43 INFO - mozprofile == 0.28 19:16:43 INFO - mozrunner == 6.11 19:16:43 INFO - mozscreenshot == 0.1 19:16:43 INFO - mozsystemmonitor == 0.0 19:16:43 INFO - moztest == 0.7 19:16:43 INFO - mozversion == 1.4 19:16:43 INFO - psutil == 3.1.1 19:16:43 INFO - requests == 1.2.3 19:16:43 INFO - wsgiref == 0.1.2 19:16:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:43 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')]} 19:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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')]} 19:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:43 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 19:16:43 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 19:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:43 INFO - 'HOME': '/Users/cltbld', 19:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:43 INFO - 'LOGNAME': 'cltbld', 19:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:43 INFO - 'MOZ_NO_REMOTE': '1', 19:16:43 INFO - 'NO_EM_RESTART': '1', 19:16:43 INFO - 'PAGER': '/bin/cat', 19:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:43 INFO - 'PWD': '/builds/slave/test', 19:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:43 INFO - 'SHELL': '/bin/bash', 19:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:43 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:43 INFO - 'USER': 'cltbld', 19:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:43 INFO - 'XPC_FLAGS': '0x0', 19:16:43 INFO - 'XPC_SERVICE_NAME': '0', 19:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 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)) 19:16:43 INFO - Cleaning up... 19:16:43 INFO - Return code: 0 19:16:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:16:43 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')]} 19:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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')]} 19:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:43 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 19:16:43 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 19:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:43 INFO - 'HOME': '/Users/cltbld', 19:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:43 INFO - 'LOGNAME': 'cltbld', 19:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:43 INFO - 'MOZ_NO_REMOTE': '1', 19:16:43 INFO - 'NO_EM_RESTART': '1', 19:16:43 INFO - 'PAGER': '/bin/cat', 19:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:43 INFO - 'PWD': '/builds/slave/test', 19:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:43 INFO - 'SHELL': '/bin/bash', 19:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:43 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:43 INFO - 'USER': 'cltbld', 19:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:43 INFO - 'XPC_FLAGS': '0x0', 19:16:43 INFO - 'XPC_SERVICE_NAME': '0', 19:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:43 INFO - Downloading/unpacking jsonschema 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:16:46 INFO - Downloading/unpacking functools32 (from jsonschema) 19:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:46 INFO - Downloading functools32-3.2.3-2.tar.gz 19:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:16:46 INFO - warning: no files found matching '*.txt' 19:16:46 INFO - no previously-included directories found matching 'build' 19:16:46 INFO - no previously-included directories found matching 'dist' 19:16:46 INFO - no previously-included directories found matching '.git*' 19:16:46 INFO - Installing collected packages: jsonschema, functools32 19:16:46 INFO - Running setup.py install for functools32 19:16:46 INFO - warning: no files found matching '*.txt' 19:16:46 INFO - no previously-included directories found matching 'build' 19:16:46 INFO - no previously-included directories found matching 'dist' 19:16:46 INFO - no previously-included directories found matching '.git*' 19:16:46 INFO - Successfully installed jsonschema functools32 19:16:46 INFO - Cleaning up... 19:16:46 INFO - Return code: 0 19:16:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:16: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')]} 19:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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')]} 19:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe373d7fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, '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.PTpcpW1ash/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.fwhglQ9KZv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 19:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:46 INFO - 'HOME': '/Users/cltbld', 19:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:46 INFO - 'LOGNAME': 'cltbld', 19:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:46 INFO - 'MOZ_NO_REMOTE': '1', 19:16:46 INFO - 'NO_EM_RESTART': '1', 19:16:46 INFO - 'PAGER': '/bin/cat', 19:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:46 INFO - 'PWD': '/builds/slave/test', 19:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:46 INFO - 'SHELL': '/bin/bash', 19:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:16:46 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:46 INFO - 'USER': 'cltbld', 19:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:46 INFO - 'XPC_FLAGS': '0x0', 19:16:46 INFO - 'XPC_SERVICE_NAME': '0', 19:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:47 INFO - Downloading/unpacking flake8 19:16:47 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 19:16:47 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 19:16:47 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 19:16:47 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 19:16:47 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 19:16:47 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 19:16:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:16:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:16:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 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 19:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:16:50 INFO - no previously-included directories found matching 'docs/_build' 19:16:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:16:51 INFO - Running setup.py install for pep8 19:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:16:51 INFO - no previously-included directories found matching 'docs/_build' 19:16:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:16:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:16:51 INFO - Cleaning up... 19:16:51 INFO - Return code: 0 19:16:51 INFO - Running post-action listener: _resource_record_post_action 19:16:51 INFO - Running post-action listener: _start_resource_monitoring 19:16:51 INFO - Starting resource monitoring. 19:16:51 INFO - ##### 19:16:51 INFO - ##### Finished create-virtualenv step (success) 19:16:51 INFO - ##### 19:16:51 INFO - ##### 19:16:51 INFO - ##### Running install step. 19:16:51 INFO - ##### 19:16:51 INFO - Running pre-action listener: _resource_record_pre_action 19:16:51 INFO - Running main action method: install 19:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:16:51 INFO - Reading from file tmpfile_stdout 19:16:51 INFO - Detecting whether we're running mozinstall >=1.0... 19:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:16:51 INFO - Reading from file tmpfile_stdout 19:16:51 INFO - Output received: 19:16:51 INFO - Usage: mozinstall [options] installer 19:16:51 INFO - Options: 19:16:51 INFO - -h, --help show this help message and exit 19:16:51 INFO - -d DEST, --destination=DEST 19:16:51 INFO - Directory to install application into. [default: 19:16:51 INFO - "/builds/slave/test"] 19:16:51 INFO - --app=APP Application being installed. [default: firefox] 19:16:51 INFO - mkdir: /builds/slave/test/build/application 19:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:17:25 INFO - Reading from file tmpfile_stdout 19:17:25 INFO - Output received: 19:17:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:17:25 INFO - Running post-action listener: _resource_record_post_action 19:17:25 INFO - ##### 19:17:25 INFO - ##### Finished install step (success) 19:17:25 INFO - ##### 19:17:25 INFO - ##### 19:17:25 INFO - ##### Running run-tests step. 19:17:25 INFO - ##### 19:17:25 INFO - Running pre-action listener: _resource_record_pre_action 19:17:25 INFO - Running main action method: run_tests 19:17:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:25 INFO - 2016-05-12 19:17:25.076 screenresolution[2066:11564] starting screenresolution argv=screenresolution get 19:17:25 INFO - 2016-05-12 19:17:25.079 screenresolution[2066:11564] Display 0: 1600x1200x32@60 19:17:25 INFO - 2016-05-12 19:17:25.085 screenresolution[2067:11566] starting screenresolution argv=screenresolution list 19:17:25 INFO - Available Modes on Display 0 19:17:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:25 INFO - Intel Iris: 19:17:25 INFO - Chipset Model: Intel Iris 19:17:25 INFO - Type: GPU 19:17:25 INFO - Bus: Built-In 19:17:25 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:25 INFO - Vendor: Intel (0x8086) 19:17:25 INFO - Device ID: 0x0a2e 19:17:25 INFO - Revision ID: 0x0009 19:17:25 INFO - Displays: 19:17:25 INFO - Display: 19:17:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:25 INFO - Display Serial Number: 1600x1200 60 19:17:25 INFO - Main Display: Yes 19:17:25 INFO - Mirror: Off 19:17:25 INFO - Online: Yes 19:17:25 INFO - Rotation: Supported 19:17:25 INFO - Return code: 0 19:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:17:25 INFO - Python 2.7.3 19:17:25 INFO - Return code: 0 19:17:25 INFO - grabbing minidump binary from tooltool 19:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:17:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cebd30>, '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 0x104b0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ce2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:17:25 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 19:17:25 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 19:17:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:17:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:17:25 INFO - Return code: 0 19:17:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:17:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:17:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:17:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:17:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:17:26 INFO - Return code: 0 19:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PTpcpW1ash/Render', 19:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:26 INFO - 'HOME': '/Users/cltbld', 19:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:26 INFO - 'LOGNAME': 'cltbld', 19:17:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:17:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:26 INFO - 'MOZ_NO_REMOTE': '1', 19:17:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:17:26 INFO - 'NO_EM_RESTART': '1', 19:17:26 INFO - 'PAGER': '/bin/cat', 19:17:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:26 INFO - 'PWD': '/builds/slave/test', 19:17:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:26 INFO - 'SHELL': '/bin/bash', 19:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fwhglQ9KZv/Listeners', 19:17:26 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 19:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:26 INFO - 'USER': 'cltbld', 19:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:26 INFO - 'XPC_FLAGS': '0x0', 19:17:26 INFO - 'XPC_SERVICE_NAME': '0', 19:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:17:26 INFO - mozversion application_buildid: 20160512162545 19:17:26 INFO - mozversion application_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 19:17:26 INFO - mozversion application_display_name: Nightly 19:17:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:17:26 INFO - mozversion application_name: Firefox 19:17:26 INFO - mozversion application_remotingname: firefox 19:17:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:17:26 INFO - mozversion application_vendor: Mozilla 19:17:26 INFO - mozversion application_version: 49.0a1 19:17:26 INFO - mozversion platform_buildid: 20160512162545 19:17:26 INFO - mozversion platform_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 19:17:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:17:26 INFO - mozversion platform_version: 49.0a1 19:17:26 INFO - using testdate: 1463105846 19:17:26 INFO - actual date: 1463105846 19:17:26 INFO - starting webserver on 'localhost:49354' 19:17:26 INFO - SUITE-START | Running 2 tests 19:17:26 INFO - TEST-START | dromaeo_css 19:17:26 INFO - operating with platform_type : mac_ 19:17:26 INFO - Initialising browser for dromaeo_css test... 19:17:26 INFO - TEST-INFO | started process 2085 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp800rsR/profile http://localhost:49354/getInfo.html) 19:17:29 INFO - PROCESS | 2085 | __metrics Screen width/height:1600/1200 19:17:29 INFO - PROCESS | 2085 | colorDepth:24 19:17:29 INFO - PROCESS | 2085 | Browser inner width/height: 1024/689 19:17:29 INFO - PROCESS | 2085 | __metrics 19:17:29 ERROR - PROCESS | 2085 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 19:17:30 INFO - TEST-INFO | 2085: exit 0 19:17:30 INFO - Browser initialized. 19:17:30 INFO - Running cycle 1/1 for dromaeo_css test... 19:17:30 INFO - TEST-INFO | started process 2089 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp800rsR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:17:42 INFO - PROCESS | 2089 | RSS: Main: 249815040 19:17:42 INFO - PROCESS | 2089 | 19:18:32 INFO - PROCESS | 2089 | 1463105912338 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] 19:20:43 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49354/tests/dromaeo/cssquery-ext.html) 19:20:44 INFO - PROCESS | 2089 | RSS: Main: 263258112 19:20:44 INFO - PROCESS | 2089 | 19:23:46 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-ext.html (next: http://localhost:49354/tests/dromaeo/cssquery-jquery.html) 19:23:47 INFO - PROCESS | 2089 | RSS: Main: 275324928 19:23:47 INFO - PROCESS | 2089 | 19:26:49 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49354/tests/dromaeo/cssquery-mootools.html) 19:26:50 INFO - PROCESS | 2089 | RSS: Main: 277270528 19:26:50 INFO - PROCESS | 2089 | 19:29:51 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49354/tests/dromaeo/cssquery-prototype.html) 19:29:52 INFO - PROCESS | 2089 | RSS: Main: 278908928 19:29:52 INFO - PROCESS | 2089 | 19:32:54 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49354/tests/dromaeo/cssquery-yui.html) 19:32:55 INFO - PROCESS | 2089 | RSS: Main: 281726976 19:32:55 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-yui.html (next: http://localhost:49354/tests/dromaeo/cssquery-dojo.html) 19:35:58 INFO - PROCESS | 2089 | RSS: Main: 282824704 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | __start_tp_report 19:35:58 INFO - PROCESS | 2089 | _x_x_mozilla_page_load 19:35:58 INFO - PROCESS | 2089 | _x_x_mozilla_page_load_details 19:35:58 INFO - PROCESS | 2089 | |i|pagename|runs| 19:35:58 INFO - PROCESS | 2089 | |0;dojo.html;3441.62142164362;3452.9309413811725;3482.54726885505;3485.9825700871493;3497.4753786931956;4637.675362724611;5777.682227477487;5888.882222355555;5942.881142377149;5986.341502434734;5822.126680997852;5900.527957763379;5910.586258961875;5947.494462970652;5987.9101813472835;5253.080710875601;5293.15309550472;5305.060972261751;5389.973050134763;5451.836444906659;5402.8919421611545;5427.3215848019;5444.047291723952;5473.233747275384;5506.944930550703;5229.267902493654;5298.81454149103;5339.786408543654;5365.302510673607;5390.110631745757;4623.9768801156115;5268.209768534713;5358.758855851496;5406.945930540684;5418.241446197536;5239.685618862881;5310.36275646921;5354.384245811742;5360.839174824761;5375.22059301403;5644.8871022579315;5761.9135712964335;5801.564882633777;5858.619189752695;5895.115732640095;6231.937680623181;6298.527610429191;6313.842153946142;6316.96841515794;6333.524985626051;6226.190595222637;6249;6260.280067792217;6334.334894836022;6337.6197428154455;5923.141144534018;6008.489278411384;6014.218151640259;6018.15745795589;6084;6381.872362552723;6393.296737358887;6427.485801135898;6428.0357946307495;6447.129637498878;6115.605440958429;6177.7837775677635;6197.81406557804;6313.779017734358;6332.746690132435;6277.905831412532;6324;6330.936690633127;6340.1440805490665;6359.872802543923;6193.504519638418;6195.380461953769;6200.348963358872;6278.434940855253;6302.779402720883;5301.469853014668;6220.066989951543;6244.2819075805955;6274.498040156868;6309.968450157719;4962.826301079445;4965.677230979974;4975.4527002029745;4983.352164218629;5010.649254552146;4444.577765112309;4465.508794032654;4465.642748580106;4485.416895803524;4532.909341813145;5813.302403711497;5994.430529099728;6015.488683461955;6043.667598282137;6066.393360663898;6291.276503202191;6353.682315884187;6362.681865906686;6364.172657554488;6397.904031439532;5099.209622508517;6028.397160284024;6042.0332746760305;6051.4553690168095;6067.817965461043;5360.705161216092;5447.428020057837;5458.563314934796;5487.533559647395;5497.038018346773;6519.674016299166;6549.083128362033;6553.705083271359;6623.105880706043;6683.866322673519;6157;6341.27075386318;6357.300696923242;6360.650164241011;6368.681565921593;5496.972515137559;6352.0789485525265;6359.586626869239;6405.903911441425;6474.384933431316;6239.594426362272;6292.685365731787;6308.558400912076;6312.842178945563;6322.715477803602;6167.938320616737;6203.503719702323;6228.719707613259;6316.684165791784;6335.081413195153;5053.620978426559;6196.47329976957;6230;6278.340774218641;6313.43179113882;6270.372962703693;6272.341404152498;6273.52948528853;6288.622682639057;6293.937060629335;5991.131285963598;6086.847828804315;6119.969400152971;6168.198134242699;6272.059191121369;6189.473894718999;6208.72060757258;6247.5939063960695;6276.215473065867;6331.493480521455;6097.993831017832;6173.351798061319;6174.506039516738;6187.690615469299;6198.070289456437 19:35:58 INFO - PROCESS | 2089 | |1;ext.html;511.5217271850819;530.7187190788881;576.4523702482643;581.070287539936;589.4105894105894;32532.1866953326;37228.25543489129;38753.224935501275;38763.80618096909;38849.028774280654;32331.51502727461;39622.20755584894;40502.98742531438;40632.39051414231;40662.796686016525;9342.205912497446;9625.037496250354;9626.037396260388;9627.037296270388;9635.61457541701;9422.34043616947;9465.432074075577;9507.286953478482;9512.143907048365;9541.522923853814;9502.714918552454;9544.045595440435;9555.283353748462;9566.23470122392;9582.377145485556;9447.291453141008;9524.619015239383;9532.14210721035;9540.33217674763;9553.570089345996;9405.200557952583;9509.381890177116;9514;9539.998300178451;9542.141207291308;37231.25537489235;37457.62542374569;37657.62342376596;37788.866334010025;37858.24283514342;2575.703794063688;3077.723004929545;3216.9384103245875;3404.3531728971416;3877.263319969197;25372.49255014908;27307.044253451233;27444.45111097787;27483.45033099347;28082.297942551395;2645.061003343824;2661.65398498194;2698.4603079384005;2733.876975536086;2750.009996401294;426268.7373126291;436882;442722.9999999968;443308;445456;29773;32185.35629287401;32433;34548.82725586406;34549.654503455145;36603.633963660555;36786.08034799151;36966.260674786354;38562.614373856464;38716.6128338713;16966.830331696525;16984.83015169857;17120.315187392614;17140.828591714173;17464;12788.104832661811;12848.743025139633;13077.869221307856;13164.14433061848;13166.73666526664;19518.31685890987;19576.70634940477;19771.308004219783;19771.40685779429;19844.305449309206;7985.560794156261;8085.272325490734;8093.993473230671;8140.796480087926;8211.548364839871;914.3736540469799;986.850319377931;993.2320469564187;1023.0996722883964;1062.5696592879897;24638.876805616215;27598.862005690244;29321.266968325963;30008;30145.69854301473;1049.5051478576322;1092.1044743310408;1156.6992581928591;1173.700706319883;1196.497471062156;15017.924910375377;15089.54731358061;15103.697926041417;15212;15537.30082146306;639121;810450;828184;829440;1041110;58864.52838678982;59518.10722839161;59839.70080149572;60021.099683504784;60472.697636512414;1459.3870574358796;1465.1282486920265;1470.1399681186626;1480.4226351722837;1486.301438323986;29628.962986295377;29674.258143546584;29804.55293170561;30266.54600181043;30387.848060759556;50803.23795143002;51197.48802511927;51482;51701.741491292305;51956.74021629868;599220;616581;622224;623415;624189;22906.885465573232;26717.33206669771;26897.193084207505;27024.054158104373;27516.724832752217;43010.56989430066;45172.77413612911;45260.321095184256;45305.546944530135;46235.306470403644;36891.63108368882;37170;37542.812285938395;37970.43044354281;38246.80876595599;33085.50371744474;36960.89117326485;37615.62384376121;37773.4333984996;37849;32203.838980804947;34479.79320723763;36330.818345908105;37453;37727.81136094302;29652.258693532833;32165.517517237728;32369.838150810036;32554.83722581372;32625.673743263214;19149.234030638618;24416.145434909693;24941.750582493944;25491.36271593225;26523.81714194309 19:35:58 INFO - PROCESS | 2089 | |2;jquery.html;393.49042989328814;400.4913759525403;401.04751215862325;401.29973196771635;403.0990735705697;12085.577004804834;20228.89885550572;20953.95230238486;21388.786112138874;21451.892740536296;20510.794892051075;20714.482137946557;20829.06269217885;20912.68630970537;21061.684074738892;5541.778328866841;5974.044152935531;6022.488088512482;6063.969680151593;6064.060070689028;5545.944540554603;5875.794347197828;5910.97044514779;5978.133170690247;6006.339302676702;5683.687397193153;5962.34414214436;5970.373110823366;5977.864205800884;5985.6408615483215;5995.730192141343;6034.215551978236;6049.274087109531;6096.237970253718;6111.144439778434;5582.368322749347;5738.8278351649515;5848.97075514624;5910.940890591082;5917.556183286228;13544.86455135446;13853.51512697061;13874.167549947035;13877.236751978739;23074.65388019181;2016.919323227069;2120.3214971209063;2124.7450305963384;2197.516546359798;2240.865548067105;9659.855102173473;9765.462899540522;9962.352447090989;10200.74498137545;10256.948715256376;1755.183839514632;1759.8856074355126;1775.9378421755177;1777.9377721779806;1822.963540729178;103041;133690;135551;136730;140308.59691403058;11704.239224450383;11705.414729263503;11772.99859023968;11818.645440636794;11927.88072119285;15165.09009459436;15208.391664333303;15253.923730381499;15282.617934551725;15286.006409583342;8706.564671766513;8719.34604904622;8744.956275218583;8779.341549383822;8836.293096552261;8562.52906090171;8600.612972416257;8640.006399264039;8707.346948978851;8720.694775682821;3965.266425711253;4564.3153526970555;4666.580007799314;4672.462666793294;4699.365585645963;1563.7498000319895;1982.9504262393555;1985.463924740312;2034.362338317639;2078;928.9303302252358;952.1858810716876;969.0987381734914;975.3611384543135;999.6001599360314;19953.800461995485;20637.17451301961;20673.50624094615;20818;20948;945.9148676619137;957.4590356448584;979.1579241852002;998.8401855703198;1031.5873650539845;9115.635374584943;10026.646402735683;10348.689539313833;10581.788364232672;10789.97495237951;504784;568201;613430;616320;626376;88381;119278;123292.76707232992;125914;131317;868.7393781865542;1028.1928685981468;1029.7837454134715;1034.7620047388991;1040.7606250562262;14881.627959301104;17707.911460442614;17754.37859674905;18042.729359059886;18059.819401805813;44346.11307773762;51663.22505162351;52275.47724522706;53601;54198.18702719383;75646.24353756612;78189.21810782047;79137;79347;80066;6940.9652951734915;7436.479446438698;7555.773326799985;7764;8541.53021583794;9130.771730706785;11925.76148477043;12573.371331433575;13010;13242.337883105998;10838.729031773953;10901;10914.345139291669;10932.289401188897;11089.22375433712;10669.946650266698;10787.892121079003;10983.121350291798;11039.061679757311;11114.44427778624;9579.760505987406;11047.613333533287;11084.944575277072;11273.985341319321;11457.197996140523;7530.887036694564;7612.733554325793;7650.349720273616;7794.610269486389;7856;9127.497987610479;10380.532876020749;11026.338419694845;11185.776284474428;11193.888061119285 19:35:58 INFO - PROCESS | 2089 | |3;mootools.html;1587.5237428771366;1609.694158109959;1867.841233495153;1940.5245714799885;1973.4178417366875;8140.633671484783;8694.478331300126;8753.037165911745;9219.861702074466;9224.538773061346;8862.955685221565;8968.730938071869;8989.685361012365;9034.90965090349;9070.183683468487;2447.853128812268;2480.4047028713157;2483.3046746910804;2485.8384205026614;2487.067349743846;2439.7072351317865;2462.729099799021;2499.962500562484;2503.2615378463397;2508.2475257422793;2389.5937690592555;2469.234537293436;2478.7149477810012;2498.725140234573;2511.849289042654;2116.767155612881;2334.3813889319345;2404.6393041043807;2418;2474.5545801755675;2380.392999785061;2410.927672169838;2420.6611074449593;2421.9273421797375;2423.818213633967;9034.954825225897;9065.864012039825;9140.268778497772;9157.81684366309;9169.220616247627;5491.725413729338;5632.633878797865;5650.5479561634975;5664.716764161775;5664.943350566483;3158.8578513966695;3630.8366123524506;3632.745707800455;3654.9817250913843;3664.59689434162;898.3217670658677;1036.9014943580348;1059.4014381874294;1059.8304271316629;1065.760203954104;12116;12221.572244971472;12232.694182645417;12236.449359778742;12248.632541023784;4901.1913034349645;4905.435874874435;5095.337606111182;5122.538971492509;5127.589792816566;10065.295429319878;10173.338732982333;10176.694699159038;10229.795404092025;10248.48757562119;5452.454754524595;5455.945440545624;5492.835214943558;5549.250851135126;5595.468430499096;4441.378207051015;4879.926801097987;5054.69671819692;5072.163093089599;5109.182530795055;4091.1817636472824;4122.643670148648;4155.4182414462;4174.3112386456505;4178.1643671265865;1148.5520646947698;1197.3235122155954;1381.2748307138631;1382.8409732880848;1387.3271463340272;827.5655280977535;895.4135041547667;921.2077613252598;922.5433410461865;938.6292414496372;5859.296884373903;5967.940320596826;6015.969920150371;6122.173506576644;6192;463.6846944078059;469.2398314730159;484.22765688726423;487.60016786235656;488.562736350971;3884.2037382336553;3986.740861843971;3991.5808840071954;4036.4348991141264;4052.0477687743355;10563.1021363185;10908.836367454494;10986.230963832615;11030.944845275882;11119.777604447865;10847.077998370227;10935.234533582572;11008.064314533194;11009.779804404026;11110.166737494721;7976.800579985431;8342;8448.957755211184;8502.277306429023;8538.70114545988;5858.238429004202;6098.298695650057;6143.1706719592285;6158.846028849135;6175.073738939015;10134.847977280495;10162;10239.948800255952;10526.421046842497;10536.157107431234;10442.373457592568;10635.361878287327;10648.307859989076;10699.304545204537;10763.730906727395;6902.8619427610165;7642.617869106633;7718.652660630173;7720.652570634223;7791.727289545065;8406.537640429835;8600.311975042106;8611.181937715906;8691.826163476822;8697.913020869963;7583.658735357032;7689.653965571674;7814.53112813233;7814.843703125792;7901.17037711032;7511.436642251743;7611.923880761343;7614.847703046019;7654.464187507044;7736.381089512713;7200.459965502503;7270.454715896435;7280.3083707047745;7303.817404564928;7308.817279568053;4858.222684370484;4976.751162441791;5057.418396884405;5099.26060721186;5104.132297509358;4350.499692535399;5046.236452222274;5049.293098966221;5097.592192624507;5121.820736274212 19:35:58 INFO - PROCESS | 2089 | |4;prototype.html;312.41734165780827;322.25719716054493;327.9819609921455;329.39227125952607;329.4695540180309;4783.569478746912;4887.460449958269;5695.601307908448;5747.224124743164;5782.190493330937;4743.576926921927;5151.74241287936;5206.8698282542955;5588.776448942048;5617.129344951539;3691.870784522542;4287.078278170193;4302.870913872589;4316.784160791964;4347.304431290994;3587.426011838106;3927.725059245854;4360.604606525913;4363.149185908733;4373.1909596724545;3858.186652273427;4013.1572369802375;4169.16496782188;4220.472440944882;4233.470816147981;3612.6026137124895;3706.981465092671;4202.054537729017;4243.684457818071;4289.92751812047;3398.5411969384063;3661.2494438640015;3662.981685091584;3663.19409729859;3688.428293614494;4358.842373025373;4897.2898929655;4921.704697718148;5120.796765604977;5226.947730522684;4062.7765472898977;4805.903881922377;4823.782929768169;4825.903481930377;4846.4798304881615;5267.28891599633;5899.498542623882;5922.815436912591;5928.407159284037;5949.107633854913;3340.498925161221;3440.4839274108836;3835.712321575893;3914.354131568288;3952.229315283521;5639.943600563983;6227.844303892393;6231.158793562857;6256.812295631138;6275.278342990615;4011.839526418969;4038.7778672172726;4041.2927737645796;4046.3728122141115;4053.6554392876346;3786.6592006718975;3918.3338832398563;4102.733322334038;4102.917941641151;4123.701034174225;4277.550857160016;4692.835750748707;4778.1399348116975;4794.712317260905;4817.108834865561;5507.989583801964;5681.431856814285;5955.672438015864;5977.760889564369;5987.371326010793;1191.4578866615668;1360.9523666671619;1369.1511263016994;1398.5035312464133;1398.8041674165624;829.668132746906;841.8610929196736;853.2278288149234;863.1023735315201;867.1935100356603;4454.510003899569;4505.341848309447;4516.706414083074;4722.9763851181215;4733.857984260478;4502.977485112619;4898.951010489921;4957.314513065575;5222.895542089137;5259.500347467061;4214.670351517089;4292.5707429257445;4679.649026322972;4681.485036645966;4752.00207956333;2565.4479040180795;2703.864806759654;2736.2488007675106;2745.643066401355;2760.3927136030043;4769.642276829251;5123.923141152885;5222.294990176353;5390.595705322038;5458.379977610769;4553.2980240781535;4892.590706174922;5007.549320561167;5134;5313.760880760375;4960.751962401865;5002.599272203789;5010.872553675466;5320.654157479751;5442.8911421771345;4552.609879813124;4810.3404325507645;4944.678595891256;5007.971771432575;5248.790048398021;4707.398839887557;4979.726115063706;5119.74401279927;5161.251618515369;5207.3230480037355;4564.657650676146;4838.661293709407;5064.4935506449065;5318.282031925795;5442.891142177214;3152.8334516229143;3181.80909145452;3388.66113388669;3540.4689296605716;3545.741261852005;4534.614557762628;4957.677750946177;4980.750962451935;5048.046892567889;5231.875146843447;4793.9280910787065;5784.161296612052;5894.911576326444;5902.2327097477055;5914.255294687991;5890.057590785454;5919.585629005928;5956.672383018802;5970.283565971938;5984.281886173513;4606.585407313224;5251.505534870906;5595.908797784393;5829.183914251923;5873.588848780544;4927.655064145475;5906.763729450774;5922.851928701817;5947.553933454921;5955.196048533393;5452.836414907559;5587.3015873015875;5723.971380143239;5839.941600583939;5840.737166827588 19:35:58 INFO - PROCESS | 2089 | |5;yui.html;686.5297271369111;721.4444877444371;754.7094368668064;759.4835511851938;760.6653072648036;14577.781333280009;15040.623984400396;15465.458708945189;15465.536033918972;15488.69022619547;12604.306763128025;14088.929555352235;14233.857661423383;14278.714425711505;14364;735.3014636095704;897.9775505612347;900.162848550849;926.5737760630118;929.3494553812325;923.3305853256369;926.9304802139833;928.4986107501941;928.7910220200469;929.9674511392103;917.4907926101012;940.407543247753;975.6975337645317;978.4227305889524;980.7597138701028;959.4962644611564;967.3083745122236;968.9563969621383;969.7527130581727;971.825071487132;710.4032612605437;717.9317964793337;723.5550136665966;725.0357025156529;726.3027493606145;14757.966942314042;14814.333354999051;14816.703665926621;14822.258887055605;14845.851541484555;11930.642080737593;12426.502939882394;12526.624201273977;12575.999999999909;12870.742585148339;9812.116909478109;9901.950490247575;10192.235582331356;10435.269531132824;10520.158387329071;3460.446328587414;3471.9086068197516;3527.1358517163203;3531.611522732497;3595.280943811222;78885.60557197177;79403;81542.96142596423;81821.59089204576;82123.17876821215;9450.298946189614;9454.33819632633;9462.763430914281;9722.805543889224;9740.36687615317;21259;21474;21487;21551.89224053901;21582.568348633253;6951.617661028591;7001.649917504206;7096.787096387014;7125.144982602121;7214.206437291894;3961.980190099031;4049.230646177276;4052.858149964737;4066.430699702044;4098.200850834059;3290.4735242361585;3370;3382.3911695894485;3407.4037043517783;3411.624721280657;597.9940200597938;605.198112500938;610.078781040623;614.1463365921372;621.9160413344231;527.773057585242;557.799192290775;567.9545636349083;578.0635370699409;593.910913362999;7999.800004999922;8105.716299929474;8250.834983300421;8290.751277461564;8372.665093396317;581.8167277307634;586.3432955090326;592.4194289596134;594.2275042444804;594.6135012242078;3788.962110378916;3794.772313661189;3884.1843212925037;3921.470601468822;3963.980180099081;87375.1262487367;89836;90310;90337.09662903287;91948;80251;81559;81681;81703;81831;71406.28593713997;72500.63749681218;72754;73196.63401682957;73918;7469.701211951461;7675.309222170032;7813.257740514642;7884.2115788423;7904.525728456311;34747;43774.56225437705;44930.97810598596;45252.77373613111;45772.54227457683;60126.699366502886;61734.382656172864;61916.071258932054;62005.68997154985;62396.37603623905;10323.225758068325;10344.482775861026;10349.379037257788;10417.84373234387;10606.09848162884;10114.393136411838;10809.891901080888;11030;11154.219204655596;11159.27464714791;10072.984014914102;10325.667082664515;10477.37135771856;10787.406692631981;10892.000099470195;10344.586775210648;10500.054995050483;10568.46749272167;10694.786104278026;11431.542738290374;9333.766655833659;10084.899151008396;10279.537420815932;10553.683389498327;11080.445977700922;8809.779755505908;8973.237274831712;9085.182333590008;9142.405743626643;9363;8234;8872.911270887467;8939.329550283885;9034.774130646787;9127.995920448879 19:35:58 INFO - PROCESS | 2089 | __end_tp_report 19:35:58 INFO - PROCESS | 2089 | __start_cc_report 19:35:58 INFO - PROCESS | 2089 | _x_x_mozilla_cycle_collect,411 19:35:58 INFO - PROCESS | 2089 | __end_cc_report 19:35:58 INFO - PROCESS | 2089 | __startTimestamp1463106958370__endTimestamp 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | ------- Summary: start ------- 19:35:58 INFO - PROCESS | 2089 | Number of tests: 6 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#0] dojo.html Cycles:165 Average:5847.23 Median:6119.97 stddev:661.05 (10.8%) stddev-sans-first:635.57 19:35:58 INFO - PROCESS | 2089 | Values: 3441.6 3452.9 3482.5 3486.0 3497.5 4637.7 5777.7 5888.9 5942.9 5986.3 5822.1 5900.5 5910.6 5947.5 5987.9 5253.1 5293.2 5305.1 5390.0 5451.8 5402.9 5427.3 5444.0 5473.2 5506.9 5229.3 5298.8 5339.8 5365.3 5390.1 4624.0 5268.2 5358.8 5406.9 5418.2 5239.7 5310.4 5354.4 5360.8 5375.2 5644.9 5761.9 5801.6 5858.6 5895.1 6231.9 6298.5 6313.8 6317.0 6333.5 6226.2 6249.0 6260.3 6334.3 6337.6 5923.1 6008.5 6014.2 6018.2 6084.0 6381.9 6393.3 6427.5 6428.0 6447.1 6115.6 6177.8 6197.8 6313.8 6332.7 6277.9 6324.0 6330.9 6340.1 6359.9 6193.5 6195.4 6200.3 6278.4 6302.8 5301.5 6220.1 6244.3 6274.5 6310.0 4962.8 4965.7 4975.5 4983.4 5010.6 4444.6 4465.5 4465.6 4485.4 4532.9 5813.3 5994.4 6015.5 6043.7 6066.4 6291.3 6353.7 6362.7 6364.2 6397.9 5099.2 6028.4 6042.0 6051.5 6067.8 5360.7 5447.4 5458.6 5487.5 5497.0 6519.7 6549.1 6553.7 6623.1 6683.9 6157.0 6341.3 6357.3 6360.7 6368.7 5497.0 6352.1 6359.6 6405.9 6474.4 6239.6 6292.7 6308.6 6312.8 6322.7 6167.9 6203.5 6228.7 6316.7 6335.1 5053.6 6196.5 6230.0 6278.3 6313.4 6270.4 6272.3 6273.5 6288.6 6293.9 5991.1 6086.8 6120.0 6168.2 6272.1 6189.5 6208.7 6247.6 6276.2 6331.5 6098.0 6173.4 6174.5 6187.7 6198.1 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#1] ext.html Cycles:180 Average:73167.31 Median:37342.13 stddev:176160.17 (471.7%) stddev-sans-first:176569.88 19:35:58 INFO - PROCESS | 2089 | Values: 511.5 530.7 576.5 581.1 589.4 32532.2 37228.3 38753.2 38763.8 38849.0 32331.5 39622.2 40503.0 40632.4 40662.8 9342.2 9625.0 9626.0 9627.0 9635.6 9422.3 9465.4 9507.3 9512.1 9541.5 9502.7 9544.0 9555.3 9566.2 9582.4 9447.3 9524.6 9532.1 9540.3 9553.6 9405.2 9509.4 9514.0 9540.0 9542.1 37231.3 37457.6 37657.6 37788.9 37858.2 2575.7 3077.7 3216.9 3404.4 3877.3 25372.5 27307.0 27444.5 27483.5 28082.3 2645.1 2661.7 2698.5 2733.9 2750.0 426268.7 436882.0 442723.0 443308.0 445456.0 29773.0 32185.4 32433.0 34548.8 34549.7 36603.6 36786.1 36966.3 38562.6 38716.6 16966.8 16984.8 17120.3 17140.8 17464.0 12788.1 12848.7 13077.9 13164.1 13166.7 19518.3 19576.7 19771.3 19771.4 19844.3 7985.6 8085.3 8094.0 8140.8 8211.5 914.4 986.9 993.2 1023.1 1062.6 24638.9 27598.9 29321.3 30008.0 30145.7 1049.5 1092.1 1156.7 1173.7 1196.5 15017.9 15089.5 15103.7 15212.0 15537.3 639121.0 810450.0 828184.0 829440.0 1041110.0 58864.5 59518.1 59839.7 60021.1 60472.7 1459.4 1465.1 1470.1 1480.4 1486.3 29629.0 29674.3 29804.6 30266.5 30387.8 50803.2 51197.5 51482.0 51701.7 51956.7 599220.0 616581.0 622224.0 623415.0 624189.0 22906.9 26717.3 26897.2 27024.1 27516.7 43010.6 45172.8 45260.3 45305.5 46235.3 36891.6 37170.0 37542.8 37970.4 38246.8 33085.5 36960.9 37615.6 37773.4 37849.0 32203.8 34479.8 36330.8 37453.0 37727.8 29652.3 32165.5 32369.8 32554.8 32625.7 19149.2 24416.1 24941.8 25491.4 26523.8 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#2] jquery.html Cycles:180 Average:34307.56 Median:12657.76 stddev:98343.94 (776.9%) stddev-sans-first:98586.85 19:35:58 INFO - PROCESS | 2089 | Values: 393.5 400.5 401.0 401.3 403.1 12085.6 20228.9 20954.0 21388.8 21451.9 20510.8 20714.5 20829.1 20912.7 21061.7 5541.8 5974.0 6022.5 6064.0 6064.1 5545.9 5875.8 5911.0 5978.1 6006.3 5683.7 5962.3 5970.4 5977.9 5985.6 5995.7 6034.2 6049.3 6096.2 6111.1 5582.4 5738.8 5849.0 5910.9 5917.6 13544.9 13853.5 13874.2 13877.2 23074.7 2016.9 2120.3 2124.7 2197.5 2240.9 9659.9 9765.5 9962.4 10200.7 10256.9 1755.2 1759.9 1775.9 1777.9 1823.0 103041.0 133690.0 135551.0 136730.0 140308.6 11704.2 11705.4 11773.0 11818.6 11927.9 15165.1 15208.4 15253.9 15282.6 15286.0 8706.6 8719.3 8745.0 8779.3 8836.3 8562.5 8600.6 8640.0 8707.3 8720.7 3965.3 4564.3 4666.6 4672.5 4699.4 1563.7 1983.0 1985.5 2034.4 2078.0 928.9 952.2 969.1 975.4 999.6 19953.8 20637.2 20673.5 20818.0 20948.0 945.9 957.5 979.2 998.8 1031.6 9115.6 10026.6 10348.7 10581.8 10790.0 504784.0 568201.0 613430.0 616320.0 626376.0 88381.0 119278.0 123292.8 125914.0 131317.0 868.7 1028.2 1029.8 1034.8 1040.8 14881.6 17707.9 17754.4 18042.7 18059.8 44346.1 51663.2 52275.5 53601.0 54198.2 75646.2 78189.2 79137.0 79347.0 80066.0 6941.0 7436.5 7555.8 7764.0 8541.5 9130.8 11925.8 12573.4 13010.0 13242.3 10838.7 10901.0 10914.3 10932.3 11089.2 10669.9 10787.9 10983.1 11039.1 11114.4 9579.8 11047.6 11084.9 11274.0 11457.2 7530.9 7612.7 7650.3 7794.6 7856.0 9127.5 10380.5 11026.3 11185.8 11193.9 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#3] mootools.html Cycles:180 Average:5884.71 Median:2683.76 stddev:3328.86 (124.0%) stddev-sans-first:3322.54 19:35:58 INFO - PROCESS | 2089 | Values: 1587.5 1609.7 1867.8 1940.5 1973.4 8140.6 8694.5 8753.0 9219.9 9224.5 8863.0 8968.7 8989.7 9034.9 9070.2 2447.9 2480.4 2483.3 2485.8 2487.1 2439.7 2462.7 2500.0 2503.3 2508.2 2389.6 2469.2 2478.7 2498.7 2511.8 2116.8 2334.4 2404.6 2418.0 2474.6 2380.4 2410.9 2420.7 2421.9 2423.8 9035.0 9065.9 9140.3 9157.8 9169.2 5491.7 5632.6 5650.5 5664.7 5664.9 3158.9 3630.8 3632.7 3655.0 3664.6 898.3 1036.9 1059.4 1059.8 1065.8 12116.0 12221.6 12232.7 12236.4 12248.6 4901.2 4905.4 5095.3 5122.5 5127.6 10065.3 10173.3 10176.7 10229.8 10248.5 5452.5 5455.9 5492.8 5549.3 5595.5 4441.4 4879.9 5054.7 5072.2 5109.2 4091.2 4122.6 4155.4 4174.3 4178.2 1148.6 1197.3 1381.3 1382.8 1387.3 827.6 895.4 921.2 922.5 938.6 5859.3 5967.9 6016.0 6122.2 6192.0 463.7 469.2 484.2 487.6 488.6 3884.2 3986.7 3991.6 4036.4 4052.0 10563.1 10908.8 10986.2 11030.9 11119.8 10847.1 10935.2 11008.1 11009.8 11110.2 7976.8 8342.0 8449.0 8502.3 8538.7 5858.2 6098.3 6143.2 6158.8 6175.1 10134.8 10162.0 10239.9 10526.4 10536.2 10442.4 10635.4 10648.3 10699.3 10763.7 6902.9 7642.6 7718.7 7720.7 7791.7 8406.5 8600.3 8611.2 8691.8 8697.9 7583.7 7689.7 7814.5 7814.8 7901.2 7511.4 7611.9 7614.8 7654.5 7736.4 7200.5 7270.5 7280.3 7303.8 7308.8 4858.2 4976.8 5057.4 5099.3 5104.1 4350.5 5046.2 5049.3 5097.6 5121.8 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#4] prototype.html Cycles:180 Average:4466.47 Median:4824.84 stddev:1369.39 (28.4%) stddev-sans-first:1337.27 19:35:58 INFO - PROCESS | 2089 | Values: 312.4 322.3 328.0 329.4 329.5 4783.6 4887.5 5695.6 5747.2 5782.2 4743.6 5151.7 5206.9 5588.8 5617.1 3691.9 4287.1 4302.9 4316.8 4347.3 3587.4 3927.7 4360.6 4363.1 4373.2 3858.2 4013.2 4169.2 4220.5 4233.5 3612.6 3707.0 4202.1 4243.7 4289.9 3398.5 3661.2 3663.0 3663.2 3688.4 4358.8 4897.3 4921.7 5120.8 5226.9 4062.8 4805.9 4823.8 4825.9 4846.5 5267.3 5899.5 5922.8 5928.4 5949.1 3340.5 3440.5 3835.7 3914.4 3952.2 5639.9 6227.8 6231.2 6256.8 6275.3 4011.8 4038.8 4041.3 4046.4 4053.7 3786.7 3918.3 4102.7 4102.9 4123.7 4277.6 4692.8 4778.1 4794.7 4817.1 5508.0 5681.4 5955.7 5977.8 5987.4 1191.5 1361.0 1369.2 1398.5 1398.8 829.7 841.9 853.2 863.1 867.2 4454.5 4505.3 4516.7 4723.0 4733.9 4503.0 4899.0 4957.3 5222.9 5259.5 4214.7 4292.6 4679.6 4681.5 4752.0 2565.4 2703.9 2736.2 2745.6 2760.4 4769.6 5123.9 5222.3 5390.6 5458.4 4553.3 4892.6 5007.5 5134.0 5313.8 4960.8 5002.6 5010.9 5320.7 5442.9 4552.6 4810.3 4944.7 5008.0 5248.8 4707.4 4979.7 5119.7 5161.3 5207.3 4564.7 4838.7 5064.5 5318.3 5442.9 3152.8 3181.8 3388.7 3540.5 3545.7 4534.6 4957.7 4980.8 5048.0 5231.9 4793.9 5784.2 5894.9 5902.2 5914.3 5890.1 5919.6 5956.7 5970.3 5984.3 4606.6 5251.5 5595.9 5829.2 5873.6 4927.7 5906.8 5922.9 5947.6 5955.2 5452.8 5587.3 5724.0 5839.9 5840.7 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - PROCESS | 2089 | [#5] yui.html Cycles:180 Average:17846.42 Median:612.11 stddev:25425.45 (4153.7%) stddev-sans-first:25464.13 19:35:58 INFO - PROCESS | 2089 | Values: 686.5 721.4 754.7 759.5 760.7 14577.8 15040.6 15465.5 15465.5 15488.7 12604.3 14088.9 14233.9 14278.7 14364.0 735.3 898.0 900.2 926.6 929.3 923.3 926.9 928.5 928.8 930.0 917.5 940.4 975.7 978.4 980.8 959.5 967.3 969.0 969.8 971.8 710.4 717.9 723.6 725.0 726.3 14758.0 14814.3 14816.7 14822.3 14845.9 11930.6 12426.5 12526.6 12576.0 12870.7 9812.1 9902.0 10192.2 10435.3 10520.2 3460.4 3471.9 3527.1 3531.6 3595.3 78885.6 79403.0 81543.0 81821.6 82123.2 9450.3 9454.3 9462.8 9722.8 9740.4 21259.0 21474.0 21487.0 21551.9 21582.6 6951.6 7001.6 7096.8 7125.1 7214.2 3962.0 4049.2 4052.9 4066.4 4098.2 3290.5 3370.0 3382.4 3407.4 3411.6 598.0 605.2 610.1 614.1 621.9 527.8 557.8 568.0 578.1 593.9 7999.8 8105.7 8250.8 8290.8 8372.7 581.8 586.3 592.4 594.2 594.6 3789.0 3794.8 3884.2 3921.5 3964.0 87375.1 89836.0 90310.0 90337.1 91948.0 80251.0 81559.0 81681.0 81703.0 81831.0 71406.3 72500.6 72754.0 73196.6 73918.0 7469.7 7675.3 7813.3 7884.2 7904.5 34747.0 43774.6 44931.0 45252.8 45772.5 60126.7 61734.4 61916.1 62005.7 62396.4 10323.2 10344.5 10349.4 10417.8 10606.1 10114.4 10809.9 11030.0 11154.2 11159.3 10073.0 10325.7 10477.4 10787.4 10892.0 10344.6 10500.1 10568.5 10694.8 11431.5 9333.8 10084.9 10279.5 10553.7 11080.4 8809.8 8973.2 9085.2 9142.4 9363.0 8234.0 8872.9 8939.3 9034.8 9128.0 19:35:58 INFO - PROCESS | 2089 | -------- Summary: end -------- 19:35:58 INFO - PROCESS | 2089 | 19:35:58 INFO - TEST-INFO | 2089: exit 0 19:35:58 INFO - TEST-OK | dromaeo_css | took 1112181ms 19:35:58 INFO - TEST-START | kraken 19:35:58 INFO - operating with platform_type : mac_ 19:35:58 INFO - Initialising browser for kraken test... 19:35:58 INFO - TEST-INFO | started process 2096 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp7iDHXc/profile http://localhost:49354/getInfo.html) 19:36:01 INFO - PROCESS | 2096 | __metrics Screen width/height:1600/1200 19:36:01 INFO - PROCESS | 2096 | colorDepth:24 19:36:01 INFO - PROCESS | 2096 | Browser inner width/height: 1024/689 19:36:01 INFO - PROCESS | 2096 | __metrics 19:36:02 ERROR - PROCESS | 2096 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 19:36:02 INFO - TEST-INFO | 2096: exit 0 19:36:02 INFO - Browser initialized. 19:36:02 INFO - Running cycle 1/1 for kraken test... 19:36:02 INFO - TEST-INFO | started process 2099 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp7iDHXc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:14 INFO - PROCESS | 2099 | RSS: Main: 249778176 19:36:14 INFO - PROCESS | 2099 | 19:36:17 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection) 19:36:18 INFO - PROCESS | 2099 | RSS: Main: 261599232 19:36:18 INFO - PROCESS | 2099 | 19:36:20 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-dft) 19:36:21 INFO - PROCESS | 2099 | RSS: Main: 266944512 19:36:21 INFO - PROCESS | 2099 | 19:36:24 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-fft) 19:36:25 INFO - PROCESS | 2099 | RSS: Main: 267014144 19:36:25 INFO - PROCESS | 2099 | 19:36:27 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator) 19:36:28 INFO - PROCESS | 2099 | RSS: Main: 267649024 19:36:28 INFO - PROCESS | 2099 | 19:36:30 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:36:31 INFO - PROCESS | 2099 | RSS: Main: 267743232 19:36:31 INFO - PROCESS | 2099 | 19:36:34 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom) 19:36:34 INFO - PROCESS | 2099 | RSS: Main: 268382208 19:36:34 INFO - PROCESS | 2099 | 19:36:37 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate) 19:36:38 INFO - PROCESS | 2099 | RSS: Main: 269713408 19:36:38 INFO - PROCESS | 2099 | 19:36:41 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial) 19:36:42 INFO - PROCESS | 2099 | RSS: Main: 270626816 19:36:42 INFO - PROCESS | 2099 | 19:36:43 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:36:44 INFO - PROCESS | 2099 | RSS: Main: 269758464 19:36:44 INFO - PROCESS | 2099 | 19:36:46 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:36:47 INFO - PROCESS | 2099 | RSS: Main: 270336000 19:36:47 INFO - PROCESS | 2099 | 19:36:49 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:36:50 INFO - PROCESS | 2099 | RSS: Main: 269889536 19:36:50 INFO - PROCESS | 2099 | 19:36:53 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:36:53 INFO - PROCESS | 2099 | RSS: Main: 270143488 19:36:53 INFO - PROCESS | 2099 | 19:36:56 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:36:57 INFO - PROCESS | 2099 | RSS: Main: 270598144 19:36:57 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49354/tests/kraken/driver.html?testName=ai-astar) 19:36:59 INFO - PROCESS | 2099 | RSS: Main: 272015360 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | __start_tp_report 19:36:59 INFO - PROCESS | 2099 | _x_x_mozilla_page_load 19:36:59 INFO - PROCESS | 2099 | _x_x_mozilla_page_load_details 19:36:59 INFO - PROCESS | 2099 | |i|pagename|runs| 19:36:59 INFO - PROCESS | 2099 | |0;ai-astar;112;121;113;116;117;114;108;117;117;115 19:36:59 INFO - PROCESS | 2099 | |1;audio-beat-detection;153;118;146;201;123;164;144;123;164;147 19:36:59 INFO - PROCESS | 2099 | |2;audio-dft;197;201;208;235;214;198;270;279;193;198 19:36:59 INFO - PROCESS | 2099 | |3;audio-fft;71;78;70;193;51;53;92;91;158;62 19:36:59 INFO - PROCESS | 2099 | |4;audio-oscillator;78;81;140;82;79;86;116;86;79;119 19:36:59 INFO - PROCESS | 2099 | |5;imaging-gaussian-blur;135;105;89;89;88;86;92;87;90;87 19:36:59 INFO - PROCESS | 2099 | |6;imaging-darkroom;99;96;97;94;95;118;150;140;95;95 19:36:59 INFO - PROCESS | 2099 | |7;imaging-desaturate;91;91;90;90;92;75;106;142;105;94 19:36:59 INFO - PROCESS | 2099 | |8;json-parse-financial;61;63;62;62;62;60;61;116;62;59 19:36:59 INFO - PROCESS | 2099 | |9;json-stringify-tinderbox;62;69;66;74;100;64;92;95;99;98 19:36:59 INFO - PROCESS | 2099 | |10;stanford-crypto-aes;85;99;110;87;85;82;87;84;87;93 19:36:59 INFO - PROCESS | 2099 | |11;stanford-crypto-ccm;144;135;150;127;151;147;155;144;216;146 19:36:59 INFO - PROCESS | 2099 | |12;stanford-crypto-pbkdf2;169;167;171;165;169;166;167;168;164;162 19:36:59 INFO - PROCESS | 2099 | |13;stanford-crypto-sha256-iterative;69;67;71;85;88;72;60;85;89;88 19:36:59 INFO - PROCESS | 2099 | __end_tp_report 19:36:59 INFO - PROCESS | 2099 | __start_cc_report 19:36:59 INFO - PROCESS | 2099 | _x_x_mozilla_cycle_collect,1075 19:36:59 INFO - PROCESS | 2099 | __end_cc_report 19:36:59 INFO - PROCESS | 2099 | __startTimestamp1463107019532__endTimestamp 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | ------- Summary: start ------- 19:36:59 INFO - PROCESS | 2099 | Number of tests: 14 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.50 stddev:3.53 (3.0%) stddev-sans-first:3.57 19:36:59 INFO - PROCESS | 2099 | Values: 112.0 121.0 113.0 116.0 117.0 114.0 108.0 117.0 117.0 115.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:150.00 stddev:24.76 (16.5%) stddev-sans-first:26.20 19:36:59 INFO - PROCESS | 2099 | Values: 153.0 118.0 146.0 201.0 123.0 164.0 144.0 123.0 164.0 147.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#2] audio-dft Cycles:10 Average:219.30 Median:211.00 stddev:31.57 (15.0%) stddev-sans-first:32.43 19:36:59 INFO - PROCESS | 2099 | Values: 197.0 201.0 208.0 235.0 214.0 198.0 270.0 279.0 193.0 198.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#3] audio-fft Cycles:10 Average:91.90 Median:70.50 stddev:46.88 (66.5%) stddev-sans-first:49.11 19:36:59 INFO - PROCESS | 2099 | Values: 71.0 78.0 70.0 193.0 51.0 53.0 92.0 91.0 158.0 62.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:80.00 stddev:22.03 (27.5%) stddev-sans-first:22.53 19:36:59 INFO - PROCESS | 2099 | Values: 78.0 81.0 140.0 82.0 79.0 86.0 116.0 86.0 79.0 119.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:88.50 stddev:15.14 (17.1%) stddev-sans-first:5.79 19:36:59 INFO - PROCESS | 2099 | Values: 135.0 105.0 89.0 89.0 88.0 86.0 92.0 87.0 90.0 87.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:95.00 stddev:20.91 (22.0%) stddev-sans-first:21.93 19:36:59 INFO - PROCESS | 2099 | Values: 99.0 96.0 97.0 94.0 95.0 118.0 150.0 140.0 95.0 95.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:90.50 stddev:17.81 (19.7%) stddev-sans-first:18.73 19:36:59 INFO - PROCESS | 2099 | Values: 91.0 91.0 90.0 90.0 92.0 75.0 106.0 142.0 105.0 94.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:62.00 stddev:17.33 (27.9%) stddev-sans-first:18.25 19:36:59 INFO - PROCESS | 2099 | Values: 61.0 63.0 62.0 62.0 62.0 60.0 61.0 116.0 62.0 59.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:83.00 stddev:16.16 (19.5%) stddev-sans-first:15.46 19:36:59 INFO - PROCESS | 2099 | Values: 62.0 69.0 66.0 74.0 100.0 64.0 92.0 95.0 99.0 98.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.00 stddev:8.61 (9.9%) stddev-sans-first:8.95 19:36:59 INFO - PROCESS | 2099 | Values: 85.0 99.0 110.0 87.0 85.0 82.0 87.0 84.0 87.0 93.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:148.50 stddev:24.06 (16.2%) stddev-sans-first:25.37 19:36:59 INFO - PROCESS | 2099 | Values: 144.0 135.0 150.0 127.0 151.0 147.0 155.0 144.0 216.0 146.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.50 stddev:2.66 (1.6%) stddev-sans-first:2.70 19:36:59 INFO - PROCESS | 2099 | Values: 169.0 167.0 171.0 165.0 169.0 166.0 167.0 168.0 164.0 162.0 19:36:59 INFO - PROCESS | 2099 | 19:36:59 INFO - PROCESS | 2099 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:85.00 stddev:10.68 (12.6%) stddev-sans-first:10.89 19:36:59 INFO - PROCESS | 2099 | Values: 69.0 67.0 71.0 85.0 88.0 72.0 60.0 85.0 89.0 88.0 19:36:59 INFO - PROCESS | 2099 | -------- Summary: end -------- 19:36:59 INFO - PROCESS | 2099 | 19:37:00 INFO - TEST-INFO | 2099: exit 0 19:37:00 INFO - TEST-OK | kraken | took 61520ms 19:37:00 INFO - SUITE-END | took 1173s 19:37:00 INFO - Completed test suite (00:19:33) 19:37:00 INFO - javascript benchmark 19:37:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7136.044065882146, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.62142164362, 3452.9309413811725, 3482.54726885505, 3485.9825700871493, 3497.4753786931956, 4637.675362724611, 5777.682227477487, 5888.882222355555, 5942.881142377149, 5986.341502434734, 5822.126680997852, 5900.527957763379, 5910.586258961875, 5947.494462970652, 5987.9101813472835, 5253.080710875601, 5293.15309550472, 5305.060972261751, 5389.973050134763, 5451.836444906659, 5402.8919421611545, 5427.3215848019, 5444.047291723952, 5473.233747275384, 5506.944930550703, 5229.267902493654, 5298.81454149103, 5339.786408543654, 5365.302510673607, 5390.110631745757, 4623.9768801156115, 5268.209768534713, 5358.758855851496, 5406.945930540684, 5418.241446197536, 5239.685618862881, 5310.36275646921, 5354.384245811742, 5360.839174824761, 5375.22059301403, 5644.8871022579315, 5761.9135712964335, 5801.564882633777, 5858.619189752695, 5895.115732640095, 6231.937680623181, 6298.527610429191, 6313.842153946142, 6316.96841515794, 6333.524985626051, 6226.190595222637, 6249.0, 6260.280067792217, 6334.334894836022, 6337.6197428154455, 5923.141144534018, 6008.489278411384, 6014.218151640259, 6018.15745795589, 6084.0, 6381.872362552723, 6393.296737358887, 6427.485801135898, 6428.0357946307495, 6447.129637498878, 6115.605440958429, 6177.7837775677635, 6197.81406557804, 6313.779017734358, 6332.746690132435, 6277.905831412532, 6324.0, 6330.936690633127, 6340.1440805490665, 6359.872802543923, 6193.504519638418, 6195.380461953769, 6200.348963358872, 6278.434940855253, 6302.779402720883, 5301.469853014668, 6220.066989951543, 6244.2819075805955, 6274.498040156868, 6309.968450157719, 4962.826301079445, 4965.677230979974, 4975.4527002029745, 4983.352164218629, 5010.649254552146, 4444.577765112309, 4465.508794032654, 4465.642748580106, 4485.416895803524, 4532.909341813145, 5813.302403711497, 5994.430529099728, 6015.488683461955, 6043.667598282137, 6066.393360663898, 6291.276503202191, 6353.682315884187, 6362.681865906686, 6364.172657554488, 6397.904031439532, 5099.209622508517, 6028.397160284024, 6042.0332746760305, 6051.4553690168095, 6067.817965461043, 5360.705161216092, 5447.428020057837, 5458.563314934796, 5487.533559647395, 5497.038018346773, 6519.674016299166, 6549.083128362033, 6553.705083271359, 6623.105880706043, 6683.866322673519, 6157.0, 6341.27075386318, 6357.300696923242, 6360.650164241011, 6368.681565921593, 5496.972515137559, 6352.0789485525265, 6359.586626869239, 6405.903911441425, 6474.384933431316, 6239.594426362272, 6292.685365731787, 6308.558400912076, 6312.842178945563, 6322.715477803602, 6167.938320616737, 6203.503719702323, 6228.719707613259, 6316.684165791784, 6335.081413195153, 5053.620978426559, 6196.47329976957, 6230.0, 6278.340774218641, 6313.43179113882, 6270.372962703693, 6272.341404152498, 6273.52948528853, 6288.622682639057, 6293.937060629335, 5991.131285963598, 6086.847828804315, 6119.969400152971, 6168.198134242699, 6272.059191121369, 6189.473894718999, 6208.72060757258, 6247.5939063960695, 6276.215473065867, 6331.493480521455, 6097.993831017832, 6173.351798061319, 6174.506039516738, 6187.690615469299, 6198.070289456437], "value": 5806.357233895017, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.5217271850819, 530.7187190788881, 576.4523702482643, 581.070287539936, 589.4105894105894, 32532.1866953326, 37228.25543489129, 38753.224935501275, 38763.80618096909, 38849.028774280654, 32331.51502727461, 39622.20755584894, 40502.98742531438, 40632.39051414231, 40662.796686016525, 9342.205912497446, 9625.037496250354, 9626.037396260388, 9627.037296270388, 9635.61457541701, 9422.34043616947, 9465.432074075577, 9507.286953478482, 9512.143907048365, 9541.522923853814, 9502.714918552454, 9544.045595440435, 9555.283353748462, 9566.23470122392, 9582.377145485556, 9447.291453141008, 9524.619015239383, 9532.14210721035, 9540.33217674763, 9553.570089345996, 9405.200557952583, 9509.381890177116, 9514.0, 9539.998300178451, 9542.141207291308, 37231.25537489235, 37457.62542374569, 37657.62342376596, 37788.866334010025, 37858.24283514342, 2575.703794063688, 3077.723004929545, 3216.9384103245875, 3404.3531728971416, 3877.263319969197, 25372.49255014908, 27307.044253451233, 27444.45111097787, 27483.45033099347, 28082.297942551395, 2645.061003343824, 2661.65398498194, 2698.4603079384005, 2733.876975536086, 2750.009996401294, 426268.7373126291, 436882.0, 442722.9999999968, 443308.0, 445456.0, 29773.0, 32185.35629287401, 32433.0, 34548.82725586406, 34549.654503455145, 36603.633963660555, 36786.08034799151, 36966.260674786354, 38562.614373856464, 38716.6128338713, 16966.830331696525, 16984.83015169857, 17120.315187392614, 17140.828591714173, 17464.0, 12788.104832661811, 12848.743025139633, 13077.869221307856, 13164.14433061848, 13166.73666526664, 19518.31685890987, 19576.70634940477, 19771.308004219783, 19771.40685779429, 19844.305449309206, 7985.560794156261, 8085.272325490734, 8093.993473230671, 8140.796480087926, 8211.548364839871, 914.3736540469799, 986.850319377931, 993.2320469564187, 1023.0996722883964, 1062.5696592879897, 24638.876805616215, 27598.862005690244, 29321.266968325963, 30008.0, 30145.69854301473, 1049.5051478576322, 1092.1044743310408, 1156.6992581928591, 1173.700706319883, 1196.497471062156, 15017.924910375377, 15089.54731358061, 15103.697926041417, 15212.0, 15537.30082146306, 639121.0, 810450.0, 828184.0, 829440.0, 1041110.0, 58864.52838678982, 59518.10722839161, 59839.70080149572, 60021.099683504784, 60472.697636512414, 1459.3870574358796, 1465.1282486920265, 1470.1399681186626, 1480.4226351722837, 1486.301438323986, 29628.962986295377, 29674.258143546584, 29804.55293170561, 30266.54600181043, 30387.848060759556, 50803.23795143002, 51197.48802511927, 51482.0, 51701.741491292305, 51956.74021629868, 599220.0, 616581.0, 622224.0, 623415.0, 624189.0, 22906.885465573232, 26717.33206669771, 26897.193084207505, 27024.054158104373, 27516.724832752217, 43010.56989430066, 45172.77413612911, 45260.321095184256, 45305.546944530135, 46235.306470403644, 36891.63108368882, 37170.0, 37542.812285938395, 37970.43044354281, 38246.80876595599, 33085.50371744474, 36960.89117326485, 37615.62384376121, 37773.4333984996, 37849.0, 32203.838980804947, 34479.79320723763, 36330.818345908105, 37453.0, 37727.81136094302, 29652.258693532833, 32165.517517237728, 32369.838150810036, 32554.83722581372, 32625.673743263214, 19149.234030638618, 24416.145434909693, 24941.750582493944, 25491.36271593225, 26523.81714194309], "value": 19213.496407852188, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.49042989328814, 400.4913759525403, 401.04751215862325, 401.29973196771635, 403.0990735705697, 12085.577004804834, 20228.89885550572, 20953.95230238486, 21388.786112138874, 21451.892740536296, 20510.794892051075, 20714.482137946557, 20829.06269217885, 20912.68630970537, 21061.684074738892, 5541.778328866841, 5974.044152935531, 6022.488088512482, 6063.969680151593, 6064.060070689028, 5545.944540554603, 5875.794347197828, 5910.97044514779, 5978.133170690247, 6006.339302676702, 5683.687397193153, 5962.34414214436, 5970.373110823366, 5977.864205800884, 5985.6408615483215, 5995.730192141343, 6034.215551978236, 6049.274087109531, 6096.237970253718, 6111.144439778434, 5582.368322749347, 5738.8278351649515, 5848.97075514624, 5910.940890591082, 5917.556183286228, 13544.86455135446, 13853.51512697061, 13874.167549947035, 13877.236751978739, 23074.65388019181, 2016.919323227069, 2120.3214971209063, 2124.7450305963384, 2197.516546359798, 2240.865548067105, 9659.855102173473, 9765.462899540522, 9962.352447090989, 10200.74498137545, 10256.948715256376, 1755.183839514632, 1759.8856074355126, 1775.9378421755177, 1777.9377721779806, 1822.963540729178, 103041.0, 133690.0, 135551.0, 136730.0, 140308.59691403058, 11704.239224450383, 11705.414729263503, 11772.99859023968, 11818.645440636794, 11927.88072119285, 15165.09009459436, 15208.391664333303, 15253.923730381499, 15282.617934551725, 15286.006409583342, 8706.564671766513, 8719.34604904622, 8744.956275218583, 8779.341549383822, 8836.293096552261, 8562.52906090171, 8600.612972416257, 8640.006399264039, 8707.346948978851, 8720.694775682821, 3965.266425711253, 4564.3153526970555, 4666.580007799314, 4672.462666793294, 4699.365585645963, 1563.7498000319895, 1982.9504262393555, 1985.463924740312, 2034.362338317639, 2078.0, 928.9303302252358, 952.1858810716876, 969.0987381734914, 975.3611384543135, 999.6001599360314, 19953.800461995485, 20637.17451301961, 20673.50624094615, 20818.0, 20948.0, 945.9148676619137, 957.4590356448584, 979.1579241852002, 998.8401855703198, 1031.5873650539845, 9115.635374584943, 10026.646402735683, 10348.689539313833, 10581.788364232672, 10789.97495237951, 504784.0, 568201.0, 613430.0, 616320.0, 626376.0, 88381.0, 119278.0, 123292.76707232992, 125914.0, 131317.0, 868.7393781865542, 1028.1928685981468, 1029.7837454134715, 1034.7620047388991, 1040.7606250562262, 14881.627959301104, 17707.911460442614, 17754.37859674905, 18042.729359059886, 18059.819401805813, 44346.11307773762, 51663.22505162351, 52275.47724522706, 53601.0, 54198.18702719383, 75646.24353756612, 78189.21810782047, 79137.0, 79347.0, 80066.0, 6940.9652951734915, 7436.479446438698, 7555.773326799985, 7764.0, 8541.53021583794, 9130.771730706785, 11925.76148477043, 12573.371331433575, 13010.0, 13242.337883105998, 10838.729031773953, 10901.0, 10914.345139291669, 10932.289401188897, 11089.22375433712, 10669.946650266698, 10787.892121079003, 10983.121350291798, 11039.061679757311, 11114.44427778624, 9579.760505987406, 11047.613333533287, 11084.944575277072, 11273.985341319321, 11457.197996140523, 7530.887036694564, 7612.733554325793, 7650.349720273616, 7794.610269486389, 7856.0, 9127.497987610479, 10380.532876020749, 11026.338419694845, 11185.776284474428, 11193.888061119285], "value": 9375.05667537162, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1587.5237428771366, 1609.694158109959, 1867.841233495153, 1940.5245714799885, 1973.4178417366875, 8140.633671484783, 8694.478331300126, 8753.037165911745, 9219.861702074466, 9224.538773061346, 8862.955685221565, 8968.730938071869, 8989.685361012365, 9034.90965090349, 9070.183683468487, 2447.853128812268, 2480.4047028713157, 2483.3046746910804, 2485.8384205026614, 2487.067349743846, 2439.7072351317865, 2462.729099799021, 2499.962500562484, 2503.2615378463397, 2508.2475257422793, 2389.5937690592555, 2469.234537293436, 2478.7149477810012, 2498.725140234573, 2511.849289042654, 2116.767155612881, 2334.3813889319345, 2404.6393041043807, 2418.0, 2474.5545801755675, 2380.392999785061, 2410.927672169838, 2420.6611074449593, 2421.9273421797375, 2423.818213633967, 9034.954825225897, 9065.864012039825, 9140.268778497772, 9157.81684366309, 9169.220616247627, 5491.725413729338, 5632.633878797865, 5650.5479561634975, 5664.716764161775, 5664.943350566483, 3158.8578513966695, 3630.8366123524506, 3632.745707800455, 3654.9817250913843, 3664.59689434162, 898.3217670658677, 1036.9014943580348, 1059.4014381874294, 1059.8304271316629, 1065.760203954104, 12116.0, 12221.572244971472, 12232.694182645417, 12236.449359778742, 12248.632541023784, 4901.1913034349645, 4905.435874874435, 5095.337606111182, 5122.538971492509, 5127.589792816566, 10065.295429319878, 10173.338732982333, 10176.694699159038, 10229.795404092025, 10248.48757562119, 5452.454754524595, 5455.945440545624, 5492.835214943558, 5549.250851135126, 5595.468430499096, 4441.378207051015, 4879.926801097987, 5054.69671819692, 5072.163093089599, 5109.182530795055, 4091.1817636472824, 4122.643670148648, 4155.4182414462, 4174.3112386456505, 4178.1643671265865, 1148.5520646947698, 1197.3235122155954, 1381.2748307138631, 1382.8409732880848, 1387.3271463340272, 827.5655280977535, 895.4135041547667, 921.2077613252598, 922.5433410461865, 938.6292414496372, 5859.296884373903, 5967.940320596826, 6015.969920150371, 6122.173506576644, 6192.0, 463.6846944078059, 469.2398314730159, 484.22765688726423, 487.60016786235656, 488.562736350971, 3884.2037382336553, 3986.740861843971, 3991.5808840071954, 4036.4348991141264, 4052.0477687743355, 10563.1021363185, 10908.836367454494, 10986.230963832615, 11030.944845275882, 11119.777604447865, 10847.077998370227, 10935.234533582572, 11008.064314533194, 11009.779804404026, 11110.166737494721, 7976.800579985431, 8342.0, 8448.957755211184, 8502.277306429023, 8538.70114545988, 5858.238429004202, 6098.298695650057, 6143.1706719592285, 6158.846028849135, 6175.073738939015, 10134.847977280495, 10162.0, 10239.948800255952, 10526.421046842497, 10536.157107431234, 10442.373457592568, 10635.361878287327, 10648.307859989076, 10699.304545204537, 10763.730906727395, 6902.8619427610165, 7642.617869106633, 7718.652660630173, 7720.652570634223, 7791.727289545065, 8406.537640429835, 8600.311975042106, 8611.181937715906, 8691.826163476822, 8697.913020869963, 7583.658735357032, 7689.653965571674, 7814.53112813233, 7814.843703125792, 7901.17037711032, 7511.436642251743, 7611.923880761343, 7614.847703046019, 7654.464187507044, 7736.381089512713, 7200.459965502503, 7270.454715896435, 7280.3083707047745, 7303.817404564928, 7308.817279568053, 4858.222684370484, 4976.751162441791, 5057.418396884405, 5099.26060721186, 5104.132297509358, 4350.499692535399, 5046.236452222274, 5049.293098966221, 5097.592192624507, 5121.820736274212], "value": 4641.965413952763, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.41734165780827, 322.25719716054493, 327.9819609921455, 329.39227125952607, 329.4695540180309, 4783.569478746912, 4887.460449958269, 5695.601307908448, 5747.224124743164, 5782.190493330937, 4743.576926921927, 5151.74241287936, 5206.8698282542955, 5588.776448942048, 5617.129344951539, 3691.870784522542, 4287.078278170193, 4302.870913872589, 4316.784160791964, 4347.304431290994, 3587.426011838106, 3927.725059245854, 4360.604606525913, 4363.149185908733, 4373.1909596724545, 3858.186652273427, 4013.1572369802375, 4169.16496782188, 4220.472440944882, 4233.470816147981, 3612.6026137124895, 3706.981465092671, 4202.054537729017, 4243.684457818071, 4289.92751812047, 3398.5411969384063, 3661.2494438640015, 3662.981685091584, 3663.19409729859, 3688.428293614494, 4358.842373025373, 4897.2898929655, 4921.704697718148, 5120.796765604977, 5226.947730522684, 4062.7765472898977, 4805.903881922377, 4823.782929768169, 4825.903481930377, 4846.4798304881615, 5267.28891599633, 5899.498542623882, 5922.815436912591, 5928.407159284037, 5949.107633854913, 3340.498925161221, 3440.4839274108836, 3835.712321575893, 3914.354131568288, 3952.229315283521, 5639.943600563983, 6227.844303892393, 6231.158793562857, 6256.812295631138, 6275.278342990615, 4011.839526418969, 4038.7778672172726, 4041.2927737645796, 4046.3728122141115, 4053.6554392876346, 3786.6592006718975, 3918.3338832398563, 4102.733322334038, 4102.917941641151, 4123.701034174225, 4277.550857160016, 4692.835750748707, 4778.1399348116975, 4794.712317260905, 4817.108834865561, 5507.989583801964, 5681.431856814285, 5955.672438015864, 5977.760889564369, 5987.371326010793, 1191.4578866615668, 1360.9523666671619, 1369.1511263016994, 1398.5035312464133, 1398.8041674165624, 829.668132746906, 841.8610929196736, 853.2278288149234, 863.1023735315201, 867.1935100356603, 4454.510003899569, 4505.341848309447, 4516.706414083074, 4722.9763851181215, 4733.857984260478, 4502.977485112619, 4898.951010489921, 4957.314513065575, 5222.895542089137, 5259.500347467061, 4214.670351517089, 4292.5707429257445, 4679.649026322972, 4681.485036645966, 4752.00207956333, 2565.4479040180795, 2703.864806759654, 2736.2488007675106, 2745.643066401355, 2760.3927136030043, 4769.642276829251, 5123.923141152885, 5222.294990176353, 5390.595705322038, 5458.379977610769, 4553.2980240781535, 4892.590706174922, 5007.549320561167, 5134.0, 5313.760880760375, 4960.751962401865, 5002.599272203789, 5010.872553675466, 5320.654157479751, 5442.8911421771345, 4552.609879813124, 4810.3404325507645, 4944.678595891256, 5007.971771432575, 5248.790048398021, 4707.398839887557, 4979.726115063706, 5119.74401279927, 5161.251618515369, 5207.3230480037355, 4564.657650676146, 4838.661293709407, 5064.4935506449065, 5318.282031925795, 5442.891142177214, 3152.8334516229143, 3181.80909145452, 3388.66113388669, 3540.4689296605716, 3545.741261852005, 4534.614557762628, 4957.677750946177, 4980.750962451935, 5048.046892567889, 5231.875146843447, 4793.9280910787065, 5784.161296612052, 5894.911576326444, 5902.2327097477055, 5914.255294687991, 5890.057590785454, 5919.585629005928, 5956.672383018802, 5970.283565971938, 5984.281886173513, 4606.585407313224, 5251.505534870906, 5595.908797784393, 5829.183914251923, 5873.588848780544, 4927.655064145475, 5906.763729450774, 5922.851928701817, 5947.553933454921, 5955.196048533393, 5452.836414907559, 5587.3015873015875, 5723.971380143239, 5839.941600583939, 5840.737166827588], "value": 4036.780163779741, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.5297271369111, 721.4444877444371, 754.7094368668064, 759.4835511851938, 760.6653072648036, 14577.781333280009, 15040.623984400396, 15465.458708945189, 15465.536033918972, 15488.69022619547, 12604.306763128025, 14088.929555352235, 14233.857661423383, 14278.714425711505, 14364.0, 735.3014636095704, 897.9775505612347, 900.162848550849, 926.5737760630118, 929.3494553812325, 923.3305853256369, 926.9304802139833, 928.4986107501941, 928.7910220200469, 929.9674511392103, 917.4907926101012, 940.407543247753, 975.6975337645317, 978.4227305889524, 980.7597138701028, 959.4962644611564, 967.3083745122236, 968.9563969621383, 969.7527130581727, 971.825071487132, 710.4032612605437, 717.9317964793337, 723.5550136665966, 725.0357025156529, 726.3027493606145, 14757.966942314042, 14814.333354999051, 14816.703665926621, 14822.258887055605, 14845.851541484555, 11930.642080737593, 12426.502939882394, 12526.624201273977, 12575.999999999909, 12870.742585148339, 9812.116909478109, 9901.950490247575, 10192.235582331356, 10435.269531132824, 10520.158387329071, 3460.446328587414, 3471.9086068197516, 3527.1358517163203, 3531.611522732497, 3595.280943811222, 78885.60557197177, 79403.0, 81542.96142596423, 81821.59089204576, 82123.17876821215, 9450.298946189614, 9454.33819632633, 9462.763430914281, 9722.805543889224, 9740.36687615317, 21259.0, 21474.0, 21487.0, 21551.89224053901, 21582.568348633253, 6951.617661028591, 7001.649917504206, 7096.787096387014, 7125.144982602121, 7214.206437291894, 3961.980190099031, 4049.230646177276, 4052.858149964737, 4066.430699702044, 4098.200850834059, 3290.4735242361585, 3370.0, 3382.3911695894485, 3407.4037043517783, 3411.624721280657, 597.9940200597938, 605.198112500938, 610.078781040623, 614.1463365921372, 621.9160413344231, 527.773057585242, 557.799192290775, 567.9545636349083, 578.0635370699409, 593.910913362999, 7999.800004999922, 8105.716299929474, 8250.834983300421, 8290.751277461564, 8372.665093396317, 581.8167277307634, 586.3432955090326, 592.4194289596134, 594.2275042444804, 594.6135012242078, 3788.962110378916, 3794.772313661189, 3884.1843212925037, 3921.470601468822, 3963.980180099081, 87375.1262487367, 89836.0, 90310.0, 90337.09662903287, 91948.0, 80251.0, 81559.0, 81681.0, 81703.0, 81831.0, 71406.28593713997, 72500.63749681218, 72754.0, 73196.63401682957, 73918.0, 7469.701211951461, 7675.309222170032, 7813.257740514642, 7884.2115788423, 7904.525728456311, 34747.0, 43774.56225437705, 44930.97810598596, 45252.77373613111, 45772.54227457683, 60126.699366502886, 61734.382656172864, 61916.071258932054, 62005.68997154985, 62396.37603623905, 10323.225758068325, 10344.482775861026, 10349.379037257788, 10417.84373234387, 10606.09848162884, 10114.393136411838, 10809.891901080888, 11030.0, 11154.219204655596, 11159.27464714791, 10072.984014914102, 10325.667082664515, 10477.37135771856, 10787.406692631981, 10892.000099470195, 10344.586775210648, 10500.054995050483, 10568.46749272167, 10694.786104278026, 11431.542738290374, 9333.766655833659, 10084.899151008396, 10279.537420815932, 10553.683389498327, 11080.445977700922, 8809.779755505908, 8973.237274831712, 9085.182333590008, 9142.405743626643, 9363.0, 8234.0, 8872.911270887467, 8939.329550283885, 9034.774130646787, 9127.995920448879], "value": 6737.207458309297, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 121.0, 113.0, 116.0, 117.0, 114.0, 108.0, 117.0, 117.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 146.0, 201.0, 123.0, 164.0, 144.0, 123.0, 164.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 208.0, 235.0, 214.0, 198.0, 270.0, 279.0, 193.0, 198.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 70.0, 193.0, 51.0, 53.0, 92.0, 91.0, 158.0, 62.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 140.0, 82.0, 79.0, 86.0, 116.0, 86.0, 79.0, 119.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 105.0, 89.0, 89.0, 88.0, 86.0, 92.0, 87.0, 90.0, 87.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 97.0, 94.0, 95.0, 118.0, 150.0, 140.0, 95.0, 95.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 90.0, 92.0, 75.0, 106.0, 142.0, 105.0, 94.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 62.0, 60.0, 61.0, 116.0, 62.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 66.0, 74.0, 100.0, 64.0, 92.0, 95.0, 99.0, 98.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 99.0, 110.0, 87.0, 85.0, 82.0, 87.0, 84.0, 87.0, 93.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 135.0, 150.0, 127.0, 151.0, 147.0, 155.0, 144.0, 216.0, 146.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 171.0, 165.0, 169.0, 166.0, 167.0, 168.0, 164.0, 162.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 71.0, 85.0, 88.0, 72.0, 60.0, 85.0, 89.0, 88.0], "value": 77.4, "unit": "score"}]}]} 19:37:00 INFO - Return code: 0 19:37:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:37:00 ERROR - # TBPL SUCCESS # 19:37:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:37:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:37:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:37:00 INFO - 2016-05-12 19:37:00.820 screenresolution[2104:14124] starting screenresolution argv=screenresolution get 19:37:00 INFO - 2016-05-12 19:37:00.823 screenresolution[2104:14124] Display 0: 1600x1200x32@60 19:37:00 INFO - 2016-05-12 19:37:00.829 screenresolution[2105:14127] starting screenresolution argv=screenresolution list 19:37:00 INFO - Available Modes on Display 0 19:37:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:37:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:37:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:37:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:37:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:37:01 INFO - Intel Iris: 19:37:01 INFO - Chipset Model: Intel Iris 19:37:01 INFO - Type: GPU 19:37:01 INFO - Bus: Built-In 19:37:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:37:01 INFO - Vendor: Intel (0x8086) 19:37:01 INFO - Device ID: 0x0a2e 19:37:01 INFO - Revision ID: 0x0009 19:37:01 INFO - Displays: 19:37:01 INFO - Display: 19:37:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:37:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:37:01 INFO - Display Serial Number: 1600x1200 60 19:37:01 INFO - Main Display: Yes 19:37:01 INFO - Mirror: Off 19:37:01 INFO - Online: Yes 19:37:01 INFO - Rotation: Supported 19:37:01 INFO - Return code: 0 19:37:01 INFO - Running post-action listener: _resource_record_post_action 19:37:01 INFO - ##### 19:37:01 INFO - ##### Finished run-tests step (success) 19:37:01 INFO - ##### 19:37:01 INFO - Running post-run listener: _resource_record_post_run 19:37:02 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 30506496; Write bytes: 695745536; Read time: 306; Write time: 2076 19:37:02 INFO - TinderboxPrint: CPU usage
55.0% 19:37:02 INFO - TinderboxPrint: I/O read bytes / time
30,506,496 / 306 19:37:02 INFO - TinderboxPrint: I/O write bytes / time
695,745,536 / 2,076 19:37:02 INFO - TinderboxPrint: CPU idle
2,176.2 (45.0%) 19:37:02 INFO - TinderboxPrint: CPU system
975.7 (20.2%) 19:37:02 INFO - TinderboxPrint: CPU user
1,685.8 (34.8%) 19:37:02 INFO - TinderboxPrint: Swap in / out
419,946,496 / 0 19:37:02 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379640320; Write bytes: 374339584; Read time: 28406; Write time: 702 19:37:02 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 30506496; Write bytes: 298652672; Read time: 306; Write time: 1326 19:37:02 INFO - Running post-run listener: _upload_blobber_files 19:37:02 INFO - Blob upload gear active. 19:37:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:37:02 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 19:37:02 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'] 19:37:02 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 19:37:02 INFO - (blobuploader) - INFO - Open directory for files ... 19:37:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:37:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:37:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:37:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:37:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:37:03 INFO - (blobuploader) - INFO - Done attempting. 19:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:37:04 INFO - (blobuploader) - INFO - Done attempting. 19:37:04 INFO - (blobuploader) - INFO - Iteration through files over. 19:37:04 INFO - Return code: 0 19:37:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:37:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:37:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0da6c7082a8e99c1ef9f78580d868204b8bd765e42c5cef3eda53686e4bfa06d9f7b2073abbaa68d4c58f3a964c05bb2ed4b274317e977b73e1f9afc94dff5cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a7aeed71b881cea31b99ac7aa5aea14f2f43513f86d396df2bb5980776ba49236b63c771ab849a83371637a0487197f255b6dce404fc18b83d2d61d6a0b73a47"} 19:37:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:37:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:37:04 INFO - Contents: 19:37:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0da6c7082a8e99c1ef9f78580d868204b8bd765e42c5cef3eda53686e4bfa06d9f7b2073abbaa68d4c58f3a964c05bb2ed4b274317e977b73e1f9afc94dff5cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a7aeed71b881cea31b99ac7aa5aea14f2f43513f86d396df2bb5980776ba49236b63c771ab849a83371637a0487197f255b6dce404fc18b83d2d61d6a0b73a47"} 19:37:04 INFO - Running post-run listener: copy_logs_to_upload_dir 19:37:04 INFO - Copying logs to upload dir... 19:37:04 INFO - mkdir: /builds/slave/test/build/upload/logs 19:37:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.324969 ========= master_lag: 1.83 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-12 19:37:06.629478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 19:37:06.636662) ========= 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.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0da6c7082a8e99c1ef9f78580d868204b8bd765e42c5cef3eda53686e4bfa06d9f7b2073abbaa68d4c58f3a964c05bb2ed4b274317e977b73e1f9afc94dff5cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a7aeed71b881cea31b99ac7aa5aea14f2f43513f86d396df2bb5980776ba49236b63c771ab849a83371637a0487197f255b6dce404fc18b83d2d61d6a0b73a47"} build_url:https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010968 build_url: 'https://queue.taskcluster.net/v1/task/Dih9vaQ7RHKhtscSVvRfdw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0da6c7082a8e99c1ef9f78580d868204b8bd765e42c5cef3eda53686e4bfa06d9f7b2073abbaa68d4c58f3a964c05bb2ed4b274317e977b73e1f9afc94dff5cd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a7aeed71b881cea31b99ac7aa5aea14f2f43513f86d396df2bb5980776ba49236b63c771ab849a83371637a0487197f255b6dce404fc18b83d2d61d6a0b73a47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 19:37:06.668024) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:37:06.671410) ========= 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.PTpcpW1ash/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.fwhglQ9KZv/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005259 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 19:37:06.719968) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 19:37:06.723314) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 19:37:06.723657) ========= ========= Total master_lag: 1.95 =========