builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1486868100.03 results: success (0) buildid: 20170211174340 builduid: a50694ebde08436997d111e53097a940 revision: 09ef6a4f41186ea6951830d1506f741005dcc46f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.046955) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.047598) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.047878) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.075110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.075513) ========= 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.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-02-11 18:55:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-02-11 18:55:00 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.592786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.692629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.692970) ========= 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.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.025593 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:00.742324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-11 18:55:00.742670) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 09ef6a4f41186ea6951830d1506f741005dcc46f --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 09ef6a4f41186ea6951830d1506f741005dcc46f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-02-11 18:55:00,847 truncating revision to first 12 chars 2017-02-11 18:55:00,848 Setting DEBUG logging. 2017-02-11 18:55:00,848 attempt 1/10 2017-02-11 18:55:00,848 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/09ef6a4f4118?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-11 18:55:02,242 unpacking tar archive at: mozilla-central-09ef6a4f4118/testing/mozharness/ program finished with exit code 0 elapsedTime=1.679723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-11 18:55:02.446105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:02.446432) ========= script_repo_revision: 09ef6a4f41186ea6951830d1506f741005dcc46f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:02.446815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:02.447078) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-11 18:55:02.468190) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-11 18:55:02.468614) ========= /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.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:55:02 INFO - MultiFileLogger online at 20170211 18:55:02 in /builds/slave/test 18:55:02 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 18:55:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:02 INFO - {'append_to_log': False, 18:55:02 INFO - 'base_work_dir': '/builds/slave/test', 18:55:02 INFO - 'blob_upload_branch': 'Firefox', 18:55:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:02 INFO - 'branch': 'Firefox', 18:55:02 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:02 INFO - 'config_files': ('talos/mac_config.py',), 18:55:02 INFO - 'default_actions': ('clobber', 18:55:02 INFO - 'read-buildbot-config', 18:55:02 INFO - 'download-and-extract', 18:55:02 INFO - 'populate-webroot', 18:55:02 INFO - 'create-virtualenv', 18:55:02 INFO - 'install', 18:55:02 INFO - 'run-tests'), 18:55:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:02 INFO - 'download_minidump_stackwalk': True, 18:55:02 INFO - 'download_symbols': 'ondemand', 18:55:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:02 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:02 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:02 INFO - 'installer_path': 'installer.exe', 18:55:02 INFO - 'log_level': 'info', 18:55:02 INFO - 'log_name': 'talos', 18:55:02 INFO - 'log_to_console': True, 18:55:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:02 INFO - 'opt_config_files': (), 18:55:02 INFO - 'pip_index': False, 18:55:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:02 INFO - 'cmd': ('bash', 18:55:02 INFO - '-c', 18:55:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:02 INFO - 'enabled': True, 18:55:02 INFO - 'halt_on_failure': False, 18:55:02 INFO - 'name': 'check_screen_resolution'},), 18:55:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:02 INFO - 'cmd': ('bash', 18:55:02 INFO - '-c', 18:55:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:02 INFO - 'enabled': True, 18:55:02 INFO - 'halt_on_failure': False, 18:55:02 INFO - 'name': 'check_screen_resolution'},), 18:55:02 INFO - 'run_cmd_checks_enabled': True, 18:55:02 INFO - 'sps_profile': False, 18:55:02 INFO - 'sps_profile_interval': 0, 18:55:02 INFO - 'suite': 'dromaeojs-e10s', 18:55:02 INFO - 'system_bits': '32', 18:55:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:02 INFO - 'title': 't-yosemite-r7-0301', 18:55:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:02 INFO - 'use_talos_json': True, 18:55:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:02 INFO - 'work_dir': 'build'} 18:55:02 INFO - [mozharness: 2017-02-12 02:55:02.680875Z] Running clobber step. 18:55:02 INFO - Running pre-action listener: _resource_record_pre_action 18:55:02 INFO - Running main action method: clobber 18:55:02 INFO - rmtree: /builds/slave/test/build 18:55:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:05 INFO - Running post-action listener: _resource_record_post_action 18:55:05 INFO - [mozharness: 2017-02-12 02:55:05.206301Z] Finished clobber step (success) 18:55:05 INFO - [mozharness: 2017-02-12 02:55:05.206456Z] Running read-buildbot-config step. 18:55:05 INFO - Running pre-action listener: _resource_record_pre_action 18:55:05 INFO - Running main action method: read_buildbot_config 18:55:05 INFO - Using buildbot properties: 18:55:05 INFO - { 18:55:05 INFO - "project": "", 18:55:05 INFO - "product": "firefox", 18:55:05 INFO - "script_repo_revision": "production", 18:55:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:55:05 INFO - "repository": "", 18:55:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:55:05 INFO - "buildid": "20170211174340", 18:55:05 INFO - "pgo_build": "False", 18:55:05 INFO - "basedir": "/builds/slave/test", 18:55:05 INFO - "buildnumber": 87, 18:55:05 INFO - "slavename": "t-yosemite-r7-0301", 18:55:05 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:55:05 INFO - "platform": "yosemite_r7", 18:55:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:55:05 INFO - "branch": "mozilla-central", 18:55:05 INFO - "repo_path": "mozilla-central", 18:55:05 INFO - "revision": "09ef6a4f41186ea6951830d1506f741005dcc46f", 18:55:05 INFO - "stage_platform": "macosx64", 18:55:05 INFO - "builduid": "a50694ebde08436997d111e53097a940", 18:55:05 INFO - "slavebuilddir": "test" 18:55:05 INFO - } 18:55:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:55:05 INFO - Running post-action listener: _resource_record_post_action 18:55:05 INFO - [mozharness: 2017-02-12 02:55:05.209779Z] Finished read-buildbot-config step (success) 18:55:05 INFO - [mozharness: 2017-02-12 02:55:05.209930Z] Running download-and-extract step. 18:55:05 INFO - Running pre-action listener: _resource_record_pre_action 18:55:05 INFO - Running main action method: download_and_extract 18:55:05 INFO - mkdir: /builds/slave/test/build/tests 18:55: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')]} 18:55:05 INFO - trying https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:55:05 INFO - Downloading https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:55:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:55:06 INFO - Downloaded 1199 bytes. 18:55:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:55:06 INFO - Using the following test package requirements: 18:55:06 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:55:06 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:06 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:55:06 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'jsshell-mac.zip'], 18:55:06 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:55:06 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:55:06 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:55:06 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:55:06 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:55:06 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:55:06 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:06 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:55:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:06 INFO - Fetch https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:55:08 INFO - Expected file size: 51502720 18:55:08 INFO - Obtained file size: 51502720 18:55:24 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:55:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:55:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:24 INFO - Fetch https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:55:26 INFO - Expected file size: 51502720 18:55:26 INFO - Obtained file size: 51502720 18:55:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:55:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:55:41 INFO - Fetch https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:55:42 INFO - Expected file size: 14403647 18:55:42 INFO - Obtained file size: 14403647 18:55:42 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')]} 18:55:42 INFO - trying https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:55:42 INFO - Downloading https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:55:44 INFO - Downloaded 70472462 bytes. 18:55:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:55:44 INFO - mkdir: /builds/slave/test/properties 18:55:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:44 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:44 INFO - Contents: 18:55:44 INFO - build_url:https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:55:45 INFO - Running post-action listener: _resource_record_post_action 18:55:45 INFO - Running post-action listener: set_extra_try_arguments 18:55:45 INFO - [mozharness: 2017-02-12 02:55:45.588881Z] Finished download-and-extract step (success) 18:55:45 INFO - [mozharness: 2017-02-12 02:55:45.589042Z] Running populate-webroot step. 18:55:45 INFO - Running pre-action listener: _resource_record_pre_action 18:55:45 INFO - Running main action method: populate_webroot 18:55:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:55:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:55:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:55:45 INFO - '--tppagecycles', 18:55:45 INFO - '7'], 18:55:45 INFO - 'tests': ['tsvgm']}}, 18:55:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:55:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:55:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:55:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:55:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['damp', 'tps']}, 18:55:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'tests': ['damp', 'tps']}, 18:55:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['dromaeo_dom']}, 18:55:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:55:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['basic_compositor_video']}, 18:55:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:55:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'other-e10s': {'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['a11yr', 18:55:45 INFO - 'ts_paint', 18:55:45 INFO - 'tpaint', 18:55:45 INFO - 'sessionrestore', 18:55:45 INFO - 'sessionrestore_no_auto_restore', 18:55:45 INFO - 'tabpaint']}, 18:55:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['tsvgx', 18:55:45 INFO - 'tsvgr_opacity', 18:55:45 INFO - 'tart', 18:55:45 INFO - 'tscrollx', 18:55:45 INFO - 'cart', 18:55:45 INFO - 'tsvg_static']}, 18:55:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:55:45 INFO - 'tsvgr_opacity', 18:55:45 INFO - 'tart', 18:55:45 INFO - 'tscrollx', 18:55:45 INFO - 'cart', 18:55:45 INFO - 'tsvg_static']}, 18:55:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'talos_options': ['--disable-e10s'], 18:55:45 INFO - 'tests': ['tp5o']}, 18:55:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:55:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'talos_options': ['--disable-e10s', 18:55:45 INFO - '--xperf_path', 18:55:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:45 INFO - 'tests': ['tp5n']}, 18:55:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:55:45 INFO - 'talos_options': ['--xperf_path', 18:55:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:45 INFO - 'tests': ['tp5n']}}, 18:55:45 INFO - 'talos.zip': {'path': '', 18:55:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:45 INFO - Running post-action listener: _resource_record_post_action 18:55:45 INFO - [mozharness: 2017-02-12 02:55:45.607396Z] Finished populate-webroot step (success) 18:55:45 INFO - [mozharness: 2017-02-12 02:55:45.607522Z] Running create-virtualenv step. 18:55:45 INFO - Running pre-action listener: _resource_record_pre_action 18:55:45 INFO - Running main action method: create_virtualenv 18:55:45 INFO - Creating virtualenv /builds/slave/test/build/venv 18:55:45 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 18:55:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:55:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:55:45 INFO - Using real prefix '/tools/python27' 18:55:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:55:46 INFO - Installing distribute.............................................................................................................................................................................................done. 18:55:49 INFO - Installing pip.................done. 18:55:49 INFO - Return code: 0 18:55:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:55:49 INFO - Reading from file tmpfile_stdout 18:55:49 INFO - Output received: 18:55:49 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) 18:55:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:55:49 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')]} 18:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:49 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')]} 18:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:49 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:55:49 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 18:55:49 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 18:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:49 INFO - 'HOME': '/Users/cltbld', 18:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:49 INFO - 'LOGNAME': 'cltbld', 18:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:49 INFO - 'MOZ_NO_REMOTE': '1', 18:55:49 INFO - 'NO_EM_RESTART': '1', 18:55:49 INFO - 'PAGER': '/bin/cat', 18:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:49 INFO - 'PWD': '/builds/slave/test', 18:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:49 INFO - 'SHELL': '/bin/bash', 18:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:55:49 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:49 INFO - 'USER': 'cltbld', 18:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:49 INFO - 'XPC_FLAGS': '0x0', 18:55:49 INFO - 'XPC_SERVICE_NAME': '0', 18:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:50 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 18:55:50 INFO - Cleaning up... 18:55:50 INFO - Return code: 0 18:55:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:55: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')]} 18:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55: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')]} 18:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:50 INFO - 'HOME': '/Users/cltbld', 18:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:50 INFO - 'LOGNAME': 'cltbld', 18:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:50 INFO - 'MOZ_NO_REMOTE': '1', 18:55:50 INFO - 'NO_EM_RESTART': '1', 18:55:50 INFO - 'PAGER': '/bin/cat', 18:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:50 INFO - 'PWD': '/builds/slave/test', 18:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:50 INFO - 'SHELL': '/bin/bash', 18:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:55:50 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:50 INFO - 'USER': 'cltbld', 18:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:50 INFO - 'XPC_FLAGS': '0x0', 18:55:50 INFO - 'XPC_SERVICE_NAME': '0', 18:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:50 INFO - Downloading/unpacking psutil>=3.1.1 18:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:55:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:54 INFO - Installing collected packages: psutil 18:55:54 INFO - Running setup.py install for psutil 18:55:54 INFO - building 'psutil._psutil_osx' extension 18:55:54 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 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 INFO - building 'psutil._psutil_posix' extension 18:55:55 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 18:55:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:55:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:55:55 INFO - ^ 18:55:55 INFO - 1 warning generated. 18:55:55 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 18:55:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:55 INFO - Successfully installed psutil 18:55:55 INFO - Cleaning up... 18:55:55 INFO - Return code: 0 18:55:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:55:55 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')]} 18:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:55 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')]} 18:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:55 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:55:55 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 18:55:55 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 18:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:55 INFO - 'HOME': '/Users/cltbld', 18:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:55 INFO - 'LOGNAME': 'cltbld', 18:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:55 INFO - 'MOZ_NO_REMOTE': '1', 18:55:55 INFO - 'NO_EM_RESTART': '1', 18:55:55 INFO - 'PAGER': '/bin/cat', 18:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:55 INFO - 'PWD': '/builds/slave/test', 18:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:55 INFO - 'SHELL': '/bin/bash', 18:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:55:55 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:55 INFO - 'USER': 'cltbld', 18:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:55 INFO - 'XPC_FLAGS': '0x0', 18:55:55 INFO - 'XPC_SERVICE_NAME': '0', 18:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 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 18:55:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:55:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:55:58 INFO - Installing collected packages: mozsystemmonitor 18:55:58 INFO - Running setup.py install for mozsystemmonitor 18:55:58 INFO - Successfully installed mozsystemmonitor 18:55:58 INFO - Cleaning up... 18:55:58 INFO - Return code: 0 18:55:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:58 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:55:58 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 18:55:58 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 18:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:58 INFO - 'HOME': '/Users/cltbld', 18:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:58 INFO - 'LOGNAME': 'cltbld', 18:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:58 INFO - 'MOZ_NO_REMOTE': '1', 18:55:58 INFO - 'NO_EM_RESTART': '1', 18:55:58 INFO - 'PAGER': '/bin/cat', 18:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:58 INFO - 'PWD': '/builds/slave/test', 18:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:58 INFO - 'SHELL': '/bin/bash', 18:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:55:58 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:58 INFO - 'USER': 'cltbld', 18:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:58 INFO - 'XPC_FLAGS': '0x0', 18:55:58 INFO - 'XPC_SERVICE_NAME': '0', 18:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:59 INFO - Downloading/unpacking jsonschema==2.5.1 18:55:59 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 18:55:59 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 18:55:59 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 18:55:59 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 18:55:59 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 18:55:59 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 18:56:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:02 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:02 INFO - warning: no files found matching '*.txt' 18:56:02 INFO - no previously-included directories found matching 'build' 18:56:02 INFO - no previously-included directories found matching 'dist' 18:56:02 INFO - no previously-included directories found matching '.git*' 18:56:02 INFO - Installing collected packages: jsonschema, functools32 18:56:02 INFO - Running setup.py install for functools32 18:56:02 INFO - warning: no files found matching '*.txt' 18:56:02 INFO - no previously-included directories found matching 'build' 18:56:02 INFO - no previously-included directories found matching 'dist' 18:56:02 INFO - no previously-included directories found matching '.git*' 18:56:02 INFO - Successfully installed jsonschema functools32 18:56:02 INFO - Cleaning up... 18:56:02 INFO - Return code: 0 18:56:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:02 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:02 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 18:56:02 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 18:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:02 INFO - 'HOME': '/Users/cltbld', 18:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:02 INFO - 'LOGNAME': 'cltbld', 18:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:02 INFO - 'MOZ_NO_REMOTE': '1', 18:56:02 INFO - 'NO_EM_RESTART': '1', 18:56:02 INFO - 'PAGER': '/bin/cat', 18:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:02 INFO - 'PWD': '/builds/slave/test', 18:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:02 INFO - 'SHELL': '/bin/bash', 18:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:02 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:02 INFO - 'USER': 'cltbld', 18:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:02 INFO - 'XPC_FLAGS': '0x0', 18:56:02 INFO - 'XPC_SERVICE_NAME': '0', 18:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:02 INFO - Cleaning up... 18:56:02 INFO - Return code: 0 18:56:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:02 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:02 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 18:56:02 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 18:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:02 INFO - 'HOME': '/Users/cltbld', 18:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:02 INFO - 'LOGNAME': 'cltbld', 18:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:02 INFO - 'MOZ_NO_REMOTE': '1', 18:56:02 INFO - 'NO_EM_RESTART': '1', 18:56:02 INFO - 'PAGER': '/bin/cat', 18:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:02 INFO - 'PWD': '/builds/slave/test', 18:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:02 INFO - 'SHELL': '/bin/bash', 18:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:02 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:02 INFO - 'USER': 'cltbld', 18:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:02 INFO - 'XPC_FLAGS': '0x0', 18:56:02 INFO - 'XPC_SERVICE_NAME': '0', 18:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:03 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 18:56:03 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 18:56:03 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 18:56:03 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 18:56:03 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 18:56:03 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 18:56:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56: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 18:56: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 18:56: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 18:56: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 18:56: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 18:56: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 18:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:06 INFO - Downloading docopt-0.6.1.tar.gz 18:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:06 INFO - Running setup.py install for blobuploader 18:56:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:07 INFO - Running setup.py install for requests 18:56:07 INFO - Running setup.py install for docopt 18:56:07 INFO - Successfully installed blobuploader requests docopt 18:56:07 INFO - Cleaning up... 18:56:07 INFO - Return code: 0 18:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:07 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')]} 18:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:07 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')]} 18:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:07 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:07 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 18:56:07 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 18:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:07 INFO - 'HOME': '/Users/cltbld', 18:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:07 INFO - 'LOGNAME': 'cltbld', 18:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:07 INFO - 'MOZ_NO_REMOTE': '1', 18:56:07 INFO - 'NO_EM_RESTART': '1', 18:56:07 INFO - 'PAGER': '/bin/cat', 18:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:07 INFO - 'PWD': '/builds/slave/test', 18:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:07 INFO - 'SHELL': '/bin/bash', 18:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:07 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:07 INFO - 'USER': 'cltbld', 18:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:07 INFO - 'XPC_FLAGS': '0x0', 18:56:07 INFO - 'XPC_SERVICE_NAME': '0', 18:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-vYw1wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mqv6yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-SqL0oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-owzJXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-tEWmpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yUMs4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LQpRfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-788tBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:08 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-k9fAci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-KHj86e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-CnO51t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-TQvZeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-74rCfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nsyPFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2j0Omh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RKgWts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nqbTO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:09 INFO - Running setup.py install for manifestparser 18:56:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Running setup.py install for mozcrash 18:56:10 INFO - Running setup.py install for mozdebug 18:56:10 INFO - Running setup.py install for mozdevice 18:56:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Running setup.py install for mozfile 18:56:10 INFO - Running setup.py install for mozhttpd 18:56:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Running setup.py install for mozinfo 18:56:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Running setup.py install for mozInstall 18:56:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Running setup.py install for mozleak 18:56:11 INFO - Running setup.py install for mozlog 18:56:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Running setup.py install for moznetwork 18:56:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Running setup.py install for mozprocess 18:56:11 INFO - Running setup.py install for mozprofile 18:56:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:11 INFO - Running setup.py install for mozrunner 18:56:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:12 INFO - Running setup.py install for mozscreenshot 18:56:12 INFO - Running setup.py install for moztest 18:56:12 INFO - Running setup.py install for mozversion 18:56:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:12 INFO - Cleaning up... 18:56:12 INFO - Return code: 0 18:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:12 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:12 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 18:56:12 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 18:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:12 INFO - 'HOME': '/Users/cltbld', 18:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:12 INFO - 'LOGNAME': 'cltbld', 18:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:12 INFO - 'MOZ_NO_REMOTE': '1', 18:56:12 INFO - 'NO_EM_RESTART': '1', 18:56:12 INFO - 'PAGER': '/bin/cat', 18:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:12 INFO - 'PWD': '/builds/slave/test', 18:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:12 INFO - 'SHELL': '/bin/bash', 18:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:12 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:12 INFO - 'USER': 'cltbld', 18:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:12 INFO - 'XPC_FLAGS': '0x0', 18:56:12 INFO - 'XPC_SERVICE_NAME': '0', 18:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-MXIrtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:12 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)) 18:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-EGcyPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pYtmhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-hqMow0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LDy7ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-VGI0QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-rw_J46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ppXk_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-G6FHvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LQpebv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:13 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)) 18:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-0vG7OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-JaG7tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FzdNZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RiBRTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:14 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)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Nw2aLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:14 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)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-UnBXA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:14 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)) 18:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-64PX7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:17 INFO - Downloading blessings-1.6.tar.gz 18:56:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:17 INFO - Installing collected packages: blessings 18:56:17 INFO - Running setup.py install for blessings 18:56:17 INFO - Successfully installed blessings 18:56:17 INFO - Cleaning up... 18:56:17 INFO - Return code: 0 18:56:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:17 INFO - Reading from file tmpfile_stdout 18:56:17 INFO - Current package versions: 18:56:17 INFO - blessings == 1.6 18:56:17 INFO - blobuploader == 1.2.4 18:56:17 INFO - docopt == 0.6.1 18:56:17 INFO - functools32 == 3.2.3-2 18:56:17 INFO - jsonschema == 2.5.1 18:56:17 INFO - manifestparser == 1.1 18:56:17 INFO - mozInstall == 1.12 18:56:17 INFO - mozcrash == 1.0 18:56:17 INFO - mozdebug == 0.1 18:56:17 INFO - mozdevice == 0.49 18:56:17 INFO - mozfile == 1.2 18:56:17 INFO - mozhttpd == 0.7 18:56:17 INFO - mozinfo == 0.9 18:56:17 INFO - mozleak == 0.1 18:56:17 INFO - mozlog == 3.4 18:56:17 INFO - moznetwork == 0.27 18:56:17 INFO - mozprocess == 0.24 18:56:17 INFO - mozprofile == 0.28 18:56:17 INFO - mozrunner == 6.13 18:56:17 INFO - mozscreenshot == 0.1 18:56:17 INFO - mozsystemmonitor == 0.3 18:56:17 INFO - moztest == 0.8 18:56:17 INFO - mozversion == 1.4 18:56:17 INFO - psutil == 3.1.1 18:56:17 INFO - requests == 1.2.3 18:56:17 INFO - wsgiref == 0.1.2 18:56:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:17 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 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:17 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 18:56:17 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 18:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:17 INFO - 'HOME': '/Users/cltbld', 18:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:17 INFO - 'LOGNAME': 'cltbld', 18:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:17 INFO - 'MOZ_NO_REMOTE': '1', 18:56:17 INFO - 'NO_EM_RESTART': '1', 18:56:17 INFO - 'PAGER': '/bin/cat', 18:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:17 INFO - 'PWD': '/builds/slave/test', 18:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:17 INFO - 'SHELL': '/bin/bash', 18:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:17 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:17 INFO - 'USER': 'cltbld', 18:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:17 INFO - 'XPC_FLAGS': '0x0', 18:56:17 INFO - 'XPC_SERVICE_NAME': '0', 18:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:56:18 INFO - Cleaning up... 18:56:18 INFO - Return code: 0 18:56:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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')]} 18:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1060e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ad68050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, '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.mXP2jLkKZL/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.TiSGirSQbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:56:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:56:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:18 INFO - 'HOME': '/Users/cltbld', 18:56:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:18 INFO - 'LOGNAME': 'cltbld', 18:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:18 INFO - 'MOZ_NO_REMOTE': '1', 18:56:18 INFO - 'NO_EM_RESTART': '1', 18:56:18 INFO - 'PAGER': '/bin/cat', 18:56:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:18 INFO - 'PWD': '/builds/slave/test', 18:56:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:18 INFO - 'SHELL': '/bin/bash', 18:56:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:18 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:18 INFO - 'USER': 'cltbld', 18:56:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:18 INFO - 'XPC_FLAGS': '0x0', 18:56:18 INFO - 'XPC_SERVICE_NAME': '0', 18:56:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:18 INFO - Cleaning up... 18:56:18 INFO - Return code: 0 18:56:18 INFO - Running post-action listener: _resource_record_post_action 18:56:18 INFO - Running post-action listener: _start_resource_monitoring 18:56:18 INFO - Starting resource monitoring. 18:56:18 INFO - [mozharness: 2017-02-12 02:56:18.493730Z] Finished create-virtualenv step (success) 18:56:18 INFO - [mozharness: 2017-02-12 02:56:18.494699Z] Running install step. 18:56:18 INFO - Running pre-action listener: _resource_record_pre_action 18:56:18 INFO - Running main action method: install 18:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:18 INFO - Reading from file tmpfile_stdout 18:56:18 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:18 INFO - Reading from file tmpfile_stdout 18:56:18 INFO - Output received: 18:56:18 INFO - Usage: mozinstall [options] installer 18:56:18 INFO - Options: 18:56:18 INFO - -h, --help show this help message and exit 18:56:18 INFO - -d DEST, --destination=DEST 18:56:18 INFO - Directory to install application into. [default: 18:56:18 INFO - "/builds/slave/test"] 18:56:18 INFO - --app=APP Application being installed. [default: firefox] 18:56:18 INFO - mkdir: /builds/slave/test/build/application 18:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:56:40 INFO - Reading from file tmpfile_stdout 18:56:40 INFO - Output received: 18:56:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:40 INFO - Running post-action listener: _resource_record_post_action 18:56:40 INFO - [mozharness: 2017-02-12 02:56:40.742835Z] Finished install step (success) 18:56:40 INFO - [mozharness: 2017-02-12 02:56:40.742995Z] Running run-tests step. 18:56:40 INFO - Running pre-action listener: _resource_record_pre_action 18:56:40 INFO - Running main action method: run_tests 18:56:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:40 INFO - 2017-02-11 18:56:40.757 screenresolution[1636:10871] starting screenresolution argv=screenresolution get 18:56:40 INFO - 2017-02-11 18:56:40.760 screenresolution[1636:10871] Display 0: 1600x1200x32@60 18:56:40 INFO - 2017-02-11 18:56:40.766 screenresolution[1637:10873] starting screenresolution argv=screenresolution list 18:56:40 INFO - Available Modes on Display 0 18:56:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:40 INFO - Intel Iris: 18:56:40 INFO - Chipset Model: Intel Iris 18:56:40 INFO - Type: GPU 18:56:40 INFO - Bus: Built-In 18:56:40 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:40 INFO - Vendor: Intel (0x8086) 18:56:40 INFO - Device ID: 0x0a2e 18:56:40 INFO - Revision ID: 0x0009 18:56:40 INFO - Displays: 18:56:40 INFO - Display: 18:56:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:40 INFO - Display Serial Number: 1600x1200 60 18:56:40 INFO - Main Display: Yes 18:56:40 INFO - Mirror: Off 18:56:40 INFO - Online: Yes 18:56:40 INFO - Rotation: Supported 18:56:41 INFO - Return code: 0 18:56:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:41 INFO - Python 2.7.3 18:56:41 INFO - Return code: 0 18:56:41 INFO - grabbing minidump binary from tooltool 18:56:41 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')]} 18:56:41 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 0x105ebfd30>, '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 0x105de6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eb4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:41 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 18:56:41 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 18:56:41 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 18:56:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:41 INFO - Return code: 0 18:56:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:41 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/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 18:56:41 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/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --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 18:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mXP2jLkKZL/Render', 18:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:41 INFO - 'HOME': '/Users/cltbld', 18:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:41 INFO - 'LOGNAME': 'cltbld', 18:56:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:41 INFO - 'MOZ_NO_REMOTE': '1', 18:56:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:41 INFO - 'NO_EM_RESTART': '1', 18:56:41 INFO - 'PAGER': '/bin/cat', 18:56:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:41 INFO - 'PWD': '/builds/slave/test', 18:56:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:41 INFO - 'SHELL': '/bin/bash', 18:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TiSGirSQbz/Listeners', 18:56:41 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:41 INFO - 'USER': 'cltbld', 18:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:41 INFO - 'XPC_FLAGS': '0x0', 18:56:41 INFO - 'XPC_SERVICE_NAME': '0', 18:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:41 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/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 18:56:41 INFO - mozversion application_buildid: 20170211174340 18:56:41 INFO - mozversion application_changeset: 09ef6a4f41186ea6951830d1506f741005dcc46f 18:56:41 INFO - mozversion application_display_name: Nightly 18:56:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:41 INFO - mozversion application_name: Firefox 18:56:41 INFO - mozversion application_remotingname: firefox 18:56:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:41 INFO - mozversion application_vendor: Mozilla 18:56:41 INFO - mozversion application_version: 54.0a1 18:56:41 INFO - mozversion platform_buildid: 20170211174340 18:56:41 INFO - mozversion platform_changeset: 09ef6a4f41186ea6951830d1506f741005dcc46f 18:56:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:41 INFO - mozversion platform_version: 54.0a1 18:56:41 INFO - using testdate: 1486868201 18:56:41 INFO - actual date: 1486868201 18:56:41 INFO - starting webserver on 'localhost:49241' 18:56:41 INFO - SUITE-START | Running 2 tests 18:56:41 INFO - TEST-START | dromaeo_css 18:56:41 INFO - operating with platform_type : mac_ 18:56:41 INFO - Initialising browser for dromaeo_css test... 18:56:41 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpzsjswq/profile http://localhost:49241/getInfo.html) 18:56:44 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:56:44 INFO - PROCESS | 1647 | 2017-02-11 18:56:44.441 plugin-container[1649:11040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 18:56:44 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:56:44 INFO - PROCESS | 1647 | 2017-02-11 18:56:44.444 plugin-container[1649:11040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 18:56:44 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:56:44 INFO - PROCESS | 1647 | 2017-02-11 18:56:44.444 plugin-container[1649:11040] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:56:44 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:56:44 INFO - PROCESS | 1647 | colorDepth:24 18:56:44 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:56:44 INFO - PROCESS | 1647 | __metrics 18:56:44 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:56:45 INFO - TEST-INFO | 1647: exit 0 18:56:45 INFO - Browser initialized. 18:56:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:56:45 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpzsjswq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:56:47 INFO - PROCESS | 1651 | 2017-02-11 18:56:47.884 plugin-container[1652:11168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 18:56:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:56:47 INFO - PROCESS | 1651 | 2017-02-11 18:56:47.884 plugin-container[1652:11168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.CFPasteboardClient' 18:56:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:56:47 INFO - PROCESS | 1651 | 2017-02-11 18:56:47.884 plugin-container[1652:11168] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:56:57 INFO - PROCESS | 1651 | RSS: Main: 233140224 18:56:57 INFO - PROCESS | 1651 | 18:59:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 19:00:01 INFO - PROCESS | 1651 | RSS: Main: 259727360 19:00:01 INFO - PROCESS | 1651 | 19:01:45 INFO - PROCESS | 1651 | ************************* 19:01:45 INFO - PROCESS | 1651 | A coding exception was thrown and uncaught in a Task. 19:01:45 INFO - PROCESS | 1651 | 19:01:45 ERROR - PROCESS | 1651 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:01:45 INFO - PROCESS | 1651 | Full stack: 19:01:45 INFO - PROCESS | 1651 | ************************* 19:03:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 19:03:04 INFO - PROCESS | 1651 | RSS: Main: 262930432 19:03:04 INFO - PROCESS | 1651 | 19:06:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 19:06:07 INFO - PROCESS | 1651 | RSS: Main: 273690624 19:06:07 INFO - PROCESS | 1651 | 19:09:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 19:09:09 INFO - PROCESS | 1651 | RSS: Main: 266457088 19:09:09 INFO - PROCESS | 1651 | 19:12:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 19:12:13 INFO - PROCESS | 1651 | RSS: Main: 270426112 19:12:13 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 19:15:16 INFO - PROCESS | 1651 | RSS: Main: 271732736 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | __start_tp_report 19:15:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 19:15:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 19:15:16 INFO - PROCESS | 1651 | |i|pagename|runs| 19:15:16 INFO - PROCESS | 1651 | |0;dojo.html;3230.806151630901;3234.822084785337;3253.7397008239336;3303.174206448387;3304.1409233599256;5433.809816656418;5486.259354987081;5488.423715509874;5523.25436066131;5533.363663178736;4566.748828814414;5281.498257665526;5328.17413300938;5429.864253393667;5451.236826844245;3903.965449155976;4966.230234313669;5020.3724534433195;5053.267276244942;5092.694438333693;5014.623903207255;5070.721110338948;5107.7446127693465;5119.180931051014;5150.098732721777;278.73586267746833;4957.727324997124;5000.899982000358;5013.724245166514;5066.41736200338;5092.8217512387255;5106.64253502255;5127.641065125443;5146.047981123466;5159.432462429129;5067.797288108472;5091;5096.79220080145;5109.974450127763;5139.640225184238;5295.841124766263;5319.148936170232;5350.5184533392185;5364.409914909357;5553.500184983371;6102.328743838174;6110.71064601923;6129.664444256052;6130.275842464065;6137.131844484721;6140.815775526741;6169.444749972524;6170.2904166021035;6178.907316390258;6183.752649894044;5425;5500.339959204841;5649.632773869767;5711.914321285184;5762.279715035621;6132.110769107974;6141.263048434216;6165.8458538536;6166.81499555014;6173.166622505994;5999.550033747487;6051.0923361496125;6064.325559580498;6100.206973093558;6104.084387341934;5957.046872500466;5958.136070269787;6031.9396806032255;6110.572259941761;6117.96941015292;4877.6829506081995;5976.551758618121;5981.581289309794;5983.910241346383;6004.519638428916;6048.516118710492;6059.545534084873;6059.7273122709585;6060.787872424444;6071.848203794852;4851.151048566558;4867.099586576494;4869.732164730903;4877.95122048775;4918.729469879191;4247.3971590852025;4366.279563871925;4369.388285640013;4373.584509471595;4374.78126093694;5408.702521361284;5466.590005749585;5471.917921231185;5514.36584792754;5626.803061892814;6067.605605635619;6107.938920610737;6132.448079672851;6136.365905528278;6145.217886812864;5566.081596536526;5609.046462101371;5630.042892708249;5641.548676105984;5641.774329026875;5298.125809241509;5326.600504962066;5344.732763361816;5359.356877174764;5385.865353366119;6079.422454866781;6107.877842443127;6110.29731580865;6127.830981140938;6134.222293196984;6123.540734445023;6163.291221509584;6170.228721409824;6170.598911070766;6220.782272620437;5637.182608521659;5749.137629355463;6151.108089326933;6202.534809889186;6207.937920620736;5848.824535263949;5914.497267732291;6084.39156084388;6089.54328425361;6095.573309868267;6055.031195008777;6068.544859135671;6088.025915853442;6102.755889764359;6103.337669688931;5993.820185394445;6008.188894499187;6019.367966363468;6020.668863212565;6063.060225665029;5357.142857142839;5531.529819965187;5793.95708772425;5941.346451890202;5992.460678538778;5461.262729531462;5489.313835770528;5548.278723765885;5665.12190610456;5744.3394009687745;5663.461971112738;5797.985352563369;5813.534917206529;5836.766529338779;5966.224390829165;5566.554675626021;5647.011772939802;5673.035583950656;5705.201271822031;5715.2855893013375 19:15:16 INFO - PROCESS | 1651 | |1;ext.html;417.1510975165539;498.5587754836969;513.6045245161229;519.5739493615234;549.1734938916932;31952.68047319526;37353.43969840451;37765.99999999996;37846.24307513848;37860.242795144084;29618.703812961863;39408.423663053516;40280;40730;40736.59263407365;7721.845563088735;9398.718038458857;9399.201067909236;9431.717048488556;10547.05076543111;10738.711354637455;10829.783404331947;10856.620018299362;10867.456627168649;10895.727606809853;10838.132949364071;10873.021428071472;10879.12966962645;10890.782184356347;10910;10831.404272764994;10890.509927053301;10890.96535829095;10908.727281818017;10916;10789;10876.347419154876;10897.455127243686;10910.072643825206;10920.563177472892;35945.46081808775;36230.45654315187;36267;36302;36311;3661.963380366189;3686.668199862025;3705.962940370616;3707.4068149096283;3845.1348446599573;27020.1893943182;27810.860945695345;28039.85980070107;28084.876604935776;28103.859480702467;2553.770160685547;3077.969220307813;3115.454795410794;3169.904902852918;3174.0795169400844;430331;430505;434123.8293808542;434563.8271808652;437894.9999999968;45945.5405445948;47118;54523.72738136284;55017.44982550203;55033.624159395535;56563;56834;56925.43074569284;61264;61444.38555614387;14204.573862784133;14280.928595356956;14320.57038288853;14354.210518421587;14361.281935903164;9097.545122743837;10898;11039.613613523488;11041.44792760375;11046.613368532224;16906.661866762595;16914.154292285337;16950.40673576419;16986.150692465573;17101.65796684084;7501.437392195608;7548.5470871747875;7549.282818132268;7551.773446796605;7593.886091708629;715.3275920634587;786.7089177004431;788.3456730913329;819.1890028871375;832.5379414424931;29235.269118271786;29281.414371712446;29512.852435738114;29637.110886673003;29729.85135074311;989.6734077754468;1004.6985904228702;1029.6910926721953;1038.2576457832686;1169.222467059413;12307.93846030782;13714.794278085838;14629.634259143393;15179.241037948059;15375.308111135026;537988;817619;822724;830406.0878173665;837562;51299.23051154236;51405.48594514082;51982.74008629932;52094;52327;1309.620210139049;1321.5374618883313;1326.5143039795298;1335.4791631264009;1353.9255340956342;25555;25633.97464101415;27815.582766258925;28213.71786282193;29035.56446653344;44263.11473770571;44750;44814.77592612147;44851.9999999987;44940.55059449495;504379;505325;506676.46661766456;510621;510745;25118.49763004693;25483.74516254888;25526.48947021012;25648.23055308344;25750.356241094123;40150.79924600475;40696.38955415612;41209;41283.587164129174;41422.75731727933;32750;34440.483392748625;34934.82532587321;35029.47455788216;35293.82353088218;32937.83531082329;33483.4977475343;33739.83130084334;34673.30653386968;34707.47938780971;31294.843525782224;33006.834965825015;33493;33840.15399615029;33885.830570846985;26948.922043118055;29367.706322936498;30488.695113048587;30607.54088688627;30761.923332683247;19266.71099933474;22518.437039074153;22872.428189294737;22932.656010159528;23047.308580742603 19:15:16 INFO - PROCESS | 1651 | |2;jquery.html;359.90282623691604;373.33173619221594;385.8572328238552;385.86880460643374;402.4948689394809;18743.43769686908;20435;20956.685649715266;21153.47116322089;21198.046087926043;18366.632667346646;20025.599488010266;20495;20737;21097.578048439023;5589.441055894418;5724.484796368316;5755.798547050854;5853.09277062056;5953.315368732608;5033.7734801934;5701.116326969326;5790.623609465371;5829.912551311733;6434.839129021789;6443.387878151591;6450.516211284173;6450.6452145132;6463.870722585545;6478.870422591545;5300.628955973083;6343.777967771129;6421.71102300393;6430;6445.1621289232335;5588.273524441796;5680.971595141998;5725.885482290372;5733.569982251348;5739.770409183628;13057.738845223137;13203.603891883164;23519.76480235193;23950.28149155528;24316.878415607986;2484.1888221415998;2504.8747562621797;2512.6984761828517;2567.33249355169;2629.9868500657567;9630.566624501915;9725;9822.508874556315;10086.9495652522;10194.031567001197;1714.7513610526403;1773.7073382891808;1777.2446710148265;1799.236747986967;1812.4472036029006;114966;120450.99999999913;120869.58260834822;121790;121850;9115.723798668192;11350.716232094264;11705.590304339477;11717.589884354176;11732.765344693058;15084.094954302776;15161.545153645407;15306.08163510193;15331.846681533041;15336.15651139198;6817.5227734059135;7977.880331795028;8236.299914507214;8267.049289331804;8366.916330836735;6489.643069631215;6545.978884576267;7764.577223243978;7879.487833290817;8107.351411887035;3872.554656214515;4698.88252793676;4729.196036673774;4743.0988112258565;4802.303665968415;1961.9215231390585;2009.9348493547166;2014.254725751452;2015.9496012599802;2019.31343343264;960.8277900960817;963.3352986439335;967.5936106835146;982.3712823792779;999.2755252441922;20316.28892988738;20641.793582063987;20687.379378618665;20704.689429658567;20959.790402095783;955.5461155950868;957.2150836314295;977.4721650308895;989.1542730964921;990.3265779270165;10126.392416455037;10408.895911040943;10529.262951593315;10550.736231594272;10600.204984626183;619807;636419;641885;655840;659459;116732.99204095393;131539;142241;149347.25326373297;150175.2491237537;909.8999110097884;997.910188083076;1019.2457581389866;1041.796849614333;1043.968680939573;18770.812291877453;19276.22895084181;20244.392668219978;20293;20322.187112515334;48806.75596621994;48863.77840554014;58919.52701182505;59715.70142149262;60569;72527.63736181497;80193.59903200447;83558;83690.74463883169;84964;7080.575165490087;8345.874811877706;8562.828743424972;8605.440646357967;8808.075152108937;12162.574309899112;12790.488380465049;13039.413226404646;13054.347282635641;13193;11126.666200014013;11218.709848367542;11236.719082023015;11237.101031917591;11391.886081139413;10576;10599.940005999339;11247.212695111264;11588.304701717923;11610.187286889837;9129.90870091317;11124.666260012213;11522.251053681484;11541.769164616493;11688.298702077562;8373.204545568162;8486.702965396182;8610.96668399796;8623.051464338792;8639.6976105836;9882.258830587878;11230.94384528072;11470.311781293149;11494;11600.419979001184 19:15:16 INFO - PROCESS | 1651 | |3;mootools.html;1199.3403628004598;1385.5704731533226;1398.6433459467835;1400.376832309622;1418.049906562603;6917.619530925804;8116.675332986673;8352.039515455725;8400.201980811828;8416.452930559517;8254.380921430902;8387.119352467978;8413.957930210356;8416.032156302028;8423.789405264872;2233.620284551626;2261.525079733258;2278.7493375728654;2281.4296425893526;2282.2924893283052;1864.692325766247;2214.2360885494477;2223.5219427823013;2238.944026399337;2266.9433264168365;1909.3221906223298;2201.9119235230573;2222.422170235742;2236.9888150559223;2274.0449011415244;2192.583409152256;2205.481711797734;2232.743234528026;2234.2068565659283;2239.428945618873;1938.980610193894;2021.6259991901545;2178.313831143185;2260.457490202356;2265.629294276955;8110.594470276463;8306.335493160532;8322.50064996096;8380.622871970714;8397.202265784741;4413.9117217655785;5012.749362531858;5136.563392111675;5167.147420675584;5168.819091331823;3102.7899119343474;3266.1508007918114;3451.2752322012407;3518.5074089627474;3533.505309256706;1198.3948106206396;1200.237848965913;1214.0498588430112;1218.2690385768467;1247.3077442019676;10635.840462393071;10677.41274229917;10693.625723099654;10756.354618722824;10756.73108172294;4091.1613119311064;4360.454943132108;4406;4424.225760491901;4431.689781715313;8613.267872230805;8628.137186281321;8650.264727498106;8671.176238257356;8680.045195028542;4957.3059771632;4989.426215985136;4995.750212489361;4996.150654388763;5003.124453220745;3908.9609103909165;4405.691601587922;4586.220342541776;4590.632749380042;4604.861854144381;3530.3115892401247;3582.0328511301805;3589.4974703541525;3637.672609465161;3658.140337020798;1118.2284223885492;1125.966932753383;1128.7347473343757;1141.4121727310442;1142.3888219802532;674.9628770417675;687.9484038697118;698.3051863395954;702.7434986230091;712.7398499547628;4797.496262892416;4992.326035985168;5385.569154467633;5651;5734.426557344315;828.90467596226;900.3697411811627;936.2463217110292;939.1266122506134;951.8667386565886;3523.4010218262956;3756.530433695788;3776.678982286536;3790.2608991246443;3804.144067584771;8774.298056155492;8966.372353935161;9382;9674.467904265333;9752.951235243778;9706.174975127053;9872.160866326443;9883.258755593359;9885.950570247247;9895.703128906145;7505.62471876404;7700.884486732703;7812.570308632966;7836.804079898048;7865.41009424284;4726.338312636165;5471.644343117685;5639.802606908738;5780.826575202751;5810.874279261771;8971.28229741606;8984.416012959136;9088.272938165062;9124.087591240823;9216.631334746804;9101.31625649257;9291.581878815334;9346.859797103185;9386.202172815114;9400.858987115336;6604.3725846044545;6705.061291419108;6721.865562688817;6741.932580674326;6754.729810807513;7666.310032097138;7757.961210193913;7789.415793815373;7833.608319584112;7837.725679601187;5535.833924982257;7041.366277035091;7129.607871567114;7176.7129314826825;7203.891941620985;5761.683107429131;6224.595401298901;6846.4522838173825;6872.518923675295;7024.6136462493005;5716.685582293014;5795.043817770021;6265.091561723616;6385.8722825544155;6427.678616069272;4817.325574419654;4871.318015477765;4962.354893863775;4970.428400733962;5040.974795126001;4381.780910954376;4905.705657660552;4929.6549241554185;5001.299818025552;5070.569001634754 19:15:16 INFO - PROCESS | 1651 | |4;prototype.html;342.7018493910298;342.878278211235;359.08791669160337;359.4060342381537;365.2931577397734;5187.533122019017;5318.1177740249595;5492.571931297866;5506.449355064497;5528.917066243999;4168.5206201286865;4763.38076050113;5287.524122828944;5376.381716102649;5378.193271009351;4086.3053280942313;4127.442795222637;4139.958600413987;4143.917121657565;4147.7926103694845;3952.940705889414;3993.780342081199;4127.938080928789;4133.751974881502;4163.292240319153;3414.880479183229;3988.2621714982674;3994.8215276493506;4010.358342665174;4086.509618845728;3589.533360663097;4045.4943132108488;4048.0164719287486;4056.574059723716;4104.856330028465;3501.2647344057523;3502.912427189315;3504.3341765064556;3505.772124811879;3514.5606799150105;4809.422869255677;4952.984638149171;5020.824271150492;5289.471052894718;5333.973330133324;4067.9220006698247;4210.5578914213875;4426.867193984185;4429.361136379528;4695.380093867644;5455.290812194429;5665.461781130786;5700.714964251771;5766.769329226826;5789.8263052108505;3108.7357574606185;3351.078453425303;3463.1342164458883;3738.9065273368383;3825.3879379299456;4544.568266014723;5692.601517893749;5832.591718579701;6002.279726432856;6012.278526576795;3210.598675165604;3746.756460830037;3912.4522566840665;3937.4684417603257;3946.861859834892;3944.230874979409;3982.581828907923;3994.2810294146752;3994.3409337459575;4003.139325045129;4619.445666520039;4624.797552636272;4664.39362882823;4670.921439959186;4673.205555055649;5099.515546023122;5702.600817942704;5801.708325211518;5803.388475186099;5860.355360910296;1167.9474423650956;1179.3690375648987;1222.3277197541495;1236.9010479161645;1289.374653293152;775.8525880082766;811.9837603247902;824.4599787139433;854.0050840770557;882.9625190401252;4463.8617152625875;4493.955060449353;4729.43246810385;4878.292647566083;5052.545270925634;4963.354763880672;5165.457626949192;5423.620346575781;5566.582506311962;5575.135853942646;4595.9080818383445;4673.626109911199;4792.849716068118;5070.315507406527;5075.644704870623;2499.562751417942;2582.483503299348;2665.5202063628735;2907.2295841601995;2993.5060714981782;4749.762511874393;4940.1968281576965;5098.417233129717;5137.640365174477;5827.912581311284;5551.944480555143;5595.4124816894455;5777.480026797524;5785.421457854265;5849.473547380756;4926.5073492650445;5326.520613144836;5881.500072493886;5961.940380596226;5976.73104710289;5442.4557544246845;5623.803166889139;5634.74643641029;5663.941471605452;5696.629719068247;5243.89512209746;5362.946370536245;5459.716966512787;5580.832575022756;5582.409013431153;5302.761375738178;5624.521915637217;5662.943370566406;5729.598928074995;5778.595498315078;3626.673599376069;3876.2635099331037;3896.181801821649;3896.8636097736444;3941.2511622791576;4906.901861962812;5637.646964728354;5762.394948530344;5928.644281343133;5956.791512297222;4653.464851542116;5082.771275292546;5091.210862316347;5228.398734145622;5385.515303622536;5539.861503462445;5720;5758;5769.884602307847;5777.277840269966;4514.530614691366;5591.832245032656;5621.381648018796;5641.210230567806;5677.403872593318;5308.601854860824;5602.663840169603;5668.461496157858;5724.65652060861;5739.684317362585;4539.455265368177;5006.749662516932;5186.948130518646;5448.863778405572;5589.484731870593 19:15:16 INFO - PROCESS | 1651 | |5;yui.html;652.4910569755592;662.2748090840528;740.9700516282369;788.3338578900829;791.0812764230058;13683.110597811148;14723.337449814771;14751.557453276391;14791.334389952452;14803.33384997675;13091.607251782461;13421.396037178327;13461.259630720306;13533.188008719484;13767.311634418289;871.0679572857046;881.8412685716569;885.8184072265202;890.3189060368823;892.0366004714892;830.4602008694336;872.4285592936636;873.5763154869883;876.1063715010686;877.7147427086222;922.2253307221932;923.7736754495124;924.8335299646061;925.9861102083474;926.2543652359848;761.1589193940699;912.9589168487435;918.0498184379202;920.2913756407562;922.0226559846551;603.7916918663054;648.9837754056139;680.1124532485154;687.3058211206717;687.6492988575812;13878.514252001232;14053.156810591398;14064.156150630995;14068.015238933278;15210.239488025665;13556.389962451614;13621.659458513517;13636.318184090756;13753.243571603558;13848.693505450572;10701.197410194267;11158.051565616932;11167.299829769825;11228;11316.773664526745;4236.068065025689;4279.679024073207;4291.120320334324;4308.590683885026;4437.378766972594;89564.92740494403;94780.99999999932;95549;96398;97347;10266.6406675766;10434.113100386401;10434.739131521772;10468.52891619879;10508.737281567868;20497.385078447373;20629.690554641693;20639.277625283343;20654.483637909172;20705.757654540776;7215.350618444365;7355.632218389059;7409.073865766779;7466.402687785073;7573.545587264782;3994.6750961025236;4069.5116586009867;4094.5700701426517;4103.8974025649595;4114.979425102915;3431.365197438432;3440.587129544421;3506.4389697648253;3519.7360197985254;3522.647735226457;664.0537234440999;672.7544446277171;673.1148539670318;714.1786945013276;731.6707481633287;613.2702084519408;634.5589815078476;663.0317549466439;679.2198391686683;695.6139342664768;9348.205402540723;9777.217822574177;9932.404055756677;9944.453055081894;10016.148627366756;628.3936001758325;650.773292343935;661.7459913463958;678.3386198456564;682.4710849091895;4175.749455032707;4772.427308722976;5403.891922161535;5405.70268635221;5583.246261754611;74217.62891185509;84721;85229.57385213158;88313;88803.55598222096;66533.94916886883;82564.17435825685;83691;85173;87292;68106.65946670334;69558.9132326031;71054;71964.92052619215;73202.26797731954;7102.538335008208;7878.56667883249;8375.62309696053;9510;10531.262811603114;43030.78484607557;44133.77933110314;46280;46354.76822615865;47292;54021;57996.55008624649;61349.38650613615;61715.13706197904;62050.37949620446;12591.24452532851;12683.492660293854;12897.226166430044;13015;13547.96780482896;12871.485140594272;13900.056865229471;14010.859891400956;14494.420223190955;14839.703205936037;13042.478300867859;13076.934615326862;13169.53906613264;13203.273819939612;13356.732865342445;13103.672408189872;13331.600051998455;13590.86409135896;13870.4451821926;13971.441142354191;12457.1902826316;12640.43118059708;13035.674108147372;13079.934600326937;13784.655383615489;9462.053794620759;10585.788284234426;10645.787084258116;10916.7270818227;11030.558777648805;9287.62849486013;9574.234061275221;10581.841272380767;10639.680809575726;10672.679819605424 19:15:16 INFO - PROCESS | 1651 | __end_tp_report 19:15:16 INFO - PROCESS | 1651 | __start_cc_report 19:15:16 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,418 19:15:16 INFO - PROCESS | 1651 | __end_cc_report 19:15:16 INFO - PROCESS | 1651 | __startTimestamp1486869316319__endTimestamp 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | ------- Summary: start ------- 19:15:16 INFO - PROCESS | 1651 | Number of tests: 6 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5529.74 Median:5663.46 stddev:758.80 (13.4%) stddev-sans-first:739.38 19:15:16 INFO - PROCESS | 1651 | Values: 3230.8 3234.8 3253.7 3303.2 3304.1 5433.8 5486.3 5488.4 5523.3 5533.4 4566.7 5281.5 5328.2 5429.9 5451.2 3904.0 4966.2 5020.4 5053.3 5092.7 5014.6 5070.7 5107.7 5119.2 5150.1 278.7 4957.7 5000.9 5013.7 5066.4 5092.8 5106.6 5127.6 5146.0 5159.4 5067.8 5091.0 5096.8 5110.0 5139.6 5295.8 5319.1 5350.5 5364.4 5553.5 6102.3 6110.7 6129.7 6130.3 6137.1 6140.8 6169.4 6170.3 6178.9 6183.8 5425.0 5500.3 5649.6 5711.9 5762.3 6132.1 6141.3 6165.8 6166.8 6173.2 5999.6 6051.1 6064.3 6100.2 6104.1 5957.0 5958.1 6031.9 6110.6 6118.0 4877.7 5976.6 5981.6 5983.9 6004.5 6048.5 6059.5 6059.7 6060.8 6071.8 4851.2 4867.1 4869.7 4878.0 4918.7 4247.4 4366.3 4369.4 4373.6 4374.8 5408.7 5466.6 5471.9 5514.4 5626.8 6067.6 6107.9 6132.4 6136.4 6145.2 5566.1 5609.0 5630.0 5641.5 5641.8 5298.1 5326.6 5344.7 5359.4 5385.9 6079.4 6107.9 6110.3 6127.8 6134.2 6123.5 6163.3 6170.2 6170.6 6220.8 5637.2 5749.1 6151.1 6202.5 6207.9 5848.8 5914.5 6084.4 6089.5 6095.6 6055.0 6068.5 6088.0 6102.8 6103.3 5993.8 6008.2 6019.4 6020.7 6063.1 5357.1 5531.5 5794.0 5941.3 5992.5 5461.3 5489.3 5548.3 5665.1 5744.3 5663.5 5798.0 5813.5 5836.8 5966.2 5566.6 5647.0 5673.0 5705.2 5715.3 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:68306.53 Median:33488.25 stddev:159314.42 (475.7%) stddev-sans-first:159679.80 19:15:16 INFO - PROCESS | 1651 | Values: 417.2 498.6 513.6 519.6 549.2 31952.7 37353.4 37766.0 37846.2 37860.2 29618.7 39408.4 40280.0 40730.0 40736.6 7721.8 9398.7 9399.2 9431.7 10547.1 10738.7 10829.8 10856.6 10867.5 10895.7 10838.1 10873.0 10879.1 10890.8 10910.0 10831.4 10890.5 10891.0 10908.7 10916.0 10789.0 10876.3 10897.5 10910.1 10920.6 35945.5 36230.5 36267.0 36302.0 36311.0 3662.0 3686.7 3706.0 3707.4 3845.1 27020.2 27810.9 28039.9 28084.9 28103.9 2553.8 3078.0 3115.5 3169.9 3174.1 430331.0 430505.0 434123.8 434563.8 437895.0 45945.5 47118.0 54523.7 55017.4 55033.6 56563.0 56834.0 56925.4 61264.0 61444.4 14204.6 14280.9 14320.6 14354.2 14361.3 9097.5 10898.0 11039.6 11041.4 11046.6 16906.7 16914.2 16950.4 16986.2 17101.7 7501.4 7548.5 7549.3 7551.8 7593.9 715.3 786.7 788.3 819.2 832.5 29235.3 29281.4 29512.9 29637.1 29729.9 989.7 1004.7 1029.7 1038.3 1169.2 12307.9 13714.8 14629.6 15179.2 15375.3 537988.0 817619.0 822724.0 830406.1 837562.0 51299.2 51405.5 51982.7 52094.0 52327.0 1309.6 1321.5 1326.5 1335.5 1353.9 25555.0 25634.0 27815.6 28213.7 29035.6 44263.1 44750.0 44814.8 44852.0 44940.6 504379.0 505325.0 506676.5 510621.0 510745.0 25118.5 25483.7 25526.5 25648.2 25750.4 40150.8 40696.4 41209.0 41283.6 41422.8 32750.0 34440.5 34934.8 35029.5 35293.8 32937.8 33483.5 33739.8 34673.3 34707.5 31294.8 33006.8 33493.0 33840.2 33885.8 26948.9 29367.7 30488.7 30607.5 30761.9 19266.7 22518.4 22872.4 22932.7 23047.3 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:36611.04 Median:2598.66 stddev:107281.49 (4128.3%) stddev-sans-first:107547.91 19:15:16 INFO - PROCESS | 1651 | Values: 359.9 373.3 385.9 385.9 402.5 18743.4 20435.0 20956.7 21153.5 21198.0 18366.6 20025.6 20495.0 20737.0 21097.6 5589.4 5724.5 5755.8 5853.1 5953.3 5033.8 5701.1 5790.6 5829.9 6434.8 6443.4 6450.5 6450.6 6463.9 6478.9 5300.6 6343.8 6421.7 6430.0 6445.2 5588.3 5681.0 5725.9 5733.6 5739.8 13057.7 13203.6 23519.8 23950.3 24316.9 2484.2 2504.9 2512.7 2567.3 2630.0 9630.6 9725.0 9822.5 10086.9 10194.0 1714.8 1773.7 1777.2 1799.2 1812.4 114966.0 120451.0 120869.6 121790.0 121850.0 9115.7 11350.7 11705.6 11717.6 11732.8 15084.1 15161.5 15306.1 15331.8 15336.2 6817.5 7977.9 8236.3 8267.0 8366.9 6489.6 6546.0 7764.6 7879.5 8107.4 3872.6 4698.9 4729.2 4743.1 4802.3 1961.9 2009.9 2014.3 2015.9 2019.3 960.8 963.3 967.6 982.4 999.3 20316.3 20641.8 20687.4 20704.7 20959.8 955.5 957.2 977.5 989.2 990.3 10126.4 10408.9 10529.3 10550.7 10600.2 619807.0 636419.0 641885.0 655840.0 659459.0 116733.0 131539.0 142241.0 149347.3 150175.2 909.9 997.9 1019.2 1041.8 1044.0 18770.8 19276.2 20244.4 20293.0 20322.2 48806.8 48863.8 58919.5 59715.7 60569.0 72527.6 80193.6 83558.0 83690.7 84964.0 7080.6 8345.9 8562.8 8605.4 8808.1 12162.6 12790.5 13039.4 13054.3 13193.0 11126.7 11218.7 11236.7 11237.1 11391.9 10576.0 10599.9 11247.2 11588.3 11610.2 9129.9 11124.7 11522.3 11541.8 11688.3 8373.2 8486.7 8611.0 8623.1 8639.7 9882.3 11230.9 11470.3 11494.0 11600.4 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5267.05 Median:5151.86 stddev:2912.66 (56.5%) stddev-sans-first:2904.78 19:15:16 INFO - PROCESS | 1651 | Values: 1199.3 1385.6 1398.6 1400.4 1418.0 6917.6 8116.7 8352.0 8400.2 8416.5 8254.4 8387.1 8414.0 8416.0 8423.8 2233.6 2261.5 2278.7 2281.4 2282.3 1864.7 2214.2 2223.5 2238.9 2266.9 1909.3 2201.9 2222.4 2237.0 2274.0 2192.6 2205.5 2232.7 2234.2 2239.4 1939.0 2021.6 2178.3 2260.5 2265.6 8110.6 8306.3 8322.5 8380.6 8397.2 4413.9 5012.7 5136.6 5167.1 5168.8 3102.8 3266.2 3451.3 3518.5 3533.5 1198.4 1200.2 1214.0 1218.3 1247.3 10635.8 10677.4 10693.6 10756.4 10756.7 4091.2 4360.5 4406.0 4424.2 4431.7 8613.3 8628.1 8650.3 8671.2 8680.0 4957.3 4989.4 4995.8 4996.2 5003.1 3909.0 4405.7 4586.2 4590.6 4604.9 3530.3 3582.0 3589.5 3637.7 3658.1 1118.2 1126.0 1128.7 1141.4 1142.4 675.0 687.9 698.3 702.7 712.7 4797.5 4992.3 5385.6 5651.0 5734.4 828.9 900.4 936.2 939.1 951.9 3523.4 3756.5 3776.7 3790.3 3804.1 8774.3 8966.4 9382.0 9674.5 9753.0 9706.2 9872.2 9883.3 9886.0 9895.7 7505.6 7700.9 7812.6 7836.8 7865.4 4726.3 5471.6 5639.8 5780.8 5810.9 8971.3 8984.4 9088.3 9124.1 9216.6 9101.3 9291.6 9346.9 9386.2 9400.9 6604.4 6705.1 6721.9 6741.9 6754.7 7666.3 7758.0 7789.4 7833.6 7837.7 5535.8 7041.4 7129.6 7176.7 7203.9 5761.7 6224.6 6846.5 6872.5 7024.6 5716.7 5795.0 6265.1 6385.9 6427.7 4817.3 4871.3 4962.4 4970.4 5041.0 4381.8 4905.7 4929.7 5001.3 5070.6 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4497.39 Median:5013.79 stddev:1391.16 (27.7%) stddev-sans-first:1359.66 19:15:16 INFO - PROCESS | 1651 | Values: 342.7 342.9 359.1 359.4 365.3 5187.5 5318.1 5492.6 5506.4 5528.9 4168.5 4763.4 5287.5 5376.4 5378.2 4086.3 4127.4 4140.0 4143.9 4147.8 3952.9 3993.8 4127.9 4133.8 4163.3 3414.9 3988.3 3994.8 4010.4 4086.5 3589.5 4045.5 4048.0 4056.6 4104.9 3501.3 3502.9 3504.3 3505.8 3514.6 4809.4 4953.0 5020.8 5289.5 5334.0 4067.9 4210.6 4426.9 4429.4 4695.4 5455.3 5665.5 5700.7 5766.8 5789.8 3108.7 3351.1 3463.1 3738.9 3825.4 4544.6 5692.6 5832.6 6002.3 6012.3 3210.6 3746.8 3912.5 3937.5 3946.9 3944.2 3982.6 3994.3 3994.3 4003.1 4619.4 4624.8 4664.4 4670.9 4673.2 5099.5 5702.6 5801.7 5803.4 5860.4 1167.9 1179.4 1222.3 1236.9 1289.4 775.9 812.0 824.5 854.0 883.0 4463.9 4494.0 4729.4 4878.3 5052.5 4963.4 5165.5 5423.6 5566.6 5575.1 4595.9 4673.6 4792.8 5070.3 5075.6 2499.6 2582.5 2665.5 2907.2 2993.5 4749.8 4940.2 5098.4 5137.6 5827.9 5551.9 5595.4 5777.5 5785.4 5849.5 4926.5 5326.5 5881.5 5961.9 5976.7 5442.5 5623.8 5634.7 5663.9 5696.6 5243.9 5362.9 5459.7 5580.8 5582.4 5302.8 5624.5 5662.9 5729.6 5778.6 3626.7 3876.3 3896.2 3896.9 3941.3 4906.9 5637.6 5762.4 5928.6 5956.8 4653.5 5082.8 5091.2 5228.4 5385.5 5539.9 5720.0 5758.0 5769.9 5777.3 4514.5 5591.8 5621.4 5641.2 5677.4 5308.6 5602.7 5668.5 5724.7 5739.7 4539.5 5006.7 5186.9 5448.9 5589.5 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:18637.25 Median:29396.71 stddev:25730.08 (87.5%) stddev-sans-first:25766.82 19:15:16 INFO - PROCESS | 1651 | Values: 652.5 662.3 741.0 788.3 791.1 13683.1 14723.3 14751.6 14791.3 14803.3 13091.6 13421.4 13461.3 13533.2 13767.3 871.1 881.8 885.8 890.3 892.0 830.5 872.4 873.6 876.1 877.7 922.2 923.8 924.8 926.0 926.3 761.2 913.0 918.0 920.3 922.0 603.8 649.0 680.1 687.3 687.6 13878.5 14053.2 14064.2 14068.0 15210.2 13556.4 13621.7 13636.3 13753.2 13848.7 10701.2 11158.1 11167.3 11228.0 11316.8 4236.1 4279.7 4291.1 4308.6 4437.4 89564.9 94781.0 95549.0 96398.0 97347.0 10266.6 10434.1 10434.7 10468.5 10508.7 20497.4 20629.7 20639.3 20654.5 20705.8 7215.4 7355.6 7409.1 7466.4 7573.5 3994.7 4069.5 4094.6 4103.9 4115.0 3431.4 3440.6 3506.4 3519.7 3522.6 664.1 672.8 673.1 714.2 731.7 613.3 634.6 663.0 679.2 695.6 9348.2 9777.2 9932.4 9944.5 10016.1 628.4 650.8 661.7 678.3 682.5 4175.7 4772.4 5403.9 5405.7 5583.2 74217.6 84721.0 85229.6 88313.0 88803.6 66533.9 82564.2 83691.0 85173.0 87292.0 68106.7 69558.9 71054.0 71964.9 73202.3 7102.5 7878.6 8375.6 9510.0 10531.3 43030.8 44133.8 46280.0 46354.8 47292.0 54021.0 57996.6 61349.4 61715.1 62050.4 12591.2 12683.5 12897.2 13015.0 13548.0 12871.5 13900.1 14010.9 14494.4 14839.7 13042.5 13076.9 13169.5 13203.3 13356.7 13103.7 13331.6 13590.9 13870.4 13971.4 12457.2 12640.4 13035.7 13079.9 13784.7 9462.1 10585.8 10645.8 10916.7 11030.6 9287.6 9574.2 10581.8 10639.7 10672.7 19:15:16 INFO - PROCESS | 1651 | -------- Summary: end -------- 19:15:16 INFO - PROCESS | 1651 | 19:15:16 INFO - TEST-INFO | 1651: exit 0 19:15:16 INFO - TEST-OK | dromaeo_css | took 1115635ms 19:15:16 INFO - TEST-START | kraken 19:15:16 INFO - operating with platform_type : mac_ 19:15:16 INFO - Initialising browser for kraken test... 19:15:17 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpEBAIqT/profile http://localhost:49241/getInfo.html) 19:15:19 INFO - PROCESS | 1660 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 19:15:19 INFO - PROCESS | 1660 | 2017-02-11 19:15:19.713 plugin-container[1661:13213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 19:15:19 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:15:19 INFO - PROCESS | 1660 | 2017-02-11 19:15:19.716 plugin-container[1661:13213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.CFPasteboardClient' 19:15:19 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:15:19 INFO - PROCESS | 1660 | 2017-02-11 19:15:19.716 plugin-container[1661:13213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:15:19 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 19:15:19 INFO - PROCESS | 1660 | colorDepth:24 19:15:19 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 19:15:19 INFO - PROCESS | 1660 | __metrics 19:15:19 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 19:15:20 INFO - TEST-INFO | 1660: exit 0 19:15:20 INFO - Browser initialized. 19:15:20 INFO - Running cycle 1/1 for kraken test... 19:15:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpEBAIqT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:23 INFO - PROCESS | 1662 | 2017-02-11 19:15:23.031 plugin-container[1663:13330] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 19:15:23 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:15:23 INFO - PROCESS | 1662 | 2017-02-11 19:15:23.032 plugin-container[1663:13330] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 19:15:23 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:15:23 INFO - PROCESS | 1662 | 2017-02-11 19:15:23.032 plugin-container[1663:13330] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:15:32 INFO - PROCESS | 1662 | RSS: Main: 233459712 19:15:32 INFO - PROCESS | 1662 | 19:15:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 19:15:36 INFO - PROCESS | 1662 | RSS: Main: 247156736 19:15:36 INFO - PROCESS | 1662 | 19:15:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 19:15:40 INFO - PROCESS | 1662 | RSS: Main: 255049728 19:15:40 INFO - PROCESS | 1662 | 19:15:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 19:15:44 INFO - PROCESS | 1662 | RSS: Main: 257703936 19:15:44 INFO - PROCESS | 1662 | 19:15:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 19:15:46 INFO - PROCESS | 1662 | RSS: Main: 258949120 19:15:46 INFO - PROCESS | 1662 | 19:15:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:15:49 INFO - PROCESS | 1662 | RSS: Main: 259551232 19:15:49 INFO - PROCESS | 1662 | 19:15:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 19:15:53 INFO - PROCESS | 1662 | RSS: Main: 266260480 19:15:53 INFO - PROCESS | 1662 | 19:15:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 19:15:56 INFO - PROCESS | 1662 | RSS: Main: 265945088 19:15:56 INFO - PROCESS | 1662 | 19:15:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 19:16:00 INFO - PROCESS | 1662 | RSS: Main: 265940992 19:16:00 INFO - PROCESS | 1662 | 19:16:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:02 INFO - PROCESS | 1662 | RSS: Main: 265670656 19:16:02 INFO - PROCESS | 1662 | 19:16:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:05 INFO - PROCESS | 1662 | RSS: Main: 265687040 19:16:05 INFO - PROCESS | 1662 | 19:16:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:16:08 INFO - PROCESS | 1662 | RSS: Main: 265699328 19:16:08 INFO - PROCESS | 1662 | 19:16:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:16:12 INFO - PROCESS | 1662 | RSS: Main: 266244096 19:16:12 INFO - PROCESS | 1662 | 19:16:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:16:15 INFO - PROCESS | 1662 | RSS: Main: 265867264 19:16:15 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 19:16:17 INFO - PROCESS | 1662 | RSS: Main: 267866112 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | __start_tp_report 19:16:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 19:16:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 19:16:17 INFO - PROCESS | 1662 | |i|pagename|runs| 19:16:17 INFO - PROCESS | 1662 | |0;ai-astar;105;116;115;117;115;116;154;105;173;141 19:16:17 INFO - PROCESS | 1662 | |1;audio-beat-detection;160;144;147;158;114;114;115;117;136;125 19:16:17 INFO - PROCESS | 1662 | |2;audio-dft;205;212;206;213;197;205;199;192;205;284 19:16:17 INFO - PROCESS | 1662 | |3;audio-fft;84;61;61;91;80;85;75;61;73;64 19:16:17 INFO - PROCESS | 1662 | |4;audio-oscillator;88;73;86;72;72;71;82;73;70;84 19:16:17 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;94;121;88;92;120;138;136;87;87 19:16:17 INFO - PROCESS | 1662 | |6;imaging-darkroom;96;96;96;97;92;100;95;135;96;97 19:16:17 INFO - PROCESS | 1662 | |7;imaging-desaturate;90;97;100;91;92;103;94;133;148;98 19:16:17 INFO - PROCESS | 1662 | |8;json-parse-financial;65;64;64;63;66;89;65;66;65;64 19:16:17 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;69;70;79;82;79;77;72;69;72;72 19:16:17 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;94;92;88;94;101;110;116;91;90;89 19:16:17 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;149;154;135;148;165;149;148;191;173;170 19:16:17 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;180;176;181;237;175;178;201;173;181;179 19:16:17 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;70;67;67;72;68;74;74;86;89 19:16:17 INFO - PROCESS | 1662 | __end_tp_report 19:16:17 INFO - PROCESS | 1662 | __start_cc_report 19:16:17 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1030 19:16:17 INFO - PROCESS | 1662 | __end_cc_report 19:16:17 INFO - PROCESS | 1662 | __startTimestamp1486869377862__endTimestamp 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | ------- Summary: start ------- 19:16:17 INFO - PROCESS | 1662 | Number of tests: 14 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:125.70 Median:116.50 stddev:22.66 (19.5%) stddev-sans-first:22.77 19:16:17 INFO - PROCESS | 1662 | Values: 105.0 116.0 115.0 117.0 115.0 116.0 154.0 105.0 173.0 141.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:140.00 stddev:18.40 (13.1%) stddev-sans-first:16.72 19:16:17 INFO - PROCESS | 1662 | Values: 160.0 144.0 147.0 158.0 114.0 114.0 115.0 117.0 136.0 125.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:211.80 Median:205.50 stddev:26.17 (12.7%) stddev-sans-first:27.64 19:16:17 INFO - PROCESS | 1662 | Values: 205.0 212.0 206.0 213.0 197.0 205.0 199.0 192.0 205.0 284.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:73.50 Median:77.50 stddev:11.32 (14.6%) stddev-sans-first:11.35 19:16:17 INFO - PROCESS | 1662 | Values: 84.0 61.0 61.0 91.0 80.0 85.0 75.0 61.0 73.0 64.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:7.02 (9.1%) stddev-sans-first:6.23 19:16:17 INFO - PROCESS | 1662 | Values: 88.0 73.0 86.0 72.0 72.0 71.0 82.0 73.0 70.0 84.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:87.50 stddev:21.12 (24.1%) stddev-sans-first:21.57 19:16:17 INFO - PROCESS | 1662 | Values: 89.0 94.0 121.0 88.0 92.0 120.0 138.0 136.0 87.0 87.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:96.00 stddev:12.45 (13.0%) stddev-sans-first:13.13 19:16:17 INFO - PROCESS | 1662 | Values: 96.0 96.0 96.0 97.0 92.0 100.0 95.0 135.0 96.0 97.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:104.60 Median:91.50 stddev:19.68 (21.5%) stddev-sans-first:20.15 19:16:17 INFO - PROCESS | 1662 | Values: 90.0 97.0 100.0 91.0 92.0 103.0 94.0 133.0 148.0 98.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:65.00 stddev:7.75 (11.9%) stddev-sans-first:8.19 19:16:17 INFO - PROCESS | 1662 | Values: 65.0 64.0 64.0 63.0 66.0 89.0 65.0 66.0 65.0 64.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:74.50 stddev:4.72 (6.3%) stddev-sans-first:4.64 19:16:17 INFO - PROCESS | 1662 | Values: 69.0 70.0 79.0 82.0 79.0 77.0 72.0 69.0 72.0 72.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:90.50 stddev:9.52 (10.5%) stddev-sans-first:10.06 19:16:17 INFO - PROCESS | 1662 | Values: 94.0 92.0 88.0 94.0 101.0 110.0 116.0 91.0 90.0 89.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:159.50 stddev:16.38 (10.3%) stddev-sans-first:17.03 19:16:17 INFO - PROCESS | 1662 | Values: 149.0 154.0 135.0 148.0 165.0 149.0 148.0 191.0 173.0 170.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:180.50 stddev:19.48 (10.8%) stddev-sans-first:20.54 19:16:17 INFO - PROCESS | 1662 | Values: 180.0 176.0 181.0 237.0 175.0 178.0 201.0 173.0 181.0 179.0 19:16:17 INFO - PROCESS | 1662 | 19:16:17 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.00 stddev:8.04 (11.0%) stddev-sans-first:8.08 19:16:17 INFO - PROCESS | 1662 | Values: 66.0 70.0 67.0 67.0 72.0 68.0 74.0 74.0 86.0 89.0 19:16:17 INFO - PROCESS | 1662 | -------- Summary: end -------- 19:16:17 INFO - PROCESS | 1662 | 19:16:18 INFO - TEST-INFO | 1662: exit 0 19:16:18 INFO - TEST-OK | kraken | took 61808ms 19:16:18 INFO - SUITE-END | took 1177s 19:16:19 INFO - Completed test suite (00:19:37) 19:16:19 INFO - javascript benchmark 19:16:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7062.638884080218, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.806151630901, 3234.822084785337, 3253.7397008239336, 3303.174206448387, 3304.1409233599256, 5433.809816656418, 5486.259354987081, 5488.423715509874, 5523.25436066131, 5533.363663178736, 4566.748828814414, 5281.498257665526, 5328.17413300938, 5429.864253393667, 5451.236826844245, 3903.965449155976, 4966.230234313669, 5020.3724534433195, 5053.267276244942, 5092.694438333693, 5014.623903207255, 5070.721110338948, 5107.7446127693465, 5119.180931051014, 5150.098732721777, 278.73586267746833, 4957.727324997124, 5000.899982000358, 5013.724245166514, 5066.41736200338, 5092.8217512387255, 5106.64253502255, 5127.641065125443, 5146.047981123466, 5159.432462429129, 5067.797288108472, 5091.0, 5096.79220080145, 5109.974450127763, 5139.640225184238, 5295.841124766263, 5319.148936170232, 5350.5184533392185, 5364.409914909357, 5553.500184983371, 6102.328743838174, 6110.71064601923, 6129.664444256052, 6130.275842464065, 6137.131844484721, 6140.815775526741, 6169.444749972524, 6170.2904166021035, 6178.907316390258, 6183.752649894044, 5425.0, 5500.339959204841, 5649.632773869767, 5711.914321285184, 5762.279715035621, 6132.110769107974, 6141.263048434216, 6165.8458538536, 6166.81499555014, 6173.166622505994, 5999.550033747487, 6051.0923361496125, 6064.325559580498, 6100.206973093558, 6104.084387341934, 5957.046872500466, 5958.136070269787, 6031.9396806032255, 6110.572259941761, 6117.96941015292, 4877.6829506081995, 5976.551758618121, 5981.581289309794, 5983.910241346383, 6004.519638428916, 6048.516118710492, 6059.545534084873, 6059.7273122709585, 6060.787872424444, 6071.848203794852, 4851.151048566558, 4867.099586576494, 4869.732164730903, 4877.95122048775, 4918.729469879191, 4247.3971590852025, 4366.279563871925, 4369.388285640013, 4373.584509471595, 4374.78126093694, 5408.702521361284, 5466.590005749585, 5471.917921231185, 5514.36584792754, 5626.803061892814, 6067.605605635619, 6107.938920610737, 6132.448079672851, 6136.365905528278, 6145.217886812864, 5566.081596536526, 5609.046462101371, 5630.042892708249, 5641.548676105984, 5641.774329026875, 5298.125809241509, 5326.600504962066, 5344.732763361816, 5359.356877174764, 5385.865353366119, 6079.422454866781, 6107.877842443127, 6110.29731580865, 6127.830981140938, 6134.222293196984, 6123.540734445023, 6163.291221509584, 6170.228721409824, 6170.598911070766, 6220.782272620437, 5637.182608521659, 5749.137629355463, 6151.108089326933, 6202.534809889186, 6207.937920620736, 5848.824535263949, 5914.497267732291, 6084.39156084388, 6089.54328425361, 6095.573309868267, 6055.031195008777, 6068.544859135671, 6088.025915853442, 6102.755889764359, 6103.337669688931, 5993.820185394445, 6008.188894499187, 6019.367966363468, 6020.668863212565, 6063.060225665029, 5357.142857142839, 5531.529819965187, 5793.95708772425, 5941.346451890202, 5992.460678538778, 5461.262729531462, 5489.313835770528, 5548.278723765885, 5665.12190610456, 5744.3394009687745, 5663.461971112738, 5797.985352563369, 5813.534917206529, 5836.766529338779, 5966.224390829165, 5566.554675626021, 5647.011772939802, 5673.035583950656, 5705.201271822031, 5715.2855893013375], "value": 5482.802227552976, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.1510975165539, 498.5587754836969, 513.6045245161229, 519.5739493615234, 549.1734938916932, 31952.68047319526, 37353.43969840451, 37765.99999999996, 37846.24307513848, 37860.242795144084, 29618.703812961863, 39408.423663053516, 40280.0, 40730.0, 40736.59263407365, 7721.845563088735, 9398.718038458857, 9399.201067909236, 9431.717048488556, 10547.05076543111, 10738.711354637455, 10829.783404331947, 10856.620018299362, 10867.456627168649, 10895.727606809853, 10838.132949364071, 10873.021428071472, 10879.12966962645, 10890.782184356347, 10910.0, 10831.404272764994, 10890.509927053301, 10890.96535829095, 10908.727281818017, 10916.0, 10789.0, 10876.347419154876, 10897.455127243686, 10910.072643825206, 10920.563177472892, 35945.46081808775, 36230.45654315187, 36267.0, 36302.0, 36311.0, 3661.963380366189, 3686.668199862025, 3705.962940370616, 3707.4068149096283, 3845.1348446599573, 27020.1893943182, 27810.860945695345, 28039.85980070107, 28084.876604935776, 28103.859480702467, 2553.770160685547, 3077.969220307813, 3115.454795410794, 3169.904902852918, 3174.0795169400844, 430331.0, 430505.0, 434123.8293808542, 434563.8271808652, 437894.9999999968, 45945.5405445948, 47118.0, 54523.72738136284, 55017.44982550203, 55033.624159395535, 56563.0, 56834.0, 56925.43074569284, 61264.0, 61444.38555614387, 14204.573862784133, 14280.928595356956, 14320.57038288853, 14354.210518421587, 14361.281935903164, 9097.545122743837, 10898.0, 11039.613613523488, 11041.44792760375, 11046.613368532224, 16906.661866762595, 16914.154292285337, 16950.40673576419, 16986.150692465573, 17101.65796684084, 7501.437392195608, 7548.5470871747875, 7549.282818132268, 7551.773446796605, 7593.886091708629, 715.3275920634587, 786.7089177004431, 788.3456730913329, 819.1890028871375, 832.5379414424931, 29235.269118271786, 29281.414371712446, 29512.852435738114, 29637.110886673003, 29729.85135074311, 989.6734077754468, 1004.6985904228702, 1029.6910926721953, 1038.2576457832686, 1169.222467059413, 12307.93846030782, 13714.794278085838, 14629.634259143393, 15179.241037948059, 15375.308111135026, 537988.0, 817619.0, 822724.0, 830406.0878173665, 837562.0, 51299.23051154236, 51405.48594514082, 51982.74008629932, 52094.0, 52327.0, 1309.620210139049, 1321.5374618883313, 1326.5143039795298, 1335.4791631264009, 1353.9255340956342, 25555.0, 25633.97464101415, 27815.582766258925, 28213.71786282193, 29035.56446653344, 44263.11473770571, 44750.0, 44814.77592612147, 44851.9999999987, 44940.55059449495, 504379.0, 505325.0, 506676.46661766456, 510621.0, 510745.0, 25118.49763004693, 25483.74516254888, 25526.48947021012, 25648.23055308344, 25750.356241094123, 40150.79924600475, 40696.38955415612, 41209.0, 41283.587164129174, 41422.75731727933, 32750.0, 34440.483392748625, 34934.82532587321, 35029.47455788216, 35293.82353088218, 32937.83531082329, 33483.4977475343, 33739.83130084334, 34673.30653386968, 34707.47938780971, 31294.843525782224, 33006.834965825015, 33493.0, 33840.15399615029, 33885.830570846985, 26948.922043118055, 29367.706322936498, 30488.695113048587, 30607.54088688627, 30761.923332683247, 19266.71099933474, 22518.437039074153, 22872.428189294737, 22932.656010159528, 23047.308580742603], "value": 18833.30767725201, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.90282623691604, 373.33173619221594, 385.8572328238552, 385.86880460643374, 402.4948689394809, 18743.43769686908, 20435.0, 20956.685649715266, 21153.47116322089, 21198.046087926043, 18366.632667346646, 20025.599488010266, 20495.0, 20737.0, 21097.578048439023, 5589.441055894418, 5724.484796368316, 5755.798547050854, 5853.09277062056, 5953.315368732608, 5033.7734801934, 5701.116326969326, 5790.623609465371, 5829.912551311733, 6434.839129021789, 6443.387878151591, 6450.516211284173, 6450.6452145132, 6463.870722585545, 6478.870422591545, 5300.628955973083, 6343.777967771129, 6421.71102300393, 6430.0, 6445.1621289232335, 5588.273524441796, 5680.971595141998, 5725.885482290372, 5733.569982251348, 5739.770409183628, 13057.738845223137, 13203.603891883164, 23519.76480235193, 23950.28149155528, 24316.878415607986, 2484.1888221415998, 2504.8747562621797, 2512.6984761828517, 2567.33249355169, 2629.9868500657567, 9630.566624501915, 9725.0, 9822.508874556315, 10086.9495652522, 10194.031567001197, 1714.7513610526403, 1773.7073382891808, 1777.2446710148265, 1799.236747986967, 1812.4472036029006, 114966.0, 120450.99999999913, 120869.58260834822, 121790.0, 121850.0, 9115.723798668192, 11350.716232094264, 11705.590304339477, 11717.589884354176, 11732.765344693058, 15084.094954302776, 15161.545153645407, 15306.08163510193, 15331.846681533041, 15336.15651139198, 6817.5227734059135, 7977.880331795028, 8236.299914507214, 8267.049289331804, 8366.916330836735, 6489.643069631215, 6545.978884576267, 7764.577223243978, 7879.487833290817, 8107.351411887035, 3872.554656214515, 4698.88252793676, 4729.196036673774, 4743.0988112258565, 4802.303665968415, 1961.9215231390585, 2009.9348493547166, 2014.254725751452, 2015.9496012599802, 2019.31343343264, 960.8277900960817, 963.3352986439335, 967.5936106835146, 982.3712823792779, 999.2755252441922, 20316.28892988738, 20641.793582063987, 20687.379378618665, 20704.689429658567, 20959.790402095783, 955.5461155950868, 957.2150836314295, 977.4721650308895, 989.1542730964921, 990.3265779270165, 10126.392416455037, 10408.895911040943, 10529.262951593315, 10550.736231594272, 10600.204984626183, 619807.0, 636419.0, 641885.0, 655840.0, 659459.0, 116732.99204095393, 131539.0, 142241.0, 149347.25326373297, 150175.2491237537, 909.8999110097884, 997.910188083076, 1019.2457581389866, 1041.796849614333, 1043.968680939573, 18770.812291877453, 19276.22895084181, 20244.392668219978, 20293.0, 20322.187112515334, 48806.75596621994, 48863.77840554014, 58919.52701182505, 59715.70142149262, 60569.0, 72527.63736181497, 80193.59903200447, 83558.0, 83690.74463883169, 84964.0, 7080.575165490087, 8345.874811877706, 8562.828743424972, 8605.440646357967, 8808.075152108937, 12162.574309899112, 12790.488380465049, 13039.413226404646, 13054.347282635641, 13193.0, 11126.666200014013, 11218.709848367542, 11236.719082023015, 11237.101031917591, 11391.886081139413, 10576.0, 10599.940005999339, 11247.212695111264, 11588.304701717923, 11610.187286889837, 9129.90870091317, 11124.666260012213, 11522.251053681484, 11541.769164616493, 11688.298702077562, 8373.204545568162, 8486.702965396182, 8610.96668399796, 8623.051464338792, 8639.6976105836, 9882.258830587878, 11230.94384528072, 11470.311781293149, 11494.0, 11600.419979001184], "value": 9629.372139163286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1199.3403628004598, 1385.5704731533226, 1398.6433459467835, 1400.376832309622, 1418.049906562603, 6917.619530925804, 8116.675332986673, 8352.039515455725, 8400.201980811828, 8416.452930559517, 8254.380921430902, 8387.119352467978, 8413.957930210356, 8416.032156302028, 8423.789405264872, 2233.620284551626, 2261.525079733258, 2278.7493375728654, 2281.4296425893526, 2282.2924893283052, 1864.692325766247, 2214.2360885494477, 2223.5219427823013, 2238.944026399337, 2266.9433264168365, 1909.3221906223298, 2201.9119235230573, 2222.422170235742, 2236.9888150559223, 2274.0449011415244, 2192.583409152256, 2205.481711797734, 2232.743234528026, 2234.2068565659283, 2239.428945618873, 1938.980610193894, 2021.6259991901545, 2178.313831143185, 2260.457490202356, 2265.629294276955, 8110.594470276463, 8306.335493160532, 8322.50064996096, 8380.622871970714, 8397.202265784741, 4413.9117217655785, 5012.749362531858, 5136.563392111675, 5167.147420675584, 5168.819091331823, 3102.7899119343474, 3266.1508007918114, 3451.2752322012407, 3518.5074089627474, 3533.505309256706, 1198.3948106206396, 1200.237848965913, 1214.0498588430112, 1218.2690385768467, 1247.3077442019676, 10635.840462393071, 10677.41274229917, 10693.625723099654, 10756.354618722824, 10756.73108172294, 4091.1613119311064, 4360.454943132108, 4406.0, 4424.225760491901, 4431.689781715313, 8613.267872230805, 8628.137186281321, 8650.264727498106, 8671.176238257356, 8680.045195028542, 4957.3059771632, 4989.426215985136, 4995.750212489361, 4996.150654388763, 5003.124453220745, 3908.9609103909165, 4405.691601587922, 4586.220342541776, 4590.632749380042, 4604.861854144381, 3530.3115892401247, 3582.0328511301805, 3589.4974703541525, 3637.672609465161, 3658.140337020798, 1118.2284223885492, 1125.966932753383, 1128.7347473343757, 1141.4121727310442, 1142.3888219802532, 674.9628770417675, 687.9484038697118, 698.3051863395954, 702.7434986230091, 712.7398499547628, 4797.496262892416, 4992.326035985168, 5385.569154467633, 5651.0, 5734.426557344315, 828.90467596226, 900.3697411811627, 936.2463217110292, 939.1266122506134, 951.8667386565886, 3523.4010218262956, 3756.530433695788, 3776.678982286536, 3790.2608991246443, 3804.144067584771, 8774.298056155492, 8966.372353935161, 9382.0, 9674.467904265333, 9752.951235243778, 9706.174975127053, 9872.160866326443, 9883.258755593359, 9885.950570247247, 9895.703128906145, 7505.62471876404, 7700.884486732703, 7812.570308632966, 7836.804079898048, 7865.41009424284, 4726.338312636165, 5471.644343117685, 5639.802606908738, 5780.826575202751, 5810.874279261771, 8971.28229741606, 8984.416012959136, 9088.272938165062, 9124.087591240823, 9216.631334746804, 9101.31625649257, 9291.581878815334, 9346.859797103185, 9386.202172815114, 9400.858987115336, 6604.3725846044545, 6705.061291419108, 6721.865562688817, 6741.932580674326, 6754.729810807513, 7666.310032097138, 7757.961210193913, 7789.415793815373, 7833.608319584112, 7837.725679601187, 5535.833924982257, 7041.366277035091, 7129.607871567114, 7176.7129314826825, 7203.891941620985, 5761.683107429131, 6224.595401298901, 6846.4522838173825, 6872.518923675295, 7024.6136462493005, 5716.685582293014, 5795.043817770021, 6265.091561723616, 6385.8722825544155, 6427.678616069272, 4817.325574419654, 4871.318015477765, 4962.354893863775, 4970.428400733962, 5040.974795126001, 4381.780910954376, 4905.705657660552, 4929.6549241554185, 5001.299818025552, 5070.569001634754], "value": 4244.446076741142, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.7018493910298, 342.878278211235, 359.08791669160337, 359.4060342381537, 365.2931577397734, 5187.533122019017, 5318.1177740249595, 5492.571931297866, 5506.449355064497, 5528.917066243999, 4168.5206201286865, 4763.38076050113, 5287.524122828944, 5376.381716102649, 5378.193271009351, 4086.3053280942313, 4127.442795222637, 4139.958600413987, 4143.917121657565, 4147.7926103694845, 3952.940705889414, 3993.780342081199, 4127.938080928789, 4133.751974881502, 4163.292240319153, 3414.880479183229, 3988.2621714982674, 3994.8215276493506, 4010.358342665174, 4086.509618845728, 3589.533360663097, 4045.4943132108488, 4048.0164719287486, 4056.574059723716, 4104.856330028465, 3501.2647344057523, 3502.912427189315, 3504.3341765064556, 3505.772124811879, 3514.5606799150105, 4809.422869255677, 4952.984638149171, 5020.824271150492, 5289.471052894718, 5333.973330133324, 4067.9220006698247, 4210.5578914213875, 4426.867193984185, 4429.361136379528, 4695.380093867644, 5455.290812194429, 5665.461781130786, 5700.714964251771, 5766.769329226826, 5789.8263052108505, 3108.7357574606185, 3351.078453425303, 3463.1342164458883, 3738.9065273368383, 3825.3879379299456, 4544.568266014723, 5692.601517893749, 5832.591718579701, 6002.279726432856, 6012.278526576795, 3210.598675165604, 3746.756460830037, 3912.4522566840665, 3937.4684417603257, 3946.861859834892, 3944.230874979409, 3982.581828907923, 3994.2810294146752, 3994.3409337459575, 4003.139325045129, 4619.445666520039, 4624.797552636272, 4664.39362882823, 4670.921439959186, 4673.205555055649, 5099.515546023122, 5702.600817942704, 5801.708325211518, 5803.388475186099, 5860.355360910296, 1167.9474423650956, 1179.3690375648987, 1222.3277197541495, 1236.9010479161645, 1289.374653293152, 775.8525880082766, 811.9837603247902, 824.4599787139433, 854.0050840770557, 882.9625190401252, 4463.8617152625875, 4493.955060449353, 4729.43246810385, 4878.292647566083, 5052.545270925634, 4963.354763880672, 5165.457626949192, 5423.620346575781, 5566.582506311962, 5575.135853942646, 4595.9080818383445, 4673.626109911199, 4792.849716068118, 5070.315507406527, 5075.644704870623, 2499.562751417942, 2582.483503299348, 2665.5202063628735, 2907.2295841601995, 2993.5060714981782, 4749.762511874393, 4940.1968281576965, 5098.417233129717, 5137.640365174477, 5827.912581311284, 5551.944480555143, 5595.4124816894455, 5777.480026797524, 5785.421457854265, 5849.473547380756, 4926.5073492650445, 5326.520613144836, 5881.500072493886, 5961.940380596226, 5976.73104710289, 5442.4557544246845, 5623.803166889139, 5634.74643641029, 5663.941471605452, 5696.629719068247, 5243.89512209746, 5362.946370536245, 5459.716966512787, 5580.832575022756, 5582.409013431153, 5302.761375738178, 5624.521915637217, 5662.943370566406, 5729.598928074995, 5778.595498315078, 3626.673599376069, 3876.2635099331037, 3896.181801821649, 3896.8636097736444, 3941.2511622791576, 4906.901861962812, 5637.646964728354, 5762.394948530344, 5928.644281343133, 5956.791512297222, 4653.464851542116, 5082.771275292546, 5091.210862316347, 5228.398734145622, 5385.515303622536, 5539.861503462445, 5720.0, 5758.0, 5769.884602307847, 5777.277840269966, 4514.530614691366, 5591.832245032656, 5621.381648018796, 5641.210230567806, 5677.403872593318, 5308.601854860824, 5602.663840169603, 5668.461496157858, 5724.65652060861, 5739.684317362585, 4539.455265368177, 5006.749662516932, 5186.948130518646, 5448.863778405572, 5589.484731870593], "value": 4059.301404884566, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.4910569755592, 662.2748090840528, 740.9700516282369, 788.3338578900829, 791.0812764230058, 13683.110597811148, 14723.337449814771, 14751.557453276391, 14791.334389952452, 14803.33384997675, 13091.607251782461, 13421.396037178327, 13461.259630720306, 13533.188008719484, 13767.311634418289, 871.0679572857046, 881.8412685716569, 885.8184072265202, 890.3189060368823, 892.0366004714892, 830.4602008694336, 872.4285592936636, 873.5763154869883, 876.1063715010686, 877.7147427086222, 922.2253307221932, 923.7736754495124, 924.8335299646061, 925.9861102083474, 926.2543652359848, 761.1589193940699, 912.9589168487435, 918.0498184379202, 920.2913756407562, 922.0226559846551, 603.7916918663054, 648.9837754056139, 680.1124532485154, 687.3058211206717, 687.6492988575812, 13878.514252001232, 14053.156810591398, 14064.156150630995, 14068.015238933278, 15210.239488025665, 13556.389962451614, 13621.659458513517, 13636.318184090756, 13753.243571603558, 13848.693505450572, 10701.197410194267, 11158.051565616932, 11167.299829769825, 11228.0, 11316.773664526745, 4236.068065025689, 4279.679024073207, 4291.120320334324, 4308.590683885026, 4437.378766972594, 89564.92740494403, 94780.99999999932, 95549.0, 96398.0, 97347.0, 10266.6406675766, 10434.113100386401, 10434.739131521772, 10468.52891619879, 10508.737281567868, 20497.385078447373, 20629.690554641693, 20639.277625283343, 20654.483637909172, 20705.757654540776, 7215.350618444365, 7355.632218389059, 7409.073865766779, 7466.402687785073, 7573.545587264782, 3994.6750961025236, 4069.5116586009867, 4094.5700701426517, 4103.8974025649595, 4114.979425102915, 3431.365197438432, 3440.587129544421, 3506.4389697648253, 3519.7360197985254, 3522.647735226457, 664.0537234440999, 672.7544446277171, 673.1148539670318, 714.1786945013276, 731.6707481633287, 613.2702084519408, 634.5589815078476, 663.0317549466439, 679.2198391686683, 695.6139342664768, 9348.205402540723, 9777.217822574177, 9932.404055756677, 9944.453055081894, 10016.148627366756, 628.3936001758325, 650.773292343935, 661.7459913463958, 678.3386198456564, 682.4710849091895, 4175.749455032707, 4772.427308722976, 5403.891922161535, 5405.70268635221, 5583.246261754611, 74217.62891185509, 84721.0, 85229.57385213158, 88313.0, 88803.55598222096, 66533.94916886883, 82564.17435825685, 83691.0, 85173.0, 87292.0, 68106.65946670334, 69558.9132326031, 71054.0, 71964.92052619215, 73202.26797731954, 7102.538335008208, 7878.56667883249, 8375.62309696053, 9510.0, 10531.262811603114, 43030.78484607557, 44133.77933110314, 46280.0, 46354.76822615865, 47292.0, 54021.0, 57996.55008624649, 61349.38650613615, 61715.13706197904, 62050.37949620446, 12591.24452532851, 12683.492660293854, 12897.226166430044, 13015.0, 13547.96780482896, 12871.485140594272, 13900.056865229471, 14010.859891400956, 14494.420223190955, 14839.703205936037, 13042.478300867859, 13076.934615326862, 13169.53906613264, 13203.273819939612, 13356.732865342445, 13103.672408189872, 13331.600051998455, 13590.86409135896, 13870.4451821926, 13971.441142354191, 12457.1902826316, 12640.43118059708, 13035.674108147372, 13079.934600326937, 13784.655383615489, 9462.053794620759, 10585.788284234426, 10645.787084258116, 10916.7270818227, 11030.558777648805, 9287.62849486013, 9574.234061275221, 10581.841272380767, 10639.680809575726, 10672.679819605424], "value": 7243.606733589422, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 117.0, 115.0, 116.0, 154.0, 105.0, 173.0, 141.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 144.0, 147.0, 158.0, 114.0, 114.0, 115.0, 117.0, 136.0, 125.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 212.0, 206.0, 213.0, 197.0, 205.0, 199.0, 192.0, 205.0, 284.0], "value": 211.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 61.0, 91.0, 80.0, 85.0, 75.0, 61.0, 73.0, 64.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 86.0, 72.0, 72.0, 71.0, 82.0, 73.0, 70.0, 84.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 121.0, 88.0, 92.0, 120.0, 138.0, 136.0, 87.0, 87.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 92.0, 100.0, 95.0, 135.0, 96.0, 97.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 100.0, 91.0, 92.0, 103.0, 94.0, 133.0, 148.0, 98.0], "value": 104.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 63.0, 66.0, 89.0, 65.0, 66.0, 65.0, 64.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 79.0, 82.0, 79.0, 77.0, 72.0, 69.0, 72.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 88.0, 94.0, 101.0, 110.0, 116.0, 91.0, 90.0, 89.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 154.0, 135.0, 148.0, 165.0, 149.0, 148.0, 191.0, 173.0, 170.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 176.0, 181.0, 237.0, 175.0, 178.0, 201.0, 173.0, 181.0, 179.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 67.0, 72.0, 68.0, 74.0, 74.0, 86.0, 89.0], "value": 73.3, "unit": "score"}]}]} 19:16:19 INFO - Return code: 0 19:16:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:16:19 ERROR - # TBPL SUCCESS # 19:16:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:19 INFO - 2017-02-11 19:16:19.184 screenresolution[1667:13716] starting screenresolution argv=screenresolution get 19:16:19 INFO - 2017-02-11 19:16:19.187 screenresolution[1667:13716] Display 0: 1600x1200x32@60 19:16:19 INFO - 2017-02-11 19:16:19.194 screenresolution[1668:13719] starting screenresolution argv=screenresolution list 19:16:19 INFO - Available Modes on Display 0 19:16:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:19 INFO - Intel Iris: 19:16:19 INFO - Chipset Model: Intel Iris 19:16:19 INFO - Type: GPU 19:16:19 INFO - Bus: Built-In 19:16:19 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:19 INFO - Vendor: Intel (0x8086) 19:16:19 INFO - Device ID: 0x0a2e 19:16:19 INFO - Revision ID: 0x0009 19:16:19 INFO - Displays: 19:16:19 INFO - Display: 19:16:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:19 INFO - Display Serial Number: 1600x1200 60 19:16:19 INFO - Main Display: Yes 19:16:19 INFO - Mirror: Off 19:16:19 INFO - Online: Yes 19:16:19 INFO - Rotation: Supported 19:16:19 INFO - Return code: 0 19:16:19 INFO - Running post-action listener: _resource_record_post_action 19:16:19 INFO - [mozharness: 2017-02-12 03:16:19.433442Z] Finished run-tests step (success) 19:16:19 INFO - Running post-run listener: _resource_record_post_run 19:16:19 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 33194496; Write bytes: 558484480; Read time: 202; Write time: 1698 19:16:19 INFO - TinderboxPrint: CPU usage
54.6% 19:16:19 INFO - TinderboxPrint: I/O read bytes / time
33,194,496 / 202 19:16:19 INFO - TinderboxPrint: I/O write bytes / time
558,484,480 / 1,698 19:16:19 INFO - TinderboxPrint: CPU idle
2,181.8 (45.4%) 19:16:19 INFO - TinderboxPrint: CPU system
966.0 (20.1%) 19:16:19 INFO - TinderboxPrint: CPU user
1,653.8 (34.4%) 19:16:19 INFO - TinderboxPrint: Swap in / out
281,825,280 / 0 19:16:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 251337216; Write bytes: 245499904; Read time: 18127; Write time: 531 19:16:19 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 29811200; Write bytes: 301597696; Read time: 179; Write time: 1130 19:16:19 INFO - Running post-run listener: _upload_blobber_files 19:16:19 INFO - Blob upload gear active. 19:16:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:16:19 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:16:19 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:16:19 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:16:20 INFO - (blobuploader) - INFO - Open directory for files ... 19:16:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:16:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:16:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:21 INFO - (blobuploader) - INFO - Done attempting. 19:16:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:16:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:16:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:22 INFO - (blobuploader) - INFO - Done attempting. 19:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:23 INFO - (blobuploader) - INFO - Done attempting. 19:16:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:16:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:16:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:25 INFO - (blobuploader) - INFO - Done attempting. 19:16:25 INFO - (blobuploader) - INFO - Iteration through files over. 19:16:25 INFO - Return code: 0 19:16:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:16:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:16:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192275ed7474645a43c671f56f952bad1c84c56ea3890ca48907c0194312017ee7cc717e96827ed77f4c7a66be3a476cc7af95e09a32a2da6a69647209aeaacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9570c5a39a6471decb36c4a013aca60560153864acd172804a565c2781cabeef8db3928b6957f69dbf866db1463207b8e430929df80f2a70d51023af8ce4ed2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf9bea6404e0b2423e92a3515730c92c849b812c16cea08e107d8e0046f41c8768ed0fb765dbd4f7323957178f7ec618e9268fbf79de97d58cfde512f82cb76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a544f913704eb498c646f60f1d4d956db975f1a22cfa2e37517f7a29dda09ef6eafa326346f265d99e1a6f4efc067053748c5b2510f0a0199c85b3b4768f29a"} 19:16:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:16:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:16:25 INFO - Contents: 19:16:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192275ed7474645a43c671f56f952bad1c84c56ea3890ca48907c0194312017ee7cc717e96827ed77f4c7a66be3a476cc7af95e09a32a2da6a69647209aeaacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9570c5a39a6471decb36c4a013aca60560153864acd172804a565c2781cabeef8db3928b6957f69dbf866db1463207b8e430929df80f2a70d51023af8ce4ed2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf9bea6404e0b2423e92a3515730c92c849b812c16cea08e107d8e0046f41c8768ed0fb765dbd4f7323957178f7ec618e9268fbf79de97d58cfde512f82cb76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a544f913704eb498c646f60f1d4d956db975f1a22cfa2e37517f7a29dda09ef6eafa326346f265d99e1a6f4efc067053748c5b2510f0a0199c85b3b4768f29a"} 19:16:25 INFO - Running post-run listener: copy_logs_to_upload_dir 19:16:25 INFO - Copying logs to upload dir... 19:16:25 INFO - mkdir: /builds/slave/test/build/upload/logs 19:16:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.464351 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-11 19:16:25.976178) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-11 19:16:25.979885) ========= 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.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/192275ed7474645a43c671f56f952bad1c84c56ea3890ca48907c0194312017ee7cc717e96827ed77f4c7a66be3a476cc7af95e09a32a2da6a69647209aeaacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9570c5a39a6471decb36c4a013aca60560153864acd172804a565c2781cabeef8db3928b6957f69dbf866db1463207b8e430929df80f2a70d51023af8ce4ed2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf9bea6404e0b2423e92a3515730c92c849b812c16cea08e107d8e0046f41c8768ed0fb765dbd4f7323957178f7ec618e9268fbf79de97d58cfde512f82cb76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a544f913704eb498c646f60f1d4d956db975f1a22cfa2e37517f7a29dda09ef6eafa326346f265d99e1a6f4efc067053748c5b2510f0a0199c85b3b4768f29a"} build_url:https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/GzPepZ70ROuRwHqncvDRvg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192275ed7474645a43c671f56f952bad1c84c56ea3890ca48907c0194312017ee7cc717e96827ed77f4c7a66be3a476cc7af95e09a32a2da6a69647209aeaacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9570c5a39a6471decb36c4a013aca60560153864acd172804a565c2781cabeef8db3928b6957f69dbf866db1463207b8e430929df80f2a70d51023af8ce4ed2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf9bea6404e0b2423e92a3515730c92c849b812c16cea08e107d8e0046f41c8768ed0fb765dbd4f7323957178f7ec618e9268fbf79de97d58cfde512f82cb76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a544f913704eb498c646f60f1d4d956db975f1a22cfa2e37517f7a29dda09ef6eafa326346f265d99e1a6f4efc067053748c5b2510f0a0199c85b3b4768f29a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-11 19:16:26.014618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 19:16:26.014960) ========= 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.mXP2jLkKZL/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.TiSGirSQbz/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005275 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-11 19:16:26.063273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-11 19:16:26.063584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-11 19:16:26.063929) ========= ========= Total master_lag: 0.18 =========