builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0142 starttime: 1462998323.7 results: success (0) buildid: 20160511104611 builduid: 0adef02fcfc744b4bcf38f175d1fcb0f revision: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:23.704996) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:23.705437) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:23.705724) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:23.724540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:23.724914) ========= 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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-11 13:25:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.6M=0s 2016-05-11 13:25:24 (24.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.333408 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:24.075733) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:24.076072) ========= 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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.026847 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:24.124219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 13:25:24.124579) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 --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 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-11 13:25:24,202 truncating revision to first 12 chars 2016-05-11 13:25:24,202 Setting DEBUG logging. 2016-05-11 13:25:24,202 attempt 1/10 2016-05-11 13:25:24,202 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45daaf6edeae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 13:25:25,191 unpacking tar archive at: fx-team-45daaf6edeae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.232738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 13:25:25.374156) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:25.374487) ========= script_repo_revision: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:25.374869) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:25.375133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 13:25:25.388221) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 13:25:25.388532) ========= /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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25:25 INFO - MultiFileLogger online at 20160511 13:25:25 in /builds/slave/test 13:25:25 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 13:25:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:25:25 INFO - {'append_to_log': False, 13:25:25 INFO - 'base_work_dir': '/builds/slave/test', 13:25:25 INFO - 'blob_upload_branch': 'Fx-Team', 13:25:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:25:25 INFO - 'branch': 'Fx-Team', 13:25:25 INFO - 'buildbot_json_path': 'buildprops.json', 13:25:25 INFO - 'config_files': ('talos/mac_config.py',), 13:25:25 INFO - 'default_actions': ('clobber', 13:25:25 INFO - 'read-buildbot-config', 13:25:25 INFO - 'download-and-extract', 13:25:25 INFO - 'populate-webroot', 13:25:25 INFO - 'create-virtualenv', 13:25:25 INFO - 'install', 13:25:25 INFO - 'run-tests'), 13:25:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:25:25 INFO - 'download_minidump_stackwalk': True, 13:25:25 INFO - 'download_symbols': 'ondemand', 13:25:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:25:25 INFO - 'tooltool.py': '/tools/tooltool.py', 13:25:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:25:25 INFO - '/tools/misc-python/virtualenv.py')}, 13:25:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:25:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:25:25 INFO - 'installer_path': 'installer.exe', 13:25:25 INFO - 'log_level': 'info', 13:25:25 INFO - 'log_name': 'talos', 13:25:25 INFO - 'log_to_console': True, 13:25:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:25:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:25:25 INFO - 'opt_config_files': (), 13:25:25 INFO - 'pip_index': False, 13:25:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:25:25 INFO - 'cmd': ('bash', 13:25:25 INFO - '-c', 13:25:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:25:25 INFO - 'enabled': True, 13:25:25 INFO - 'halt_on_failure': False, 13:25:25 INFO - 'name': 'check_screen_resolution'},), 13:25:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:25:25 INFO - 'cmd': ('bash', 13:25:25 INFO - '-c', 13:25:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:25:25 INFO - 'enabled': True, 13:25:25 INFO - 'halt_on_failure': False, 13:25:25 INFO - 'name': 'check_screen_resolution'},), 13:25:25 INFO - 'run_cmd_checks_enabled': True, 13:25:25 INFO - 'sps_profile': False, 13:25:25 INFO - 'sps_profile_interval': 0, 13:25:25 INFO - 'suite': 'dromaeojs-e10s', 13:25:25 INFO - 'system_bits': '32', 13:25:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:25:25 INFO - 'title': 't-yosemite-r7-0142', 13:25:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:25:25 INFO - 'use_talos_json': True, 13:25:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:25:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:25:25 INFO - 'work_dir': 'build'} 13:25:25 INFO - ##### 13:25:25 INFO - ##### Running clobber step. 13:25:25 INFO - ##### 13:25:25 INFO - Running pre-action listener: _resource_record_pre_action 13:25:25 INFO - Running main action method: clobber 13:25:25 INFO - rmtree: /builds/slave/test/build 13:25:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:25:27 INFO - Running post-action listener: _resource_record_post_action 13:25:27 INFO - ##### 13:25:27 INFO - ##### Finished clobber step (success) 13:25:27 INFO - ##### 13:25:27 INFO - ##### 13:25:27 INFO - ##### Running read-buildbot-config step. 13:25:27 INFO - ##### 13:25:27 INFO - Running pre-action listener: _resource_record_pre_action 13:25:27 INFO - Running main action method: read_buildbot_config 13:25:27 INFO - Using buildbot properties: 13:25:27 INFO - { 13:25:27 INFO - "project": "", 13:25:27 INFO - "product": "firefox", 13:25:27 INFO - "script_repo_revision": "production", 13:25:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 13:25:27 INFO - "repository": "", 13:25:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 13:25:27 INFO - "buildid": "20160511104611", 13:25:27 INFO - "pgo_build": "False", 13:25:27 INFO - "basedir": "/builds/slave/test", 13:25:27 INFO - "buildnumber": 662, 13:25:27 INFO - "slavename": "t-yosemite-r7-0142", 13:25:27 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:25:27 INFO - "platform": "yosemite_r7", 13:25:27 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 13:25:27 INFO - "branch": "fx-team", 13:25:27 INFO - "repo_path": "integration/fx-team", 13:25:27 INFO - "revision": "45daaf6edeae80ec8c67da50fa1d31f4a1b1a454", 13:25:27 INFO - "stage_platform": "macosx64", 13:25:27 INFO - "builduid": "0adef02fcfc744b4bcf38f175d1fcb0f", 13:25:27 INFO - "slavebuilddir": "test" 13:25:27 INFO - } 13:25:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:25:27 INFO - Running post-action listener: _resource_record_post_action 13:25:27 INFO - ##### 13:25:27 INFO - ##### Finished read-buildbot-config step (success) 13:25:27 INFO - ##### 13:25:27 INFO - ##### 13:25:27 INFO - ##### Running download-and-extract step. 13:25:27 INFO - ##### 13:25:27 INFO - Running pre-action listener: _resource_record_pre_action 13:25:27 INFO - Running main action method: download_and_extract 13:25:27 INFO - mkdir: /builds/slave/test/build/tests 13:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:25:27 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:25:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:25:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:25:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:28 INFO - Downloaded 1199 bytes. 13:25:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:25:28 INFO - Using the following test package requirements: 13:25:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:25:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:25:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:25:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'jsshell-mac.zip'], 13:25:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:25:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:25:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:25:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:25:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:25:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:25:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:25:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:25: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')]} 13:25:28 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:25:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:25:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:25:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:29 INFO - Downloaded 18150555 bytes. 13:25:29 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'] 13:25:29 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 13:25:32 INFO - Return code: 0 13:25:32 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 13:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:25:32 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:25:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:25:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:25:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:33 INFO - Downloaded 18150555 bytes. 13:25:33 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'] 13:25:33 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 13:25:37 INFO - Return code: 0 13:25:37 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')]} 13:25:37 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:25:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:25:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:25:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:38 INFO - Downloaded 10840152 bytes. 13:25:38 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'] 13:25:38 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 13:25:38 INFO - Return code: 0 13:25: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')]} 13:25:38 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:25:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:25:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:25:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:25:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 13:25:41 INFO - Downloaded 109797959 bytes. 13:25:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:25:41 INFO - mkdir: /builds/slave/test/properties 13:25:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:25:41 INFO - Writing to file /builds/slave/test/properties/build_url 13:25:41 INFO - Contents: 13:25:41 INFO - build_url:https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:25:42 INFO - Running post-action listener: _resource_record_post_action 13:25:42 INFO - Running post-action listener: set_extra_try_arguments 13:25:42 INFO - ##### 13:25:42 INFO - ##### Finished download-and-extract step (success) 13:25:42 INFO - ##### 13:25:42 INFO - ##### 13:25:42 INFO - ##### Running populate-webroot step. 13:25:42 INFO - ##### 13:25:42 INFO - Running pre-action listener: _resource_record_pre_action 13:25:42 INFO - Running main action method: populate_webroot 13:25:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:25:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:25:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:25:42 INFO - '--tppagecycles', 13:25:42 INFO - '7'], 13:25:42 INFO - 'tests': ['tsvgm']}}, 13:25:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:25:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:25:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:25:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:25:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:25:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:25:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['damp', 'tps']}, 13:25:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'tests': ['damp', 'tps']}, 13:25:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['dromaeo_dom']}, 13:25:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:25:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'other-e10s': {'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['a11yr', 13:25:42 INFO - 'ts_paint', 13:25:42 INFO - 'tpaint', 13:25:42 INFO - 'sessionrestore', 13:25:42 INFO - 'sessionrestore_no_auto_restore', 13:25:42 INFO - 'tabpaint']}, 13:25:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['tsvgx', 13:25:42 INFO - 'tsvgr_opacity', 13:25:42 INFO - 'tart', 13:25:42 INFO - 'tscrollx', 13:25:42 INFO - 'cart']}, 13:25:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:25:42 INFO - 'tsvgr_opacity', 13:25:42 INFO - 'tart', 13:25:42 INFO - 'tscrollx', 13:25:42 INFO - 'cart']}, 13:25:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'talos_options': ['--disable-e10s'], 13:25:42 INFO - 'tests': ['tp5o']}, 13:25:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'tests': ['tp5o']}, 13:25:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'talos_options': ['--disable-e10s', 13:25:42 INFO - '--xperf_path', 13:25:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:25:42 INFO - 'tests': ['tp5n']}, 13:25:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:42 INFO - 'talos_options': ['--xperf_path', 13:25:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:25:42 INFO - 'tests': ['tp5n']}}, 13:25:42 INFO - 'talos.zip': {'path': '', 13:25:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:25:42 INFO - Running post-action listener: _resource_record_post_action 13:25:42 INFO - ##### 13:25:42 INFO - ##### Finished populate-webroot step (success) 13:25:42 INFO - ##### 13:25:42 INFO - ##### 13:25:42 INFO - ##### Running create-virtualenv step. 13:25:42 INFO - ##### 13:25:42 INFO - Running pre-action listener: _resource_record_pre_action 13:25:42 INFO - Running main action method: create_virtualenv 13:25:42 INFO - Creating virtualenv /builds/slave/test/build/venv 13:25:42 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 13:25:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:25:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:25:42 INFO - Using real prefix '/tools/python27' 13:25:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:25:43 INFO - Installing distribute.............................................................................................................................................................................................done. 13:25:46 INFO - Installing pip.................done. 13:25:46 INFO - Return code: 0 13:25:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:25: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')]} 13:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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')]} 13:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25: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', 'pip>=1.5'] in /builds/slave/test/build 13:25: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 pip>=1.5 13:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:25:46 INFO - 'HOME': '/Users/cltbld', 13:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:25:46 INFO - 'LOGNAME': 'cltbld', 13:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:46 INFO - 'MOZ_NO_REMOTE': '1', 13:25:46 INFO - 'NO_EM_RESTART': '1', 13:25:46 INFO - 'PAGER': '/bin/cat', 13:25:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:46 INFO - 'PWD': '/builds/slave/test', 13:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:25:46 INFO - 'SHELL': '/bin/bash', 13:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:25:46 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:25:46 INFO - 'USER': 'cltbld', 13:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:46 INFO - 'XPC_FLAGS': '0x0', 13:25:46 INFO - 'XPC_SERVICE_NAME': '0', 13:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:46 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 13:25:46 INFO - Cleaning up... 13:25:46 INFO - Return code: 0 13:25:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:25: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')]} 13:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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')]} 13:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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', '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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25: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', 'psutil>=0.7.1'] in /builds/slave/test/build 13:25: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 psutil>=0.7.1 13:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:25:46 INFO - 'HOME': '/Users/cltbld', 13:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:25:46 INFO - 'LOGNAME': 'cltbld', 13:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:46 INFO - 'MOZ_NO_REMOTE': '1', 13:25:46 INFO - 'NO_EM_RESTART': '1', 13:25:46 INFO - 'PAGER': '/bin/cat', 13:25:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:46 INFO - 'PWD': '/builds/slave/test', 13:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:25:46 INFO - 'SHELL': '/bin/bash', 13:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:25:46 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:25:46 INFO - 'USER': 'cltbld', 13:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:46 INFO - 'XPC_FLAGS': '0x0', 13:25:46 INFO - 'XPC_SERVICE_NAME': '0', 13:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:47 INFO - Downloading/unpacking psutil>=0.7.1 13:25: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 13:25: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 13:25: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 13:25: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 13:25: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 13:25: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 13:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:25:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:25:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:25:49 INFO - Installing collected packages: psutil 13:25:49 INFO - Running setup.py install for psutil 13:25:49 INFO - building 'psutil._psutil_osx' extension 13:25:49 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 13:25:50 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 13:25:50 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 13:25:50 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 13:25:50 INFO - building 'psutil._psutil_posix' extension 13:25:50 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 13:25:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:25:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:25:50 INFO - ^ 13:25:50 INFO - 1 warning generated. 13:25:50 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 13:25:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:25:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:25:50 INFO - Successfully installed psutil 13:25:50 INFO - Cleaning up... 13:25:50 INFO - Return code: 0 13:25:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:25:50 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')]} 13:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:50 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')]} 13:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:50 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25:50 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 13:25:50 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 13:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:25:50 INFO - 'HOME': '/Users/cltbld', 13:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:25:50 INFO - 'LOGNAME': 'cltbld', 13:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:50 INFO - 'MOZ_NO_REMOTE': '1', 13:25:50 INFO - 'NO_EM_RESTART': '1', 13:25:50 INFO - 'PAGER': '/bin/cat', 13:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:50 INFO - 'PWD': '/builds/slave/test', 13:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:25:50 INFO - 'SHELL': '/bin/bash', 13:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:25:50 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:25:50 INFO - 'USER': 'cltbld', 13:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:50 INFO - 'XPC_FLAGS': '0x0', 13:25:50 INFO - 'XPC_SERVICE_NAME': '0', 13:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:25:51 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 13:25:51 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 13:25:51 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 13:25:51 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 13:25:51 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 13:25:51 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 13:25:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:25:53 INFO - Installing collected packages: mozsystemmonitor 13:25:53 INFO - Running setup.py install for mozsystemmonitor 13:25:53 INFO - Successfully installed mozsystemmonitor 13:25:53 INFO - Cleaning up... 13:25:53 INFO - Return code: 0 13:25:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:25:53 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')]} 13:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:53 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')]} 13:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:53 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25:53 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 13:25:53 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 13:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:25:53 INFO - 'HOME': '/Users/cltbld', 13:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:25:53 INFO - 'LOGNAME': 'cltbld', 13:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:53 INFO - 'MOZ_NO_REMOTE': '1', 13:25:53 INFO - 'NO_EM_RESTART': '1', 13:25:53 INFO - 'PAGER': '/bin/cat', 13:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:53 INFO - 'PWD': '/builds/slave/test', 13:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:25:53 INFO - 'SHELL': '/bin/bash', 13:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:25:53 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:25:53 INFO - 'USER': 'cltbld', 13:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:53 INFO - 'XPC_FLAGS': '0x0', 13:25:53 INFO - 'XPC_SERVICE_NAME': '0', 13:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:54 INFO - Downloading/unpacking blobuploader==1.2.4 13:25:54 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 13:25:54 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 13:25:54 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 13:25:54 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 13:25:54 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 13:25:54 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 13:25:56 INFO - Downloading blobuploader-1.2.4.tar.gz 13:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:25:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:25:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:25:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:25:57 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 13:25:57 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 13:25:57 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 13:25:57 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 13:25:57 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 13:25:57 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 13:25:57 INFO - Downloading docopt-0.6.1.tar.gz 13:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:25:57 INFO - Installing collected packages: blobuploader, requests, docopt 13:25:57 INFO - Running setup.py install for blobuploader 13:25:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:25:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:25:57 INFO - Running setup.py install for requests 13:25:58 INFO - Running setup.py install for docopt 13:25:58 INFO - Successfully installed blobuploader requests docopt 13:25:58 INFO - Cleaning up... 13:25:58 INFO - Return code: 0 13:25:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:25:58 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')]} 13:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:58 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')]} 13:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:58 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:25:58 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 13:25:58 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 13:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:25:58 INFO - 'HOME': '/Users/cltbld', 13:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:25:58 INFO - 'LOGNAME': 'cltbld', 13:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:58 INFO - 'MOZ_NO_REMOTE': '1', 13:25:58 INFO - 'NO_EM_RESTART': '1', 13:25:58 INFO - 'PAGER': '/bin/cat', 13:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:58 INFO - 'PWD': '/builds/slave/test', 13:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:25:58 INFO - 'SHELL': '/bin/bash', 13:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:25:58 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:25:58 INFO - 'USER': 'cltbld', 13:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:58 INFO - 'XPC_FLAGS': '0x0', 13:25:58 INFO - 'XPC_SERVICE_NAME': '0', 13:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:25:58 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qd_1Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bifAMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-CClMb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-QrEVP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-37H9VD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qRjlBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_JVlHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YMLLa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-On7lcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Me2Swc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:25:59 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0bt2L7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-5K3hm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Aax2Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-7L2VpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-f66KIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-umwQXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:26:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-48z77c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:26:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:26:00 INFO - Running setup.py install for manifestparser 13:26:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:26:00 INFO - Running setup.py install for mozcrash 13:26:00 INFO - Running setup.py install for mozdebug 13:26:01 INFO - Running setup.py install for mozdevice 13:26:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Running setup.py install for mozfile 13:26:01 INFO - Running setup.py install for mozhttpd 13:26:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Running setup.py install for mozinfo 13:26:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Running setup.py install for mozInstall 13:26:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:26:01 INFO - Running setup.py install for mozleak 13:26:02 INFO - Running setup.py install for mozlog 13:26:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Running setup.py install for moznetwork 13:26:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Running setup.py install for mozprocess 13:26:02 INFO - Running setup.py install for mozprofile 13:26:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Running setup.py install for mozrunner 13:26:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:26:02 INFO - Running setup.py install for mozscreenshot 13:26:02 INFO - Running setup.py install for moztest 13:26:03 INFO - Running setup.py install for mozversion 13:26:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:26:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:26:03 INFO - Cleaning up... 13:26:03 INFO - Return code: 0 13:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:03 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:26:03 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 13:26:03 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 13:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:03 INFO - 'HOME': '/Users/cltbld', 13:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:03 INFO - 'LOGNAME': 'cltbld', 13:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:03 INFO - 'MOZ_NO_REMOTE': '1', 13:26:03 INFO - 'NO_EM_RESTART': '1', 13:26:03 INFO - 'PAGER': '/bin/cat', 13:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:03 INFO - 'PWD': '/builds/slave/test', 13:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:03 INFO - 'SHELL': '/bin/bash', 13:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:26:03 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:03 INFO - 'USER': 'cltbld', 13:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:03 INFO - 'XPC_FLAGS': '0x0', 13:26:03 INFO - 'XPC_SERVICE_NAME': '0', 13:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:26:03 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-l16kWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:26:03 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)) 13:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:26:03 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-QLD9qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:26:03 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)) 13:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:26:03 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RIfc4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:26:03 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)) 13:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:26:03 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-hx_Hc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:26:03 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-KI_GL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Gh4pTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-yILq4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-ThKord-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-k2LpR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-lqAxiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-L1Zy5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-69RsOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-XgifA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:26:04 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)) 13:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:26:04 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-mcpFRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:26:05 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)) 13:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:05 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-lGhzf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:05 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)) 13:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:26:05 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zyBUiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:26:05 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)) 13:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:26:05 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-3iGDW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:26:05 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)) 13:26:05 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)) 13:26:05 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)) 13:26:05 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)) 13:26:05 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)) 13:26:05 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)) 13:26:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:26:05 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 13:26:05 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 13:26:05 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 13:26:05 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 13:26:05 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 13:26:05 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 13:26:07 INFO - Downloading blessings-1.6.tar.gz 13:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:26:07 INFO - Installing collected packages: blessings 13:26:07 INFO - Running setup.py install for blessings 13:26:08 INFO - Successfully installed blessings 13:26:08 INFO - Cleaning up... 13:26:08 INFO - Return code: 0 13:26:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:26:08 INFO - Reading from file tmpfile_stdout 13:26:08 INFO - Current package versions: 13:26:08 INFO - blessings == 1.6 13:26:08 INFO - blobuploader == 1.2.4 13:26:08 INFO - docopt == 0.6.1 13:26:08 INFO - manifestparser == 1.1 13:26:08 INFO - mozInstall == 1.12 13:26:08 INFO - mozcrash == 0.17 13:26:08 INFO - mozdebug == 0.1 13:26:08 INFO - mozdevice == 0.48 13:26:08 INFO - mozfile == 1.2 13:26:08 INFO - mozhttpd == 0.7 13:26:08 INFO - mozinfo == 0.9 13:26:08 INFO - mozleak == 0.1 13:26:08 INFO - mozlog == 3.1 13:26:08 INFO - moznetwork == 0.27 13:26:08 INFO - mozprocess == 0.22 13:26:08 INFO - mozprofile == 0.28 13:26:08 INFO - mozrunner == 6.11 13:26:08 INFO - mozscreenshot == 0.1 13:26:08 INFO - mozsystemmonitor == 0.0 13:26:08 INFO - moztest == 0.7 13:26:08 INFO - mozversion == 1.4 13:26:08 INFO - psutil == 3.1.1 13:26:08 INFO - requests == 1.2.3 13:26:08 INFO - wsgiref == 0.1.2 13:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:08 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:26:08 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 13:26:08 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 13:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:08 INFO - 'HOME': '/Users/cltbld', 13:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:08 INFO - 'LOGNAME': 'cltbld', 13:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:08 INFO - 'MOZ_NO_REMOTE': '1', 13:26:08 INFO - 'NO_EM_RESTART': '1', 13:26:08 INFO - 'PAGER': '/bin/cat', 13:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:08 INFO - 'PWD': '/builds/slave/test', 13:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:08 INFO - 'SHELL': '/bin/bash', 13:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:26:08 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:08 INFO - 'USER': 'cltbld', 13:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:08 INFO - 'XPC_FLAGS': '0x0', 13:26:08 INFO - 'XPC_SERVICE_NAME': '0', 13:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 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)) 13:26:08 INFO - Cleaning up... 13:26:08 INFO - Return code: 0 13:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:08 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:26:08 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 13:26:08 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 13:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:08 INFO - 'HOME': '/Users/cltbld', 13:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:08 INFO - 'LOGNAME': 'cltbld', 13:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:08 INFO - 'MOZ_NO_REMOTE': '1', 13:26:08 INFO - 'NO_EM_RESTART': '1', 13:26:08 INFO - 'PAGER': '/bin/cat', 13:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:08 INFO - 'PWD': '/builds/slave/test', 13:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:08 INFO - 'SHELL': '/bin/bash', 13:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:26:08 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:08 INFO - 'USER': 'cltbld', 13:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:08 INFO - 'XPC_FLAGS': '0x0', 13:26:08 INFO - 'XPC_SERVICE_NAME': '0', 13:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:08 INFO - Downloading/unpacking jsonschema 13:26:08 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 13:26:08 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 13:26:08 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 13:26:08 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 13:26:08 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 13:26:08 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 13:26:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:26:11 INFO - Downloading/unpacking functools32 (from jsonschema) 13:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:11 INFO - Downloading functools32-3.2.3-2.tar.gz 13:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:26:11 INFO - warning: no files found matching '*.txt' 13:26:11 INFO - no previously-included directories found matching 'build' 13:26:11 INFO - no previously-included directories found matching 'dist' 13:26:11 INFO - no previously-included directories found matching '.git*' 13:26:11 INFO - Installing collected packages: jsonschema, functools32 13:26:11 INFO - Running setup.py install for functools32 13:26:12 INFO - warning: no files found matching '*.txt' 13:26:12 INFO - no previously-included directories found matching 'build' 13:26:12 INFO - no previously-included directories found matching 'dist' 13:26:12 INFO - no previously-included directories found matching '.git*' 13:26:12 INFO - Successfully installed jsonschema functools32 13:26:12 INFO - Cleaning up... 13:26:12 INFO - Return code: 0 13:26:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:26: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')]} 13:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:12 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 0x106f9ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fa4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8e34eb980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 13:26:12 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 13:26:12 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 13:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:12 INFO - 'HOME': '/Users/cltbld', 13:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:12 INFO - 'LOGNAME': 'cltbld', 13:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:12 INFO - 'MOZ_NO_REMOTE': '1', 13:26:12 INFO - 'NO_EM_RESTART': '1', 13:26:12 INFO - 'PAGER': '/bin/cat', 13:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:12 INFO - 'PWD': '/builds/slave/test', 13:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:12 INFO - 'SHELL': '/bin/bash', 13:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:26:12 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:12 INFO - 'USER': 'cltbld', 13:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:12 INFO - 'XPC_FLAGS': '0x0', 13:26:12 INFO - 'XPC_SERVICE_NAME': '0', 13:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:12 INFO - Downloading/unpacking flake8 13:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:26:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:26:14 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 13:26:14 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 13:26:14 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 13:26:14 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 13:26:14 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 13:26:14 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 13:26:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:26:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:26:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:26:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:26:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:26:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:26:16 INFO - no previously-included directories found matching 'docs/_build' 13:26:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:26:16 INFO - Running setup.py install for pep8 13:26:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:26:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:26:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:26:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:26:16 INFO - no previously-included directories found matching 'docs/_build' 13:26:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:26:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:26:16 INFO - Cleaning up... 13:26:16 INFO - Return code: 0 13:26:16 INFO - Running post-action listener: _resource_record_post_action 13:26:16 INFO - Running post-action listener: _start_resource_monitoring 13:26:16 INFO - Starting resource monitoring. 13:26:16 INFO - ##### 13:26:16 INFO - ##### Finished create-virtualenv step (success) 13:26:16 INFO - ##### 13:26:16 INFO - ##### 13:26:16 INFO - ##### Running install step. 13:26:16 INFO - ##### 13:26:16 INFO - Running pre-action listener: _resource_record_pre_action 13:26:16 INFO - Running main action method: install 13:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:26:16 INFO - Reading from file tmpfile_stdout 13:26:16 INFO - Detecting whether we're running mozinstall >=1.0... 13:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:26:16 INFO - Reading from file tmpfile_stdout 13:26:16 INFO - Output received: 13:26:16 INFO - Usage: mozinstall [options] installer 13:26:16 INFO - Options: 13:26:16 INFO - -h, --help show this help message and exit 13:26:16 INFO - -d DEST, --destination=DEST 13:26:16 INFO - Directory to install application into. [default: 13:26:16 INFO - "/builds/slave/test"] 13:26:16 INFO - --app=APP Application being installed. [default: firefox] 13:26:16 INFO - mkdir: /builds/slave/test/build/application 13:26:16 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'] 13:26:16 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 13:26:50 INFO - Reading from file tmpfile_stdout 13:26:50 INFO - Output received: 13:26:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:26:50 INFO - Running post-action listener: _resource_record_post_action 13:26:50 INFO - ##### 13:26:50 INFO - ##### Finished install step (success) 13:26:50 INFO - ##### 13:26:50 INFO - ##### 13:26:50 INFO - ##### Running run-tests step. 13:26:50 INFO - ##### 13:26:50 INFO - Running pre-action listener: _resource_record_pre_action 13:26:50 INFO - Running main action method: run_tests 13:26:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:26:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:26:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:26:50 INFO - 2016-05-11 13:26:50.515 screenresolution[1644:7561] starting screenresolution argv=screenresolution get 13:26:50 INFO - 2016-05-11 13:26:50.518 screenresolution[1644:7561] Display 0: 1600x1200x32@60 13:26:50 INFO - 2016-05-11 13:26:50.523 screenresolution[1645:7563] starting screenresolution argv=screenresolution list 13:26:50 INFO - Available Modes on Display 0 13:26:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:26:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:26:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:26:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:26:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:26:50 INFO - Intel Iris: 13:26:50 INFO - Chipset Model: Intel Iris 13:26:50 INFO - Type: GPU 13:26:50 INFO - Bus: Built-In 13:26:50 INFO - VRAM (Dynamic, Max): 1536 MB 13:26:50 INFO - Vendor: Intel (0x8086) 13:26:50 INFO - Device ID: 0x0a2e 13:26:50 INFO - Revision ID: 0x0009 13:26:50 INFO - Displays: 13:26:50 INFO - Display: 13:26:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:26:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:26:50 INFO - Display Serial Number: 1600x1200 60 13:26:50 INFO - Main Display: Yes 13:26:50 INFO - Mirror: Off 13:26:50 INFO - Online: Yes 13:26:50 INFO - Rotation: Supported 13:26:50 INFO - Return code: 0 13:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:26:50 INFO - Python 2.7.3 13:26:50 INFO - Return code: 0 13:26:50 INFO - grabbing minidump binary from tooltool 13:26:50 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')]} 13:26:50 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 0x106e42d30>, '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 0x106c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:26:50 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 13:26:50 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 13:26:50 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 13:26:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:26:50 INFO - Return code: 0 13:26:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:26:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:26:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:26:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:26:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:26:52 INFO - Return code: 0 13:26:52 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 13:26:52 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 13:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.clgSNbQ6T5/Render', 13:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:52 INFO - 'HOME': '/Users/cltbld', 13:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:52 INFO - 'LOGNAME': 'cltbld', 13:26:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:26:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:52 INFO - 'MOZ_NO_REMOTE': '1', 13:26:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:26:52 INFO - 'NO_EM_RESTART': '1', 13:26:52 INFO - 'PAGER': '/bin/cat', 13:26:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:52 INFO - 'PWD': '/builds/slave/test', 13:26:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:52 INFO - 'SHELL': '/bin/bash', 13:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EWdkfQ8Lqs/Listeners', 13:26:52 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 13:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:52 INFO - 'USER': 'cltbld', 13:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:52 INFO - 'XPC_FLAGS': '0x0', 13:26:52 INFO - 'XPC_SERVICE_NAME': '0', 13:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:52 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 13:26:52 INFO - mozversion application_buildid: 20160511104611 13:26:52 INFO - mozversion application_changeset: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 13:26:52 INFO - mozversion application_display_name: Nightly 13:26:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:26:52 INFO - mozversion application_name: Firefox 13:26:52 INFO - mozversion application_remotingname: firefox 13:26:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:26:52 INFO - mozversion application_vendor: Mozilla 13:26:52 INFO - mozversion application_version: 49.0a1 13:26:52 INFO - mozversion platform_buildid: 20160511104611 13:26:52 INFO - mozversion platform_changeset: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 13:26:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:26:52 INFO - mozversion platform_version: 49.0a1 13:26:52 INFO - using testdate: 1462998412 13:26:52 INFO - actual date: 1462998412 13:26:52 INFO - starting webserver on 'localhost:49275' 13:26:52 INFO - SUITE-START | Running 2 tests 13:26:52 INFO - TEST-START | dromaeo_css 13:26:52 INFO - operating with platform_type : mac_ 13:26:52 INFO - Initialising browser for dromaeo_css test... 13:26:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpZZIQmY/profile http://localhost:49275/getInfo.html) 13:26:55 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 13:26:55 INFO - PROCESS | 1663 | colorDepth:24 13:26:55 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 13:26:55 INFO - PROCESS | 1663 | __metrics 13:26:55 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:26:55 INFO - TEST-INFO | 1663: exit 0 13:26:55 INFO - Browser initialized. 13:26:55 INFO - Running cycle 1/1 for dromaeo_css test... 13:26:55 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpZZIQmY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:27:07 INFO - PROCESS | 1666 | RSS: Main: 249794560 13:27:07 INFO - PROCESS | 1666 | 13:27:57 INFO - PROCESS | 1666 | 1462998477398 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] 13:30:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:30:10 INFO - PROCESS | 1666 | RSS: Main: 263471104 13:30:10 INFO - PROCESS | 1666 | 13:33:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:33:13 INFO - PROCESS | 1666 | RSS: Main: 275279872 13:33:13 INFO - PROCESS | 1666 | 13:36:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:36:15 INFO - PROCESS | 1666 | RSS: Main: 276815872 13:36:15 INFO - PROCESS | 1666 | 13:39:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:39:18 INFO - PROCESS | 1666 | RSS: Main: 278487040 13:39:18 INFO - PROCESS | 1666 | 13:42:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:42:21 INFO - PROCESS | 1666 | RSS: Main: 281427968 13:42:21 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:45:24 INFO - PROCESS | 1666 | RSS: Main: 282443776 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | __start_tp_report 13:45:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:45:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:45:24 INFO - PROCESS | 1666 | |i|pagename|runs| 13:45:24 INFO - PROCESS | 1666 | |0;dojo.html;2891.522898721711;3192.521121831725;3246.2371342734455;3438.8280585970697;3501.9474707879385;4628.69913455626;5758.481736643701;5776.942230577698;5876.735546900389;5932;4743.217369134098;5701;5826.504747096502;5882.411758824115;5916.2308899843065;5202.479752024805;5232.45059268777;5259.421463638997;5341.1988201769655;5366.221897824813;4854.355432994226;5342.626016178869;5364.704941228231;5402.297701298826;5483.670979741209;4628.028114096044;4923.286123512106;5270.604704647167;5288.312519372495;5290.100682883919;5241.107943054871;5242.81650142247;5265.0259701955265;5274.55166310864;5274.657147285453;5176.042432150064;5225.555827754645;5237.083510385705;5265.183896496049;5322.760475778599;5558.666480011212;5695.886082278373;5758.165066065397;5758.452946970031;5816.272965879265;6195.535334849904;6230.968845155745;6248.437640612321;6258.217722784652;6264.310925798159;6155.476784473325;6190.0405437157315;6259.780907668255;6274.654893980829;6292.119103325538;5949.494292985101;6013.909791353133;6026.54800889935;6043.697815109227;6067.878642427171;6340.524460665469;6373.808785736435;6381.4575761059905;6394.584352017104;6437.163168788027;5882;6101.60339577926;6181.350958149419;6238.469730072904;6296.118543403927;6207.906881396782;6264.68676566179;6284.591501552384;6287.591306565058;6307.085472606446;6104.459155447136;6178.6601736904;6192.752289908353;6226.844328891814;6243.469305109116;6118.223266370716;6143.481172719164;6147.938520614736;6193.256809182837;6226.782062627877;5024.171011783088;5040;5047.899042019139;5049.166887463601;5072.442031376546;3348.631650518441;4493.775311234425;4512.503624601291;4564.292534657134;4570.245909424974;5692.601517893708;5852.795152169653;5903.173555702205;5942.405759424023;5995.46040856325;6228.408301211378;6253.838127143666;6261.342559031236;6276.811695649138;6289.7484100635465;5970.223870896755;5982.970085149546;5988.28140623128;6004.699765011732;6036.124761909498;5413;5481.671099733949;5514.558835293167;5539.19681646159;5553.361363443175;5447.782088716407;6453.774117905851;6463.515236357198;6479.481641468766;6492.0261960706275;5079.466656001215;6159.29168145669;6271.059341098871;6280.874382512416;6401.751658426749;6337.302896681454;6346.301906790157;6392.616443013434;6478.611283323015;6490.578112422678;6133.38666133401;6152.107944347955;6185.103160041679;6210.596311239755;6217.09852071438;5382.892342153056;5903.498202652822;6235.064740288993;6248.531360148098;6257.499400047895;5063.443021267732;5228.6078544110105;5403.243545903498;6126.540509461719;6237.282712488122;6207.131001659681;6213.471854892385;6226.377362263918;6231.065340199007;6239.563230573816;6002.159697642421;6046.697665116639;6104.298005729398;6117;6127.172831667668;5101.750071232572;5303.522682958398;6090.177825993434;6107.297660769068;6155.2921414037955;6127.632342059491;6145.569810113249;6152.753889844356;6161.815145545641;6170.10533472635 13:45:24 INFO - PROCESS | 1666 | |1;ext.html;507.11255303219366;532.4276402866917;563.5942121672394;580.0631979353345;587.3715124816447;29753.553696694566;37545.812270938644;39213.21573568527;39236.01909952253;39259.607403925955;39815.60184398155;40387.39418908719;40695.38956915648;41023.79488102563;41167;9478.72510597455;9647.421154730704;9660.613575457008;9663.75840603987;9709.36889102206;9609.471479068648;9611.759706007337;9614.615415383376;9736.26977976651;9745.269104817167;9269.563217701267;9664.323497355188;9715.028497150299;9784.755381115494;9798.559064842064;8852.8672069919;9673.177779888718;9694.22446204302;9724.611015559369;9733.56198971041;9529.428234305895;9644.276679249015;9663.323567350288;9664.613415463373;9674;37411.877643670734;37503.62496375056;37583.62416375856;37955.43066853999;38007.61992380068;3123.5314702794535;3337.4660054391425;3398.2523844754114;3707.388280933643;3791.071187559038;27216.183514494598;28501;28845.42309153805;30618.387632247453;30970.38059238825;2676.9196924092307;2681.8659067046765;2780.5829125631117;2855.4431885782283;2866.3693987322754;494945.5252723713;500545;504634;508079.45960270334;508539;68153;68844.6557767218;69043.65478172577;69903.60192796115;70016.64991675009;51081.72295692563;51749.2237616436;51945.48054519406;52690.47309526932;52852.47147528552;16875.746863797052;17224.82775172232;17373.65252694939;17375.218115184845;17390;12784.808227876589;13045.34773261333;13092;13213.207207567577;13224.404901779442;17429.65140697179;20262.59474810525;21345;21445.57108857814;21484.677729834064;7532.887006694904;8762.912370876336;9028.781114549583;9029.322800789836;9145.405548639317;943.9103285187896;1061.7770268243755;1072.5602502973743;1076.8169411199958;1101.2657598783542;28952.131436056952;29463.852680736458;29633.555496667566;30216.09351719408;30363.392732145232;1024.6791018343458;1099.14816017587;1173.4602083041725;1192.3799624195544;1229.2931564350533;12490.375481225903;12777.93611031939;13297.601071967856;15220.847791522165;15456.459023934109;638804;638980;647433;655855;657071;46068.61794146181;60159;60173;60816;60938.390616094155;1263.279930439659;1452.6223181972764;1499.242882344409;1501.1893577468045;1504.1952555382818;28971;29026;29145.562816557343;29371.853140734158;30196.5470517938;51332.48667513426;51501;51534.74232628813;51624;51645;555594;556264;573105;574948;577755;41492.79253603713;42127.15745685008;42153.156936860476;42167.57832421637;42710.359344609235;45936.770316148206;45996.47019413964;46237.768811157075;47614.28578571255;48192.036159275915;37857.432138518496;37909.431358529095;37968.050798730255;38613;38760.41859372168;37323;37482;37533.81233093817;37676.43485347667;38209.04477388087;29809.25476863096;34372;37885;38482.422763659124;40246.59753402429;31140.53289200709;33163.66836331606;33475.66524334823;33608;33616.83191584027;21685.698858068565;24207.75792242055;24937.625935610617;25167.74832251654;25279.87360063188 13:45:24 INFO - PROCESS | 1666 | |2;jquery.html;391.29957376296414;397.729543910141;399.3530480621393;399.91201935574173;419.37931860845947;14758.33587488563;19698.310559130434;20633.277835275767;20834;20852.270170544034;20303.49241268969;20663.070161842716;21254;21531.892340538274;21544.676829847565;5511.889762204753;5540.889182216353;5929.73316200772;6003.369646187153;6013.669248191348;5491.093969495028;5615.326160860682;5890;5928;5933.288005439354;5539.639923404986;5820.272465941757;5940.52475801937;5942.078977758454;5959.344472108065;4703.482616912137;4726.314684370781;4803.18345881201;4829.444613869415;4892.486288939664;4595.609373203281;4681.729361259786;4696.694714843524;4751.572358487752;4783.306420568998;24086.63870041951;24708.752912470824;25469.23592292216;25536.48927021468;25537.361565960815;1879.6710575649206;1958.9519607010322;2020.2323117215365;2047.4164863014119;2300.6664033715183;9549.570269337897;9728.951355243249;9807.117359437614;9829.164521015722;9959.153471954893;1717.3645751072183;1774.8935063896208;1808.5569035586366;1810.5786957238504;1858.7490688757116;118963.40518297353;123853.38073309665;125144;127028.99999999907;127250;11511.884881151249;11705.180637355303;11714.121440891968;11724.70688232801;11820.290782553075;14992.775108373384;15011.24943752808;15032.17323047221;15045.924770376298;15096.433606286957;8397.37019723511;8470.745877623682;8626.094260102724;8691.826163476695;8740.8688869667;7033.859322813515;8386.371022173362;8433.19884610961;8470.66117355299;8567;3887.8530833404216;4157.750534967911;4539.364488971547;4668.929966050512;4688.624910007259;1756.2360373237682;1972.723818665388;2026.4731169895933;2035.6742921132548;2053.6098141353095;885.3399875233937;920.8434566123775;938.7324612485409;1016.1819735113305;1051.164324362134;18972.146253418352;20007.39977800639;20213;20343.08456119478;20460.181592736124;974.2936371130874;982.7493989032894;983.5770618634056;1017.4404077757351;1020.6172685243034;10455.059044686015;10589.470526473646;10639.361638301727;10676.252662313564;10707.518161682743;568783;572394;605993;616869;625328;135305.32347338396;136203;137509;137914.1730039444;138356.30821846027;999.4752754803641;1018.7656838927088;1024.8360262358135;1043.5095505112586;1044.6291566493937;17741.822581774533;17907.462776116736;17976;18006.459806205836;18325.541861453035;51998.74000630124;52824.47175528196;54288.72855635855;55129;55689.266412355406;72058;81411.77882331652;81470;82396.17603823885;82836.58581707053;5272.815451459181;5312.096943519534;7836.882446763417;7935.166807485131;7958.761237162895;10612.734681632712;11319.547218111184;12267;12347.567835125728;12616.810747838606;9320.254379649747;9381.202597778993;10795.6221532246;10817.1346292298;10859.457027148768;10066.295359324777;10321.277510574488;10527.526261318106;10607.098396636373;11122.61070862516;10377.636782712569;10722.571097156026;11045.39250341239;11087.611933582286;11326.716832079264;7830.725924592612;7928.08826984881;8062.677492900453;8141.674333026613;8278.172182781673;9752.658656946973;10932.453377331261;10957.452127393757;10967.396793176453;11057.889421105687 13:45:24 INFO - PROCESS | 1666 | |3;mootools.html;1617.086346214389;1642.6878893010328;1910.4746194796437;1955.1006536992982;1967.1147983407461;7938.483998540105;8474.067852536216;8479.788005299863;8975.596098175582;8977.96753373363;8598.87101693475;8637.65449382024;8662.610182541785;8817.559122043887;8822.77943051424;2271.6597207647483;2279.7492275849645;2395.018042602529;2421.6609674645565;2426.5874801283735;2388.534235824015;2414.4929564791414;2420.685310909579;2439.243834411338;2445.8287919845616;2384.415818124562;2386.7374588795305;2392.712874455068;2399.436132508859;2399.940001499959;2400.9639855402183;2403.435192729707;2421.4914867877765;2431.659567660529;2439.829211955164;2358.056777289098;2374.6200607902824;2403.579373609611;2408.4099395648177;2428.210831479776;8745.081766414498;8826.691065812665;8853.247473964657;8860.822783544292;8872.778180545474;5299.49654782795;5368.1679339702405;5373.596980226499;5479.53423958964;5501.339839219279;3364.34395292918;3474.5656792900886;3522.964770352289;3538.062413460435;3545.6454354564594;1024.5440778853413;1032.0866033560278;1051.8061999630397;1052.315994603514;1052.6315789473622;11450.94274528622;11506;11542.36516991565;11627.360495172847;11677.941610291979;3591.3894637911617;4562.657800664897;4579.290210017452;4701;4867.73438905887;9098.499582523025;9168.26653867689;9189.356745027917;9214.170724634681;9219.585118669676;5201.219817027476;5230.790768369223;5237.769124255797;5255.737213139328;5310.867228319323;3911.7261791674878;4218.945263684079;4392.846250381285;4808.2547205183255;4856.708597484162;3836.865709700147;3876.476675648751;3927.8821635350987;3947.763134211899;3999.7000224983244;1039.3088596083671;1222.1689251309017;1222.407132540717;1230.772307123185;1243.6257934981895;833.7248707926491;892.6250974590557;897.5243121145803;927.096081320707;949.3544389814854;5631.690257035821;5787.334456537468;5799.2460980073165;5869.911951320733;5924.4667979882015;450.29753584407774;465.0582570295112;467.24773115284364;1005.0652892809611;1049.6011515624014;3845.8461661533784;3917.2165566886165;3953.090789118519;3983.2830090583393;3990.501187351581;10450.372977621368;10612.20408469368;10779.083777878968;10978.396188209725;11001.779964400666;10785.83821242682;10847.294925829796;10971.396573188551;10981.61564345244;10983.61557345489;5397.8380648580605;8095.55474448911;8096.10942796292;8172.836543269102;8399.62201700925;5857.092150716646;5912.852178695567;5945.375735547878;5980.28236611592;5983.9401605983385;9409.200217981548;9536.094071063239;9793.167580755431;9796.559154838193;9972.800543988935;10023.599056037678;10162.847557286499;10202;10225.181985441295;10238.283320167517;6958.756443524452;7432.851342973218;7452.403807695263;7632.542047477169;7719.536827790351;8550.82898342042;8568.485890846569;8617.526036068077;8627.913720862962;8640.524771137647;7409.184989651242;7485.66314515859;7601.049868766404;7613.733519326797;7669.693212271447;7351.926480735124;7388.852222955404;7417.332440080419;7446.478746487694;7451.2921272479025;7046.365827075588;7081.114860642419;7130.144382674112;7185.389241914287;7190.173130089897;4459.7993090310365;4536.820426689019;4798.760061996816;4836.975815120902;5053.570446512088;4907.190313598217;4942.233953737176;4955.231939049453;5017.397912250407;5140.151874940593 13:45:24 INFO - PROCESS | 1666 | |4;prototype.html;313.7584060273589;316.04869343276744;319.87205117952817;320.9759268054895;326.08207894776217;4680.672352935296;4691.671582989188;4722.079194557063;4941.431735350436;5555.194626713509;4623.398958135446;5191.922121168176;5293.311869456975;5465.152901300295;5499;3551.8634037108136;3943.2507823513583;3952.5454572724216;4038.899027524306;4068.4507591475067;3213.5019072043756;4063.4920634920636;4106.527749308838;4170.728902621335;4181.285672874123;4105.979470102645;4134.421181034642;4149.439825623547;4167.145735124307;4194.643455306303;3453.810040447787;3803.4215800442785;4109.123613470552;4122.42286079949;4213.199492096507;3001.849907504616;3520.471929210639;3545.2023294758474;3551.1122219445137;3714;5231.973840130813;5293.232481290192;5303.4696530346655;5334.679919204821;5334.91997620036;4097.569755175693;4230.830766769325;4602.401687780601;4680.859574212779;4740.19416699162;5044.243363495469;5707.429257074301;5712.42940676693;5734.27621879608;5765.250517432749;3411.727061835047;3917.0794863207125;3942.093318536724;3943.8028098594955;3948;4768.0451015083645;4826.058918510892;4944.505549445063;5048.242763585455;6087.3303936566945;3782.413725872494;3806.3926210545865;3916.3342231820657;3966.7619942803526;3972.900677483086;3438.7764795288226;4040.8989775255855;4041.413994970713;4053.310937140693;4058.5941405859767;3961.801909904493;4443.555644435531;4709.3877795887;4710.788014539354;4733.431988161374;5188.057076621794;5390;5814.709264536841;5827.872826418326;5839.562032847553;1366.3782978744648;1367.8768910798076;1370.3422357268566;1391.0679844504207;1393.324237744693;807.4347956430522;830.1860434949655;846.0650980666398;882.2280504558511;897.5871099294267;4706.576408123286;5228.914940185309;5291.920621190685;5296.894062118736;5402.945970540323;5584.078627026576;5610.297203845443;5815.476607105381;5833;5852.561057920588;4437.134758722018;4443.422355093817;4910.680805747615;5172.5344718975475;5345.289507357608;2834;2865.0660803957244;3062.9540556891666;3078.6459557150765;3123.8438078096005;5103.770330335144;5494.890102198014;5668.214512428654;6141.723622437001;6145.265356916129;4865.440474345425;5743.798967036218;5999.340072592011;6061.181740465069;6134.675054086858;5345.86635334112;5386.784528618889;5628.634138781048;5679.858003549779;6162.568620196633;5270.288511050959;5857.707114644166;5884.235049443545;5907.911381329198;5911.970440147944;5182.859770850407;5261.289725887109;5772.7690892363835;5834.533237340917;5860.648361098348;4937.5556199942175;5384;5437.456254374689;6121.765555572095;6123.458764612636;3664.303782281358;3748.5126933498473;3908.7459315144424;3983.7211395202057;3986.32232520478;5312.760925758273;5882.49998750111;5971.134185543159;6017.669028203491;6092.75628974836;5298.2052692096495;5734.741936612779;5780.624259423124;5790.971045144747;5824.592278540631;4447.799849006868;4919.458859525521;5603.2155498231095;5773.480386765212;5883.646981181143;5180.559652429435;5267.868303292448;5293.009449660929;5328.600354973315;5648.689322087324;5076.796928122833;5545.417731138173;5821.864736376536;5835.737391817293;5869.413058694096;4852.975735121302;5276.841694749163;5724.685142317046;5728.770849166153;5750.7987220447085 13:45:24 INFO - PROCESS | 1666 | |5;yui.html;619.358173937249;727.2291371146582;741.7885902517781;763.461759459581;777.8716511775557;14820.55538333849;15171.165585892799;15306.54080377589;15315;15349.923250383747;13865.237411942338;13866.306684665777;14125;14156.787648185285;14393;912.5802131019739;928.8281667891428;933.3326671429926;933.995330023349;934.2339281788936;717.8528401677644;899.6266549382032;923.6905636611743;928.5728564860138;929.2194556572477;972.6304004478288;973.3429934793986;975.6195083917278;976.5166242709869;978.7259567321162;958.6309270930673;967.487231767161;971.5822196455522;976.1605019683067;980.3872579637726;718.7520305494596;718.9964050179768;721.2246834652769;723.8552289542113;724.6231959381092;14886.925565372212;15067.849321506865;15073.924630376778;15091.24543772818;15168.31742571587;12578.308193049379;12704.301263430507;12755.553555625509;12769.106162568623;13017.934910325483;10034.59861605535;10164.94917525415;10234.846477302846;10308.381497110122;10459.215558833119;3510.5436293281964;3528.047427194643;3543.9291214175573;3572.9285414291567;3594.0835087052633;51491.97016059695;57407;65426.34573654298;71757.56484870274;71759;8093.312068474128;8131.390145739093;8861.424007439497;8872.911270887338;8911.643534258686;17957.551061223574;19315.64790464654;20281.69577456339;21266.574668506542;22021.449463763533;7076.4692648051605;7164.104486939133;7222.241664625244;7239.529430586995;7279.927200728031;3182.140821978053;4026.074002979331;4101.774402407897;4155.542890282067;4160.83356665736;3463.619001909788;3532.8410221540094;3541.805200713934;3565.5899571549603;3567.340042092221;647.9157709497736;649.490150232075;681.4888833374969;689.899964505144;693.968771405288;481.3694060780389;590.1325052173298;596.5317225977586;596.7016491754123;608.2883026858586;7612.619369031637;8133.796655083552;8226.506409615442;8232.547209903512;8239.340852731768;577.1083675721021;580.89834279001;611.6116266171014;628.7987843890003;633.5628416392674;3775.3581891078584;3876;3877.9612203878164;3995.081131339808;4018.4374187613757;90289.54855225813;90842.54578727196;91668.62497062549;92613.5369323149;93174.13651726927;57888.710556446946;63279;73744.63127684435;76439.47121057547;81630.18369816344;68749.999999999;69622;69801.65099174573;69892;70041;7101.680424381018;7240.312170343809;7529.661165247468;7693.88459173124;7723.304902558797;31751.364972700878;38632.64785732486;39279.41080883846;39655.80172099121;40241.79879100586;51784.48215517797;51785.44643660696;54441.18338225009;56975.715121424124;57328.71335643295;8577.914220857961;9727.562259698188;9976.102150806171;10007.849882251918;10271.589136434459;10779.407132607781;10826.885783402571;10961.780764384826;11011.327181274595;11127.554897803997;10160.898391015995;10370.222233332379;10384.273100882865;10463.633772817915;11102.44487775592;10143.644972426226;10171.338862973884;10571.471426428494;10735.731606709895;10738.081247696957;8687.74027765983;10218;10335.431551264752;10551.050405463546;10895.727606809576;8103.311218546254;8576.485410875368;8763.430377025474;8790.648374064966;8800.47197168172;8515.40392172542;8655.307575393828;8825.029246782977;8943.597538110671;9095.545222738969 13:45:24 INFO - PROCESS | 1666 | __end_tp_report 13:45:24 INFO - PROCESS | 1666 | __start_cc_report 13:45:24 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,415 13:45:24 INFO - PROCESS | 1666 | __end_cc_report 13:45:24 INFO - PROCESS | 1666 | __startTimestamp1462999524499__endTimestamp 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:45:24 INFO - PROCESS | 1666 | Number of tests: 6 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5747.85 Median:6026.55 stddev:693.79 (11.5%) stddev-sans-first:658.75 13:45:24 INFO - PROCESS | 1666 | Values: 2891.5 3192.5 3246.2 3438.8 3501.9 4628.7 5758.5 5776.9 5876.7 5932.0 4743.2 5701.0 5826.5 5882.4 5916.2 5202.5 5232.5 5259.4 5341.2 5366.2 4854.4 5342.6 5364.7 5402.3 5483.7 4628.0 4923.3 5270.6 5288.3 5290.1 5241.1 5242.8 5265.0 5274.6 5274.7 5176.0 5225.6 5237.1 5265.2 5322.8 5558.7 5695.9 5758.2 5758.5 5816.3 6195.5 6231.0 6248.4 6258.2 6264.3 6155.5 6190.0 6259.8 6274.7 6292.1 5949.5 6013.9 6026.5 6043.7 6067.9 6340.5 6373.8 6381.5 6394.6 6437.2 5882.0 6101.6 6181.4 6238.5 6296.1 6207.9 6264.7 6284.6 6287.6 6307.1 6104.5 6178.7 6192.8 6226.8 6243.5 6118.2 6143.5 6147.9 6193.3 6226.8 5024.2 5040.0 5047.9 5049.2 5072.4 3348.6 4493.8 4512.5 4564.3 4570.2 5692.6 5852.8 5903.2 5942.4 5995.5 6228.4 6253.8 6261.3 6276.8 6289.7 5970.2 5983.0 5988.3 6004.7 6036.1 5413.0 5481.7 5514.6 5539.2 5553.4 5447.8 6453.8 6463.5 6479.5 6492.0 5079.5 6159.3 6271.1 6280.9 6401.8 6337.3 6346.3 6392.6 6478.6 6490.6 6133.4 6152.1 6185.1 6210.6 6217.1 5382.9 5903.5 6235.1 6248.5 6257.5 5063.4 5228.6 5403.2 6126.5 6237.3 6207.1 6213.5 6226.4 6231.1 6239.6 6002.2 6046.7 6104.3 6117.0 6127.2 5101.8 5303.5 6090.2 6107.3 6155.3 6127.6 6145.6 6152.8 6161.8 6170.1 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:70626.52 Median:39224.62 stddev:153864.35 (392.3%) stddev-sans-first:154205.91 13:45:24 INFO - PROCESS | 1666 | Values: 507.1 532.4 563.6 580.1 587.4 29753.6 37545.8 39213.2 39236.0 39259.6 39815.6 40387.4 40695.4 41023.8 41167.0 9478.7 9647.4 9660.6 9663.8 9709.4 9609.5 9611.8 9614.6 9736.3 9745.3 9269.6 9664.3 9715.0 9784.8 9798.6 8852.9 9673.2 9694.2 9724.6 9733.6 9529.4 9644.3 9663.3 9664.6 9674.0 37411.9 37503.6 37583.6 37955.4 38007.6 3123.5 3337.5 3398.3 3707.4 3791.1 27216.2 28501.0 28845.4 30618.4 30970.4 2676.9 2681.9 2780.6 2855.4 2866.4 494945.5 500545.0 504634.0 508079.5 508539.0 68153.0 68844.7 69043.7 69903.6 70016.6 51081.7 51749.2 51945.5 52690.5 52852.5 16875.7 17224.8 17373.7 17375.2 17390.0 12784.8 13045.3 13092.0 13213.2 13224.4 17429.7 20262.6 21345.0 21445.6 21484.7 7532.9 8762.9 9028.8 9029.3 9145.4 943.9 1061.8 1072.6 1076.8 1101.3 28952.1 29463.9 29633.6 30216.1 30363.4 1024.7 1099.1 1173.5 1192.4 1229.3 12490.4 12777.9 13297.6 15220.8 15456.5 638804.0 638980.0 647433.0 655855.0 657071.0 46068.6 60159.0 60173.0 60816.0 60938.4 1263.3 1452.6 1499.2 1501.2 1504.2 28971.0 29026.0 29145.6 29371.9 30196.5 51332.5 51501.0 51534.7 51624.0 51645.0 555594.0 556264.0 573105.0 574948.0 577755.0 41492.8 42127.2 42153.2 42167.6 42710.4 45936.8 45996.5 46237.8 47614.3 48192.0 37857.4 37909.4 37968.1 38613.0 38760.4 37323.0 37482.0 37533.8 37676.4 38209.0 29809.3 34372.0 37885.0 38482.4 40246.6 31140.5 33163.7 33475.7 33608.0 33616.8 21685.7 24207.8 24937.6 25167.7 25279.9 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35288.67 Median:24397.70 stddev:100355.73 (411.3%) stddev-sans-first:100603.04 13:45:24 INFO - PROCESS | 1666 | Values: 391.3 397.7 399.4 399.9 419.4 14758.3 19698.3 20633.3 20834.0 20852.3 20303.5 20663.1 21254.0 21531.9 21544.7 5511.9 5540.9 5929.7 6003.4 6013.7 5491.1 5615.3 5890.0 5928.0 5933.3 5539.6 5820.3 5940.5 5942.1 5959.3 4703.5 4726.3 4803.2 4829.4 4892.5 4595.6 4681.7 4696.7 4751.6 4783.3 24086.6 24708.8 25469.2 25536.5 25537.4 1879.7 1959.0 2020.2 2047.4 2300.7 9549.6 9729.0 9807.1 9829.2 9959.2 1717.4 1774.9 1808.6 1810.6 1858.7 118963.4 123853.4 125144.0 127029.0 127250.0 11511.9 11705.2 11714.1 11724.7 11820.3 14992.8 15011.2 15032.2 15045.9 15096.4 8397.4 8470.7 8626.1 8691.8 8740.9 7033.9 8386.4 8433.2 8470.7 8567.0 3887.9 4157.8 4539.4 4668.9 4688.6 1756.2 1972.7 2026.5 2035.7 2053.6 885.3 920.8 938.7 1016.2 1051.2 18972.1 20007.4 20213.0 20343.1 20460.2 974.3 982.7 983.6 1017.4 1020.6 10455.1 10589.5 10639.4 10676.3 10707.5 568783.0 572394.0 605993.0 616869.0 625328.0 135305.3 136203.0 137509.0 137914.2 138356.3 999.5 1018.8 1024.8 1043.5 1044.6 17741.8 17907.5 17976.0 18006.5 18325.5 51998.7 52824.5 54288.7 55129.0 55689.3 72058.0 81411.8 81470.0 82396.2 82836.6 5272.8 5312.1 7836.9 7935.2 7958.8 10612.7 11319.5 12267.0 12347.6 12616.8 9320.3 9381.2 10795.6 10817.1 10859.5 10066.3 10321.3 10527.5 10607.1 11122.6 10377.6 10722.6 11045.4 11087.6 11326.7 7830.7 7928.1 8062.7 8141.7 8278.2 9752.7 10932.5 10957.5 10967.4 11057.9 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5670.97 Median:4822.62 stddev:3188.33 (66.1%) stddev-sans-first:3182.72 13:45:24 INFO - PROCESS | 1666 | Values: 1617.1 1642.7 1910.5 1955.1 1967.1 7938.5 8474.1 8479.8 8975.6 8978.0 8598.9 8637.7 8662.6 8817.6 8822.8 2271.7 2279.7 2395.0 2421.7 2426.6 2388.5 2414.5 2420.7 2439.2 2445.8 2384.4 2386.7 2392.7 2399.4 2399.9 2401.0 2403.4 2421.5 2431.7 2439.8 2358.1 2374.6 2403.6 2408.4 2428.2 8745.1 8826.7 8853.2 8860.8 8872.8 5299.5 5368.2 5373.6 5479.5 5501.3 3364.3 3474.6 3523.0 3538.1 3545.6 1024.5 1032.1 1051.8 1052.3 1052.6 11450.9 11506.0 11542.4 11627.4 11677.9 3591.4 4562.7 4579.3 4701.0 4867.7 9098.5 9168.3 9189.4 9214.2 9219.6 5201.2 5230.8 5237.8 5255.7 5310.9 3911.7 4218.9 4392.8 4808.3 4856.7 3836.9 3876.5 3927.9 3947.8 3999.7 1039.3 1222.2 1222.4 1230.8 1243.6 833.7 892.6 897.5 927.1 949.4 5631.7 5787.3 5799.2 5869.9 5924.5 450.3 465.1 467.2 1005.1 1049.6 3845.8 3917.2 3953.1 3983.3 3990.5 10450.4 10612.2 10779.1 10978.4 11001.8 10785.8 10847.3 10971.4 10981.6 10983.6 5397.8 8095.6 8096.1 8172.8 8399.6 5857.1 5912.9 5945.4 5980.3 5983.9 9409.2 9536.1 9793.2 9796.6 9972.8 10023.6 10162.8 10202.0 10225.2 10238.3 6958.8 7432.9 7452.4 7632.5 7719.5 8550.8 8568.5 8617.5 8627.9 8640.5 7409.2 7485.7 7601.0 7613.7 7669.7 7351.9 7388.9 7417.3 7446.5 7451.3 7046.4 7081.1 7130.1 7185.4 7190.2 4459.8 4536.8 4798.8 4837.0 5053.6 4907.2 4942.2 4955.2 5017.4 5140.2 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4565.95 Median:5138.15 stddev:1405.76 (27.4%) stddev-sans-first:1372.99 13:45:24 INFO - PROCESS | 1666 | Values: 313.8 316.0 319.9 321.0 326.1 4680.7 4691.7 4722.1 4941.4 5555.2 4623.4 5191.9 5293.3 5465.2 5499.0 3551.9 3943.3 3952.5 4038.9 4068.5 3213.5 4063.5 4106.5 4170.7 4181.3 4106.0 4134.4 4149.4 4167.1 4194.6 3453.8 3803.4 4109.1 4122.4 4213.2 3001.8 3520.5 3545.2 3551.1 3714.0 5232.0 5293.2 5303.5 5334.7 5334.9 4097.6 4230.8 4602.4 4680.9 4740.2 5044.2 5707.4 5712.4 5734.3 5765.3 3411.7 3917.1 3942.1 3943.8 3948.0 4768.0 4826.1 4944.5 5048.2 6087.3 3782.4 3806.4 3916.3 3966.8 3972.9 3438.8 4040.9 4041.4 4053.3 4058.6 3961.8 4443.6 4709.4 4710.8 4733.4 5188.1 5390.0 5814.7 5827.9 5839.6 1366.4 1367.9 1370.3 1391.1 1393.3 807.4 830.2 846.1 882.2 897.6 4706.6 5228.9 5291.9 5296.9 5402.9 5584.1 5610.3 5815.5 5833.0 5852.6 4437.1 4443.4 4910.7 5172.5 5345.3 2834.0 2865.1 3063.0 3078.6 3123.8 5103.8 5494.9 5668.2 6141.7 6145.3 4865.4 5743.8 5999.3 6061.2 6134.7 5345.9 5386.8 5628.6 5679.9 6162.6 5270.3 5857.7 5884.2 5907.9 5912.0 5182.9 5261.3 5772.8 5834.5 5860.6 4937.6 5384.0 5437.5 6121.8 6123.5 3664.3 3748.5 3908.7 3983.7 3986.3 5312.8 5882.5 5971.1 6017.7 6092.8 5298.2 5734.7 5780.6 5791.0 5824.6 4447.8 4919.5 5603.2 5773.5 5883.6 5180.6 5267.9 5293.0 5328.6 5648.7 5076.8 5545.4 5821.9 5835.7 5869.4 4853.0 5276.8 5724.7 5728.8 5750.8 13:45:24 INFO - PROCESS | 1666 | 13:45:24 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:16606.92 Median:609.95 stddev:23186.72 (3801.4%) stddev-sans-first:23220.68 13:45:24 INFO - PROCESS | 1666 | Values: 619.4 727.2 741.8 763.5 777.9 14820.6 15171.2 15306.5 15315.0 15349.9 13865.2 13866.3 14125.0 14156.8 14393.0 912.6 928.8 933.3 934.0 934.2 717.9 899.6 923.7 928.6 929.2 972.6 973.3 975.6 976.5 978.7 958.6 967.5 971.6 976.2 980.4 718.8 719.0 721.2 723.9 724.6 14886.9 15067.8 15073.9 15091.2 15168.3 12578.3 12704.3 12755.6 12769.1 13017.9 10034.6 10164.9 10234.8 10308.4 10459.2 3510.5 3528.0 3543.9 3572.9 3594.1 51492.0 57407.0 65426.3 71757.6 71759.0 8093.3 8131.4 8861.4 8872.9 8911.6 17957.6 19315.6 20281.7 21266.6 22021.4 7076.5 7164.1 7222.2 7239.5 7279.9 3182.1 4026.1 4101.8 4155.5 4160.8 3463.6 3532.8 3541.8 3565.6 3567.3 647.9 649.5 681.5 689.9 694.0 481.4 590.1 596.5 596.7 608.3 7612.6 8133.8 8226.5 8232.5 8239.3 577.1 580.9 611.6 628.8 633.6 3775.4 3876.0 3878.0 3995.1 4018.4 90289.5 90842.5 91668.6 92613.5 93174.1 57888.7 63279.0 73744.6 76439.5 81630.2 68750.0 69622.0 69801.7 69892.0 70041.0 7101.7 7240.3 7529.7 7693.9 7723.3 31751.4 38632.6 39279.4 39655.8 40241.8 51784.5 51785.4 54441.2 56975.7 57328.7 8577.9 9727.6 9976.1 10007.8 10271.6 10779.4 10826.9 10961.8 11011.3 11127.6 10160.9 10370.2 10384.3 10463.6 11102.4 10143.6 10171.3 10571.5 10735.7 10738.1 8687.7 10218.0 10335.4 10551.1 10895.7 8103.3 8576.5 8763.4 8790.6 8800.5 8515.4 8655.3 8825.0 8943.6 9095.5 13:45:24 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:45:24 INFO - PROCESS | 1666 | 13:45:25 INFO - TEST-INFO | 1666: exit 0 13:45:25 INFO - TEST-OK | dromaeo_css | took 1112827ms 13:45:25 INFO - TEST-START | kraken 13:45:25 INFO - operating with platform_type : mac_ 13:45:25 INFO - Initialising browser for kraken test... 13:45:25 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmp3QjI1r/profile http://localhost:49275/getInfo.html) 13:45:27 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 13:45:27 INFO - PROCESS | 1679 | colorDepth:24 13:45:27 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 13:45:27 INFO - PROCESS | 1679 | __metrics 13:45:27 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:45:28 INFO - TEST-INFO | 1679: exit 0 13:45:28 INFO - Browser initialized. 13:45:28 INFO - Running cycle 1/1 for kraken test... 13:45:28 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmp3QjI1r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:45:40 INFO - PROCESS | 1682 | RSS: Main: 250093568 13:45:40 INFO - PROCESS | 1682 | 13:45:43 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:45:44 INFO - PROCESS | 1682 | RSS: Main: 261967872 13:45:44 INFO - PROCESS | 1682 | 13:45:47 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:45:47 INFO - PROCESS | 1682 | RSS: Main: 266772480 13:45:47 INFO - PROCESS | 1682 | 13:45:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:45:51 INFO - PROCESS | 1682 | RSS: Main: 267128832 13:45:51 INFO - PROCESS | 1682 | 13:45:54 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:45:54 INFO - PROCESS | 1682 | RSS: Main: 267812864 13:45:54 INFO - PROCESS | 1682 | 13:45:56 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:45:57 INFO - PROCESS | 1682 | RSS: Main: 268775424 13:45:57 INFO - PROCESS | 1682 | 13:46:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:46:01 INFO - PROCESS | 1682 | RSS: Main: 268242944 13:46:01 INFO - PROCESS | 1682 | 13:46:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:46:05 INFO - PROCESS | 1682 | RSS: Main: 268537856 13:46:05 INFO - PROCESS | 1682 | 13:46:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:46:09 INFO - PROCESS | 1682 | RSS: Main: 268722176 13:46:09 INFO - PROCESS | 1682 | 13:46:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:46:11 INFO - PROCESS | 1682 | RSS: Main: 269033472 13:46:11 INFO - PROCESS | 1682 | 13:46:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:46:14 INFO - PROCESS | 1682 | RSS: Main: 269115392 13:46:14 INFO - PROCESS | 1682 | 13:46:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:46:16 INFO - PROCESS | 1682 | RSS: Main: 268947456 13:46:16 INFO - PROCESS | 1682 | 13:46:19 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:46:20 INFO - PROCESS | 1682 | RSS: Main: 269508608 13:46:20 INFO - PROCESS | 1682 | 13:46:23 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:46:24 INFO - PROCESS | 1682 | RSS: Main: 269430784 13:46:24 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:46:26 INFO - PROCESS | 1682 | RSS: Main: 271618048 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | __start_tp_report 13:46:26 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 13:46:26 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 13:46:26 INFO - PROCESS | 1682 | |i|pagename|runs| 13:46:26 INFO - PROCESS | 1682 | |0;ai-astar;115;116;109;116;115;116;106;117;124;116 13:46:26 INFO - PROCESS | 1682 | |1;audio-beat-detection;186;151;167;200;122;141;146;125;165;123 13:46:26 INFO - PROCESS | 1682 | |2;audio-dft;200;200;234;235;223;198;217;196;214;271 13:46:26 INFO - PROCESS | 1682 | |3;audio-fft;71;114;103;178;56;49;63;96;144;61 13:46:26 INFO - PROCESS | 1682 | |4;audio-oscillator;80;89;140;75;81;84;122;82;84;142 13:46:26 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;91;90;90;114;90;76;136;128;112;115 13:46:26 INFO - PROCESS | 1682 | |6;imaging-darkroom;150;96;95;102;145;102;96;95;97;96 13:46:26 INFO - PROCESS | 1682 | |7;imaging-desaturate;113;87;141;147;138;99;110;117;91;89 13:46:26 INFO - PROCESS | 1682 | |8;json-parse-financial;59;64;61;61;63;61;63;122;60;59 13:46:26 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;65;85;77;70;73;68;67;66;66;96 13:46:26 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;86;87;85;86;85;88;89;87;87;86 13:46:26 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;141;143;144;143;134;143;151;151;209;166 13:46:26 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;172;173;170;167;170;167;171;196;228;230 13:46:26 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;68;69;69;70;70;65;70;67;86;92 13:46:26 INFO - PROCESS | 1682 | __end_tp_report 13:46:26 INFO - PROCESS | 1682 | __start_cc_report 13:46:26 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1072 13:46:26 INFO - PROCESS | 1682 | __end_cc_report 13:46:26 INFO - PROCESS | 1682 | __startTimestamp1462999586314__endTimestamp 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | ------- Summary: start ------- 13:46:26 INFO - PROCESS | 1682 | Number of tests: 14 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:4.78 (4.1%) stddev-sans-first:5.07 13:46:26 INFO - PROCESS | 1682 | Values: 115.0 116.0 109.0 116.0 115.0 116.0 106.0 117.0 124.0 116.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:158.00 stddev:26.83 (17.0%) stddev-sans-first:25.59 13:46:26 INFO - PROCESS | 1682 | Values: 186.0 151.0 167.0 200.0 122.0 141.0 146.0 125.0 165.0 123.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:218.80 Median:220.00 stddev:23.38 (10.6%) stddev-sans-first:23.79 13:46:26 INFO - PROCESS | 1682 | Values: 200.0 200.0 234.0 235.0 223.0 198.0 217.0 196.0 214.0 271.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:93.50 Median:58.50 stddev:42.28 (72.3%) stddev-sans-first:44.05 13:46:26 INFO - PROCESS | 1682 | Values: 71.0 114.0 103.0 178.0 56.0 49.0 63.0 96.0 144.0 61.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:81.50 stddev:26.13 (32.1%) stddev-sans-first:26.90 13:46:26 INFO - PROCESS | 1682 | Values: 80.0 89.0 140.0 75.0 81.0 84.0 122.0 82.0 84.0 142.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:104.20 Median:83.00 stddev:19.51 (23.5%) stddev-sans-first:20.10 13:46:26 INFO - PROCESS | 1682 | Values: 91.0 90.0 90.0 114.0 90.0 76.0 136.0 128.0 112.0 115.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:95.50 stddev:21.32 (22.3%) stddev-sans-first:16.11 13:46:26 INFO - PROCESS | 1682 | Values: 150.0 96.0 95.0 102.0 145.0 102.0 96.0 95.0 97.0 96.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:113.20 Median:117.00 stddev:22.41 (19.2%) stddev-sans-first:23.77 13:46:26 INFO - PROCESS | 1682 | Values: 113.0 87.0 141.0 147.0 138.0 99.0 110.0 117.0 91.0 89.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:61.00 stddev:19.29 (31.6%) stddev-sans-first:20.23 13:46:26 INFO - PROCESS | 1682 | Values: 59.0 64.0 61.0 61.0 63.0 61.0 63.0 122.0 60.0 59.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:71.50 stddev:10.11 (14.1%) stddev-sans-first:10.27 13:46:26 INFO - PROCESS | 1682 | Values: 65.0 85.0 77.0 70.0 73.0 68.0 67.0 66.0 66.0 96.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 13:46:26 INFO - PROCESS | 1682 | Values: 86.0 87.0 85.0 86.0 85.0 88.0 89.0 87.0 87.0 86.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:147.50 stddev:21.59 (14.6%) stddev-sans-first:22.50 13:46:26 INFO - PROCESS | 1682 | Values: 141.0 143.0 144.0 143.0 134.0 143.0 151.0 151.0 209.0 166.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:172.50 stddev:24.94 (14.5%) stddev-sans-first:26.05 13:46:26 INFO - PROCESS | 1682 | Values: 172.0 173.0 170.0 167.0 170.0 167.0 171.0 196.0 228.0 230.0 13:46:26 INFO - PROCESS | 1682 | 13:46:26 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:70.00 stddev:8.90 (12.7%) stddev-sans-first:9.28 13:46:26 INFO - PROCESS | 1682 | Values: 68.0 69.0 69.0 70.0 70.0 65.0 70.0 67.0 86.0 92.0 13:46:26 INFO - PROCESS | 1682 | -------- Summary: end -------- 13:46:26 INFO - PROCESS | 1682 | 13:46:27 INFO - TEST-INFO | 1682: exit 0 13:46:27 INFO - TEST-OK | kraken | took 62022ms 13:46:27 INFO - SUITE-END | took 1174s 13:46:27 INFO - Completed test suite (00:19:35) 13:46:27 INFO - javascript benchmark 13:46:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2891.522898721711, 3192.521121831725, 3246.2371342734455, 3438.8280585970697, 3501.9474707879385, 4628.69913455626, 5758.481736643701, 5776.942230577698, 5876.735546900389, 5932.0, 4743.217369134098, 5701.0, 5826.504747096502, 5882.411758824115, 5916.2308899843065, 5202.479752024805, 5232.45059268777, 5259.421463638997, 5341.1988201769655, 5366.221897824813, 4854.355432994226, 5342.626016178869, 5364.704941228231, 5402.297701298826, 5483.670979741209, 4628.028114096044, 4923.286123512106, 5270.604704647167, 5288.312519372495, 5290.100682883919, 5241.107943054871, 5242.81650142247, 5265.0259701955265, 5274.55166310864, 5274.657147285453, 5176.042432150064, 5225.555827754645, 5237.083510385705, 5265.183896496049, 5322.760475778599, 5558.666480011212, 5695.886082278373, 5758.165066065397, 5758.452946970031, 5816.272965879265, 6195.535334849904, 6230.968845155745, 6248.437640612321, 6258.217722784652, 6264.310925798159, 6155.476784473325, 6190.0405437157315, 6259.780907668255, 6274.654893980829, 6292.119103325538, 5949.494292985101, 6013.909791353133, 6026.54800889935, 6043.697815109227, 6067.878642427171, 6340.524460665469, 6373.808785736435, 6381.4575761059905, 6394.584352017104, 6437.163168788027, 5882.0, 6101.60339577926, 6181.350958149419, 6238.469730072904, 6296.118543403927, 6207.906881396782, 6264.68676566179, 6284.591501552384, 6287.591306565058, 6307.085472606446, 6104.459155447136, 6178.6601736904, 6192.752289908353, 6226.844328891814, 6243.469305109116, 6118.223266370716, 6143.481172719164, 6147.938520614736, 6193.256809182837, 6226.782062627877, 5024.171011783088, 5040.0, 5047.899042019139, 5049.166887463601, 5072.442031376546, 3348.631650518441, 4493.775311234425, 4512.503624601291, 4564.292534657134, 4570.245909424974, 5692.601517893708, 5852.795152169653, 5903.173555702205, 5942.405759424023, 5995.46040856325, 6228.408301211378, 6253.838127143666, 6261.342559031236, 6276.811695649138, 6289.7484100635465, 5970.223870896755, 5982.970085149546, 5988.28140623128, 6004.699765011732, 6036.124761909498, 5413.0, 5481.671099733949, 5514.558835293167, 5539.19681646159, 5553.361363443175, 5447.782088716407, 6453.774117905851, 6463.515236357198, 6479.481641468766, 6492.0261960706275, 5079.466656001215, 6159.29168145669, 6271.059341098871, 6280.874382512416, 6401.751658426749, 6337.302896681454, 6346.301906790157, 6392.616443013434, 6478.611283323015, 6490.578112422678, 6133.38666133401, 6152.107944347955, 6185.103160041679, 6210.596311239755, 6217.09852071438, 5382.892342153056, 5903.498202652822, 6235.064740288993, 6248.531360148098, 6257.499400047895, 5063.443021267732, 5228.6078544110105, 5403.243545903498, 6126.540509461719, 6237.282712488122, 6207.131001659681, 6213.471854892385, 6226.377362263918, 6231.065340199007, 6239.563230573816, 6002.159697642421, 6046.697665116639, 6104.298005729398, 6117.0, 6127.172831667668, 5101.750071232572, 5303.522682958398, 6090.177825993434, 6107.297660769068, 6155.2921414037955, 6127.632342059491, 6145.569810113249, 6152.753889844356, 6161.815145545641, 6170.10533472635], "value": 5703.145599229398, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.11255303219366, 532.4276402866917, 563.5942121672394, 580.0631979353345, 587.3715124816447, 29753.553696694566, 37545.812270938644, 39213.21573568527, 39236.01909952253, 39259.607403925955, 39815.60184398155, 40387.39418908719, 40695.38956915648, 41023.79488102563, 41167.0, 9478.72510597455, 9647.421154730704, 9660.613575457008, 9663.75840603987, 9709.36889102206, 9609.471479068648, 9611.759706007337, 9614.615415383376, 9736.26977976651, 9745.269104817167, 9269.563217701267, 9664.323497355188, 9715.028497150299, 9784.755381115494, 9798.559064842064, 8852.8672069919, 9673.177779888718, 9694.22446204302, 9724.611015559369, 9733.56198971041, 9529.428234305895, 9644.276679249015, 9663.323567350288, 9664.613415463373, 9674.0, 37411.877643670734, 37503.62496375056, 37583.62416375856, 37955.43066853999, 38007.61992380068, 3123.5314702794535, 3337.4660054391425, 3398.2523844754114, 3707.388280933643, 3791.071187559038, 27216.183514494598, 28501.0, 28845.42309153805, 30618.387632247453, 30970.38059238825, 2676.9196924092307, 2681.8659067046765, 2780.5829125631117, 2855.4431885782283, 2866.3693987322754, 494945.5252723713, 500545.0, 504634.0, 508079.45960270334, 508539.0, 68153.0, 68844.6557767218, 69043.65478172577, 69903.60192796115, 70016.64991675009, 51081.72295692563, 51749.2237616436, 51945.48054519406, 52690.47309526932, 52852.47147528552, 16875.746863797052, 17224.82775172232, 17373.65252694939, 17375.218115184845, 17390.0, 12784.808227876589, 13045.34773261333, 13092.0, 13213.207207567577, 13224.404901779442, 17429.65140697179, 20262.59474810525, 21345.0, 21445.57108857814, 21484.677729834064, 7532.887006694904, 8762.912370876336, 9028.781114549583, 9029.322800789836, 9145.405548639317, 943.9103285187896, 1061.7770268243755, 1072.5602502973743, 1076.8169411199958, 1101.2657598783542, 28952.131436056952, 29463.852680736458, 29633.555496667566, 30216.09351719408, 30363.392732145232, 1024.6791018343458, 1099.14816017587, 1173.4602083041725, 1192.3799624195544, 1229.2931564350533, 12490.375481225903, 12777.93611031939, 13297.601071967856, 15220.847791522165, 15456.459023934109, 638804.0, 638980.0, 647433.0, 655855.0, 657071.0, 46068.61794146181, 60159.0, 60173.0, 60816.0, 60938.390616094155, 1263.279930439659, 1452.6223181972764, 1499.242882344409, 1501.1893577468045, 1504.1952555382818, 28971.0, 29026.0, 29145.562816557343, 29371.853140734158, 30196.5470517938, 51332.48667513426, 51501.0, 51534.74232628813, 51624.0, 51645.0, 555594.0, 556264.0, 573105.0, 574948.0, 577755.0, 41492.79253603713, 42127.15745685008, 42153.156936860476, 42167.57832421637, 42710.359344609235, 45936.770316148206, 45996.47019413964, 46237.768811157075, 47614.28578571255, 48192.036159275915, 37857.432138518496, 37909.431358529095, 37968.050798730255, 38613.0, 38760.41859372168, 37323.0, 37482.0, 37533.81233093817, 37676.43485347667, 38209.04477388087, 29809.25476863096, 34372.0, 37885.0, 38482.422763659124, 40246.59753402429, 31140.53289200709, 33163.66836331606, 33475.66524334823, 33608.0, 33616.83191584027, 21685.698858068565, 24207.75792242055, 24937.625935610617, 25167.74832251654, 25279.87360063188], "value": 20208.19183797256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.29957376296414, 397.729543910141, 399.3530480621393, 399.91201935574173, 419.37931860845947, 14758.33587488563, 19698.310559130434, 20633.277835275767, 20834.0, 20852.270170544034, 20303.49241268969, 20663.070161842716, 21254.0, 21531.892340538274, 21544.676829847565, 5511.889762204753, 5540.889182216353, 5929.73316200772, 6003.369646187153, 6013.669248191348, 5491.093969495028, 5615.326160860682, 5890.0, 5928.0, 5933.288005439354, 5539.639923404986, 5820.272465941757, 5940.52475801937, 5942.078977758454, 5959.344472108065, 4703.482616912137, 4726.314684370781, 4803.18345881201, 4829.444613869415, 4892.486288939664, 4595.609373203281, 4681.729361259786, 4696.694714843524, 4751.572358487752, 4783.306420568998, 24086.63870041951, 24708.752912470824, 25469.23592292216, 25536.48927021468, 25537.361565960815, 1879.6710575649206, 1958.9519607010322, 2020.2323117215365, 2047.4164863014119, 2300.6664033715183, 9549.570269337897, 9728.951355243249, 9807.117359437614, 9829.164521015722, 9959.153471954893, 1717.3645751072183, 1774.8935063896208, 1808.5569035586366, 1810.5786957238504, 1858.7490688757116, 118963.40518297353, 123853.38073309665, 125144.0, 127028.99999999907, 127250.0, 11511.884881151249, 11705.180637355303, 11714.121440891968, 11724.70688232801, 11820.290782553075, 14992.775108373384, 15011.24943752808, 15032.17323047221, 15045.924770376298, 15096.433606286957, 8397.37019723511, 8470.745877623682, 8626.094260102724, 8691.826163476695, 8740.8688869667, 7033.859322813515, 8386.371022173362, 8433.19884610961, 8470.66117355299, 8567.0, 3887.8530833404216, 4157.750534967911, 4539.364488971547, 4668.929966050512, 4688.624910007259, 1756.2360373237682, 1972.723818665388, 2026.4731169895933, 2035.6742921132548, 2053.6098141353095, 885.3399875233937, 920.8434566123775, 938.7324612485409, 1016.1819735113305, 1051.164324362134, 18972.146253418352, 20007.39977800639, 20213.0, 20343.08456119478, 20460.181592736124, 974.2936371130874, 982.7493989032894, 983.5770618634056, 1017.4404077757351, 1020.6172685243034, 10455.059044686015, 10589.470526473646, 10639.361638301727, 10676.252662313564, 10707.518161682743, 568783.0, 572394.0, 605993.0, 616869.0, 625328.0, 135305.32347338396, 136203.0, 137509.0, 137914.1730039444, 138356.30821846027, 999.4752754803641, 1018.7656838927088, 1024.8360262358135, 1043.5095505112586, 1044.6291566493937, 17741.822581774533, 17907.462776116736, 17976.0, 18006.459806205836, 18325.541861453035, 51998.74000630124, 52824.47175528196, 54288.72855635855, 55129.0, 55689.266412355406, 72058.0, 81411.77882331652, 81470.0, 82396.17603823885, 82836.58581707053, 5272.815451459181, 5312.096943519534, 7836.882446763417, 7935.166807485131, 7958.761237162895, 10612.734681632712, 11319.547218111184, 12267.0, 12347.567835125728, 12616.810747838606, 9320.254379649747, 9381.202597778993, 10795.6221532246, 10817.1346292298, 10859.457027148768, 10066.295359324777, 10321.277510574488, 10527.526261318106, 10607.098396636373, 11122.61070862516, 10377.636782712569, 10722.571097156026, 11045.39250341239, 11087.611933582286, 11326.716832079264, 7830.725924592612, 7928.08826984881, 8062.677492900453, 8141.674333026613, 8278.172182781673, 9752.658656946973, 10932.453377331261, 10957.452127393757, 10967.396793176453, 11057.889421105687], "value": 9366.437888717868, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1617.086346214389, 1642.6878893010328, 1910.4746194796437, 1955.1006536992982, 1967.1147983407461, 7938.483998540105, 8474.067852536216, 8479.788005299863, 8975.596098175582, 8977.96753373363, 8598.87101693475, 8637.65449382024, 8662.610182541785, 8817.559122043887, 8822.77943051424, 2271.6597207647483, 2279.7492275849645, 2395.018042602529, 2421.6609674645565, 2426.5874801283735, 2388.534235824015, 2414.4929564791414, 2420.685310909579, 2439.243834411338, 2445.8287919845616, 2384.415818124562, 2386.7374588795305, 2392.712874455068, 2399.436132508859, 2399.940001499959, 2400.9639855402183, 2403.435192729707, 2421.4914867877765, 2431.659567660529, 2439.829211955164, 2358.056777289098, 2374.6200607902824, 2403.579373609611, 2408.4099395648177, 2428.210831479776, 8745.081766414498, 8826.691065812665, 8853.247473964657, 8860.822783544292, 8872.778180545474, 5299.49654782795, 5368.1679339702405, 5373.596980226499, 5479.53423958964, 5501.339839219279, 3364.34395292918, 3474.5656792900886, 3522.964770352289, 3538.062413460435, 3545.6454354564594, 1024.5440778853413, 1032.0866033560278, 1051.8061999630397, 1052.315994603514, 1052.6315789473622, 11450.94274528622, 11506.0, 11542.36516991565, 11627.360495172847, 11677.941610291979, 3591.3894637911617, 4562.657800664897, 4579.290210017452, 4701.0, 4867.73438905887, 9098.499582523025, 9168.26653867689, 9189.356745027917, 9214.170724634681, 9219.585118669676, 5201.219817027476, 5230.790768369223, 5237.769124255797, 5255.737213139328, 5310.867228319323, 3911.7261791674878, 4218.945263684079, 4392.846250381285, 4808.2547205183255, 4856.708597484162, 3836.865709700147, 3876.476675648751, 3927.8821635350987, 3947.763134211899, 3999.7000224983244, 1039.3088596083671, 1222.1689251309017, 1222.407132540717, 1230.772307123185, 1243.6257934981895, 833.7248707926491, 892.6250974590557, 897.5243121145803, 927.096081320707, 949.3544389814854, 5631.690257035821, 5787.334456537468, 5799.2460980073165, 5869.911951320733, 5924.4667979882015, 450.29753584407774, 465.0582570295112, 467.24773115284364, 1005.0652892809611, 1049.6011515624014, 3845.8461661533784, 3917.2165566886165, 3953.090789118519, 3983.2830090583393, 3990.501187351581, 10450.372977621368, 10612.20408469368, 10779.083777878968, 10978.396188209725, 11001.779964400666, 10785.83821242682, 10847.294925829796, 10971.396573188551, 10981.61564345244, 10983.61557345489, 5397.8380648580605, 8095.55474448911, 8096.10942796292, 8172.836543269102, 8399.62201700925, 5857.092150716646, 5912.852178695567, 5945.375735547878, 5980.28236611592, 5983.9401605983385, 9409.200217981548, 9536.094071063239, 9793.167580755431, 9796.559154838193, 9972.800543988935, 10023.599056037678, 10162.847557286499, 10202.0, 10225.181985441295, 10238.283320167517, 6958.756443524452, 7432.851342973218, 7452.403807695263, 7632.542047477169, 7719.536827790351, 8550.82898342042, 8568.485890846569, 8617.526036068077, 8627.913720862962, 8640.524771137647, 7409.184989651242, 7485.66314515859, 7601.049868766404, 7613.733519326797, 7669.693212271447, 7351.926480735124, 7388.852222955404, 7417.332440080419, 7446.478746487694, 7451.2921272479025, 7046.365827075588, 7081.114860642419, 7130.144382674112, 7185.389241914287, 7190.173130089897, 4459.7993090310365, 4536.820426689019, 4798.760061996816, 4836.975815120902, 5053.570446512088, 4907.190313598217, 4942.233953737176, 4955.231939049453, 5017.397912250407, 5140.151874940593], "value": 4534.579850888975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7584060273589, 316.04869343276744, 319.87205117952817, 320.9759268054895, 326.08207894776217, 4680.672352935296, 4691.671582989188, 4722.079194557063, 4941.431735350436, 5555.194626713509, 4623.398958135446, 5191.922121168176, 5293.311869456975, 5465.152901300295, 5499.0, 3551.8634037108136, 3943.2507823513583, 3952.5454572724216, 4038.899027524306, 4068.4507591475067, 3213.5019072043756, 4063.4920634920636, 4106.527749308838, 4170.728902621335, 4181.285672874123, 4105.979470102645, 4134.421181034642, 4149.439825623547, 4167.145735124307, 4194.643455306303, 3453.810040447787, 3803.4215800442785, 4109.123613470552, 4122.42286079949, 4213.199492096507, 3001.849907504616, 3520.471929210639, 3545.2023294758474, 3551.1122219445137, 3714.0, 5231.973840130813, 5293.232481290192, 5303.4696530346655, 5334.679919204821, 5334.91997620036, 4097.569755175693, 4230.830766769325, 4602.401687780601, 4680.859574212779, 4740.19416699162, 5044.243363495469, 5707.429257074301, 5712.42940676693, 5734.27621879608, 5765.250517432749, 3411.727061835047, 3917.0794863207125, 3942.093318536724, 3943.8028098594955, 3948.0, 4768.0451015083645, 4826.058918510892, 4944.505549445063, 5048.242763585455, 6087.3303936566945, 3782.413725872494, 3806.3926210545865, 3916.3342231820657, 3966.7619942803526, 3972.900677483086, 3438.7764795288226, 4040.8989775255855, 4041.413994970713, 4053.310937140693, 4058.5941405859767, 3961.801909904493, 4443.555644435531, 4709.3877795887, 4710.788014539354, 4733.431988161374, 5188.057076621794, 5390.0, 5814.709264536841, 5827.872826418326, 5839.562032847553, 1366.3782978744648, 1367.8768910798076, 1370.3422357268566, 1391.0679844504207, 1393.324237744693, 807.4347956430522, 830.1860434949655, 846.0650980666398, 882.2280504558511, 897.5871099294267, 4706.576408123286, 5228.914940185309, 5291.920621190685, 5296.894062118736, 5402.945970540323, 5584.078627026576, 5610.297203845443, 5815.476607105381, 5833.0, 5852.561057920588, 4437.134758722018, 4443.422355093817, 4910.680805747615, 5172.5344718975475, 5345.289507357608, 2834.0, 2865.0660803957244, 3062.9540556891666, 3078.6459557150765, 3123.8438078096005, 5103.770330335144, 5494.890102198014, 5668.214512428654, 6141.723622437001, 6145.265356916129, 4865.440474345425, 5743.798967036218, 5999.340072592011, 6061.181740465069, 6134.675054086858, 5345.86635334112, 5386.784528618889, 5628.634138781048, 5679.858003549779, 6162.568620196633, 5270.288511050959, 5857.707114644166, 5884.235049443545, 5907.911381329198, 5911.970440147944, 5182.859770850407, 5261.289725887109, 5772.7690892363835, 5834.533237340917, 5860.648361098348, 4937.5556199942175, 5384.0, 5437.456254374689, 6121.765555572095, 6123.458764612636, 3664.303782281358, 3748.5126933498473, 3908.7459315144424, 3983.7211395202057, 3986.32232520478, 5312.760925758273, 5882.49998750111, 5971.134185543159, 6017.669028203491, 6092.75628974836, 5298.2052692096495, 5734.741936612779, 5780.624259423124, 5790.971045144747, 5824.592278540631, 4447.799849006868, 4919.458859525521, 5603.2155498231095, 5773.480386765212, 5883.646981181143, 5180.559652429435, 5267.868303292448, 5293.009449660929, 5328.600354973315, 5648.689322087324, 5076.796928122833, 5545.417731138173, 5821.864736376536, 5835.737391817293, 5869.413058694096, 4852.975735121302, 5276.841694749163, 5724.685142317046, 5728.770849166153, 5750.7987220447085], "value": 4121.638569165389, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.358173937249, 727.2291371146582, 741.7885902517781, 763.461759459581, 777.8716511775557, 14820.55538333849, 15171.165585892799, 15306.54080377589, 15315.0, 15349.923250383747, 13865.237411942338, 13866.306684665777, 14125.0, 14156.787648185285, 14393.0, 912.5802131019739, 928.8281667891428, 933.3326671429926, 933.995330023349, 934.2339281788936, 717.8528401677644, 899.6266549382032, 923.6905636611743, 928.5728564860138, 929.2194556572477, 972.6304004478288, 973.3429934793986, 975.6195083917278, 976.5166242709869, 978.7259567321162, 958.6309270930673, 967.487231767161, 971.5822196455522, 976.1605019683067, 980.3872579637726, 718.7520305494596, 718.9964050179768, 721.2246834652769, 723.8552289542113, 724.6231959381092, 14886.925565372212, 15067.849321506865, 15073.924630376778, 15091.24543772818, 15168.31742571587, 12578.308193049379, 12704.301263430507, 12755.553555625509, 12769.106162568623, 13017.934910325483, 10034.59861605535, 10164.94917525415, 10234.846477302846, 10308.381497110122, 10459.215558833119, 3510.5436293281964, 3528.047427194643, 3543.9291214175573, 3572.9285414291567, 3594.0835087052633, 51491.97016059695, 57407.0, 65426.34573654298, 71757.56484870274, 71759.0, 8093.312068474128, 8131.390145739093, 8861.424007439497, 8872.911270887338, 8911.643534258686, 17957.551061223574, 19315.64790464654, 20281.69577456339, 21266.574668506542, 22021.449463763533, 7076.4692648051605, 7164.104486939133, 7222.241664625244, 7239.529430586995, 7279.927200728031, 3182.140821978053, 4026.074002979331, 4101.774402407897, 4155.542890282067, 4160.83356665736, 3463.619001909788, 3532.8410221540094, 3541.805200713934, 3565.5899571549603, 3567.340042092221, 647.9157709497736, 649.490150232075, 681.4888833374969, 689.899964505144, 693.968771405288, 481.3694060780389, 590.1325052173298, 596.5317225977586, 596.7016491754123, 608.2883026858586, 7612.619369031637, 8133.796655083552, 8226.506409615442, 8232.547209903512, 8239.340852731768, 577.1083675721021, 580.89834279001, 611.6116266171014, 628.7987843890003, 633.5628416392674, 3775.3581891078584, 3876.0, 3877.9612203878164, 3995.081131339808, 4018.4374187613757, 90289.54855225813, 90842.54578727196, 91668.62497062549, 92613.5369323149, 93174.13651726927, 57888.710556446946, 63279.0, 73744.63127684435, 76439.47121057547, 81630.18369816344, 68749.999999999, 69622.0, 69801.65099174573, 69892.0, 70041.0, 7101.680424381018, 7240.312170343809, 7529.661165247468, 7693.88459173124, 7723.304902558797, 31751.364972700878, 38632.64785732486, 39279.41080883846, 39655.80172099121, 40241.79879100586, 51784.48215517797, 51785.44643660696, 54441.18338225009, 56975.715121424124, 57328.71335643295, 8577.914220857961, 9727.562259698188, 9976.102150806171, 10007.849882251918, 10271.589136434459, 10779.407132607781, 10826.885783402571, 10961.780764384826, 11011.327181274595, 11127.554897803997, 10160.898391015995, 10370.222233332379, 10384.273100882865, 10463.633772817915, 11102.44487775592, 10143.644972426226, 10171.338862973884, 10571.471426428494, 10735.731606709895, 10738.081247696957, 8687.74027765983, 10218.0, 10335.431551264752, 10551.050405463546, 10895.727606809576, 8103.311218546254, 8576.485410875368, 8763.430377025474, 8790.648374064966, 8800.47197168172, 8515.40392172542, 8655.307575393828, 8825.029246782977, 8943.597538110671, 9095.545222738969], "value": 6594.67421853577, "unit": "score"}], "name": "dromaeo_css", "value": 7145.135955975641, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 109.0, 116.0, 115.0, 116.0, 106.0, 117.0, 124.0, 116.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 151.0, 167.0, 200.0, 122.0, 141.0, 146.0, 125.0, 165.0, 123.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 234.0, 235.0, 223.0, 198.0, 217.0, 196.0, 214.0, 271.0], "value": 218.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 114.0, 103.0, 178.0, 56.0, 49.0, 63.0, 96.0, 144.0, 61.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 89.0, 140.0, 75.0, 81.0, 84.0, 122.0, 82.0, 84.0, 142.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 114.0, 90.0, 76.0, 136.0, 128.0, 112.0, 115.0], "value": 104.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 96.0, 95.0, 102.0, 145.0, 102.0, 96.0, 95.0, 97.0, 96.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 87.0, 141.0, 147.0, 138.0, 99.0, 110.0, 117.0, 91.0, 89.0], "value": 113.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 61.0, 61.0, 63.0, 61.0, 63.0, 122.0, 60.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 85.0, 77.0, 70.0, 73.0, 68.0, 67.0, 66.0, 66.0, 96.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 86.0, 85.0, 88.0, 89.0, 87.0, 87.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 144.0, 143.0, 134.0, 143.0, 151.0, 151.0, 209.0, 166.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 170.0, 167.0, 170.0, 167.0, 171.0, 196.0, 228.0, 230.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 70.0, 70.0, 65.0, 70.0, 67.0, 86.0, 92.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1639.3, "alertThreshold": 2.0}]} 13:46:27 INFO - Return code: 0 13:46:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:46:27 ERROR - # TBPL SUCCESS # 13:46:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:46:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:46:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:46:27 INFO - 2016-05-11 13:46:27.609 screenresolution[1687:10456] starting screenresolution argv=screenresolution get 13:46:27 INFO - 2016-05-11 13:46:27.612 screenresolution[1687:10456] Display 0: 1600x1200x32@60 13:46:27 INFO - 2016-05-11 13:46:27.617 screenresolution[1688:10459] starting screenresolution argv=screenresolution list 13:46:27 INFO - Available Modes on Display 0 13:46:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:46:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:46:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:46:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:46:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:46:27 INFO - Intel Iris: 13:46:27 INFO - Chipset Model: Intel Iris 13:46:27 INFO - Type: GPU 13:46:27 INFO - Bus: Built-In 13:46:27 INFO - VRAM (Dynamic, Max): 1536 MB 13:46:27 INFO - Vendor: Intel (0x8086) 13:46:27 INFO - Device ID: 0x0a2e 13:46:27 INFO - Revision ID: 0x0009 13:46:27 INFO - Displays: 13:46:27 INFO - Display: 13:46:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:46:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:46:27 INFO - Display Serial Number: 1600x1200 60 13:46:27 INFO - Main Display: Yes 13:46:27 INFO - Mirror: Off 13:46:27 INFO - Online: Yes 13:46:27 INFO - Rotation: Supported 13:46:27 INFO - Return code: 0 13:46:27 INFO - Running post-action listener: _resource_record_post_action 13:46:27 INFO - ##### 13:46:27 INFO - ##### Finished run-tests step (success) 13:46:27 INFO - ##### 13:46:27 INFO - Running post-run listener: _resource_record_post_run 13:46:28 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 37942784; Write bytes: 694292480; Read time: 400; Write time: 2820 13:46:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395270656; Write bytes: 396720128; Read time: 29844; Write time: 828 13:46:28 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35976704; Write bytes: 296724480; Read time: 373; Write time: 1990 13:46:28 INFO - Running post-run listener: _upload_blobber_files 13:46:28 INFO - Blob upload gear active. 13:46:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:46:28 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 13:46:28 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'] 13:46:28 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 13:46:29 INFO - (blobuploader) - INFO - Open directory for files ... 13:46:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:46:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:46:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:46:30 INFO - (blobuploader) - INFO - Done attempting. 13:46:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:46:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:46:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:46:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:46:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:46:31 INFO - (blobuploader) - INFO - Done attempting. 13:46:31 INFO - (blobuploader) - INFO - Iteration through files over. 13:46:31 INFO - Return code: 0 13:46:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:46:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:46:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70a4f5e654200da1e6ac248d79d12f75ad04f74904c87a406b1659bd93dde8033f5c4ee15fa6ec09360cc72135fb6c710f0a81eb18a815b20c342b7a4a2a7e8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a10c1a8e4680bd78b403e961d7233e14f7e218a888a38dece329efd3c45982013428d463038e85a34b44054508b31fd03385efc7230a3c7888e72472cd3d1c3e"} 13:46:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:46:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:46:31 INFO - Contents: 13:46:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70a4f5e654200da1e6ac248d79d12f75ad04f74904c87a406b1659bd93dde8033f5c4ee15fa6ec09360cc72135fb6c710f0a81eb18a815b20c342b7a4a2a7e8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a10c1a8e4680bd78b403e961d7233e14f7e218a888a38dece329efd3c45982013428d463038e85a34b44054508b31fd03385efc7230a3c7888e72472cd3d1c3e"} 13:46:31 INFO - Running post-run listener: copy_logs_to_upload_dir 13:46:31 INFO - Copying logs to upload dir... 13:46:31 INFO - mkdir: /builds/slave/test/build/upload/logs 13:46:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.102696 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 13:46:31.522652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 13:46:31.525880) ========= 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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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/70a4f5e654200da1e6ac248d79d12f75ad04f74904c87a406b1659bd93dde8033f5c4ee15fa6ec09360cc72135fb6c710f0a81eb18a815b20c342b7a4a2a7e8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a10c1a8e4680bd78b403e961d7233e14f7e218a888a38dece329efd3c45982013428d463038e85a34b44054508b31fd03385efc7230a3c7888e72472cd3d1c3e"} build_url:https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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/70a4f5e654200da1e6ac248d79d12f75ad04f74904c87a406b1659bd93dde8033f5c4ee15fa6ec09360cc72135fb6c710f0a81eb18a815b20c342b7a4a2a7e8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a10c1a8e4680bd78b403e961d7233e14f7e218a888a38dece329efd3c45982013428d463038e85a34b44054508b31fd03385efc7230a3c7888e72472cd3d1c3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 13:46:31.557036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:46:31.557359) ========= 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.clgSNbQ6T5/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.EWdkfQ8Lqs/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005064 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 13:46:31.606346) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 13:46:31.606689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 13:46:31.607000) ========= ========= Total master_lag: 0.15 =========