builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0148 starttime: 1490061791.33 results: success (0) buildid: 20170320175028 builduid: 9f594cd99a624b22956a2b30b6f8ee0f revision: f897fc6fb17f13251963aa4236e541064e9881d3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.332277) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.332782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.333075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.359542) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.359932) ========= 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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 --2017-03-20 19:03:11-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.34M=0.001s 2017-03-20 19:03:11 (9.34 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.472927) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.473284) ========= 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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.034469 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.532019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:11.532414) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f897fc6fb17f13251963aa4236e541064e9881d3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f897fc6fb17f13251963aa4236e541064e9881d3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 2017-03-20 19:03:11,631 truncating revision to first 12 chars 2017-03-20 19:03:11,631 Setting DEBUG logging. 2017-03-20 19:03:11,631 attempt 1/10 2017-03-20 19:03:11,631 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f897fc6fb17f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 19:03:12,308 unpacking tar archive at: mozilla-central-f897fc6fb17f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.954896 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:12.513346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:12.513892) ========= script_repo_revision: f897fc6fb17f13251963aa4236e541064e9881d3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:12.514534) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:12.515007) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 19:03:12.535168) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-20 19:03:12.535577) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:03:12 INFO - MultiFileLogger online at 20170320 19:03:12 in /builds/slave/test 19:03:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:03:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:03:12 INFO - {'append_to_log': False, 19:03:12 INFO - 'base_work_dir': '/builds/slave/test', 19:03:12 INFO - 'blob_upload_branch': 'Firefox', 19:03:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:03:12 INFO - 'branch': 'Firefox', 19:03:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:03:12 INFO - 'config_files': ('talos/mac_config.py',), 19:03:12 INFO - 'default_actions': ('clobber', 19:03:12 INFO - 'read-buildbot-config', 19:03:12 INFO - 'download-and-extract', 19:03:12 INFO - 'populate-webroot', 19:03:12 INFO - 'create-virtualenv', 19:03:12 INFO - 'install', 19:03:12 INFO - 'run-tests'), 19:03:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:03:12 INFO - 'download_minidump_stackwalk': True, 19:03:12 INFO - 'download_symbols': 'ondemand', 19:03:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:03:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:03:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:03:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:03:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:03:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:03:12 INFO - 'gecko_profile': False, 19:03:12 INFO - 'gecko_profile_interval': 0, 19:03:12 INFO - 'installer_path': 'installer.exe', 19:03:12 INFO - 'log_level': 'info', 19:03:12 INFO - 'log_name': 'talos', 19:03:12 INFO - 'log_to_console': True, 19:03:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:03:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:03:12 INFO - 'opt_config_files': (), 19:03:12 INFO - 'pip_index': False, 19:03:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:03:12 INFO - 'cmd': ('bash', 19:03:12 INFO - '-c', 19:03:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:03:12 INFO - 'enabled': True, 19:03:12 INFO - 'halt_on_failure': False, 19:03:12 INFO - 'name': 'check_screen_resolution'},), 19:03:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:03:12 INFO - 'cmd': ('bash', 19:03:12 INFO - '-c', 19:03:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:03:12 INFO - 'enabled': True, 19:03:12 INFO - 'halt_on_failure': False, 19:03:12 INFO - 'name': 'check_screen_resolution'},), 19:03:12 INFO - 'run_cmd_checks_enabled': True, 19:03:12 INFO - 'suite': 'dromaeojs-e10s', 19:03:12 INFO - 'system_bits': '32', 19:03:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:03:12 INFO - 'title': 't-yosemite-r7-0148', 19:03:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:03:12 INFO - 'use_talos_json': True, 19:03:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:03:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:03:12 INFO - 'work_dir': 'build'} 19:03:12 INFO - [mozharness: 2017-03-21 02:03:12.747135Z] Running clobber step. 19:03:12 INFO - Running pre-action listener: _resource_record_pre_action 19:03:12 INFO - Running main action method: clobber 19:03:12 INFO - rmtree: /builds/slave/test/build 19:03:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:03:15 INFO - Running post-action listener: _resource_record_post_action 19:03:15 INFO - [mozharness: 2017-03-21 02:03:15.410764Z] Finished clobber step (success) 19:03:15 INFO - [mozharness: 2017-03-21 02:03:15.410900Z] Running read-buildbot-config step. 19:03:15 INFO - Running pre-action listener: _resource_record_pre_action 19:03:15 INFO - Running main action method: read_buildbot_config 19:03:15 INFO - Using buildbot properties: 19:03:15 INFO - { 19:03:15 INFO - "project": "", 19:03:15 INFO - "product": "firefox", 19:03:15 INFO - "script_repo_revision": "production", 19:03:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:03:15 INFO - "repository": "", 19:03:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:03:15 INFO - "buildid": "20170320175028", 19:03:15 INFO - "pgo_build": "False", 19:03:15 INFO - "basedir": "/builds/slave/test", 19:03:15 INFO - "buildnumber": 128, 19:03:15 INFO - "slavename": "t-yosemite-r7-0148", 19:03:15 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:03:15 INFO - "platform": "yosemite_r7", 19:03:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:03:15 INFO - "branch": "mozilla-central", 19:03:15 INFO - "repo_path": "mozilla-central", 19:03:15 INFO - "revision": "f897fc6fb17f13251963aa4236e541064e9881d3", 19:03:15 INFO - "stage_platform": "macosx64", 19:03:15 INFO - "builduid": "9f594cd99a624b22956a2b30b6f8ee0f", 19:03:15 INFO - "slavebuilddir": "test" 19:03:15 INFO - } 19:03:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:03:15 INFO - Running post-action listener: _resource_record_post_action 19:03:15 INFO - [mozharness: 2017-03-21 02:03:15.414145Z] Finished read-buildbot-config step (success) 19:03:15 INFO - [mozharness: 2017-03-21 02:03:15.414253Z] Running download-and-extract step. 19:03:15 INFO - Running pre-action listener: _resource_record_pre_action 19:03:15 INFO - Running main action method: download_and_extract 19:03:15 INFO - mkdir: /builds/slave/test/build/tests 19:03:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:15 INFO - trying https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:15 INFO - Downloading https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:03:16 INFO - Downloaded 1325 bytes. 19:03:16 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:16 INFO - Using the following test package requirements: 19:03:16 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:03:16 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:16 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:03:16 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:03:16 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'jsshell-mac.zip'], 19:03:16 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:03:16 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:16 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:03:16 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:03:16 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:03:16 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:16 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:03:16 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:03:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:03:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:16 INFO - Fetch https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:03:18 INFO - Expected file size: 52476628 19:03:18 INFO - Obtained file size: 52476628 19:03:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:03:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:03:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:34 INFO - Fetch https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:03:36 INFO - Expected file size: 52476628 19:03:36 INFO - Obtained file size: 52476628 19:03:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:03:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:03:52 INFO - Fetch https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:03:53 INFO - Expected file size: 14404171 19:03:53 INFO - Obtained file size: 14404171 19:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:53 INFO - trying https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:53 INFO - Downloading https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:03:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:03:55 INFO - Downloaded 75183407 bytes. 19:03:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:55 INFO - mkdir: /builds/slave/test/properties 19:03:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:55 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:55 INFO - Contents: 19:03:55 INFO - build_url:https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:56 INFO - Running post-action listener: _resource_record_post_action 19:03:56 INFO - Running post-action listener: set_extra_try_arguments 19:03:56 INFO - [mozharness: 2017-03-21 02:03:56.173991Z] Finished download-and-extract step (success) 19:03:56 INFO - [mozharness: 2017-03-21 02:03:56.174137Z] Running populate-webroot step. 19:03:56 INFO - Running pre-action listener: _resource_record_pre_action 19:03:56 INFO - Running main action method: populate_webroot 19:03:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:56 INFO - '--tppagecycles', 19:03:56 INFO - '7'], 19:03:56 INFO - 'tests': ['tsvgm']}}, 19:03:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:03:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['damp', 'tps']}, 19:03:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'tests': ['damp', 'tps']}, 19:03:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['dromaeo_dom']}, 19:03:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['basic_compositor_video']}, 19:03:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:03:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['a11yr', 19:03:56 INFO - 'ts_paint', 19:03:56 INFO - 'tpaint', 19:03:56 INFO - 'sessionrestore', 19:03:56 INFO - 'sessionrestore_no_auto_restore', 19:03:56 INFO - 'tabpaint']}, 19:03:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['tsvgx', 19:03:56 INFO - 'tsvgr_opacity', 19:03:56 INFO - 'tart', 19:03:56 INFO - 'tscrollx', 19:03:56 INFO - 'cart', 19:03:56 INFO - 'tsvg_static']}, 19:03:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:56 INFO - 'tsvgr_opacity', 19:03:56 INFO - 'tart', 19:03:56 INFO - 'tscrollx', 19:03:56 INFO - 'cart', 19:03:56 INFO - 'tsvg_static']}, 19:03:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'talos_options': ['--disable-e10s'], 19:03:56 INFO - 'tests': ['tp5o']}, 19:03:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:03:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'talos_options': ['--disable-e10s', 19:03:56 INFO - '--xperf_path', 19:03:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:56 INFO - 'tests': ['tp5n']}, 19:03:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:03:56 INFO - 'talos_options': ['--xperf_path', 19:03:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:56 INFO - 'tests': ['tp5n']}}, 19:03:56 INFO - 'talos.zip': {'path': '', 19:03:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:56 INFO - Running post-action listener: _resource_record_post_action 19:03:56 INFO - [mozharness: 2017-03-21 02:03:56.192198Z] Finished populate-webroot step (success) 19:03:56 INFO - [mozharness: 2017-03-21 02:03:56.192309Z] Running create-virtualenv step. 19:03:56 INFO - Running pre-action listener: _resource_record_pre_action 19:03:56 INFO - Running main action method: create_virtualenv 19:03:56 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:03:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:56 INFO - Using real prefix '/tools/python27' 19:03:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:57 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:59 INFO - Installing pip.................done. 19:03:59 INFO - Return code: 0 19:03:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:04:00 INFO - Reading from file tmpfile_stdout 19:04:00 INFO - Output received: 19:04:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:04:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:00 INFO - 'HOME': '/Users/cltbld', 19:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:00 INFO - 'LOGNAME': 'cltbld', 19:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:00 INFO - 'MOZ_NO_REMOTE': '1', 19:04:00 INFO - 'NO_EM_RESTART': '1', 19:04:00 INFO - 'PAGER': '/bin/cat', 19:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:00 INFO - 'PWD': '/builds/slave/test', 19:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:00 INFO - 'SHELL': '/bin/bash', 19:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:00 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:00 INFO - 'USER': 'cltbld', 19:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:00 INFO - 'XPC_FLAGS': '0x0', 19:04:00 INFO - 'XPC_SERVICE_NAME': '0', 19:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:04:00 INFO - Cleaning up... 19:04:00 INFO - Return code: 0 19:04:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:00 INFO - 'HOME': '/Users/cltbld', 19:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:00 INFO - 'LOGNAME': 'cltbld', 19:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:00 INFO - 'MOZ_NO_REMOTE': '1', 19:04:00 INFO - 'NO_EM_RESTART': '1', 19:04:00 INFO - 'PAGER': '/bin/cat', 19:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:00 INFO - 'PWD': '/builds/slave/test', 19:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:00 INFO - 'SHELL': '/bin/bash', 19:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:00 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:00 INFO - 'USER': 'cltbld', 19:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:00 INFO - 'XPC_FLAGS': '0x0', 19:04:00 INFO - 'XPC_SERVICE_NAME': '0', 19:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:00 INFO - Downloading/unpacking psutil>=3.1.1 19:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:04:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:04 INFO - Installing collected packages: psutil 19:04:04 INFO - Running setup.py install for psutil 19:04:04 INFO - building 'psutil._psutil_osx' extension 19:04:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:04:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:04:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:04:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:04:05 INFO - building 'psutil._psutil_posix' extension 19:04:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:04:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:04:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:04:05 INFO - ^ 19:04:05 INFO - 1 warning generated. 19:04:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:04:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:05 INFO - Successfully installed psutil 19:04:05 INFO - Cleaning up... 19:04:05 INFO - Return code: 0 19:04:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:05 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:05 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.3'] in /builds/slave/test/build 19:04:05 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.3 19:04:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:05 INFO - 'HOME': '/Users/cltbld', 19:04:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:05 INFO - 'LOGNAME': 'cltbld', 19:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:05 INFO - 'MOZ_NO_REMOTE': '1', 19:04:05 INFO - 'NO_EM_RESTART': '1', 19:04:05 INFO - 'PAGER': '/bin/cat', 19:04:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:05 INFO - 'PWD': '/builds/slave/test', 19:04:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:05 INFO - 'SHELL': '/bin/bash', 19:04:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:05 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:05 INFO - 'USER': 'cltbld', 19:04:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:05 INFO - 'XPC_FLAGS': '0x0', 19:04:05 INFO - 'XPC_SERVICE_NAME': '0', 19:04:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:04:08 INFO - Installing collected packages: mozsystemmonitor 19:04:08 INFO - Running setup.py install for mozsystemmonitor 19:04:08 INFO - Successfully installed mozsystemmonitor 19:04:08 INFO - Cleaning up... 19:04:08 INFO - Return code: 0 19:04:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:09 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:09 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==2.5.1'] in /builds/slave/test/build 19:04:09 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==2.5.1 19:04:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:09 INFO - 'HOME': '/Users/cltbld', 19:04:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:09 INFO - 'LOGNAME': 'cltbld', 19:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:09 INFO - 'MOZ_NO_REMOTE': '1', 19:04:09 INFO - 'NO_EM_RESTART': '1', 19:04:09 INFO - 'PAGER': '/bin/cat', 19:04:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:09 INFO - 'PWD': '/builds/slave/test', 19:04:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:09 INFO - 'SHELL': '/bin/bash', 19:04:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:09 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:09 INFO - 'USER': 'cltbld', 19:04:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:09 INFO - 'XPC_FLAGS': '0x0', 19:04:09 INFO - 'XPC_SERVICE_NAME': '0', 19:04:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:09 INFO - Downloading/unpacking jsonschema==2.5.1 19:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:04:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:13 INFO - Downloading functools32-3.2.3-2.tar.gz 19:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:04:13 INFO - warning: no files found matching '*.txt' 19:04:13 INFO - no previously-included directories found matching 'build' 19:04:13 INFO - no previously-included directories found matching 'dist' 19:04:13 INFO - no previously-included directories found matching '.git*' 19:04:13 INFO - Installing collected packages: jsonschema, functools32 19:04:13 INFO - Running setup.py install for functools32 19:04:13 INFO - warning: no files found matching '*.txt' 19:04:13 INFO - no previously-included directories found matching 'build' 19:04:13 INFO - no previously-included directories found matching 'dist' 19:04:13 INFO - no previously-included directories found matching '.git*' 19:04:13 INFO - Successfully installed jsonschema functools32 19:04:13 INFO - Cleaning up... 19:04:13 INFO - Return code: 0 19:04:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:13 INFO - 'HOME': '/Users/cltbld', 19:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:13 INFO - 'LOGNAME': 'cltbld', 19:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:13 INFO - 'MOZ_NO_REMOTE': '1', 19:04:13 INFO - 'NO_EM_RESTART': '1', 19:04:13 INFO - 'PAGER': '/bin/cat', 19:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:13 INFO - 'PWD': '/builds/slave/test', 19:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:13 INFO - 'SHELL': '/bin/bash', 19:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:13 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:13 INFO - 'USER': 'cltbld', 19:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:13 INFO - 'XPC_FLAGS': '0x0', 19:04:13 INFO - 'XPC_SERVICE_NAME': '0', 19:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:04:13 INFO - Cleaning up... 19:04:13 INFO - Return code: 0 19:04:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:13 INFO - 'HOME': '/Users/cltbld', 19:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:13 INFO - 'LOGNAME': 'cltbld', 19:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:13 INFO - 'MOZ_NO_REMOTE': '1', 19:04:13 INFO - 'NO_EM_RESTART': '1', 19:04:13 INFO - 'PAGER': '/bin/cat', 19:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:13 INFO - 'PWD': '/builds/slave/test', 19:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:13 INFO - 'SHELL': '/bin/bash', 19:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:13 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:13 INFO - 'USER': 'cltbld', 19:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:13 INFO - 'XPC_FLAGS': '0x0', 19:04:13 INFO - 'XPC_SERVICE_NAME': '0', 19:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:13 INFO - Downloading/unpacking blobuploader==1.2.4 19:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:16 INFO - Downloading blobuploader-1.2.4.tar.gz 19:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:04:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:04:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:17 INFO - Downloading docopt-0.6.1.tar.gz 19:04:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:04:17 INFO - Installing collected packages: blobuploader, requests, docopt 19:04:17 INFO - Running setup.py install for blobuploader 19:04:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:04:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:04:18 INFO - Running setup.py install for requests 19:04:18 INFO - Running setup.py install for docopt 19:04:18 INFO - Successfully installed blobuploader requests docopt 19:04:18 INFO - Cleaning up... 19:04:18 INFO - Return code: 0 19:04:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:18 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 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:18 INFO - 'HOME': '/Users/cltbld', 19:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:18 INFO - 'LOGNAME': 'cltbld', 19:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:18 INFO - 'MOZ_NO_REMOTE': '1', 19:04:18 INFO - 'NO_EM_RESTART': '1', 19:04:18 INFO - 'PAGER': '/bin/cat', 19:04:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:18 INFO - 'PWD': '/builds/slave/test', 19:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:18 INFO - 'SHELL': '/bin/bash', 19:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:18 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:18 INFO - 'USER': 'cltbld', 19:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:18 INFO - 'XPC_FLAGS': '0x0', 19:04:18 INFO - 'XPC_SERVICE_NAME': '0', 19:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-okhl2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-EBRpyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-1z2xaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-SId1ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6A8euc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-40QAR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-dF76tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-AsoJ1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6d3x2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:19 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-TBRu5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-S9YT2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-z_UoTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-qP_0i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-7ctc_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-fIBfnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-70DiQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:20 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-aopXz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:04:20 INFO - Running setup.py install for manifestparser 19:04:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:04:20 INFO - Running setup.py install for mozcrash 19:04:21 INFO - Running setup.py install for mozdebug 19:04:21 INFO - Running setup.py install for mozdevice 19:04:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Running setup.py install for mozfile 19:04:21 INFO - Running setup.py install for mozhttpd 19:04:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Running setup.py install for mozinfo 19:04:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Running setup.py install for mozInstall 19:04:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:04:21 INFO - Running setup.py install for mozleak 19:04:22 INFO - Running setup.py install for mozlog 19:04:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Running setup.py install for moznetwork 19:04:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Running setup.py install for mozprocess 19:04:22 INFO - Running setup.py install for mozprofile 19:04:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Running setup.py install for mozrunner 19:04:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:04:22 INFO - Running setup.py install for mozscreenshot 19:04:23 INFO - Running setup.py install for moztest 19:04:23 INFO - Running setup.py install for mozversion 19:04:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:04:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:04:23 INFO - Cleaning up... 19:04:23 INFO - Return code: 0 19:04:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:23 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 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:04:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:23 INFO - 'HOME': '/Users/cltbld', 19:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:23 INFO - 'LOGNAME': 'cltbld', 19:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:23 INFO - 'MOZ_NO_REMOTE': '1', 19:04:23 INFO - 'NO_EM_RESTART': '1', 19:04:23 INFO - 'PAGER': '/bin/cat', 19:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:23 INFO - 'PWD': '/builds/slave/test', 19:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:23 INFO - 'SHELL': '/bin/bash', 19:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:23 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:23 INFO - 'USER': 'cltbld', 19:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:23 INFO - 'XPC_FLAGS': '0x0', 19:04:23 INFO - 'XPC_SERVICE_NAME': '0', 19:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-azxEPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-gKB3rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-0QdFag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-5d9hGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ohKhbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-SEVz02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-JJzdMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-jAJKSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-e_C37o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-59fLE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-kNQKvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-9L7ECT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-iqQYOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-gtfSoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6zamC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-yMXkRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6vYgMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:04:25 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:04:25 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:04:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:28 INFO - Downloading blessings-1.6.tar.gz 19:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:04:28 INFO - Installing collected packages: blessings 19:04:28 INFO - Running setup.py install for blessings 19:04:28 INFO - Successfully installed blessings 19:04:28 INFO - Cleaning up... 19:04:28 INFO - Return code: 0 19:04:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:04:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:28 INFO - Reading from file tmpfile_stdout 19:04:28 INFO - Current package versions: 19:04:28 INFO - blessings == 1.6 19:04:28 INFO - blobuploader == 1.2.4 19:04:28 INFO - docopt == 0.6.1 19:04:28 INFO - functools32 == 3.2.3-2 19:04:28 INFO - jsonschema == 2.5.1 19:04:28 INFO - manifestparser == 1.1 19:04:28 INFO - mozInstall == 1.12 19:04:28 INFO - mozcrash == 1.0 19:04:28 INFO - mozdebug == 0.1 19:04:28 INFO - mozdevice == 0.49 19:04:28 INFO - mozfile == 1.2 19:04:28 INFO - mozhttpd == 0.7 19:04:28 INFO - mozinfo == 0.9 19:04:28 INFO - mozleak == 0.1 19:04:28 INFO - mozlog == 3.4 19:04:28 INFO - moznetwork == 0.27 19:04:28 INFO - mozprocess == 0.25 19:04:28 INFO - mozprofile == 0.28 19:04:28 INFO - mozrunner == 6.13 19:04:28 INFO - mozscreenshot == 0.1 19:04:28 INFO - mozsystemmonitor == 0.3 19:04:28 INFO - moztest == 0.8 19:04:28 INFO - mozversion == 1.4 19:04:28 INFO - psutil == 3.1.1 19:04:28 INFO - requests == 1.2.3 19:04:28 INFO - wsgiref == 0.1.2 19:04:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:28 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 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:04:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:28 INFO - 'HOME': '/Users/cltbld', 19:04:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:28 INFO - 'LOGNAME': 'cltbld', 19:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:28 INFO - 'MOZ_NO_REMOTE': '1', 19:04:28 INFO - 'NO_EM_RESTART': '1', 19:04:28 INFO - 'PAGER': '/bin/cat', 19:04:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:28 INFO - 'PWD': '/builds/slave/test', 19:04:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:28 INFO - 'SHELL': '/bin/bash', 19:04:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:28 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:28 INFO - 'USER': 'cltbld', 19:04:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:28 INFO - 'XPC_FLAGS': '0x0', 19:04:28 INFO - 'XPC_SERVICE_NAME': '0', 19:04:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:04:29 INFO - Cleaning up... 19:04:29 INFO - Return code: 0 19:04:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:04:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:29 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 0x108a79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b295b51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, '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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:29 INFO - 'HOME': '/Users/cltbld', 19:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:29 INFO - 'LOGNAME': 'cltbld', 19:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:29 INFO - 'MOZ_NO_REMOTE': '1', 19:04:29 INFO - 'NO_EM_RESTART': '1', 19:04:29 INFO - 'PAGER': '/bin/cat', 19:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:29 INFO - 'PWD': '/builds/slave/test', 19:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:29 INFO - 'SHELL': '/bin/bash', 19:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:29 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:29 INFO - 'USER': 'cltbld', 19:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:29 INFO - 'XPC_FLAGS': '0x0', 19:04:29 INFO - 'XPC_SERVICE_NAME': '0', 19:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:04:29 INFO - Cleaning up... 19:04:29 INFO - Return code: 0 19:04:29 INFO - Running post-action listener: _resource_record_post_action 19:04:29 INFO - Running post-action listener: _start_resource_monitoring 19:04:29 INFO - Starting resource monitoring. 19:04:29 INFO - [mozharness: 2017-03-21 02:04:29.326228Z] Finished create-virtualenv step (success) 19:04:29 INFO - [mozharness: 2017-03-21 02:04:29.327239Z] Running install step. 19:04:29 INFO - Running pre-action listener: _resource_record_pre_action 19:04:29 INFO - Running main action method: install 19:04:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:29 INFO - Reading from file tmpfile_stdout 19:04:29 INFO - Detecting whether we're running mozinstall >=1.0... 19:04:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:04:29 INFO - Reading from file tmpfile_stdout 19:04:29 INFO - Output received: 19:04:29 INFO - Usage: mozinstall [options] installer 19:04:29 INFO - Options: 19:04:29 INFO - -h, --help show this help message and exit 19:04:29 INFO - -d DEST, --destination=DEST 19:04:29 INFO - Directory to install application into. [default: 19:04:29 INFO - "/builds/slave/test"] 19:04:29 INFO - --app=APP Application being installed. [default: firefox] 19:04:29 INFO - mkdir: /builds/slave/test/build/application 19:04:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:04:49 INFO - Reading from file tmpfile_stdout 19:04:49 INFO - Output received: 19:04:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:04:49 INFO - Running post-action listener: _resource_record_post_action 19:04:49 INFO - [mozharness: 2017-03-21 02:04:49.904775Z] Finished install step (success) 19:04:49 INFO - [mozharness: 2017-03-21 02:04:49.904934Z] Running run-tests step. 19:04:49 INFO - Running pre-action listener: _resource_record_pre_action 19:04:49 INFO - Running main action method: run_tests 19:04:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:04:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:04:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:04:49 INFO - 2017-03-20 19:04:49.919 screenresolution[1616:7260] starting screenresolution argv=screenresolution get 19:04:49 INFO - 2017-03-20 19:04:49.922 screenresolution[1616:7260] Display 0: 1600x1200x32@60 19:04:49 INFO - 2017-03-20 19:04:49.928 screenresolution[1617:7263] starting screenresolution argv=screenresolution list 19:04:49 INFO - Available Modes on Display 0 19:04:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:04:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:04:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:04:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:04:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:04:50 INFO - Intel Iris: 19:04:50 INFO - Chipset Model: Intel Iris 19:04:50 INFO - Type: GPU 19:04:50 INFO - Bus: Built-In 19:04:50 INFO - VRAM (Dynamic, Max): 1536 MB 19:04:50 INFO - Vendor: Intel (0x8086) 19:04:50 INFO - Device ID: 0x0a2e 19:04:50 INFO - Revision ID: 0x0009 19:04:50 INFO - Displays: 19:04:50 INFO - Display: 19:04:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:04:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:04:50 INFO - Display Serial Number: 1600x1200 60 19:04:50 INFO - Main Display: Yes 19:04:50 INFO - Mirror: Off 19:04:50 INFO - Online: Yes 19:04:50 INFO - Rotation: Supported 19:04:50 INFO - Return code: 0 19:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:50 INFO - Python 2.7.3 19:04:50 INFO - Return code: 0 19:04:50 INFO - grabbing minidump binary from tooltool 19:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04: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 0x108857d30>, '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 0x10877edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10884cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04: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 19:04: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 19:04: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 19:04:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:50 INFO - Return code: 0 19:04:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:04:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:50 INFO - ENV: RUST_BACKTRACE is now 1 19:04:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:04:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0148 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TljIG9Zk9W/Render', 19:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:50 INFO - 'HOME': '/Users/cltbld', 19:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:50 INFO - 'LOGNAME': 'cltbld', 19:04:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:50 INFO - 'MOZ_NO_REMOTE': '1', 19:04:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:50 INFO - 'NO_EM_RESTART': '1', 19:04:50 INFO - 'PAGER': '/bin/cat', 19:04:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:50 INFO - 'PWD': '/builds/slave/test', 19:04:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:50 INFO - 'RUST_BACKTRACE': '1', 19:04:50 INFO - 'SHELL': '/bin/bash', 19:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UKfMJ2Twy5/Listeners', 19:04:50 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 19:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:50 INFO - 'USER': 'cltbld', 19:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:50 INFO - 'XPC_FLAGS': '0x0', 19:04:50 INFO - 'XPC_SERVICE_NAME': '0', 19:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:04:50 INFO - mozversion application_buildid: 20170320175028 19:04:50 INFO - mozversion application_changeset: f897fc6fb17f13251963aa4236e541064e9881d3 19:04:50 INFO - mozversion application_display_name: Nightly 19:04:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:50 INFO - mozversion application_name: Firefox 19:04:50 INFO - mozversion application_remotingname: firefox 19:04:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:04:50 INFO - mozversion application_vendor: Mozilla 19:04:50 INFO - mozversion application_version: 55.0a1 19:04:50 INFO - mozversion platform_buildid: 20170320175028 19:04:50 INFO - mozversion platform_changeset: f897fc6fb17f13251963aa4236e541064e9881d3 19:04:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:04:50 INFO - mozversion platform_version: 55.0a1 19:04:50 INFO - using testdate: 1490061890 19:04:50 INFO - actual date: 1490061890 19:04:50 INFO - starting webserver on 'localhost:49235' 19:04:50 INFO - SUITE-START | Running 2 tests 19:04:50 INFO - TEST-START | dromaeo_css 19:04:50 INFO - operating with platform_type : mac_ 19:04:50 INFO - Initialising browser for dromaeo_css test... 19:04:50 INFO - TEST-INFO | started process 1627 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpOi4b_y/profile http://localhost:49235/getInfo.html) 19:04:53 INFO - PID 1627 | 2017-03-20 19:04:53.548 plugin-container[1629:7438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 19:04:53 INFO - PID 1627 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:53 INFO - PID 1627 | 2017-03-20 19:04:53.551 plugin-container[1629:7438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 19:04:53 INFO - PID 1627 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:53 INFO - PID 1627 | 2017-03-20 19:04:53.551 plugin-container[1629:7438] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:04:53 INFO - PID 1627 | __metrics Screen width/height:1600/1200 19:04:53 INFO - PID 1627 | colorDepth:24 19:04:53 INFO - PID 1627 | Browser inner width/height: 1024/663 19:04:53 INFO - PID 1627 | __metrics 19:04:53 ERROR - PID 1627 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:04:54 INFO - TEST-INFO | 1627: exit 0 19:04:54 INFO - Browser initialized. 19:04:54 INFO - Running cycle 1/1 for dromaeo_css test... 19:04:54 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpOi4b_y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:57 INFO - PID 1630 | 2017-03-20 19:04:57.142 plugin-container[1631:7565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 19:04:57 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:57 INFO - PID 1630 | 2017-03-20 19:04:57.142 plugin-container[1631:7565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 19:04:57 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:57 INFO - PID 1630 | 2017-03-20 19:04:57.142 plugin-container[1631:7565] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:06 INFO - PID 1630 | RSS: Main: 242094080 19:05:06 INFO - PID 1630 | 19:08:10 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 19:08:11 INFO - PID 1630 | RSS: Main: 257957888 19:08:11 INFO - PID 1630 | 19:09:54 INFO - PID 1630 | ************************* 19:09:54 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 19:09:54 INFO - PID 1630 | 19:09:54 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:09:54 INFO - PID 1630 | Full stack: 19:09:54 INFO - PID 1630 | ************************* 19:11:14 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 19:11:14 INFO - PID 1630 | RSS: Main: 264114176 19:11:14 INFO - PID 1630 | 19:14:16 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 19:14:17 INFO - PID 1630 | RSS: Main: 275083264 19:14:17 INFO - PID 1630 | 19:17:19 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 19:17:19 INFO - PID 1630 | RSS: Main: 267857920 19:17:19 INFO - PID 1630 | 19:20:22 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 19:20:23 INFO - PID 1630 | RSS: Main: 277536768 19:20:23 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 19:23:26 INFO - PID 1630 | RSS: Main: 271376384 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | __start_tp_report 19:23:26 INFO - PID 1630 | _x_x_mozilla_page_load 19:23:26 INFO - PID 1630 | _x_x_mozilla_page_load_details 19:23:26 INFO - PID 1630 | |i|pagename|runs| 19:23:26 INFO - PID 1630 | |0;dojo.html;2972.4055188962207;3460.9307813843725;3462.7576069675133;3481;3527.259275552134;4960.925586116202;5960.8972340117;5974.55190860685;5975.790847320339;6015.759369625215;5841.503472204857;5916.171735956969;6058.242719660043;6074.939250607493;6145;4704.717716936978;5073.731567108223;5463.644863083907;5469.972650136744;5511.669299842002;5349.973250133744;5449.863753406157;5536.584756143286;5546.944530554703;5586;4895.265710143472;5183.937292854956;5404.270423492857;5427.43011983744;5460.126379779256;5415.891682166374;5426.864328391783;5433.212184233304;5457.890842183134;5473.425290344536;5383.057964856173;5387.24578559002;5390.703511306877;5400.486953739389;5424.05079111154;5842.824715258549;5896.911546326808;5911.29064512257;5971.641701497924;5978.97010514949;5688.488036076773;5870.471657550798;6244.5316601254635;6272.905906411407;6295.842603934938;6437.806865794034;6453.515986301046;6457.1282876811965;6460.515461340418;6460.644664543448;5720.971395143039;5852.824415267549;6093.66484843329;6134.2332208473945;6148.1699970504305;5706.514946229534;6113.597930965863;6418.390252925965;6543.083968244449;6629.701663425158;5721.942780572141;5990.820275391745;6212.1303017577575;6292.748290068437;6320.62076275425;6301.243850737941;6331.651759153198;6411.455026322815;6417.839554011187;6419.51853610981;6306.558540902093;6322.525810564226;6331;6363.109164716943;6388.105665206875;6203.379662033851;6304.117423560705;6312.495000399957;6320.936790632035;6327.272363678144;5095.694258344512;5113.181890897438;5171.870703232374;5202.167653175549;5227.980543793924;4617.191991401492;4643.071385722869;4653;4678.415198100238;4681.67228294023;5641.774329026875;5660.122680984454;5661.405552417019;5673.971630141906;5675.5459563234845;6165.907511387333;6251.781187658501;6275;6296.307406185316;6308.148399965946;5597.692126933058;5651.858703532363;5663.405342439067;5675.233843431167;5700.458456446631;5514.786746915672;5515.558755299567;5516.806911758069;5536.335639723259;5543.805966791143;6306.936930630635;6468.514861385509;6742.056112144303;6754.8649027020165;6760.729570817112;6282.434580887742;6284.591501552384;6308.895943209828;6309.936900630935;6324.272708638566;6600.966995164993;6635.270120286861;6666.799996000128;6666.966665166643;6667.733290668513;6257.749690012349;6282.183316168869;6300.149479820166;6325.019621958604;6333.429991300805;6256;6270.4670103039925;6284.025975973731;6327.145835312174;6345.143405640364;6243.531735119794;6263.404976527223;6268.247810262797;6286.905696414466;6313.873722525432;6136.1821086261625;6144.723487443165;6224.657643829632;6258.812235632756;6280.780172694116;4534.863954081383;5340.78636854537;6055.637481566789;6140.50424869748;6217.813465595858;5066.41736200327;5186.481351864783;5373.220882972026;6136.907946380718;6217.409346112112;6110.558759196553;6111.561351782825;6136.753076119853;6149.877002460015;6150.938490615036 19:23:26 INFO - PID 1630 | |1;ext.html;716.2837162837163;754.2306847016043;795.8686816675249;842.6376658037045;916.3356566489292;37707.24585508288;38263.85208443744;38280.61719382805;38296;38338;30152.547711784286;36499.27001459969;36748.26503469929;36827.815860920724;37671.623283767156;7545.886811697829;10543.420111893844;10765.354078755261;10766.515506802174;10795.514201860935;10728.624498142566;10835.620753273597;10836.403997780119;10866.61966831161;10868.967448092459;10806.459677016117;10845.566177352897;10847.457627118612;10880.67357979262;10931.56273749049;10821.134309255242;10888.965548272941;10986.780264394667;11001.954814292629;11023;10749.677509674722;10824.891751082467;10831.945840270828;10831.945840270828;10845.83731244024;37145.62854371449;37313;37408.62591374078;37433.876983690534;37471.43792843109;3712.5173727415263;3736.6263373662687;3742.8315725792404;3757.455169000507;3893.7079719021185;27521;27655.861720691468;28353.716462835313;28548.000819971203;28668.856655716587;3138.2781960149296;3181.9499565143446;3233.369492948876;3250.951235731466;3259.608846938359;242900;246875;307362.9999999978;355150;357769;49018.754906225244;49249.26126108111;49258.50741492539;49319.75340123277;49381.75309123431;50872;51172.74413627983;51242.23136652953;51530.742346288025;51576.22635660468;14574.562763117123;14684.26578671062;14703.485378011717;14711.896607754461;14729.484468043567;12025.939870300592;12028.398580070962;12061.155719099579;12068;12077;13755.518556850462;13821.516246931511;13894.722105557832;13918.58244252674;14044.789328160086;5197.948020519822;6094.908576371358;6106.2061931948565;6128.693565321716;6142.324344322121;1115.4144074361025;1121.43928035982;1135.381217236608;1151.4473052934638;1155.8853664116727;22654.546909062057;25903.74096259051;25927.74072259253;26250.737492625212;26289.868550657124;1217.2513903949184;1224.3082658298033;1261.8548866880426;1268.8413948256468;1275.0819410024767;13143.408546615425;13238.669033274053;14024.579262622137;15380;15391.384344626089;483990;486049;487003;487034;487437;56628.43371566314;56930.86138277211;57053;57111;57241;1356.7286542691502;1437.7627691431005;1438.6043837944649;1439.9136051836924;1450.151661278143;28349;28659.57010644884;29031.7096829029;29580.852095739385;29595.704042960155;45180.322295164944;45225;45332.8666783333;45352.77323613361;45592.54407455883;378772;441208;441727;443456;444167;24927.501449971263;25253.747462525138;30208.395832083675;30780.692193077783;32440.675593243766;32844.67155328416;37380.62619373771;38019.619803802714;38290;38690.613093869826;30815.38369232558;32144.839275804406;32646.83676581602;32694.83652581722;33018.834905826276;31611;32993.35033248376;33218.66781332156;33300.66699332976;34158.82920585381;27974.72025279721;33822.66177338293;33916;33955.66044339525;34018.489722654675;27684;29677.109686709435;30218;30274.54588181135;30593.08220753381;23270.2302705347;23381.41546461352;23518.952315783637;23691.763082368954;23749.762502374757 19:23:26 INFO - PID 1630 | |2;jquery.html;401.1375542583447;403.09304065851836;415.6446238466111;432.8203795424898;433.6920786241768;19357.935313557766;20701.96490175491;21295.25466608669;21430.357089287307;21551.892240538797;21541.030653620586;21675;21681.132754689832;22225.666615000748;22493.775062249373;6555.573887697296;6894.758683446081;6930.930690693104;6937.757178498754;6953.443724502028;5936.88126237475;6406.2953075161695;6832.624205668686;6846.794596162098;6854.451643868503;6677.766278180265;6719.462443004572;6829.6243706596115;6859.2454829968665;6878.174619045696;6829.692663830115;6835.350641689031;6902.378785909267;6905.205901321362;6919.792406227771;6383.234011918599;6563.474922006276;6647.235567909704;6659.500537459661;6662.5003124765835;23608;25433.745662543322;26250.474990500083;26527.46945061107;27043;3269.9836500817582;3326.700596946262;3403.4894765785084;3411.096059544223;3504.4392897136336;15480.690386192326;16074.357025718957;16548.93138357705;16609;16897.40859069939;1766.4435702753592;2029.4520479470461;2036.867603605761;2051.1180192517213;2070.4823794051485;150580.2470987649;157090;157628;160001.1999939993;161574.99999999884;20469.181232750525;21791.67312490314;21904;22121;22211.444713882283;33943.4908476373;34110.317793644484;34123.63505459803;34210.315793683985;34251;10213.846792298122;10405.583776649;10639.091945043087;10640.201984851166;10820.837687434694;7351.632418379059;8502.362322825813;8622;8774.341924355698;8776.736697899074;8582.742517724479;9790.75523111928;10300.742481437874;10408.635697750542;10447.477626118663;3824.6366595173413;4111.464140838876;4178.874633760931;4255.893063194033;4278.251306021496;882.6557642519423;992.5483904823288;994.786120983992;1017.5827910556636;1071.0293060768224;19364.515887102934;19600;22370.55258894813;22401.775982240295;22840.771592284196;823.3084209264247;988.5502096546054;989.8762654668167;991.1822746234385;1040.266612038511;10100.747481313027;11108.944455277833;11512.309261444172;11623.709407264885;11925.761484770255;651362;661387;676983;690978;692088;68800.6559967197;73358;110696.4465177685;141611;157249.2137539305;1198.8261702053153;1265.6899059730424;1278.105326271595;1282.8396450443881;1288.5296866643796;19019.71470427917;21412;21522;21690.34928952134;22007.889960550096;58843;59778.70110649419;59954.40045599488;60026.39973600208;61412;81992;82925;83103;83482.74775878488;85059.29881402462;11854.288742675468;11869.109816763605;12407.44166512491;12613.68465788326;12729.618111456672;13278.871295939953;13600.387982540611;13826.723465530835;14144.575662730134;14169.29153542298;11168.944155279172;11588.536458541565;11838.881611184122;12030.398480075786;12157.392130393268;11714.88285117138;11733.120015999006;11835.171537992257;11840.996301547812;12025.338606376734;11824.70438239051;11912.821307680566;12168.939155304166;12217.266963982189;12255.877441225473;11082.501287441923;12259.632211033326;12379.700131486064;12443.75112497763;12536.874631253571;9675.854862176931;11426.143039272254;11526.193166478266;11767;11789.292642441482 19:23:26 INFO - PID 1630 | |3;mootools.html;1307.8822905938464;1461.2985766831914;1555.0125670199427;1594.1550977981667;1663.7753903223065;10785.83821242682;10976.396298203616;11078.501467433975;11259.605913793008;11334;11060.22578419511;11212.159088068407;11254.774904501905;11257.437128143602;11353.43232838359;1820.171821821075;2086.760022597398;2343.4258606641397;2373.8694371809543;2386.689730335054;2308.4690521180137;2329.545738580969;2346.1319311854554;2350.9412264693433;2352;2307.9307620771406;2314.6990891184123;2318.3508617587104;2325.8139348852137;2334.673145759595;2282.4251266625974;2299.2297580310533;2300.919467818618;2301.2981040782556;2306.7693230676964;2304.0898844956314;2355.528894221163;2358.2807243790635;2361.161787565407;2369.7037870266217;11313.886861131366;11416.828747568794;11443.713907152387;11521.423928803526;11549.307041577533;5759.65442073477;5885.79399721012;5928.318243401977;5967.403259674042;5996.820095397145;3400.9829950850335;3502.667246611568;3659.835307411173;3710.9072273193115;3730.2539492101687;1137.9928763044682;1208.3233389301931;1241.3234787041085;1245.0537591430577;1781.3320004998125;14128.646783830383;14251.358688859027;15056.247187640574;15560.844391556053;15742.9212853935;4505.887352816206;4615.076984603093;4756.048790241965;4759.976200119047;4777.4267087949665;11325.093992480583;11377.943110284561;11423.600173993871;11428.542858285575;11439.656810295704;5739.311282646109;5819.010768169422;5837.708114594253;5862.736176872051;5868.618539794899;5156.63903526757;5261.579073674096;5289.6032797539565;5302.496262855023;5317.1226747586215;3301.8018918864946;3381.526586277923;3743.828579423982;3814.122751767109;3844.9038774030873;1013.839154168473;1047.4762618690654;1051.6897515233024;1053.8629978102797;1059.5179193467109;890.5013192612158;905.2350763604743;923.422860712055;946.9242460603135;958.2860768727343;5080.74596270185;5801.245838041028;6271.867565027913;6470.579412338183;6606.339366063451;728.7704373122449;846.7883029242689;961.6200751921016;963.8722694447513;978.4814048554277;3270.9509357359657;3725.1059745661387;3803.752756070902;3875.457435958968;3998.7600743955454;9634.566444510014;12930.353482326034;12989.870101299055;13039.217646941213;13071.215727056408;12910.483580656668;12924.676883077998;12957.343744532349;13002.674933126747;13278.004149688619;8722.738317850475;9779.608815647294;10020.799584008279;10041.246906481896;10048.246381521269;5814.273215848019;6551.115599394021;6682.164729408824;6717.798466046026;6748.156480439945;9603;11617;11791.646250612495;11843.52625894989;12061.577844775742;9241.630334786802;9734.464604446823;12175.330356830105;12209.023278137905;12227.938860305998;9876.308658393844;10360;10438.26932114775;10449.529771160163;10461.529231184462;9873.555689993824;10329.74175645615;10371.844422333521;10390.324628899098;10417.218708597036;8821.911780882108;8925.464472131693;9009.864852027094;9169.083091690778;9341.766455838659;8763.3427492937;8826.691065812665;8976.955115224382;9031.683891063782;9201.953990230006;7974.16271291506;8111.269985701315;8120;8125.431219814556;8133.1053584106885;5339.825238447535;5730.484256416942;5920.437558432114;6000.0999850022845;6049.092636104443;5019.498050194951;5937.109433584997;5937.910931335947;5951.226340575698;6024.156618073385 19:23:26 INFO - PID 1630 | |4;prototype.html;328.0241282185497;332.9900102996911;365.1473808656786;386.1639550373442;393.840494599687;4910.828121015765;5315.654482458637;5382.677039377635;5449.264349312836;5825.747464295176;5546.667199967995;5596.300462442195;5743.655380677163;5803.506701930331;5836.746927765003;3912.765234085964;5094.312267843849;5110.8466745997675;5121.308623335839;5147.27938088666;3710.9443358349513;4132.5040995080635;4929.447224124389;5140.820071297505;5158;4041.696872734542;4541.077745126891;5074.974625126869;5076.44159142494;5157.71632560209;5101.336826212587;5128.102582048126;5128.53843154116;5152.433232344477;5160.92258616121;4061.715679902408;4678.701515431612;4771.188897887333;4848.10310092634;4854.538818812207;4208.978955105235;5721.513671337942;5731;5740.712964351807;5747.511461525775;5236.842894713165;5915.5859089863725;5957.285125784933;5987.964092208626;5994.1608174855555;5044.54599086084;5925.466707996258;5946.732397042144;6317.93682063178;6351.015592583157;4148.336266197394;4945.406551213841;4979.551840334351;4984.177610694231;4998.4751601081725;5893.499052580493;6319.9368006320265;6522.869542609169;6638.767643132515;6692.263850976388;4290.570942905684;4293.8711838644895;5134.433997630758;5137.229415587692;5148.6910785353;4193.370994350872;4610.239310513728;4972.8508144755715;5114.5652619527755;5277.577793776489;4559.703619264737;4618.95381046192;4621.560951709582;5273.472652734696;5449.727513624303;5402.756875940592;6238.937610623926;6255.499560035186;6264.592801467981;6271.404216599416;1745.764321816564;1754.7806524184477;1785.3037315446986;1796.9191386387645;1802.8918264903884;1149.2644707387278;1302.2381906584565;1320.7688654485426;1337.4650139944101;1346.2864681718704;5522.641028333226;5532.778688852401;5542.695151766691;5558.887622703347;5577.721113944286;6336.461400780893;6354.299422745315;6358.205224346957;6375.617462952145;6385.5210859185745;5269.420363759983;5422.5119739223655;5637.774489020475;5641.492265696025;5647.915281270784;3298.65364136767;3334.866605335808;3336.866525338959;3342.331533693271;3349.4138525757894;5554.527865131428;5661.433856614306;6486.67566621667;6757.831054223586;6820.590764554044;5163.8192663256605;5534.893021395737;6348.444631065418;6383.627520083204;6712.664366781642;5390.838274851761;6470.870582588322;6751.189857217166;6755.4257888080065;6764.763233286812;5633.0705433604635;6377.202849643794;6392.808215753535;6397.584157029778;6401.93598064032;5308.6462951947005;5936.940630593639;6420.357964203729;6427.58220715652;6543.738250469928;6148.631082135086;6460.418562329413;6669.7332106715185;6739.898901516579;6745.5952642841585;5296.523312901857;5371;5520.558355331484;5681.914771278517;5700.657960522382;4880.511948805091;6178.258608966953;6249.468795152463;6532.314107018885;6540.444062254572;5701.572382071278;6078.513718902565;6220.409061139185;6321.00640494023;6335.303116657072;5712.942870571407;6308.52686048539;6310.4320611143385;6332.52506062038;6344.746210151727;5256.211568264638;5582.3859375467855;6176.227971503562;6198.442140207403;6202.658853763086;5041.6722913010535;5145.742712864267;5596.356419011866;6114.48026917717;6276.623402595859;5257.421683614876;6090.390960903874;6133.172021777182;6163.229596300463;6221.751129954751 19:23:26 INFO - PID 1630 | |5;yui.html;707.3916431868594;709.4608097845643;746.1233051164884;769.5498133591849;810.8986376702912;14936.327865246063;15852.682946341067;15882.205889705498;15894.682106357866;15931;14653.6336591585;14802.925985370059;14844.33200505977;14853.257337133155;15094.396224151022;910.4218821048648;918.8116436130578;928.1043792740005;949.7957939043105;955.813616344813;808.337163525909;940.5814412586402;950.8288508068545;954.2747511890943;966.2994329111408;844.2317491083115;991.6628346362236;992.2260636703382;993.4238141877694;997.4563862694813;980.2794945714929;986.7286496213527;990.2672022703184;990.2919412620012;990.6532713550272;728.2644529025722;730.3208016544611;741.37724311578;742.5878637356265;747.4319517166938;13528.32358382077;16633.83366166347;16664.00015999044;16736;16747;14662.486812961602;14779.911845070721;14829.48096816617;14837.554873353709;14863.405463781435;11801.586944456989;11834.940825295904;11848.46681899308;11981.820272695917;12321.137520373577;3683;3739.0278527582755;3887.00881275273;3894.9415758763357;3900.921981560353;96879.6537140366;101138;106756;110681.89318106796;113412.43293783478;11105.167112466597;11557.24877882935;11611.361375124448;11744.94127529357;12012.399380031138;24399.63400548993;24428.755712443002;24472.632910506356;24514;24534.631980520306;7505.211952744883;7796.29833314993;7925.0886148093705;8078.39412044099;8095.311898488577;4233.7883105844585;4287.164003019444;4438.245498265303;4448.110377924428;4452.265376212953;3380.797152170878;3453.9654603453646;3465.8786942457396;3473.3400653875683;3478.4260597001717;586.5805948746666;602.6664023392938;612.5314134686968;612.6416046612782;619.9907001394982;630.6752022708309;632.8354627796714;640.3884290502559;655.4035827397141;669.5547460938519;8645.95677021611;9075.319351048565;9459.24326053914;10203.846942295871;10354.068133867837;657.8421178917121;677.7898851356096;688.8667992047714;709.3403135084318;713.1442269276888;4883.560479556786;4923.90152196954;4981.37732783402;4991.226359914219;4991.750412479361;102668;106319.4684026575;107612;108154.45922770335;111224.33163502553;69115.96326055113;82663.5866820662;95712.56431153539;95888;96173;66822.33177668162;67017.66491167514;74823.25176748163;75074.49851002949;76330;10691.09125724291;10765.407902565434;10846.511906964048;10855.565777368816;11066.833997490205;44744.32883506685;46150.769246153555;47037.294440584104;48078.00862211038;48920.75539622279;43651;52380.47619523908;58860.82278354494;60632.69683651553;62708.37291627025;10721.463926803473;13613.79579306331;13670.355224518598;13961.930190348983;14076.929615351857;14737.335647732727;15018.849811502183;15080.472183473526;15179.316930737923;15554.688906222038;13374.398152082986;13693.863061369657;13699.315034248048;14009;14811.629709257355;11885.940570297093;13710.314484275947;13764.587062388144;13983.161010339412;14766;10928.890711092787;11448.855114488484;13155.276459794803;13286.402111904794;13602.803715022283;10832.620858269922;10921.563137474412;11190.216684832309;11974.281543107441;12011.219270747373;10543.57825686964;10939.726506837393;11077;11085.944570277097;11193.216474847008 19:23:26 INFO - PID 1630 | __end_tp_report 19:23:26 INFO - PID 1630 | __start_cc_report 19:23:26 INFO - PID 1630 | _x_x_mozilla_cycle_collect,431 19:23:26 INFO - PID 1630 | __end_cc_report 19:23:26 INFO - PID 1630 | __startTimestamp1490063006757__endTimestamp 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | ------- Summary: start ------- 19:23:26 INFO - PID 1630 | Number of tests: 6 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#0] dojo.html Cycles:165 Average:5842.50 Median:6093.66 stddev:667.55 (11.0%) stddev-sans-first:630.48 19:23:26 INFO - PID 1630 | Values: 2972.4 3460.9 3462.8 3481.0 3527.3 4960.9 5960.9 5974.6 5975.8 6015.8 5841.5 5916.2 6058.2 6074.9 6145.0 4704.7 5073.7 5463.6 5470.0 5511.7 5350.0 5449.9 5536.6 5546.9 5586.0 4895.3 5183.9 5404.3 5427.4 5460.1 5415.9 5426.9 5433.2 5457.9 5473.4 5383.1 5387.2 5390.7 5400.5 5424.1 5842.8 5896.9 5911.3 5971.6 5979.0 5688.5 5870.5 6244.5 6272.9 6295.8 6437.8 6453.5 6457.1 6460.5 6460.6 5721.0 5852.8 6093.7 6134.2 6148.2 5706.5 6113.6 6418.4 6543.1 6629.7 5721.9 5990.8 6212.1 6292.7 6320.6 6301.2 6331.7 6411.5 6417.8 6419.5 6306.6 6322.5 6331.0 6363.1 6388.1 6203.4 6304.1 6312.5 6320.9 6327.3 5095.7 5113.2 5171.9 5202.2 5228.0 4617.2 4643.1 4653.0 4678.4 4681.7 5641.8 5660.1 5661.4 5674.0 5675.5 6165.9 6251.8 6275.0 6296.3 6308.1 5597.7 5651.9 5663.4 5675.2 5700.5 5514.8 5515.6 5516.8 5536.3 5543.8 6306.9 6468.5 6742.1 6754.9 6760.7 6282.4 6284.6 6308.9 6309.9 6324.3 6601.0 6635.3 6666.8 6667.0 6667.7 6257.7 6282.2 6300.1 6325.0 6333.4 6256.0 6270.5 6284.0 6327.1 6345.1 6243.5 6263.4 6268.2 6286.9 6313.9 6136.2 6144.7 6224.7 6258.8 6280.8 4534.9 5340.8 6055.6 6140.5 6217.8 5066.4 5186.5 5373.2 6136.9 6217.4 6110.6 6111.6 6136.8 6149.9 6150.9 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#1] ext.html Cycles:180 Average:54380.59 Median:168978.04 stddev:110130.12 (65.2%) stddev-sans-first:110365.36 19:23:26 INFO - PID 1630 | Values: 716.3 754.2 795.9 842.6 916.3 37707.2 38263.9 38280.6 38296.0 38338.0 30152.5 36499.3 36748.3 36827.8 37671.6 7545.9 10543.4 10765.4 10766.5 10795.5 10728.6 10835.6 10836.4 10866.6 10869.0 10806.5 10845.6 10847.5 10880.7 10931.6 10821.1 10889.0 10986.8 11002.0 11023.0 10749.7 10824.9 10831.9 10831.9 10845.8 37145.6 37313.0 37408.6 37433.9 37471.4 3712.5 3736.6 3742.8 3757.5 3893.7 27521.0 27655.9 28353.7 28548.0 28668.9 3138.3 3181.9 3233.4 3251.0 3259.6 242900.0 246875.0 307363.0 355150.0 357769.0 49018.8 49249.3 49258.5 49319.8 49381.8 50872.0 51172.7 51242.2 51530.7 51576.2 14574.6 14684.3 14703.5 14711.9 14729.5 12025.9 12028.4 12061.2 12068.0 12077.0 13755.5 13821.5 13894.7 13918.6 14044.8 5197.9 6094.9 6106.2 6128.7 6142.3 1115.4 1121.4 1135.4 1151.4 1155.9 22654.5 25903.7 25927.7 26250.7 26289.9 1217.3 1224.3 1261.9 1268.8 1275.1 13143.4 13238.7 14024.6 15380.0 15391.4 483990.0 486049.0 487003.0 487034.0 487437.0 56628.4 56930.9 57053.0 57111.0 57241.0 1356.7 1437.8 1438.6 1439.9 1450.2 28349.0 28659.6 29031.7 29580.9 29595.7 45180.3 45225.0 45332.9 45352.8 45592.5 378772.0 441208.0 441727.0 443456.0 444167.0 24927.5 25253.7 30208.4 30780.7 32440.7 32844.7 37380.6 38019.6 38290.0 38690.6 30815.4 32144.8 32646.8 32694.8 33018.8 31611.0 32993.4 33218.7 33300.7 34158.8 27974.7 33822.7 33916.0 33955.7 34018.5 27684.0 29677.1 30218.0 30274.5 30593.1 23270.2 23381.4 23519.0 23691.8 23749.8 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#2] jquery.html Cycles:180 Average:40022.96 Median:22064.44 stddev:112423.62 (509.5%) stddev-sans-first:112699.63 19:23:26 INFO - PID 1630 | Values: 401.1 403.1 415.6 432.8 433.7 19357.9 20702.0 21295.3 21430.4 21551.9 21541.0 21675.0 21681.1 22225.7 22493.8 6555.6 6894.8 6930.9 6937.8 6953.4 5936.9 6406.3 6832.6 6846.8 6854.5 6677.8 6719.5 6829.6 6859.2 6878.2 6829.7 6835.4 6902.4 6905.2 6919.8 6383.2 6563.5 6647.2 6659.5 6662.5 23608.0 25433.7 26250.5 26527.5 27043.0 3270.0 3326.7 3403.5 3411.1 3504.4 15480.7 16074.4 16548.9 16609.0 16897.4 1766.4 2029.5 2036.9 2051.1 2070.5 150580.2 157090.0 157628.0 160001.2 161575.0 20469.2 21791.7 21904.0 22121.0 22211.4 33943.5 34110.3 34123.6 34210.3 34251.0 10213.8 10405.6 10639.1 10640.2 10820.8 7351.6 8502.4 8622.0 8774.3 8776.7 8582.7 9790.8 10300.7 10408.6 10447.5 3824.6 4111.5 4178.9 4255.9 4278.3 882.7 992.5 994.8 1017.6 1071.0 19364.5 19600.0 22370.6 22401.8 22840.8 823.3 988.6 989.9 991.2 1040.3 10100.7 11108.9 11512.3 11623.7 11925.8 651362.0 661387.0 676983.0 690978.0 692088.0 68800.7 73358.0 110696.4 141611.0 157249.2 1198.8 1265.7 1278.1 1282.8 1288.5 19019.7 21412.0 21522.0 21690.3 22007.9 58843.0 59778.7 59954.4 60026.4 61412.0 81992.0 82925.0 83103.0 83482.7 85059.3 11854.3 11869.1 12407.4 12613.7 12729.6 13278.9 13600.4 13826.7 14144.6 14169.3 11168.9 11588.5 11838.9 12030.4 12157.4 11714.9 11733.1 11835.2 11841.0 12025.3 11824.7 11912.8 12168.9 12217.3 12255.9 11082.5 12259.6 12379.7 12443.8 12536.9 9675.9 11426.1 11526.2 11767.0 11789.3 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#3] mootools.html Cycles:180 Average:6621.12 Median:2380.28 stddev:4121.74 (173.2%) stddev-sans-first:4113.96 19:23:26 INFO - PID 1630 | Values: 1307.9 1461.3 1555.0 1594.2 1663.8 10785.8 10976.4 11078.5 11259.6 11334.0 11060.2 11212.2 11254.8 11257.4 11353.4 1820.2 2086.8 2343.4 2373.9 2386.7 2308.5 2329.5 2346.1 2350.9 2352.0 2307.9 2314.7 2318.4 2325.8 2334.7 2282.4 2299.2 2300.9 2301.3 2306.8 2304.1 2355.5 2358.3 2361.2 2369.7 11313.9 11416.8 11443.7 11521.4 11549.3 5759.7 5885.8 5928.3 5967.4 5996.8 3401.0 3502.7 3659.8 3710.9 3730.3 1138.0 1208.3 1241.3 1245.1 1781.3 14128.6 14251.4 15056.2 15560.8 15742.9 4505.9 4615.1 4756.0 4760.0 4777.4 11325.1 11377.9 11423.6 11428.5 11439.7 5739.3 5819.0 5837.7 5862.7 5868.6 5156.6 5261.6 5289.6 5302.5 5317.1 3301.8 3381.5 3743.8 3814.1 3844.9 1013.8 1047.5 1051.7 1053.9 1059.5 890.5 905.2 923.4 946.9 958.3 5080.7 5801.2 6271.9 6470.6 6606.3 728.8 846.8 961.6 963.9 978.5 3271.0 3725.1 3803.8 3875.5 3998.8 9634.6 12930.4 12989.9 13039.2 13071.2 12910.5 12924.7 12957.3 13002.7 13278.0 8722.7 9779.6 10020.8 10041.2 10048.2 5814.3 6551.1 6682.2 6717.8 6748.2 9603.0 11617.0 11791.6 11843.5 12061.6 9241.6 9734.5 12175.3 12209.0 12227.9 9876.3 10360.0 10438.3 10449.5 10461.5 9873.6 10329.7 10371.8 10390.3 10417.2 8821.9 8925.5 9009.9 9169.1 9341.8 8763.3 8826.7 8977.0 9031.7 9202.0 7974.2 8111.3 8120.0 8125.4 8133.1 5339.8 5730.5 5920.4 6000.1 6049.1 5019.5 5937.1 5937.9 5951.2 6024.2 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#4] prototype.html Cycles:180 Average:5205.21 Median:5550.60 stddev:1459.54 (26.3%) stddev-sans-first:1416.98 19:23:26 INFO - PID 1630 | Values: 328.0 333.0 365.1 386.2 393.8 4910.8 5315.7 5382.7 5449.3 5825.7 5546.7 5596.3 5743.7 5803.5 5836.7 3912.8 5094.3 5110.8 5121.3 5147.3 3710.9 4132.5 4929.4 5140.8 5158.0 4041.7 4541.1 5075.0 5076.4 5157.7 5101.3 5128.1 5128.5 5152.4 5160.9 4061.7 4678.7 4771.2 4848.1 4854.5 4209.0 5721.5 5731.0 5740.7 5747.5 5236.8 5915.6 5957.3 5988.0 5994.2 5044.5 5925.5 5946.7 6317.9 6351.0 4148.3 4945.4 4979.6 4984.2 4998.5 5893.5 6319.9 6522.9 6638.8 6692.3 4290.6 4293.9 5134.4 5137.2 5148.7 4193.4 4610.2 4972.9 5114.6 5277.6 4559.7 4619.0 4621.6 5273.5 5449.7 5402.8 6238.9 6255.5 6264.6 6271.4 1745.8 1754.8 1785.3 1796.9 1802.9 1149.3 1302.2 1320.8 1337.5 1346.3 5522.6 5532.8 5542.7 5558.9 5577.7 6336.5 6354.3 6358.2 6375.6 6385.5 5269.4 5422.5 5637.8 5641.5 5647.9 3298.7 3334.9 3336.9 3342.3 3349.4 5554.5 5661.4 6486.7 6757.8 6820.6 5163.8 5534.9 6348.4 6383.6 6712.7 5390.8 6470.9 6751.2 6755.4 6764.8 5633.1 6377.2 6392.8 6397.6 6401.9 5308.6 5936.9 6420.4 6427.6 6543.7 6148.6 6460.4 6669.7 6739.9 6745.6 5296.5 5371.0 5520.6 5681.9 5700.7 4880.5 6178.3 6249.5 6532.3 6540.4 5701.6 6078.5 6220.4 6321.0 6335.3 5712.9 6308.5 6310.4 6332.5 6344.7 5256.2 5582.4 6176.2 6198.4 6202.7 5041.7 5145.7 5596.4 6114.5 6276.6 5257.4 6090.4 6133.2 6163.2 6221.8 19:23:26 INFO - PID 1630 | 19:23:26 INFO - PID 1630 | [#5] yui.html Cycles:180 Average:20215.94 Median:3580.71 stddev:28838.62 (805.4%) stddev-sans-first:28882.32 19:23:26 INFO - PID 1630 | Values: 707.4 709.5 746.1 769.5 810.9 14936.3 15852.7 15882.2 15894.7 15931.0 14653.6 14802.9 14844.3 14853.3 15094.4 910.4 918.8 928.1 949.8 955.8 808.3 940.6 950.8 954.3 966.3 844.2 991.7 992.2 993.4 997.5 980.3 986.7 990.3 990.3 990.7 728.3 730.3 741.4 742.6 747.4 13528.3 16633.8 16664.0 16736.0 16747.0 14662.5 14779.9 14829.5 14837.6 14863.4 11801.6 11834.9 11848.5 11981.8 12321.1 3683.0 3739.0 3887.0 3894.9 3900.9 96879.7 101138.0 106756.0 110681.9 113412.4 11105.2 11557.2 11611.4 11744.9 12012.4 24399.6 24428.8 24472.6 24514.0 24534.6 7505.2 7796.3 7925.1 8078.4 8095.3 4233.8 4287.2 4438.2 4448.1 4452.3 3380.8 3454.0 3465.9 3473.3 3478.4 586.6 602.7 612.5 612.6 620.0 630.7 632.8 640.4 655.4 669.6 8646.0 9075.3 9459.2 10203.8 10354.1 657.8 677.8 688.9 709.3 713.1 4883.6 4923.9 4981.4 4991.2 4991.8 102668.0 106319.5 107612.0 108154.5 111224.3 69116.0 82663.6 95712.6 95888.0 96173.0 66822.3 67017.7 74823.3 75074.5 76330.0 10691.1 10765.4 10846.5 10855.6 11066.8 44744.3 46150.8 47037.3 48078.0 48920.8 43651.0 52380.5 58860.8 60632.7 62708.4 10721.5 13613.8 13670.4 13961.9 14076.9 14737.3 15018.8 15080.5 15179.3 15554.7 13374.4 13693.9 13699.3 14009.0 14811.6 11885.9 13710.3 13764.6 13983.2 14766.0 10928.9 11448.9 13155.3 13286.4 13602.8 10832.6 10921.6 11190.2 11974.3 12011.2 10543.6 10939.7 11077.0 11085.9 11193.2 19:23:26 INFO - PID 1630 | -------- Summary: end -------- 19:23:26 INFO - PID 1630 | 19:23:27 INFO - TEST-INFO | 1630: exit 0 19:23:27 INFO - TEST-OK | dromaeo_css | took 1116924ms 19:23:27 INFO - TEST-START | kraken 19:23:27 INFO - operating with platform_type : mac_ 19:23:27 INFO - Initialising browser for kraken test... 19:23:27 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpcmUFEC/profile http://localhost:49235/getInfo.html) 19:23:30 INFO - PID 1641 | 2017-03-20 19:23:30.514 plugin-container[1642:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 19:23:30 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:30 INFO - PID 1641 | 2017-03-20 19:23:30.516 plugin-container[1642:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 19:23:30 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:30 INFO - PID 1641 | 2017-03-20 19:23:30.516 plugin-container[1642:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:23:30 INFO - PID 1641 | __metrics Screen width/height:1600/1200 19:23:30 INFO - PID 1641 | colorDepth:24 19:23:30 INFO - PID 1641 | Browser inner width/height: 1024/663 19:23:30 INFO - PID 1641 | __metrics 19:23:30 ERROR - PID 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:23:31 INFO - TEST-INFO | 1641: exit 0 19:23:31 INFO - Browser initialized. 19:23:31 INFO - Running cycle 1/1 for kraken test... 19:23:31 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpcmUFEC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:34 INFO - PID 1643 | 2017-03-20 19:23:34.191 plugin-container[1644:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 19:23:34 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:34 INFO - PID 1643 | 2017-03-20 19:23:34.192 plugin-container[1644:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 19:23:34 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:34 INFO - PID 1643 | 2017-03-20 19:23:34.192 plugin-container[1644:10170] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:23:43 INFO - PID 1643 | RSS: Main: 242110464 19:23:43 INFO - PID 1643 | 19:23:46 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 19:23:47 INFO - PID 1643 | RSS: Main: 250724352 19:23:47 INFO - PID 1643 | 19:23:50 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 19:23:51 INFO - PID 1643 | RSS: Main: 258715648 19:23:51 INFO - PID 1643 | 19:23:54 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 19:23:55 INFO - PID 1643 | RSS: Main: 256380928 19:23:55 INFO - PID 1643 | 19:23:56 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 19:23:57 INFO - PID 1643 | RSS: Main: 258465792 19:23:57 INFO - PID 1643 | 19:23:59 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:24:00 INFO - PID 1643 | RSS: Main: 258797568 19:24:00 INFO - PID 1643 | 19:24:03 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 19:24:03 INFO - PID 1643 | RSS: Main: 264081408 19:24:03 INFO - PID 1643 | 19:24:07 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 19:24:07 INFO - PID 1643 | RSS: Main: 264826880 19:24:07 INFO - PID 1643 | 19:24:10 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 19:24:11 INFO - PID 1643 | RSS: Main: 264630272 19:24:11 INFO - PID 1643 | 19:24:13 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:24:13 INFO - PID 1643 | RSS: Main: 263954432 19:24:13 INFO - PID 1643 | 19:24:15 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:24:16 INFO - PID 1643 | RSS: Main: 263995392 19:24:16 INFO - PID 1643 | 19:24:18 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:24:19 INFO - PID 1643 | RSS: Main: 264138752 19:24:19 INFO - PID 1643 | 19:24:22 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:24:22 INFO - PID 1643 | RSS: Main: 264495104 19:24:22 INFO - PID 1643 | 19:24:26 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:24:26 INFO - PID 1643 | RSS: Main: 264380416 19:24:26 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 19:24:28 INFO - PID 1643 | RSS: Main: 266047488 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | __start_tp_report 19:24:28 INFO - PID 1643 | _x_x_mozilla_page_load 19:24:28 INFO - PID 1643 | _x_x_mozilla_page_load_details 19:24:28 INFO - PID 1643 | |i|pagename|runs| 19:24:28 INFO - PID 1643 | |0;ai-astar;107;125;120;120;147;177;119;124;120;121 19:24:28 INFO - PID 1643 | |1;audio-beat-detection;147;125;99;175;150;147;125;115;145;144 19:24:28 INFO - PID 1643 | |2;audio-dft;206;213;208;243;248;202;201;197;196;194 19:24:28 INFO - PID 1643 | |3;audio-fft;81;58;60;85;60;60;60;56;73;62 19:24:28 INFO - PID 1643 | |4;audio-oscillator;90;72;86;71;73;70;84;71;57;95 19:24:28 INFO - PID 1643 | |5;imaging-gaussian-blur;88;94;90;88;90;88;133;129;91;91 19:24:28 INFO - PID 1643 | |6;imaging-darkroom;97;102;98;115;155;136;98;98;97;97 19:24:28 INFO - PID 1643 | |7;imaging-desaturate;142;148;92;95;127;144;92;90;91;94 19:24:28 INFO - PID 1643 | |8;json-parse-financial;66;68;65;65;67;67;67;66;68;68 19:24:28 INFO - PID 1643 | |9;json-stringify-tinderbox;57;62;59;60;65;65;59;61;58;57 19:24:28 INFO - PID 1643 | |10;stanford-crypto-aes;88;88;83;86;83;84;85;107;82;86 19:24:28 INFO - PID 1643 | |11;stanford-crypto-ccm;150;154;152;149;150;157;139;141;183;173 19:24:28 INFO - PID 1643 | |12;stanford-crypto-pbkdf2;190;197;188;202;212;190;187;192;190;194 19:24:28 INFO - PID 1643 | |13;stanford-crypto-sha256-iterative;69;68;69;68;69;71;69;67;71;71 19:24:28 INFO - PID 1643 | __end_tp_report 19:24:28 INFO - PID 1643 | __start_cc_report 19:24:28 INFO - PID 1643 | _x_x_mozilla_cycle_collect,1009 19:24:28 INFO - PID 1643 | __end_cc_report 19:24:28 INFO - PID 1643 | __startTimestamp1490063068669__endTimestamp 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | ------- Summary: start ------- 19:24:28 INFO - PID 1643 | Number of tests: 14 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#0] ai-astar Cycles:10 Average:128.00 Median:122.50 stddev:19.86 (16.2%) stddev-sans-first:19.56 19:24:28 INFO - PID 1643 | Values: 107.0 125.0 120.0 120.0 147.0 177.0 119.0 124.0 120.0 121.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:147.00 stddev:21.45 (14.6%) stddev-sans-first:22.46 19:24:28 INFO - PID 1643 | Values: 147.0 125.0 99.0 175.0 150.0 147.0 125.0 115.0 145.0 144.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#2] audio-dft Cycles:10 Average:210.80 Median:207.00 stddev:19.21 (9.3%) stddev-sans-first:20.30 19:24:28 INFO - PID 1643 | Values: 206.0 213.0 208.0 243.0 248.0 202.0 201.0 197.0 196.0 194.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.00 stddev:10.31 (16.9%) stddev-sans-first:9.28 19:24:28 INFO - PID 1643 | Values: 81.0 58.0 60.0 85.0 60.0 60.0 60.0 56.0 73.0 62.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.50 stddev:11.47 (14.6%) stddev-sans-first:11.15 19:24:28 INFO - PID 1643 | Values: 90.0 72.0 86.0 71.0 73.0 70.0 84.0 71.0 57.0 95.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:90.00 stddev:17.41 (19.3%) stddev-sans-first:18.07 19:24:28 INFO - PID 1643 | Values: 88.0 94.0 90.0 88.0 90.0 88.0 133.0 129.0 91.0 91.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:97.00 stddev:20.34 (21.0%) stddev-sans-first:21.08 19:24:28 INFO - PID 1643 | Values: 97.0 102.0 98.0 115.0 155.0 136.0 98.0 98.0 97.0 97.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#7] imaging-desaturate Cycles:10 Average:111.50 Median:91.50 stddev:25.34 (27.7%) stddev-sans-first:24.36 19:24:28 INFO - PID 1643 | Values: 142.0 148.0 92.0 95.0 127.0 144.0 92.0 90.0 91.0 94.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 19:24:28 INFO - PID 1643 | Values: 66.0 68.0 65.0 65.0 67.0 67.0 67.0 66.0 68.0 68.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:2.95 (4.9%) stddev-sans-first:2.87 19:24:28 INFO - PID 1643 | Values: 57.0 62.0 59.0 60.0 65.0 65.0 59.0 61.0 58.0 57.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.50 stddev:7.25 (8.5%) stddev-sans-first:7.69 19:24:28 INFO - PID 1643 | Values: 88.0 88.0 83.0 86.0 83.0 84.0 85.0 107.0 82.0 86.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:153.00 stddev:13.58 (8.9%) stddev-sans-first:14.29 19:24:28 INFO - PID 1643 | Values: 150.0 154.0 152.0 149.0 150.0 157.0 139.0 141.0 183.0 173.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:193.00 stddev:7.70 (4.0%) stddev-sans-first:8.02 19:24:28 INFO - PID 1643 | Values: 190.0 197.0 188.0 202.0 212.0 190.0 187.0 192.0 190.0 194.0 19:24:28 INFO - PID 1643 | 19:24:28 INFO - PID 1643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:1.40 (2.0%) stddev-sans-first:1.48 19:24:28 INFO - PID 1643 | Values: 69.0 68.0 69.0 68.0 69.0 71.0 69.0 67.0 71.0 71.0 19:24:28 INFO - PID 1643 | -------- Summary: end -------- 19:24:28 INFO - PID 1643 | 19:24:29 INFO - TEST-INFO | 1643: exit 0 19:24:29 INFO - TEST-OK | kraken | took 62178ms 19:24:29 INFO - SUITE-END | took 1179s 19:24:29 INFO - Completed test suite (00:19:39) 19:24:29 INFO - javascript benchmark 19:24:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7846.9768111244075, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.4055188962207, 3460.9307813843725, 3462.7576069675133, 3481.0, 3527.259275552134, 4960.925586116202, 5960.8972340117, 5974.55190860685, 5975.790847320339, 6015.759369625215, 5841.503472204857, 5916.171735956969, 6058.242719660043, 6074.939250607493, 6145.0, 4704.717716936978, 5073.731567108223, 5463.644863083907, 5469.972650136744, 5511.669299842002, 5349.973250133744, 5449.863753406157, 5536.584756143286, 5546.944530554703, 5586.0, 4895.265710143472, 5183.937292854956, 5404.270423492857, 5427.43011983744, 5460.126379779256, 5415.891682166374, 5426.864328391783, 5433.212184233304, 5457.890842183134, 5473.425290344536, 5383.057964856173, 5387.24578559002, 5390.703511306877, 5400.486953739389, 5424.05079111154, 5842.824715258549, 5896.911546326808, 5911.29064512257, 5971.641701497924, 5978.97010514949, 5688.488036076773, 5870.471657550798, 6244.5316601254635, 6272.905906411407, 6295.842603934938, 6437.806865794034, 6453.515986301046, 6457.1282876811965, 6460.515461340418, 6460.644664543448, 5720.971395143039, 5852.824415267549, 6093.66484843329, 6134.2332208473945, 6148.1699970504305, 5706.514946229534, 6113.597930965863, 6418.390252925965, 6543.083968244449, 6629.701663425158, 5721.942780572141, 5990.820275391745, 6212.1303017577575, 6292.748290068437, 6320.62076275425, 6301.243850737941, 6331.651759153198, 6411.455026322815, 6417.839554011187, 6419.51853610981, 6306.558540902093, 6322.525810564226, 6331.0, 6363.109164716943, 6388.105665206875, 6203.379662033851, 6304.117423560705, 6312.495000399957, 6320.936790632035, 6327.272363678144, 5095.694258344512, 5113.181890897438, 5171.870703232374, 5202.167653175549, 5227.980543793924, 4617.191991401492, 4643.071385722869, 4653.0, 4678.415198100238, 4681.67228294023, 5641.774329026875, 5660.122680984454, 5661.405552417019, 5673.971630141906, 5675.5459563234845, 6165.907511387333, 6251.781187658501, 6275.0, 6296.307406185316, 6308.148399965946, 5597.692126933058, 5651.858703532363, 5663.405342439067, 5675.233843431167, 5700.458456446631, 5514.786746915672, 5515.558755299567, 5516.806911758069, 5536.335639723259, 5543.805966791143, 6306.936930630635, 6468.514861385509, 6742.056112144303, 6754.8649027020165, 6760.729570817112, 6282.434580887742, 6284.591501552384, 6308.895943209828, 6309.936900630935, 6324.272708638566, 6600.966995164993, 6635.270120286861, 6666.799996000128, 6666.966665166643, 6667.733290668513, 6257.749690012349, 6282.183316168869, 6300.149479820166, 6325.019621958604, 6333.429991300805, 6256.0, 6270.4670103039925, 6284.025975973731, 6327.145835312174, 6345.143405640364, 6243.531735119794, 6263.404976527223, 6268.247810262797, 6286.905696414466, 6313.873722525432, 6136.1821086261625, 6144.723487443165, 6224.657643829632, 6258.812235632756, 6280.780172694116, 4534.863954081383, 5340.78636854537, 6055.637481566789, 6140.50424869748, 6217.813465595858, 5066.41736200327, 5186.481351864783, 5373.220882972026, 6136.907946380718, 6217.409346112112, 6110.558759196553, 6111.561351782825, 6136.753076119853, 6149.877002460015, 6150.938490615036], "value": 5801.377313949633, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.2837162837163, 754.2306847016043, 795.8686816675249, 842.6376658037045, 916.3356566489292, 37707.24585508288, 38263.85208443744, 38280.61719382805, 38296.0, 38338.0, 30152.547711784286, 36499.27001459969, 36748.26503469929, 36827.815860920724, 37671.623283767156, 7545.886811697829, 10543.420111893844, 10765.354078755261, 10766.515506802174, 10795.514201860935, 10728.624498142566, 10835.620753273597, 10836.403997780119, 10866.61966831161, 10868.967448092459, 10806.459677016117, 10845.566177352897, 10847.457627118612, 10880.67357979262, 10931.56273749049, 10821.134309255242, 10888.965548272941, 10986.780264394667, 11001.954814292629, 11023.0, 10749.677509674722, 10824.891751082467, 10831.945840270828, 10831.945840270828, 10845.83731244024, 37145.62854371449, 37313.0, 37408.62591374078, 37433.876983690534, 37471.43792843109, 3712.5173727415263, 3736.6263373662687, 3742.8315725792404, 3757.455169000507, 3893.7079719021185, 27521.0, 27655.861720691468, 28353.716462835313, 28548.000819971203, 28668.856655716587, 3138.2781960149296, 3181.9499565143446, 3233.369492948876, 3250.951235731466, 3259.608846938359, 242900.0, 246875.0, 307362.9999999978, 355150.0, 357769.0, 49018.754906225244, 49249.26126108111, 49258.50741492539, 49319.75340123277, 49381.75309123431, 50872.0, 51172.74413627983, 51242.23136652953, 51530.742346288025, 51576.22635660468, 14574.562763117123, 14684.26578671062, 14703.485378011717, 14711.896607754461, 14729.484468043567, 12025.939870300592, 12028.398580070962, 12061.155719099579, 12068.0, 12077.0, 13755.518556850462, 13821.516246931511, 13894.722105557832, 13918.58244252674, 14044.789328160086, 5197.948020519822, 6094.908576371358, 6106.2061931948565, 6128.693565321716, 6142.324344322121, 1115.4144074361025, 1121.43928035982, 1135.381217236608, 1151.4473052934638, 1155.8853664116727, 22654.546909062057, 25903.74096259051, 25927.74072259253, 26250.737492625212, 26289.868550657124, 1217.2513903949184, 1224.3082658298033, 1261.8548866880426, 1268.8413948256468, 1275.0819410024767, 13143.408546615425, 13238.669033274053, 14024.579262622137, 15380.0, 15391.384344626089, 483990.0, 486049.0, 487003.0, 487034.0, 487437.0, 56628.43371566314, 56930.86138277211, 57053.0, 57111.0, 57241.0, 1356.7286542691502, 1437.7627691431005, 1438.6043837944649, 1439.9136051836924, 1450.151661278143, 28349.0, 28659.57010644884, 29031.7096829029, 29580.852095739385, 29595.704042960155, 45180.322295164944, 45225.0, 45332.8666783333, 45352.77323613361, 45592.54407455883, 378772.0, 441208.0, 441727.0, 443456.0, 444167.0, 24927.501449971263, 25253.747462525138, 30208.395832083675, 30780.692193077783, 32440.675593243766, 32844.67155328416, 37380.62619373771, 38019.619803802714, 38290.0, 38690.613093869826, 30815.38369232558, 32144.839275804406, 32646.83676581602, 32694.83652581722, 33018.834905826276, 31611.0, 32993.35033248376, 33218.66781332156, 33300.66699332976, 34158.82920585381, 27974.72025279721, 33822.66177338293, 33916.0, 33955.66044339525, 34018.489722654675, 27684.0, 29677.109686709435, 30218.0, 30274.54588181135, 30593.08220753381, 23270.2302705347, 23381.41546461352, 23518.952315783637, 23691.763082368954, 23749.762502374757], "value": 18731.31800798863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.1375542583447, 403.09304065851836, 415.6446238466111, 432.8203795424898, 433.6920786241768, 19357.935313557766, 20701.96490175491, 21295.25466608669, 21430.357089287307, 21551.892240538797, 21541.030653620586, 21675.0, 21681.132754689832, 22225.666615000748, 22493.775062249373, 6555.573887697296, 6894.758683446081, 6930.930690693104, 6937.757178498754, 6953.443724502028, 5936.88126237475, 6406.2953075161695, 6832.624205668686, 6846.794596162098, 6854.451643868503, 6677.766278180265, 6719.462443004572, 6829.6243706596115, 6859.2454829968665, 6878.174619045696, 6829.692663830115, 6835.350641689031, 6902.378785909267, 6905.205901321362, 6919.792406227771, 6383.234011918599, 6563.474922006276, 6647.235567909704, 6659.500537459661, 6662.5003124765835, 23608.0, 25433.745662543322, 26250.474990500083, 26527.46945061107, 27043.0, 3269.9836500817582, 3326.700596946262, 3403.4894765785084, 3411.096059544223, 3504.4392897136336, 15480.690386192326, 16074.357025718957, 16548.93138357705, 16609.0, 16897.40859069939, 1766.4435702753592, 2029.4520479470461, 2036.867603605761, 2051.1180192517213, 2070.4823794051485, 150580.2470987649, 157090.0, 157628.0, 160001.1999939993, 161574.99999999884, 20469.181232750525, 21791.67312490314, 21904.0, 22121.0, 22211.444713882283, 33943.4908476373, 34110.317793644484, 34123.63505459803, 34210.315793683985, 34251.0, 10213.846792298122, 10405.583776649, 10639.091945043087, 10640.201984851166, 10820.837687434694, 7351.632418379059, 8502.362322825813, 8622.0, 8774.341924355698, 8776.736697899074, 8582.742517724479, 9790.75523111928, 10300.742481437874, 10408.635697750542, 10447.477626118663, 3824.6366595173413, 4111.464140838876, 4178.874633760931, 4255.893063194033, 4278.251306021496, 882.6557642519423, 992.5483904823288, 994.786120983992, 1017.5827910556636, 1071.0293060768224, 19364.515887102934, 19600.0, 22370.55258894813, 22401.775982240295, 22840.771592284196, 823.3084209264247, 988.5502096546054, 989.8762654668167, 991.1822746234385, 1040.266612038511, 10100.747481313027, 11108.944455277833, 11512.309261444172, 11623.709407264885, 11925.761484770255, 651362.0, 661387.0, 676983.0, 690978.0, 692088.0, 68800.6559967197, 73358.0, 110696.4465177685, 141611.0, 157249.2137539305, 1198.8261702053153, 1265.6899059730424, 1278.105326271595, 1282.8396450443881, 1288.5296866643796, 19019.71470427917, 21412.0, 21522.0, 21690.34928952134, 22007.889960550096, 58843.0, 59778.70110649419, 59954.40045599488, 60026.39973600208, 61412.0, 81992.0, 82925.0, 83103.0, 83482.74775878488, 85059.29881402462, 11854.288742675468, 11869.109816763605, 12407.44166512491, 12613.68465788326, 12729.618111456672, 13278.871295939953, 13600.387982540611, 13826.723465530835, 14144.575662730134, 14169.29153542298, 11168.944155279172, 11588.536458541565, 11838.881611184122, 12030.398480075786, 12157.392130393268, 11714.88285117138, 11733.120015999006, 11835.171537992257, 11840.996301547812, 12025.338606376734, 11824.70438239051, 11912.821307680566, 12168.939155304166, 12217.266963982189, 12255.877441225473, 11082.501287441923, 12259.632211033326, 12379.700131486064, 12443.75112497763, 12536.874631253571, 9675.854862176931, 11426.143039272254, 11526.193166478266, 11767.0, 11789.292642441482], "value": 11697.63532948466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.8822905938464, 1461.2985766831914, 1555.0125670199427, 1594.1550977981667, 1663.7753903223065, 10785.83821242682, 10976.396298203616, 11078.501467433975, 11259.605913793008, 11334.0, 11060.22578419511, 11212.159088068407, 11254.774904501905, 11257.437128143602, 11353.43232838359, 1820.171821821075, 2086.760022597398, 2343.4258606641397, 2373.8694371809543, 2386.689730335054, 2308.4690521180137, 2329.545738580969, 2346.1319311854554, 2350.9412264693433, 2352.0, 2307.9307620771406, 2314.6990891184123, 2318.3508617587104, 2325.8139348852137, 2334.673145759595, 2282.4251266625974, 2299.2297580310533, 2300.919467818618, 2301.2981040782556, 2306.7693230676964, 2304.0898844956314, 2355.528894221163, 2358.2807243790635, 2361.161787565407, 2369.7037870266217, 11313.886861131366, 11416.828747568794, 11443.713907152387, 11521.423928803526, 11549.307041577533, 5759.65442073477, 5885.79399721012, 5928.318243401977, 5967.403259674042, 5996.820095397145, 3400.9829950850335, 3502.667246611568, 3659.835307411173, 3710.9072273193115, 3730.2539492101687, 1137.9928763044682, 1208.3233389301931, 1241.3234787041085, 1245.0537591430577, 1781.3320004998125, 14128.646783830383, 14251.358688859027, 15056.247187640574, 15560.844391556053, 15742.9212853935, 4505.887352816206, 4615.076984603093, 4756.048790241965, 4759.976200119047, 4777.4267087949665, 11325.093992480583, 11377.943110284561, 11423.600173993871, 11428.542858285575, 11439.656810295704, 5739.311282646109, 5819.010768169422, 5837.708114594253, 5862.736176872051, 5868.618539794899, 5156.63903526757, 5261.579073674096, 5289.6032797539565, 5302.496262855023, 5317.1226747586215, 3301.8018918864946, 3381.526586277923, 3743.828579423982, 3814.122751767109, 3844.9038774030873, 1013.839154168473, 1047.4762618690654, 1051.6897515233024, 1053.8629978102797, 1059.5179193467109, 890.5013192612158, 905.2350763604743, 923.422860712055, 946.9242460603135, 958.2860768727343, 5080.74596270185, 5801.245838041028, 6271.867565027913, 6470.579412338183, 6606.339366063451, 728.7704373122449, 846.7883029242689, 961.6200751921016, 963.8722694447513, 978.4814048554277, 3270.9509357359657, 3725.1059745661387, 3803.752756070902, 3875.457435958968, 3998.7600743955454, 9634.566444510014, 12930.353482326034, 12989.870101299055, 13039.217646941213, 13071.215727056408, 12910.483580656668, 12924.676883077998, 12957.343744532349, 13002.674933126747, 13278.004149688619, 8722.738317850475, 9779.608815647294, 10020.799584008279, 10041.246906481896, 10048.246381521269, 5814.273215848019, 6551.115599394021, 6682.164729408824, 6717.798466046026, 6748.156480439945, 9603.0, 11617.0, 11791.646250612495, 11843.52625894989, 12061.577844775742, 9241.630334786802, 9734.464604446823, 12175.330356830105, 12209.023278137905, 12227.938860305998, 9876.308658393844, 10360.0, 10438.26932114775, 10449.529771160163, 10461.529231184462, 9873.555689993824, 10329.74175645615, 10371.844422333521, 10390.324628899098, 10417.218708597036, 8821.911780882108, 8925.464472131693, 9009.864852027094, 9169.083091690778, 9341.766455838659, 8763.3427492937, 8826.691065812665, 8976.955115224382, 9031.683891063782, 9201.953990230006, 7974.16271291506, 8111.269985701315, 8120.0, 8125.431219814556, 8133.1053584106885, 5339.825238447535, 5730.484256416942, 5920.437558432114, 6000.0999850022845, 6049.092636104443, 5019.498050194951, 5937.109433584997, 5937.910931335947, 5951.226340575698, 6024.156618073385], "value": 5047.315698589528, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.0241282185497, 332.9900102996911, 365.1473808656786, 386.1639550373442, 393.840494599687, 4910.828121015765, 5315.654482458637, 5382.677039377635, 5449.264349312836, 5825.747464295176, 5546.667199967995, 5596.300462442195, 5743.655380677163, 5803.506701930331, 5836.746927765003, 3912.765234085964, 5094.312267843849, 5110.8466745997675, 5121.308623335839, 5147.27938088666, 3710.9443358349513, 4132.5040995080635, 4929.447224124389, 5140.820071297505, 5158.0, 4041.696872734542, 4541.077745126891, 5074.974625126869, 5076.44159142494, 5157.71632560209, 5101.336826212587, 5128.102582048126, 5128.53843154116, 5152.433232344477, 5160.92258616121, 4061.715679902408, 4678.701515431612, 4771.188897887333, 4848.10310092634, 4854.538818812207, 4208.978955105235, 5721.513671337942, 5731.0, 5740.712964351807, 5747.511461525775, 5236.842894713165, 5915.5859089863725, 5957.285125784933, 5987.964092208626, 5994.1608174855555, 5044.54599086084, 5925.466707996258, 5946.732397042144, 6317.93682063178, 6351.015592583157, 4148.336266197394, 4945.406551213841, 4979.551840334351, 4984.177610694231, 4998.4751601081725, 5893.499052580493, 6319.9368006320265, 6522.869542609169, 6638.767643132515, 6692.263850976388, 4290.570942905684, 4293.8711838644895, 5134.433997630758, 5137.229415587692, 5148.6910785353, 4193.370994350872, 4610.239310513728, 4972.8508144755715, 5114.5652619527755, 5277.577793776489, 4559.703619264737, 4618.95381046192, 4621.560951709582, 5273.472652734696, 5449.727513624303, 5402.756875940592, 6238.937610623926, 6255.499560035186, 6264.592801467981, 6271.404216599416, 1745.764321816564, 1754.7806524184477, 1785.3037315446986, 1796.9191386387645, 1802.8918264903884, 1149.2644707387278, 1302.2381906584565, 1320.7688654485426, 1337.4650139944101, 1346.2864681718704, 5522.641028333226, 5532.778688852401, 5542.695151766691, 5558.887622703347, 5577.721113944286, 6336.461400780893, 6354.299422745315, 6358.205224346957, 6375.617462952145, 6385.5210859185745, 5269.420363759983, 5422.5119739223655, 5637.774489020475, 5641.492265696025, 5647.915281270784, 3298.65364136767, 3334.866605335808, 3336.866525338959, 3342.331533693271, 3349.4138525757894, 5554.527865131428, 5661.433856614306, 6486.67566621667, 6757.831054223586, 6820.590764554044, 5163.8192663256605, 5534.893021395737, 6348.444631065418, 6383.627520083204, 6712.664366781642, 5390.838274851761, 6470.870582588322, 6751.189857217166, 6755.4257888080065, 6764.763233286812, 5633.0705433604635, 6377.202849643794, 6392.808215753535, 6397.584157029778, 6401.93598064032, 5308.6462951947005, 5936.940630593639, 6420.357964203729, 6427.58220715652, 6543.738250469928, 6148.631082135086, 6460.418562329413, 6669.7332106715185, 6739.898901516579, 6745.5952642841585, 5296.523312901857, 5371.0, 5520.558355331484, 5681.914771278517, 5700.657960522382, 4880.511948805091, 6178.258608966953, 6249.468795152463, 6532.314107018885, 6540.444062254572, 5701.572382071278, 6078.513718902565, 6220.409061139185, 6321.00640494023, 6335.303116657072, 5712.942870571407, 6308.52686048539, 6310.4320611143385, 6332.52506062038, 6344.746210151727, 5256.211568264638, 5582.3859375467855, 6176.227971503562, 6198.442140207403, 6202.658853763086, 5041.6722913010535, 5145.742712864267, 5596.356419011866, 6114.48026917717, 6276.623402595859, 5257.421683614876, 6090.390960903874, 6133.172021777182, 6163.229596300463, 6221.751129954751], "value": 4766.417660921072, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.3916431868594, 709.4608097845643, 746.1233051164884, 769.5498133591849, 810.8986376702912, 14936.327865246063, 15852.682946341067, 15882.205889705498, 15894.682106357866, 15931.0, 14653.6336591585, 14802.925985370059, 14844.33200505977, 14853.257337133155, 15094.396224151022, 910.4218821048648, 918.8116436130578, 928.1043792740005, 949.7957939043105, 955.813616344813, 808.337163525909, 940.5814412586402, 950.8288508068545, 954.2747511890943, 966.2994329111408, 844.2317491083115, 991.6628346362236, 992.2260636703382, 993.4238141877694, 997.4563862694813, 980.2794945714929, 986.7286496213527, 990.2672022703184, 990.2919412620012, 990.6532713550272, 728.2644529025722, 730.3208016544611, 741.37724311578, 742.5878637356265, 747.4319517166938, 13528.32358382077, 16633.83366166347, 16664.00015999044, 16736.0, 16747.0, 14662.486812961602, 14779.911845070721, 14829.48096816617, 14837.554873353709, 14863.405463781435, 11801.586944456989, 11834.940825295904, 11848.46681899308, 11981.820272695917, 12321.137520373577, 3683.0, 3739.0278527582755, 3887.00881275273, 3894.9415758763357, 3900.921981560353, 96879.6537140366, 101138.0, 106756.0, 110681.89318106796, 113412.43293783478, 11105.167112466597, 11557.24877882935, 11611.361375124448, 11744.94127529357, 12012.399380031138, 24399.63400548993, 24428.755712443002, 24472.632910506356, 24514.0, 24534.631980520306, 7505.211952744883, 7796.29833314993, 7925.0886148093705, 8078.39412044099, 8095.311898488577, 4233.7883105844585, 4287.164003019444, 4438.245498265303, 4448.110377924428, 4452.265376212953, 3380.797152170878, 3453.9654603453646, 3465.8786942457396, 3473.3400653875683, 3478.4260597001717, 586.5805948746666, 602.6664023392938, 612.5314134686968, 612.6416046612782, 619.9907001394982, 630.6752022708309, 632.8354627796714, 640.3884290502559, 655.4035827397141, 669.5547460938519, 8645.95677021611, 9075.319351048565, 9459.24326053914, 10203.846942295871, 10354.068133867837, 657.8421178917121, 677.7898851356096, 688.8667992047714, 709.3403135084318, 713.1442269276888, 4883.560479556786, 4923.90152196954, 4981.37732783402, 4991.226359914219, 4991.750412479361, 102668.0, 106319.4684026575, 107612.0, 108154.45922770335, 111224.33163502553, 69115.96326055113, 82663.5866820662, 95712.56431153539, 95888.0, 96173.0, 66822.33177668162, 67017.66491167514, 74823.25176748163, 75074.49851002949, 76330.0, 10691.09125724291, 10765.407902565434, 10846.511906964048, 10855.565777368816, 11066.833997490205, 44744.32883506685, 46150.769246153555, 47037.294440584104, 48078.00862211038, 48920.75539622279, 43651.0, 52380.47619523908, 58860.82278354494, 60632.69683651553, 62708.37291627025, 10721.463926803473, 13613.79579306331, 13670.355224518598, 13961.930190348983, 14076.929615351857, 14737.335647732727, 15018.849811502183, 15080.472183473526, 15179.316930737923, 15554.688906222038, 13374.398152082986, 13693.863061369657, 13699.315034248048, 14009.0, 14811.629709257355, 11885.940570297093, 13710.314484275947, 13764.587062388144, 13983.161010339412, 14766.0, 10928.890711092787, 11448.855114488484, 13155.276459794803, 13286.402111904794, 13602.803715022283, 10832.620858269922, 10921.563137474412, 11190.216684832309, 11974.281543107441, 12011.219270747373, 10543.57825686964, 10939.726506837393, 11077.0, 11085.944570277097, 11193.216474847008], "value": 7633.557462604296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 125.0, 120.0, 120.0, 147.0, 177.0, 119.0, 124.0, 120.0, 121.0], "value": 128.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 125.0, 99.0, 175.0, 150.0, 147.0, 125.0, 115.0, 145.0, 144.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 213.0, 208.0, 243.0, 248.0, 202.0, 201.0, 197.0, 196.0, 194.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 85.0, 60.0, 60.0, 60.0, 56.0, 73.0, 62.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 86.0, 71.0, 73.0, 70.0, 84.0, 71.0, 57.0, 95.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 90.0, 88.0, 90.0, 88.0, 133.0, 129.0, 91.0, 91.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 102.0, 98.0, 115.0, 155.0, 136.0, 98.0, 98.0, 97.0, 97.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 148.0, 92.0, 95.0, 127.0, 144.0, 92.0, 90.0, 91.0, 94.0], "value": 111.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 65.0, 67.0, 67.0, 67.0, 66.0, 68.0, 68.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 59.0, 60.0, 65.0, 65.0, 59.0, 61.0, 58.0, 57.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 83.0, 86.0, 83.0, 84.0, 85.0, 107.0, 82.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 152.0, 149.0, 150.0, 157.0, 139.0, 141.0, 183.0, 173.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 197.0, 188.0, 202.0, 212.0, 190.0, 187.0, 192.0, 190.0, 194.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 69.0, 71.0, 69.0, 67.0, 71.0, 71.0], "value": 69.2, "unit": "score"}]}]} 19:24:29 INFO - Return code: 0 19:24:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:24:30 ERROR - # TBPL SUCCESS # 19:24:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:24:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:24:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:24:30 INFO - 2017-03-20 19:24:30.016 screenresolution[1648:10560] starting screenresolution argv=screenresolution get 19:24:30 INFO - 2017-03-20 19:24:30.019 screenresolution[1648:10560] Display 0: 1600x1200x32@60 19:24:30 INFO - 2017-03-20 19:24:30.025 screenresolution[1649:10563] starting screenresolution argv=screenresolution list 19:24:30 INFO - Available Modes on Display 0 19:24:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:24:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:24:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:24:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:24:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:24:30 INFO - Intel Iris: 19:24:30 INFO - Chipset Model: Intel Iris 19:24:30 INFO - Type: GPU 19:24:30 INFO - Bus: Built-In 19:24:30 INFO - VRAM (Dynamic, Max): 1536 MB 19:24:30 INFO - Vendor: Intel (0x8086) 19:24:30 INFO - Device ID: 0x0a2e 19:24:30 INFO - Revision ID: 0x0009 19:24:30 INFO - Displays: 19:24:30 INFO - Display: 19:24:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:24:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:24:30 INFO - Display Serial Number: 1600x1200 60 19:24:30 INFO - Main Display: Yes 19:24:30 INFO - Mirror: Off 19:24:30 INFO - Online: Yes 19:24:30 INFO - Rotation: Supported 19:24:30 INFO - Return code: 0 19:24:30 INFO - Running post-action listener: _resource_record_post_action 19:24:30 INFO - [mozharness: 2017-03-21 02:24:30.263468Z] Finished run-tests step (success) 19:24:30 INFO - Running post-run listener: _resource_record_post_run 19:24:30 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 37702144; Write bytes: 517055488; Read time: 387; Write time: 1786 19:24:30 INFO - TinderboxPrint: CPU usage
54.5% 19:24:30 INFO - TinderboxPrint: I/O read bytes / time
37,702,144 / 387 19:24:30 INFO - TinderboxPrint: I/O write bytes / time
517,055,488 / 1,786 19:24:30 INFO - TinderboxPrint: CPU idle
2,184.4 (45.5%) 19:24:30 INFO - TinderboxPrint: CPU system
976.1 (20.3%) 19:24:30 INFO - TinderboxPrint: CPU user
1,641.2 (34.2%) 19:24:30 INFO - TinderboxPrint: Swap in / out
247,783,424 / 0 19:24:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203844096; Write bytes: 204298240; Read time: 15721; Write time: 523 19:24:30 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35727872; Write bytes: 301956096; Read time: 361; Write time: 1189 19:24:30 INFO - Running post-run listener: _upload_blobber_files 19:24:30 INFO - Blob upload gear active. 19:24:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:24:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:24:30 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:24:30 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:24:31 INFO - (blobuploader) - INFO - Open directory for files ... 19:24:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:24:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:24:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:32 INFO - (blobuploader) - INFO - Done attempting. 19:24:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:24:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:24:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:33 INFO - (blobuploader) - INFO - Done attempting. 19:24:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:24:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:34 INFO - (blobuploader) - INFO - Done attempting. 19:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:24:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:36 INFO - (blobuploader) - INFO - Done attempting. 19:24:36 INFO - (blobuploader) - INFO - Iteration through files over. 19:24:36 INFO - Return code: 0 19:24:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:24:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:24:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f987edc1235e9970cd335f3a7560d0de602412d8ef1a2c6873ca96afdd7e7ad15d89968fc91357101cc0ff861b58766798c7533b9c69851dbe9ca7bbfd980f3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573aa293a474d9a949933b4ddc40a77dd624b3b205fc6a18b3b6e4c2715c5a90f7e93da8aa9a6e8a2df6369ef09e7330ec08ca1a43a40ece590db68473892f61", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/827c1f514d6cfca506a2fa4fd21f4f040a1f3dc2bccd9821ac87ed51633b9ce89f5545629cf29ca18a8898b7491a7ea63c957faf54192c3e063ea14a375dc9f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e76604256416e534902feaea34d12dd82f0276aee5d080ed52c79b59121bb250baace888fd7415b406f27cf44027720c61add6c75969f1193e05e8479aa0d30"} 19:24:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:24:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:24:36 INFO - Contents: 19:24:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f987edc1235e9970cd335f3a7560d0de602412d8ef1a2c6873ca96afdd7e7ad15d89968fc91357101cc0ff861b58766798c7533b9c69851dbe9ca7bbfd980f3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573aa293a474d9a949933b4ddc40a77dd624b3b205fc6a18b3b6e4c2715c5a90f7e93da8aa9a6e8a2df6369ef09e7330ec08ca1a43a40ece590db68473892f61", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/827c1f514d6cfca506a2fa4fd21f4f040a1f3dc2bccd9821ac87ed51633b9ce89f5545629cf29ca18a8898b7491a7ea63c957faf54192c3e063ea14a375dc9f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e76604256416e534902feaea34d12dd82f0276aee5d080ed52c79b59121bb250baace888fd7415b406f27cf44027720c61add6c75969f1193e05e8479aa0d30"} 19:24:36 INFO - Running post-run listener: copy_logs_to_upload_dir 19:24:36 INFO - Copying logs to upload dir... 19:24:36 INFO - mkdir: /builds/slave/test/build/upload/logs 19:24:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.637797 ========= master_lag: 0.57 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-20 19:24:36.748330) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 19:24:36.751770) ========= 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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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/Firefox/sha512/9f987edc1235e9970cd335f3a7560d0de602412d8ef1a2c6873ca96afdd7e7ad15d89968fc91357101cc0ff861b58766798c7533b9c69851dbe9ca7bbfd980f3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573aa293a474d9a949933b4ddc40a77dd624b3b205fc6a18b3b6e4c2715c5a90f7e93da8aa9a6e8a2df6369ef09e7330ec08ca1a43a40ece590db68473892f61", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/827c1f514d6cfca506a2fa4fd21f4f040a1f3dc2bccd9821ac87ed51633b9ce89f5545629cf29ca18a8898b7491a7ea63c957faf54192c3e063ea14a375dc9f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e76604256416e534902feaea34d12dd82f0276aee5d080ed52c79b59121bb250baace888fd7415b406f27cf44027720c61add6c75969f1193e05e8479aa0d30"} build_url:https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011066 build_url: 'https://queue.taskcluster.net/v1/task/C2wQWhBrTUK2J1MqnPRvYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f987edc1235e9970cd335f3a7560d0de602412d8ef1a2c6873ca96afdd7e7ad15d89968fc91357101cc0ff861b58766798c7533b9c69851dbe9ca7bbfd980f3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/573aa293a474d9a949933b4ddc40a77dd624b3b205fc6a18b3b6e4c2715c5a90f7e93da8aa9a6e8a2df6369ef09e7330ec08ca1a43a40ece590db68473892f61", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/827c1f514d6cfca506a2fa4fd21f4f040a1f3dc2bccd9821ac87ed51633b9ce89f5545629cf29ca18a8898b7491a7ea63c957faf54192c3e063ea14a375dc9f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e76604256416e534902feaea34d12dd82f0276aee5d080ed52c79b59121bb250baace888fd7415b406f27cf44027720c61add6c75969f1193e05e8479aa0d30"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 19:24:36.789782) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:24:36.790127) ========= 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.TljIG9Zk9W/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.UKfMJ2Twy5/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.005313 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 19:24:36.839131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 19:24:36.839461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 19:24:36.839794) ========= ========= Total master_lag: 0.72 =========