builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0119 starttime: 1493874538.4 results: success (0) buildid: 20170503200720 builduid: f0490d962d6844a9a2bb98bf77f102cf revision: 21bcd10f1a108706b8b7bafcddeb71edca037488 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.400986) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.401431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.401749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.428831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.429182) ========= 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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-05-03 22:08:58-- 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% 65.6M=0s 2017-05-03 22:08:58 (65.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214098 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.668686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.669044) ========= 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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.028430 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:08:58.779714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 22:08:58.780076) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21bcd10f1a108706b8b7bafcddeb71edca037488 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21bcd10f1a108706b8b7bafcddeb71edca037488 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-05-03 22:08:58,912 truncating revision to first 12 chars 2017-05-03 22:08:58,912 Setting DEBUG logging. 2017-05-03 22:08:58,913 attempt 1/10 2017-05-03 22:08:58,913 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/21bcd10f1a10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 22:09:00,684 unpacking tar archive at: mozilla-beta-21bcd10f1a10/testing/mozharness/ program finished with exit code 0 elapsedTime=2.039366 ========= master_lag: 0.51 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 22:09:01.327370) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 22:09:01.327713) ========= script_repo_revision: 21bcd10f1a108706b8b7bafcddeb71edca037488 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 22:09:01.328107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 22:09:01.328385) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 22:09:01.349433) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-03 22:09:01.349796) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:01 INFO - MultiFileLogger online at 20170503 22:09:01 in /builds/slave/test 22:09:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:09:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:09:01 INFO - {'append_to_log': False, 22:09:01 INFO - 'base_work_dir': '/builds/slave/test', 22:09:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:09:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:09:01 INFO - 'branch': 'Mozilla-Beta', 22:09:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:09:01 INFO - 'config_files': ('talos/mac_config.py',), 22:09:01 INFO - 'default_actions': ('clobber', 22:09:01 INFO - 'read-buildbot-config', 22:09:01 INFO - 'download-and-extract', 22:09:01 INFO - 'populate-webroot', 22:09:01 INFO - 'create-virtualenv', 22:09:01 INFO - 'install', 22:09:01 INFO - 'run-tests'), 22:09:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:09:01 INFO - 'download_minidump_stackwalk': True, 22:09:01 INFO - 'download_symbols': 'ondemand', 22:09:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:09:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:09:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:09:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:09:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:09:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:09:01 INFO - 'gecko_profile': False, 22:09:01 INFO - 'gecko_profile_interval': 0, 22:09:01 INFO - 'installer_path': 'installer.exe', 22:09:01 INFO - 'log_level': 'info', 22:09:01 INFO - 'log_name': 'talos', 22:09:01 INFO - 'log_to_console': True, 22:09:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:09:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:09:01 INFO - 'opt_config_files': (), 22:09:01 INFO - 'pip_index': False, 22:09:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:01 INFO - 'cmd': ('bash', 22:09:01 INFO - '-c', 22:09:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:01 INFO - 'enabled': True, 22:09:01 INFO - 'halt_on_failure': False, 22:09:01 INFO - 'name': 'check_screen_resolution'},), 22:09:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:01 INFO - 'cmd': ('bash', 22:09:01 INFO - '-c', 22:09:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:01 INFO - 'enabled': True, 22:09:01 INFO - 'halt_on_failure': False, 22:09:01 INFO - 'name': 'check_screen_resolution'},), 22:09:01 INFO - 'run_cmd_checks_enabled': True, 22:09:01 INFO - 'suite': 'dromaeojs-e10s', 22:09:01 INFO - 'system_bits': '32', 22:09:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:09:01 INFO - 'title': 't-yosemite-r7-0119', 22:09:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:09:01 INFO - 'use_talos_json': True, 22:09:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:09:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:09:01 INFO - 'work_dir': 'build'} 22:09:01 INFO - [mozharness: 2017-05-04 05:09:01.592741Z] Running clobber step. 22:09:01 INFO - Running pre-action listener: _resource_record_pre_action 22:09:01 INFO - Running main action method: clobber 22:09:01 INFO - rmtree: /builds/slave/test/build 22:09:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:09:04 INFO - Running post-action listener: _resource_record_post_action 22:09:04 INFO - [mozharness: 2017-05-04 05:09:04.043055Z] Finished clobber step (success) 22:09:04 INFO - [mozharness: 2017-05-04 05:09:04.043223Z] Running read-buildbot-config step. 22:09:04 INFO - Running pre-action listener: _resource_record_pre_action 22:09:04 INFO - Running main action method: read_buildbot_config 22:09:04 INFO - Using buildbot properties: 22:09:04 INFO - { 22:09:04 INFO - "project": "", 22:09:04 INFO - "product": "firefox", 22:09:04 INFO - "script_repo_revision": "production", 22:09:04 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:09:04 INFO - "repository": "", 22:09:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:09:04 INFO - "buildid": "20170503200720", 22:09:04 INFO - "pgo_build": "False", 22:09:04 INFO - "basedir": "/builds/slave/test", 22:09:04 INFO - "buildnumber": 31, 22:09:04 INFO - "slavename": "t-yosemite-r7-0119", 22:09:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:09:04 INFO - "platform": "yosemite_r7", 22:09:04 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:09:04 INFO - "branch": "mozilla-beta", 22:09:04 INFO - "repo_path": "releases/mozilla-beta", 22:09:04 INFO - "revision": "21bcd10f1a108706b8b7bafcddeb71edca037488", 22:09:04 INFO - "stage_platform": "macosx64", 22:09:04 INFO - "builduid": "f0490d962d6844a9a2bb98bf77f102cf", 22:09:04 INFO - "slavebuilddir": "test" 22:09:04 INFO - } 22:09:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 22:09:04 INFO - Running post-action listener: _resource_record_post_action 22:09:04 INFO - [mozharness: 2017-05-04 05:09:04.046576Z] Finished read-buildbot-config step (success) 22:09:04 INFO - [mozharness: 2017-05-04 05:09:04.046707Z] Running download-and-extract step. 22:09:04 INFO - Running pre-action listener: _resource_record_pre_action 22:09:04 INFO - Running main action method: download_and_extract 22:09:04 INFO - mkdir: /builds/slave/test/build/tests 22:09:04 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')]} 22:09:04 INFO - trying https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 22:09:04 INFO - Downloading https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 22:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 22:09:05 INFO - Downloaded 1287 bytes. 22:09:05 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 22:09:05 INFO - Using the following test package requirements: 22:09:05 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 22:09:05 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 22:09:05 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 22:09:05 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 22:09:05 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:09:05 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 22:09:05 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 22:09:05 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 22:09:05 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 22:09:05 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 22:09:05 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 22:09:05 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 22:09:05 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 22:09:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 22:09:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 22:09:05 INFO - Fetch https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 22:09:06 INFO - Expected file size: 51223841 22:09:06 INFO - Obtained file size: 51223841 22:09:22 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:09:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 22:09:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 22:09:22 INFO - Fetch https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 22:09:24 INFO - Expected file size: 51223841 22:09:24 INFO - Obtained file size: 51223841 22:09:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 22:09:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 22:09:39 INFO - Fetch https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 22:09:40 INFO - Expected file size: 14404449 22:09:40 INFO - Obtained file size: 14404449 22:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:40 INFO - trying https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 22:09:40 INFO - Downloading https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 22:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 22:09:42 INFO - Downloaded 59547115 bytes. 22:09:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 22:09:42 INFO - mkdir: /builds/slave/test/properties 22:09:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:42 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:42 INFO - Contents: 22:09:42 INFO - build_url:https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 22:09:43 INFO - Running post-action listener: _resource_record_post_action 22:09:43 INFO - Running post-action listener: set_extra_try_arguments 22:09:43 INFO - [mozharness: 2017-05-04 05:09:43.487946Z] Finished download-and-extract step (success) 22:09:43 INFO - [mozharness: 2017-05-04 05:09:43.488093Z] Running populate-webroot step. 22:09:43 INFO - Running pre-action listener: _resource_record_pre_action 22:09:43 INFO - Running main action method: populate_webroot 22:09:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:43 INFO - '--tppagecycles', 22:09:43 INFO - '7'], 22:09:43 INFO - 'tests': ['tsvgm']}}, 22:09:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['damp', 'tps']}, 22:09:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'tests': ['damp', 'tps']}, 22:09:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['dromaeo_dom']}, 22:09:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['basic_compositor_video']}, 22:09:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:09:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['a11yr', 22:09:43 INFO - 'ts_paint', 22:09:43 INFO - 'tpaint', 22:09:43 INFO - 'sessionrestore', 22:09:43 INFO - 'sessionrestore_no_auto_restore', 22:09:43 INFO - 'tabpaint']}, 22:09:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['tsvgx', 22:09:43 INFO - 'tsvgr_opacity', 22:09:43 INFO - 'tart', 22:09:43 INFO - 'tscrollx', 22:09:43 INFO - 'cart', 22:09:43 INFO - 'tsvg_static']}, 22:09:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:43 INFO - 'tsvgr_opacity', 22:09:43 INFO - 'tart', 22:09:43 INFO - 'tscrollx', 22:09:43 INFO - 'cart', 22:09:43 INFO - 'tsvg_static']}, 22:09:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'talos_options': ['--disable-e10s'], 22:09:43 INFO - 'tests': ['tp5o']}, 22:09:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:09:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'talos_options': ['--disable-e10s', 22:09:43 INFO - '--xperf_path', 22:09:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:43 INFO - 'tests': ['tp5n']}, 22:09:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:09:43 INFO - 'talos_options': ['--xperf_path', 22:09:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:43 INFO - 'tests': ['tp5n']}}, 22:09:43 INFO - 'talos.zip': {'path': '', 22:09:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:43 INFO - Running post-action listener: _resource_record_post_action 22:09:43 INFO - [mozharness: 2017-05-04 05:09:43.506372Z] Finished populate-webroot step (success) 22:09:43 INFO - [mozharness: 2017-05-04 05:09:43.506488Z] Running create-virtualenv step. 22:09:43 INFO - Running pre-action listener: _resource_record_pre_action 22:09:43 INFO - Running main action method: create_virtualenv 22:09:43 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:43 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 22:09:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:43 INFO - Using real prefix '/tools/python27' 22:09:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:44 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:47 INFO - Installing pip.................done. 22:09:47 INFO - Return code: 0 22:09:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:09:47 INFO - Reading from file tmpfile_stdout 22:09:47 INFO - Output received: 22:09:47 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) 22:09:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09:47 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')]} 22:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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')]} 22:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:47 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 22:09:47 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 22:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:47 INFO - 'HOME': '/Users/cltbld', 22:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:47 INFO - 'LOGNAME': 'cltbld', 22:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:47 INFO - 'MOZ_NO_REMOTE': '1', 22:09:47 INFO - 'NO_EM_RESTART': '1', 22:09:47 INFO - 'PAGER': '/bin/cat', 22:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:47 INFO - 'PWD': '/builds/slave/test', 22:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:47 INFO - 'SHELL': '/bin/bash', 22:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:47 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:47 INFO - 'USER': 'cltbld', 22:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:47 INFO - 'XPC_FLAGS': '0x0', 22:09:47 INFO - 'XPC_SERVICE_NAME': '0', 22:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:47 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 22:09:47 INFO - Cleaning up... 22:09:47 INFO - Return code: 0 22:09:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:47 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')]} 22:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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')]} 22:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:47 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 22:09:47 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 22:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:47 INFO - 'HOME': '/Users/cltbld', 22:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:47 INFO - 'LOGNAME': 'cltbld', 22:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:47 INFO - 'MOZ_NO_REMOTE': '1', 22:09:47 INFO - 'NO_EM_RESTART': '1', 22:09:47 INFO - 'PAGER': '/bin/cat', 22:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:47 INFO - 'PWD': '/builds/slave/test', 22:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:47 INFO - 'SHELL': '/bin/bash', 22:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:47 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:47 INFO - 'USER': 'cltbld', 22:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:47 INFO - 'XPC_FLAGS': '0x0', 22:09:47 INFO - 'XPC_SERVICE_NAME': '0', 22:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:47 INFO - Downloading/unpacking psutil>=3.1.1 22:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:48 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 22:09:48 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 22:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:50 INFO - Installing collected packages: psutil 22:09:50 INFO - Running setup.py install for psutil 22:09:50 INFO - building 'psutil._psutil_osx' extension 22:09:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:09:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:09:51 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 22:09:51 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 22:09:51 INFO - building 'psutil._psutil_posix' extension 22:09:51 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 22:09:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:09:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:09:51 INFO - ^ 22:09:51 INFO - 1 warning generated. 22:09:51 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 22:09:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:51 INFO - Successfully installed psutil 22:09:51 INFO - Cleaning up... 22:09:51 INFO - Return code: 0 22:09:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09:51 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')]} 22:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:51 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')]} 22:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:52 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:52 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 22:09:52 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 22:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:52 INFO - 'HOME': '/Users/cltbld', 22:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:52 INFO - 'LOGNAME': 'cltbld', 22:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:52 INFO - 'MOZ_NO_REMOTE': '1', 22:09:52 INFO - 'NO_EM_RESTART': '1', 22:09:52 INFO - 'PAGER': '/bin/cat', 22:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:52 INFO - 'PWD': '/builds/slave/test', 22:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:52 INFO - 'SHELL': '/bin/bash', 22:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:52 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:52 INFO - 'USER': 'cltbld', 22:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:52 INFO - 'XPC_FLAGS': '0x0', 22:09:52 INFO - 'XPC_SERVICE_NAME': '0', 22:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:54 INFO - Installing collected packages: mozsystemmonitor 22:09:54 INFO - Running setup.py install for mozsystemmonitor 22:09:54 INFO - Successfully installed mozsystemmonitor 22:09:54 INFO - Cleaning up... 22:09:54 INFO - Return code: 0 22:09:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:09:54 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')]} 22:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:54 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')]} 22:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:54 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:54 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 22:09:54 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 22:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:54 INFO - 'HOME': '/Users/cltbld', 22:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:54 INFO - 'LOGNAME': 'cltbld', 22:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:54 INFO - 'MOZ_NO_REMOTE': '1', 22:09:54 INFO - 'NO_EM_RESTART': '1', 22:09:54 INFO - 'PAGER': '/bin/cat', 22:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:54 INFO - 'PWD': '/builds/slave/test', 22:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:54 INFO - 'SHELL': '/bin/bash', 22:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:54 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:54 INFO - 'USER': 'cltbld', 22:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:54 INFO - 'XPC_FLAGS': '0x0', 22:09:54 INFO - 'XPC_SERVICE_NAME': '0', 22:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:54 INFO - Downloading/unpacking jsonschema==2.5.1 22:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:56 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:56 INFO - warning: no files found matching '*.txt' 22:09:56 INFO - no previously-included directories found matching 'build' 22:09:56 INFO - no previously-included directories found matching 'dist' 22:09:56 INFO - no previously-included directories found matching '.git*' 22:09:56 INFO - Installing collected packages: jsonschema, functools32 22:09:56 INFO - Running setup.py install for functools32 22:09:56 INFO - warning: no files found matching '*.txt' 22:09:56 INFO - no previously-included directories found matching 'build' 22:09:56 INFO - no previously-included directories found matching 'dist' 22:09:56 INFO - no previously-included directories found matching '.git*' 22:09:56 INFO - Successfully installed jsonschema functools32 22:09:56 INFO - Cleaning up... 22:09:56 INFO - Return code: 0 22:09:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:56 INFO - 'HOME': '/Users/cltbld', 22:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:56 INFO - 'LOGNAME': 'cltbld', 22:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:56 INFO - 'MOZ_NO_REMOTE': '1', 22:09:56 INFO - 'NO_EM_RESTART': '1', 22:09:56 INFO - 'PAGER': '/bin/cat', 22:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:56 INFO - 'PWD': '/builds/slave/test', 22:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:56 INFO - 'SHELL': '/bin/bash', 22:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:56 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:56 INFO - 'USER': 'cltbld', 22:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:56 INFO - 'XPC_FLAGS': '0x0', 22:09:56 INFO - 'XPC_SERVICE_NAME': '0', 22:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:09:57 INFO - Cleaning up... 22:09:57 INFO - Return code: 0 22:09:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:57 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')]} 22:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:57 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')]} 22:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:57 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:09:57 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 22:09:57 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 22:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:57 INFO - 'HOME': '/Users/cltbld', 22:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:57 INFO - 'LOGNAME': 'cltbld', 22:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:57 INFO - 'MOZ_NO_REMOTE': '1', 22:09:57 INFO - 'NO_EM_RESTART': '1', 22:09:57 INFO - 'PAGER': '/bin/cat', 22:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:57 INFO - 'PWD': '/builds/slave/test', 22:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:57 INFO - 'SHELL': '/bin/bash', 22:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:09:57 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:57 INFO - 'USER': 'cltbld', 22:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:57 INFO - 'XPC_FLAGS': '0x0', 22:09:57 INFO - 'XPC_SERVICE_NAME': '0', 22:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:57 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:58 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 22:09:58 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 22:09:58 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 22:09:58 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 22:09:58 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 22:09:58 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 22:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09:59 INFO - Downloading docopt-0.6.1.tar.gz 22:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:59 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:59 INFO - Running setup.py install for blobuploader 22:09:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:59 INFO - Running setup.py install for requests 22:10:00 INFO - Running setup.py install for docopt 22:10:00 INFO - Successfully installed blobuploader requests docopt 22:10:00 INFO - Cleaning up... 22:10:00 INFO - Return code: 0 22:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:00 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:10:00 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 22:10:00 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 22:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:00 INFO - 'HOME': '/Users/cltbld', 22:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:00 INFO - 'LOGNAME': 'cltbld', 22:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:00 INFO - 'MOZ_NO_REMOTE': '1', 22:10:00 INFO - 'NO_EM_RESTART': '1', 22:10:00 INFO - 'PAGER': '/bin/cat', 22:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:00 INFO - 'PWD': '/builds/slave/test', 22:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:00 INFO - 'SHELL': '/bin/bash', 22:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:10:00 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:00 INFO - 'USER': 'cltbld', 22:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:00 INFO - 'XPC_FLAGS': '0x0', 22:10:00 INFO - 'XPC_SERVICE_NAME': '0', 22:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:00 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-CHJ8L3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:00 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ANW4zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:00 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-qUKrCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-4DAbfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-gUnnsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-fxRXxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-rEonSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VRNBKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZGJlLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-98Deqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-CMSUKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-jtoFDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:01 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZuMkPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:02 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-tjN0k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:02 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-trl5rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:02 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-y2vQil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:02 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-592Fp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:10:02 INFO - Running setup.py install for manifestparser 22:10:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:10:02 INFO - Running setup.py install for mozcrash 22:10:02 INFO - Running setup.py install for mozdebug 22:10:02 INFO - Running setup.py install for mozdevice 22:10:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Running setup.py install for mozfile 22:10:03 INFO - Running setup.py install for mozhttpd 22:10:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Running setup.py install for mozinfo 22:10:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Running setup.py install for mozInstall 22:10:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Running setup.py install for mozleak 22:10:03 INFO - Running setup.py install for mozlog 22:10:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:10:03 INFO - Running setup.py install for moznetwork 22:10:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:10:04 INFO - Running setup.py install for mozprocess 22:10:04 INFO - Running setup.py install for mozprofile 22:10:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:10:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:10:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:10:04 INFO - Running setup.py install for mozrunner 22:10:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:10:04 INFO - Running setup.py install for mozscreenshot 22:10:04 INFO - Running setup.py install for moztest 22:10:04 INFO - Running setup.py install for mozversion 22:10:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:10:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:10:05 INFO - Cleaning up... 22:10:05 INFO - Return code: 0 22:10:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10: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')]} 22:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10: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')]} 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:10:05 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 22:10:05 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 22:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:05 INFO - 'HOME': '/Users/cltbld', 22:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:05 INFO - 'LOGNAME': 'cltbld', 22:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:05 INFO - 'MOZ_NO_REMOTE': '1', 22:10:05 INFO - 'NO_EM_RESTART': '1', 22:10:05 INFO - 'PAGER': '/bin/cat', 22:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:05 INFO - 'PWD': '/builds/slave/test', 22:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:05 INFO - 'SHELL': '/bin/bash', 22:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:10:05 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:05 INFO - 'USER': 'cltbld', 22:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:05 INFO - 'XPC_FLAGS': '0x0', 22:10:05 INFO - 'XPC_SERVICE_NAME': '0', 22:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-M95FA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-mfGPSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VV7KWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-M9e6nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-84YLUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-WvwSZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:05 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)) 22:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-222r9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-d6XAqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-6XHDMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-gblmeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-FrHeAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ArEaWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-T_2Ddo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-72PSgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-C7GnUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:06 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-hUQuxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:06 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)) 22:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:07 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OKZQgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:07 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)) 22:10:07 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)) 22:10:07 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)) 22:10:07 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)) 22:10:07 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)) 22:10:07 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)) 22:10:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - Downloading blessings-1.6.tar.gz 22:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:08 INFO - Installing collected packages: blessings 22:10:08 INFO - Running setup.py install for blessings 22:10:08 INFO - Successfully installed blessings 22:10:08 INFO - Cleaning up... 22:10:08 INFO - Return code: 0 22:10:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:09 INFO - Reading from file tmpfile_stdout 22:10:09 INFO - Current package versions: 22:10:09 INFO - blessings == 1.6 22:10:09 INFO - blobuploader == 1.2.4 22:10:09 INFO - docopt == 0.6.1 22:10:09 INFO - functools32 == 3.2.3-2 22:10:09 INFO - jsonschema == 2.5.1 22:10:09 INFO - manifestparser == 1.1 22:10:09 INFO - mozInstall == 1.12 22:10:09 INFO - mozcrash == 1.0 22:10:09 INFO - mozdebug == 0.1 22:10:09 INFO - mozdevice == 0.49 22:10:09 INFO - mozfile == 1.2 22:10:09 INFO - mozhttpd == 0.7 22:10:09 INFO - mozinfo == 0.9 22:10:09 INFO - mozleak == 0.1 22:10:09 INFO - mozlog == 3.4 22:10:09 INFO - moznetwork == 0.27 22:10:09 INFO - mozprocess == 0.25 22:10:09 INFO - mozprofile == 0.28 22:10:09 INFO - mozrunner == 6.13 22:10:09 INFO - mozscreenshot == 0.1 22:10:09 INFO - mozsystemmonitor == 0.3 22:10:09 INFO - moztest == 0.8 22:10:09 INFO - mozversion == 1.4 22:10:09 INFO - psutil == 3.1.1 22:10:09 INFO - requests == 1.2.3 22:10:09 INFO - wsgiref == 0.1.2 22:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 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 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:10:09 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 22:10:09 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 22:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:09 INFO - 'HOME': '/Users/cltbld', 22:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:09 INFO - 'LOGNAME': 'cltbld', 22:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:09 INFO - 'MOZ_NO_REMOTE': '1', 22:10:09 INFO - 'NO_EM_RESTART': '1', 22:10:09 INFO - 'PAGER': '/bin/cat', 22:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:09 INFO - 'PWD': '/builds/slave/test', 22:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:09 INFO - 'SHELL': '/bin/bash', 22:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:10:09 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:09 INFO - 'USER': 'cltbld', 22:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:09 INFO - 'XPC_FLAGS': '0x0', 22:10:09 INFO - 'XPC_SERVICE_NAME': '0', 22:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 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)) 22:10:09 INFO - Cleaning up... 22:10:09 INFO - Return code: 0 22:10:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea00d89140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, '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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 22:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:09 INFO - 'HOME': '/Users/cltbld', 22:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:09 INFO - 'LOGNAME': 'cltbld', 22:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:09 INFO - 'MOZ_NO_REMOTE': '1', 22:10:09 INFO - 'NO_EM_RESTART': '1', 22:10:09 INFO - 'PAGER': '/bin/cat', 22:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:09 INFO - 'PWD': '/builds/slave/test', 22:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:09 INFO - 'SHELL': '/bin/bash', 22:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:10:09 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:09 INFO - 'USER': 'cltbld', 22:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:09 INFO - 'XPC_FLAGS': '0x0', 22:10:09 INFO - 'XPC_SERVICE_NAME': '0', 22:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:10:09 INFO - Cleaning up... 22:10:09 INFO - Return code: 0 22:10:09 INFO - Running post-action listener: _resource_record_post_action 22:10:09 INFO - Running post-action listener: _start_resource_monitoring 22:10:09 INFO - Starting resource monitoring. 22:10:09 INFO - [mozharness: 2017-05-04 05:10:09.608597Z] Finished create-virtualenv step (success) 22:10:09 INFO - [mozharness: 2017-05-04 05:10:09.609533Z] Running install step. 22:10:09 INFO - Running pre-action listener: _resource_record_pre_action 22:10:09 INFO - Running main action method: install 22:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:09 INFO - Reading from file tmpfile_stdout 22:10:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:10:09 INFO - Reading from file tmpfile_stdout 22:10:09 INFO - Output received: 22:10:09 INFO - Usage: mozinstall [options] installer 22:10:09 INFO - Options: 22:10:09 INFO - -h, --help show this help message and exit 22:10:09 INFO - -d DEST, --destination=DEST 22:10:09 INFO - Directory to install application into. [default: 22:10:09 INFO - "/builds/slave/test"] 22:10:09 INFO - --app=APP Application being installed. [default: firefox] 22:10:09 INFO - mkdir: /builds/slave/test/build/application 22:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:10:24 INFO - Reading from file tmpfile_stdout 22:10:24 INFO - Output received: 22:10:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:10:24 INFO - Running post-action listener: _resource_record_post_action 22:10:24 INFO - [mozharness: 2017-05-04 05:10:24.591446Z] Finished install step (success) 22:10:24 INFO - [mozharness: 2017-05-04 05:10:24.591582Z] Running run-tests step. 22:10:24 INFO - Running pre-action listener: _resource_record_pre_action 22:10:24 INFO - Running main action method: run_tests 22:10:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:10:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:10:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:10:24 INFO - 2017-05-03 22:10:24.606 screenresolution[1620:7255] starting screenresolution argv=screenresolution get 22:10:24 INFO - 2017-05-03 22:10:24.608 screenresolution[1620:7255] Display 0: 1600x1200x32@60 22:10:24 INFO - 2017-05-03 22:10:24.614 screenresolution[1621:7257] starting screenresolution argv=screenresolution list 22:10:24 INFO - Available Modes on Display 0 22:10:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:10:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:10:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:10:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:10:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:10:24 INFO - Intel Iris: 22:10:24 INFO - Chipset Model: Intel Iris 22:10:24 INFO - Type: GPU 22:10:24 INFO - Bus: Built-In 22:10:24 INFO - VRAM (Dynamic, Max): 1536 MB 22:10:24 INFO - Vendor: Intel (0x8086) 22:10:24 INFO - Device ID: 0x0a2e 22:10:24 INFO - Revision ID: 0x0009 22:10:24 INFO - Displays: 22:10:24 INFO - Display: 22:10:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:10:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:10:24 INFO - Display Serial Number: 1600x1200 60 22:10:24 INFO - Main Display: Yes 22:10:24 INFO - Mirror: Off 22:10:24 INFO - Online: Yes 22:10:24 INFO - Rotation: Supported 22:10:24 INFO - Return code: 0 22:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:10:24 INFO - Python 2.7.3 22:10:24 INFO - Return code: 0 22:10:24 INFO - grabbing minidump binary from tooltool 22:10:24 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')]} 22:10:24 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 0x107b3ed30>, '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 0x107a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b33be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:10:24 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 22:10:24 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 22:10:24 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 22:10:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:10:24 INFO - Return code: 0 22:10:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:10:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:10:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:10:24 INFO - ENV: RUST_BACKTRACE is now 1 22:10:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:10:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 22:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --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 22:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBk0GtZvRL/Render', 22:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:24 INFO - 'HOME': '/Users/cltbld', 22:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:24 INFO - 'LOGNAME': 'cltbld', 22:10:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:10:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:24 INFO - 'MOZ_NO_REMOTE': '1', 22:10:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:10:24 INFO - 'NO_EM_RESTART': '1', 22:10:24 INFO - 'PAGER': '/bin/cat', 22:10:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:24 INFO - 'PWD': '/builds/slave/test', 22:10:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:24 INFO - 'RUST_BACKTRACE': '1', 22:10:24 INFO - 'SHELL': '/bin/bash', 22:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8CWkEpkdxf/Listeners', 22:10:24 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 22:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:24 INFO - 'USER': 'cltbld', 22:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:24 INFO - 'XPC_FLAGS': '0x0', 22:10:24 INFO - 'XPC_SERVICE_NAME': '0', 22:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 22:10:25 INFO - mozversion application_buildid: 20170503200720 22:10:25 INFO - mozversion application_changeset: 21bcd10f1a108706b8b7bafcddeb71edca037488 22:10:25 INFO - mozversion application_display_name: Firefox 22:10:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:10:25 INFO - mozversion application_name: Firefox 22:10:25 INFO - mozversion application_remotingname: firefox 22:10:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:10:25 INFO - mozversion application_vendor: Mozilla 22:10:25 INFO - mozversion application_version: 54.0 22:10:25 INFO - mozversion platform_buildid: 20170503200720 22:10:25 INFO - mozversion platform_changeset: 21bcd10f1a108706b8b7bafcddeb71edca037488 22:10:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:10:25 INFO - mozversion platform_version: 54.0 22:10:25 INFO - using testdate: 1493874625 22:10:25 INFO - actual date: 1493874625 22:10:25 INFO - starting webserver on 'localhost:49234' 22:10:25 INFO - SUITE-START | Running 2 tests 22:10:25 INFO - TEST-START | dromaeo_css 22:10:25 INFO - operating with platform_type : mac_ 22:10:25 INFO - Initialising browser for dromaeo_css test... 22:10:25 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpwg_Am4/profile http://localhost:49234/getInfo.html) 22:10:28 INFO - PROCESS | 1631 | 2017-05-03 22:10:28.019 plugin-container[1634:7427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3b, name = 'com.apple.tsm.portname' 22:10:28 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:28 INFO - PROCESS | 1631 | 2017-05-03 22:10:28.020 plugin-container[1634:7427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 22:10:28 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:28 INFO - PROCESS | 1631 | 2017-05-03 22:10:28.020 plugin-container[1634:7427] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:10:28 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 22:10:28 INFO - PROCESS | 1631 | colorDepth:24 22:10:28 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 22:10:28 INFO - PROCESS | 1631 | __metrics 22:10:28 ERROR - PROCESS | 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 22:10:28 INFO - TEST-INFO | 1631: exit 0 22:10:28 INFO - Browser initialized. 22:10:28 INFO - Running cycle 1/1 for dromaeo_css test... 22:10:28 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpwg_Am4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:31 INFO - PROCESS | 1635 | 2017-05-03 22:10:31.031 plugin-container[1636:7564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 22:10:31 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:31 INFO - PROCESS | 1635 | 2017-05-03 22:10:31.032 plugin-container[1636:7564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 22:10:31 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:31 INFO - PROCESS | 1635 | 2017-05-03 22:10:31.032 plugin-container[1636:7564] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:10:40 INFO - PROCESS | 1635 | RSS: Main: 174268416 22:10:40 INFO - PROCESS | 1635 | 22:13:44 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 22:13:46 INFO - PROCESS | 1635 | RSS: Main: 189517824 22:13:46 INFO - PROCESS | 1635 | 22:16:48 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 22:16:49 INFO - PROCESS | 1635 | RSS: Main: 202588160 22:16:49 INFO - PROCESS | 1635 | 22:19:50 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 22:19:51 INFO - PROCESS | 1635 | RSS: Main: 204853248 22:19:51 INFO - PROCESS | 1635 | 22:22:53 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 22:22:54 INFO - PROCESS | 1635 | RSS: Main: 209981440 22:22:54 INFO - PROCESS | 1635 | 22:25:56 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 22:25:57 INFO - PROCESS | 1635 | RSS: Main: 212676608 22:25:57 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 22:29:00 INFO - PROCESS | 1635 | RSS: Main: 210960384 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | __start_tp_report 22:29:00 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 22:29:00 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 22:29:00 INFO - PROCESS | 1635 | |i|pagename|runs| 22:29:00 INFO - PROCESS | 1635 | |0;dojo.html;3616.8372423240953;3619.6561326673955;3619.83710733017;3637.7453578249524;3669.8165091745423;6195.2255968004;6206.7827626033095;6219.689015549221;6225.879341718489;6237.5945563538335;6097.634141951475;6105.175801266828;6202.1006953991755;6260.686965651711;6272.1219029335925;5533.418991005947;5601.439856014406;5624.29696287964;5635.915461268084;5677.829665110033;5659.179418984266;5659.4340565943485;5661.433856614347;5672.17753425753;5676.375598684142;5485.506304432601;5498.890022199554;5587.888242235153;5669.773209071632;5674.2888457131385;5672.262605861232;5676.602637815355;5682.346530149044;5726.770929162829;5732.570057245724;5464.726763661841;5631.7465714042555;5631.746571404296;5645.689487078209;5670.177824215507;5572.275604171472;6034.969825150846;6225.470834979033;6260.935640941006;6288.685565721741;6399.77600783975;6403.7438502459845;6432.903506447407;6472.093906853044;6505.121808555832;5270.7628156733035;5995.591036106512;6633.436157926534;6659.267480577133;6691.297413771581;6242;6269.592476489105;6311.779087731908;6321.367863213642;6330.082138089997;6514.706838192293;6650.567713098633;6829.6243706596615;6874.621895795679;6883.483738719516;6373.330800265998;6400.743970241138;6447;6478.643674597943;6482.092507049017;5714.7428365723645;6568.737250510022;6709.5303328767495;6734.494912881553;6738.932610673831;5370.8657283567445;6587.473002159816;6624.470042396597;6640.369164929324;6690.698918548677;5965.642061476326;6128.816135515942;6322.9051564226575;6384.3934826191435;6539.574927629689;5371.033214021513;5373.704446255493;5374.3013408256675;5378.838634840961;5398.757055932414;4777.259524773665;4795.136875362468;4799.112164249625;4812.205986012269;4850.393700787402;4893.608511319086;5414.485623865805;6112.144299798032;6221.440070393596;6245.750169993149;6636.469082473391;6694.531382803254;6710.798676039727;6715.059891615178;6726.529142959946;6285.2457705075685;6288.245410550671;6302.401271879165;6315.652639104802;6327.525435592349;5682.431756824367;5707.600467967202;5840.970795145997;5901.881962360729;5910.468057874812;6799.1840979081835;6823.317668233137;6838.179418469616;6838.452923765987;6865.862682746218;6647.900281495685;6671.499637527302;6680.933190668031;6691.029800678972;6717.596944183365;5745.166950792028;6543.247526534319;6785.423239024738;6820.965895170492;6827.078344423638;6321.683915804283;6467.385598368148;6473.352664733678;6539.607623542603;6547.214334279917;6675.766348177985;6682.431993280626;6690.866182676416;6690.996350547457;6710.228323742832;6240.282367527792;6253.124562561154;6329.461995730415;6445.291017987924;6448.5486015980305;6331.90502142459;6516.218053833571;6518.250401203923;6524.739010439529;6538.574992625464;6160.907586386297;6292.811215663537;6351.079093531504;6377.617342959437;6378.649174295459;6471.7734879279;6475.935240647534;6495.512836537373;6497.285298617243;6516.837079072871;6018.21763170785;6184.350643182581;6207.875842483035;6257.4681152102585;6369.777057802955 22:29:00 INFO - PROCESS | 1635 | |1;ext.html;796.2197046894044;842.5871323051705;862.0560486267536;994.5822754443142;1001.859739636451;38909.805450972744;39458.60541394585;40949;41063.589364106425;41170.17659646809;40119;40310.59689403105;40401;40501.392479112765;40519.18961620766;8237.876431853512;8382.036065852444;11282.548698052067;11529.423528823525;11690.298582085059;11269.380184089872;11356.034737047361;11362.772744545104;11373.260738052;11387.08903287735;11381.658550243465;11391.974722274994;11405.828912566318;11417.486213120388;11432;11319.20765546412;11323.547058117665;11339.319640821535;11349.489272982735;11361.886381136166;11164;11204.103671706243;11204.607838725606;11219;11238.66284011481;35399.64600353989;35416.82291588551;35696.46455303173;35753;35773.64226357729;3961.742486738353;3976.880693579197;3999.5200575930785;4001.3997900315185;4041.6564592009713;25639;25682.87158564195;25782;26667.733322666718;27333.04334348288;3145.276586385144;3195.1532843796413;3307.1897385140555;3383.796972181677;3396.439587468065;316142;342839;353094;357544;359673.9999999974;45191;45228.77385613116;45258.321125183145;45268.547314527095;45361.773191133834;46064.53935460602;46090.07819843652;46132.846678833295;46133;46247.76876115598;13508.851747601564;13763.105398149288;14817.407303707947;14821.703565928836;14938.402463901539;12681.556145534862;12767.616971490685;12813.615591532269;12817.295048772221;12839.614811555668;9726.805463890683;10888.455577221108;11458.19792614509;12076.275423474619;12298.077644176723;4546;5704.743286552115;5710.172025056344;5834.474897259267;5835.503982161479;1082.3722241100143;1111.6053800900725;1123.8089524780808;1179.728662407634;1199.3523497311528;23924;28052;28885.133445996653;29647;30448;1073.0878753059837;1098.0172745392829;1140.766142940695;1144.1590431033185;1149.6378640728144;13925;14366.425342986164;14438.855611443962;14522.128672279698;14621.195834229007;474755;474983;475197;475624;478663;49451.50548494541;49684;49717.50282497201;49769.75115124401;50876.74561627242;1458.4239225506078;1480.7334679757532;1508.1403599948144;1508.6756347385365;1512.5084347587078;27812.443751124458;27843.721562784922;28331.57502637421;28698.56952145678;28967.5654865173;43065.78467107644;43132.78433607812;43210.13579728451;43280;43325.78337108294;501812;503918;506243;506350;507891;31115.377692446476;31150;31342.843285783427;31367.372652546364;31729.8413507931;39334.40998384969;39370;39728.60271397365;39950.80024599859;39972.00069998157;27868.442631147667;28194.43611127807;28222.44776537228;28452.715472845008;29208.123756287347;28004.719952801028;28037.57943630888;28130;28773.712262877103;28796.568051478826;31726.841365793025;32836.83581582077;33002.504962426065;33092.503612445355;33225.335493290484;27969;28105.016324428547;28152;28516.287092822844;28904.7109528902;18321.816781832546;21484.140634375075;22008.339749807532;23081.769182308635;23546.52906941886 22:29:00 INFO - PROCESS | 1635 | |2;jquery.html;403.01515038435696;439.8607606299683;443.36840427227787;450.1739308369142;454.56588957545534;19773.50566235845;20608.793912060875;21972.560548789013;22065.448363790892;22094.668579971312;22516.986735596896;22927.19754808582;23926.162584309553;24016.799160042014;24151.154709585167;6751.324867513258;6818.9659051704675;6880.552764070319;7112.85774284514;7120.074390329276;6929.7574584889535;6957.269486703899;6959.408450281732;6973.302669733037;6975.965120174392;6626.105475760759;6896.069030680844;6905.067815844847;6936.79189624312;6962.1297337832775;6632.767853125166;6772.255051944282;6924.446044316443;6945.201301850301;6974.197967233782;6762.560433571802;6772.661366931634;6776.15298087739;6781.186257649064;6806.965965170192;19615.411537653894;26433.867830660914;27335;27674.446511069866;27798.583021254697;3001.5947847040807;3026.651935027478;3061.20408693739;3071.5699802027734;3087.1047396255058;15856.445024424205;16780.077095759727;16833.905796123334;17062;17141.828581714148;2092.5396412789164;2124.341454149219;2151.95696086079;2158.0504577985635;2170.240415854454;131863;140472;159764;176604;189252.16121758323;20109.798902011084;21271.149154033974;21599.56800863974;21726.13095476195;22504.099836006702;32519.34961300761;32542.511862322084;32570.674293256765;32593.185170370456;32671;10600.151987840954;10837.078848297826;10984.890151098387;11015.339079655087;11157.27477713946;9128.813254276902;9157.862632060524;9182.63269469227;9340.365436048663;9370.531473426301;7961.203879611992;8113.6754529818145;8309.961254843145;9586.472743999007;9662.75843103928;3559.341521818472;3706.609237042093;3977.8066580026457;4196.51740049892;4268.100695190672;899.8740176375313;910.3445519226146;926.1016813690709;940.5626383731598;982.6216906491053;19672.409827705193;21962.89018554897;23056.4235894104;23185.652215216785;23222.883885580464;747.3274053351918;889.7152911068525;951.70497145885;976.272676855747;982.40564458503;9172;9464.52677366129;11285.774284514428;11989.580364687194;12039.458224379749;766592;772686;803191;825858;828570;110729.89270107356;127150;158700;163233;172574;1167.9852254860298;1201.3332600406684;1300.3368282175968;1338.852726200117;1342;20184.990750462126;20412.489687757923;20943.58112837704;20999;21007.474813129793;53430.4656953441;67214.65570688462;67646.66176669086;67678.30804229934;69398;94827.10345793184;94847.52576237074;96142.55786163073;96647.51676241573;98091.50954245184;8910.242629376575;11779.941100294443;11789.41052947332;12210.6947326314;12217.328046957502;13267.535636252685;13311;13334.466621335427;13412.597622071353;13687.973401994692;9743.658971935949;11245.718857028312;11261.21171517986;11470.19708620422;11616.477258523555;9217.400868943498;10530.999555042259;10664.786704265716;10793.892061079603;10935.781284374427;11474.368909710047;11719.414029298672;11809.881901180877;11839.230450020721;12035.699107522032;11768.293902365886;11847.644570662895;11934.761304773681;12085.214461060003;12180.390980451119;11379.829302560303;11593.478293476983;11595.420228988685;11668.88331116712;11883.049356051668 22:29:00 INFO - PROCESS | 1635 | |3;mootools.html;1600.199900049975;1629.0307267176033;1645.7120003999298;1649.7360422332422;1652.206940668479;8560.957195214023;9745.22038236941;9981.251406144533;11572.594959176431;11669.299842009475;10923.344599324026;11290;11465.254758440695;11486.368249746281;11491.885081149187;2365.751596082408;2419.177479656916;2419.7217320008162;2424.648425978232;2425.7938075263623;2381.261808839257;2406.109739396417;2406.4224586099385;2407.1093695332665;2408.157144999254;2358.84667496613;2362.7637236276405;2372.074890792592;2373.7982271506944;2378.6550950112223;2334.4163959010248;2345.26124270855;2357.9292621221307;2376.4652953085506;2405.783479486837;2352.282553821084;2372.9525409491885;2392.294273189396;2396.197273913241;2396.532676128156;11444.656660300205;11533.192676512648;11564.017058550033;11613.6515904523;11637.47631356591;5743.10981797822;5867.178594996704;5931.288245410536;5948.37542058082;5951.077582974646;2988.2678743707716;3482.703970162539;3605.963940360615;3624.691901188402;3661.9450708239397;1229.8585662648732;1230.2188110549864;1266.7909794883833;1280.1487011137583;1281.6283277849413;16243.675126497405;16276.26756795947;16452.917735411247;16475;16479.423220187236;4699.624030077585;4862.805487780449;4863.294822250754;4874;4981.626378021663;12014.939925300492;12041.458134383975;12096.334701591497;12134.271943683407;12139.75720485603;5301.257823904656;5309.91146814902;5749.683767392834;5750.769969201268;5762.308522977186;4432.578905004019;5266.8156614519075;5293.708846013506;5367.704776237267;5389.838304850782;3891.1828516011956;4001.7198796084576;4014.4781178446615;4042.171354872244;4099.1596722672375;1046.7173863057085;1054.0671505717496;1054.752133248686;1062.8565143705655;1069.9304545204536;909.3452714045876;936.5691781780321;945.9337846350827;961.7355227312545;974.2985050763439;6651.501137414713;6677.866442671217;6735.730570777114;6881.277465866012;6909.654517274116;955.9235261179089;973.634886917406;992.9950350248203;1009.7980403919246;1053.6522947427331;3393.423118069934;4193.790310484464;4253.340732186516;4293.441852559147;4341.39220509129;13820.308984550733;14400.783988240184;14412.567622971328;14413;14436.422543098368;13018.804717929239;14313.57059288223;14336.139831610137;14345.713085738227;14353.282335883165;10363.94818025905;10617.416042117758;10618;10641.73395665099;10646;6334.176557047738;6730.163806630369;6868.828279293058;6994.720211191699;7068.3285087916565;10265;12322.815157772462;12693.746125077632;12768.297743624189;12922.676933076747;12775.680607984874;12954.935225323814;13102;13142.27717475548;13152.868471315165;8394.916050839414;9314.882214134348;9558.713238602853;9691.418514889128;9706.805863882824;10877.945610271898;10927.344359338465;11056.11551075928;11057.447127643747;11112.999830015337;9254.398464099811;9295;9305.488198149167;9332.906670933475;9412.529373531434;9003.54982250898;9080.772980675536;9155.908440915506;9163.404378715362;9227.44635321856;8137.430379873589;8205.343572514064;8228.670853166046;8276.337892968668;8294.83410331802;5847.502962248256;5868.295804503487;5922.466977972003;6049.637021778707;6115.327313995368;4991.9001619966675;5878.441548052928;6025.18659980897;6208.379162083756;6295.244570651551 22:29:00 INFO - PROCESS | 1635 | |4;prototype.html;313.5171835373525;322.6241428735523;364.7209884438405;383.06340996264146;385.4738282244736;5252.50101240382;5331.667083229193;5341.893162136755;5450.345958484977;5456.972715136424;3985.641292283694;4263.868065967016;5242.7640756165965;5315.521603055724;5395.406505284415;4062.431259623655;4304.827806887721;4424.734515929038;4438.644908407327;4452.421185245914;3709.9797555672135;3719.553653561563;3887.9805600972095;3914;4456.2647163218035;3704.6478035516975;3708.8701895433665;4332.740035597874;4384.18892504886;4412.294032954728;3591.317649646559;3917.764934103963;4125.195586860534;4421.38100665907;4467.910641787179;3475.9745874966693;3629.800360980145;3954.3475326571083;4030.5163380394542;4065;4117.505899292074;4132.73137246078;4899.583535399495;4965;5105.693658380509;4932.630052746058;4964.6276529260085;5132.538071573576;5147.6654017488745;5216.530512253916;4684.578387945101;4761.8095276188915;4959.82640607577;5327.413984461706;5593.300837395325;3658.835352409148;3765.435184722286;4505.189065968125;4571.959646834851;4579.358889755437;5779.653220806723;5888.939990801654;5945.286565612111;6091.177691011745;6128.63228206309;4401.361802538614;5007;5087.796488140507;5100.336956195671;5142.948570514322;4877.853664390074;4938.4320803107385;5263.7104959226845;5269.314989051399;5337.959097975935;4000.5199376075057;4475.977620111878;4525.253333200051;5394.649347792381;5439;5582.162675598693;5689.601727879009;5691.800786972436;5696.943030569724;5720.8283751487525;1670.807857096425;1696.5588946873654;1739.652069586088;1769.2077346384951;1815.5551889787087;1431.914085154894;1446.674498237888;1476.3577843638052;1478.9038712483655;1501.4444655477544;4297.989972356494;4342.934855977162;4997.27757269377;5239.847608015032;5407.405185429599;4562.973331000565;5713.000674645761;5971.790987315423;6012.278526576752;6018.6990650467305;4595.333676616872;4693.882652933704;5095.815115552779;5454.4545545445135;5487.451254874561;2711.945761084767;2729.4677537879925;2908.2438565973;2968.1095671298526;3069.938601227963;5487.835364939058;5737.684427356536;5756.366799652035;6074.726637301332;6165.784197553064;5229.895402092013;5392.40683524812;5828.357276702061;5986.293906236701;6097.085437184369;5190.740462976761;5404.702741349185;5416.593755468356;5792.710364481759;6117.388261173936;5255.344566461552;5330.760115794876;5601.355844077983;5949.405059494189;5966.492848107786;4946.901061978669;4955.925661115159;5489.600151961384;5529.834104976857;5775.480206781242;4873.538862874226;5195.454477279831;5476.589255805751;5546.833594992157;5708.743106560298;4701.247800351927;4824.324594556835;5153.4073581538605;5174;5211.400688920671;5244.685318880879;5702.857428564319;5863.120531920246;5946.762129514944;6000.039993601003;4844.685095468924;4942.406911170682;5346.492083252085;5775.566832487664;5803.506701930383;5343.866403339947;5453.045716999589;5761.135829625589;5830.271216097988;5830.62100963436;5087.820112429731;5295.947040529545;5535.612507124462;5636.492715655447;5682;4643.9783247685455;4917.680350777188;5392.784288628411;5755;5822.7670893163795;4941.382327209099;5450.339959334712;5487.314085739283;5583.832485025456;5775.718591156386 22:29:00 INFO - PROCESS | 1635 | |5;yui.html;652.8890088684923;753.9132999705035;791.1811275330031;857.1942374168282;872.8297981893529;15497.22513874306;17602.29590816369;17758.82241177588;17759.73360399592;17809.910950445246;15484.612884677888;15607;16079.678406431864;16180.19099045046;16303.347866085342;997.930144889858;1026.922980776441;1032.7314898126504;1033.3231733214718;1033.6640591807654;977.4037836919473;1029.1406675426028;1036.1141224253267;1042.1610603464208;1043.2332235806675;1077.7736675298197;1088.1947358954396;1088.2273585754124;1092.1372116028328;1092.1699508373613;1079.1744315598573;1082.7455547946265;1084.8481212630238;1085.3108276244564;1087.0922779479145;806.2703253555483;806.6773290683774;808.296781799838;808.9887640449438;812.4231795425255;17279.654406911915;17402.47792566225;17410.564735881704;17725.645487090314;17918.641627167515;14472.862143855888;14702.632434189123;14787.482438114614;14888;15140.94013419061;10561.996610322007;11896.762064758743;12165;12308.015358771278;12522.937385313106;3844.6347596978244;3923.431102490123;4064.187162567469;4073.5926407359025;4081.530623978251;80083;110638;116433;116538;120990;8764.079771624016;10011.49942502872;11354.432278386214;11704.531818727326;11808.409579520989;17397.47807565775;19157.904210478857;21382.679259811117;23660.64509032366;23728.881355593458;8304.626291816883;8323.375746819012;8391.580420978926;8411.957940210259;8495.787605309917;4533.32000199966;4576.427946506687;4672.369230153886;4777.617790576745;4778.187708089633;3482.519929030152;3569.464580312974;3657.817109144532;3668.834902429397;3678.319510890494;575.7178982298643;661.256086902235;663.6018388966581;664.4916638771247;666.2105405094978;643.4209211709499;711.669178635954;723.6345645449014;747.484235877243;754.7962050246513;9773.755656108655;9860.359076659994;10603.469826508644;10957.232993690364;11130.888691113147;681.8636272745471;728.5631446173015;734.2437289591622;735.3088097188626;737.0712901743841;5095.8980820383385;5272.762725677277;5381;5382.703951282717;5404.108322126884;103967;106014;106108.46945765222;106941;108678.45660771804;72786;89668;91635;92522.53738731264;92576.07423925675;77390.84954297572;78175.2182478168;78944.60527697325;78998.6050069746;80205.59897200477;10001.14990225839;10336.948315258376;10340;10360.222983276133;10370.429626370622;41660.79169604133;42859.7857010713;49337.51987440383;51871;52753.73623132013;55502.72248638893;66910.66544667441;68137;68196.63010650151;69220;11579.768404632028;11699.965544275565;11720.531178752753;11922.225055371373;12491;11146.49840757184;13736.862631373559;13815.240161791198;13856.722865542833;13971.816934779898;10647.787044259227;12532.248065116122;12752;12919.935400323315;12977.351132443151;12202.975462866216;12261.877381226073;12780.936095319465;12809.359532023547;13191.67020824487;12427.378631068592;12513.874861251272;12619.558315458915;12766.744665106831;12932.03009774252;9039.59321830506;9479.241660666992;9709.56306966174;9806.264530160124;9894.06006429388;9035.096490350912;9827.4103553787;10341.586336546754;10581.853310544759;10744.24575335101 22:29:00 INFO - PROCESS | 1635 | __end_tp_report 22:29:00 INFO - PROCESS | 1635 | __start_cc_report 22:29:00 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,382 22:29:00 INFO - PROCESS | 1635 | __end_cc_report 22:29:00 INFO - PROCESS | 1635 | __startTimestamp1493875740726__endTimestamp 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | ------- Summary: start ------- 22:29:00 INFO - PROCESS | 1635 | Number of tests: 6 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:6104.11 Median:6288.69 stddev:660.83 (10.5%) stddev-sans-first:633.39 22:29:00 INFO - PROCESS | 1635 | Values: 3616.8 3619.7 3619.8 3637.7 3669.8 6195.2 6206.8 6219.7 6225.9 6237.6 6097.6 6105.2 6202.1 6260.7 6272.1 5533.4 5601.4 5624.3 5635.9 5677.8 5659.2 5659.4 5661.4 5672.2 5676.4 5485.5 5498.9 5587.9 5669.8 5674.3 5672.3 5676.6 5682.3 5726.8 5732.6 5464.7 5631.7 5631.7 5645.7 5670.2 5572.3 6035.0 6225.5 6260.9 6288.7 6399.8 6403.7 6432.9 6472.1 6505.1 5270.8 5995.6 6633.4 6659.3 6691.3 6242.0 6269.6 6311.8 6321.4 6330.1 6514.7 6650.6 6829.6 6874.6 6883.5 6373.3 6400.7 6447.0 6478.6 6482.1 5714.7 6568.7 6709.5 6734.5 6738.9 5370.9 6587.5 6624.5 6640.4 6690.7 5965.6 6128.8 6322.9 6384.4 6539.6 5371.0 5373.7 5374.3 5378.8 5398.8 4777.3 4795.1 4799.1 4812.2 4850.4 4893.6 5414.5 6112.1 6221.4 6245.8 6636.5 6694.5 6710.8 6715.1 6726.5 6285.2 6288.2 6302.4 6315.7 6327.5 5682.4 5707.6 5841.0 5901.9 5910.5 6799.2 6823.3 6838.2 6838.5 6865.9 6647.9 6671.5 6680.9 6691.0 6717.6 5745.2 6543.2 6785.4 6821.0 6827.1 6321.7 6467.4 6473.4 6539.6 6547.2 6675.8 6682.4 6690.9 6691.0 6710.2 6240.3 6253.1 6329.5 6445.3 6448.5 6331.9 6516.2 6518.3 6524.7 6538.6 6160.9 6292.8 6351.1 6377.6 6378.6 6471.8 6475.9 6495.5 6497.3 6516.8 6018.2 6184.4 6207.9 6257.5 6369.8 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:56856.23 Median:29427.56 stddev:119175.04 (405.0%) stddev-sans-first:119435.02 22:29:00 INFO - PROCESS | 1635 | Values: 796.2 842.6 862.1 994.6 1001.9 38909.8 39458.6 40949.0 41063.6 41170.2 40119.0 40310.6 40401.0 40501.4 40519.2 8237.9 8382.0 11282.5 11529.4 11690.3 11269.4 11356.0 11362.8 11373.3 11387.1 11381.7 11392.0 11405.8 11417.5 11432.0 11319.2 11323.5 11339.3 11349.5 11361.9 11164.0 11204.1 11204.6 11219.0 11238.7 35399.6 35416.8 35696.5 35753.0 35773.6 3961.7 3976.9 3999.5 4001.4 4041.7 25639.0 25682.9 25782.0 26667.7 27333.0 3145.3 3195.2 3307.2 3383.8 3396.4 316142.0 342839.0 353094.0 357544.0 359674.0 45191.0 45228.8 45258.3 45268.5 45361.8 46064.5 46090.1 46132.8 46133.0 46247.8 13508.9 13763.1 14817.4 14821.7 14938.4 12681.6 12767.6 12813.6 12817.3 12839.6 9726.8 10888.5 11458.2 12076.3 12298.1 4546.0 5704.7 5710.2 5834.5 5835.5 1082.4 1111.6 1123.8 1179.7 1199.4 23924.0 28052.0 28885.1 29647.0 30448.0 1073.1 1098.0 1140.8 1144.2 1149.6 13925.0 14366.4 14438.9 14522.1 14621.2 474755.0 474983.0 475197.0 475624.0 478663.0 49451.5 49684.0 49717.5 49769.8 50876.7 1458.4 1480.7 1508.1 1508.7 1512.5 27812.4 27843.7 28331.6 28698.6 28967.6 43065.8 43132.8 43210.1 43280.0 43325.8 501812.0 503918.0 506243.0 506350.0 507891.0 31115.4 31150.0 31342.8 31367.4 31729.8 39334.4 39370.0 39728.6 39950.8 39972.0 27868.4 28194.4 28222.4 28452.7 29208.1 28004.7 28037.6 28130.0 28773.7 28796.6 31726.8 32836.8 33002.5 33092.5 33225.3 27969.0 28105.0 28152.0 28516.3 28904.7 18321.8 21484.1 22008.3 23081.8 23546.5 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:44980.20 Median:25292.51 stddev:133111.20 (526.3%) stddev-sans-first:133442.53 22:29:00 INFO - PROCESS | 1635 | Values: 403.0 439.9 443.4 450.2 454.6 19773.5 20608.8 21972.6 22065.4 22094.7 22517.0 22927.2 23926.2 24016.8 24151.2 6751.3 6819.0 6880.6 7112.9 7120.1 6929.8 6957.3 6959.4 6973.3 6976.0 6626.1 6896.1 6905.1 6936.8 6962.1 6632.8 6772.3 6924.4 6945.2 6974.2 6762.6 6772.7 6776.2 6781.2 6807.0 19615.4 26433.9 27335.0 27674.4 27798.6 3001.6 3026.7 3061.2 3071.6 3087.1 15856.4 16780.1 16833.9 17062.0 17141.8 2092.5 2124.3 2152.0 2158.1 2170.2 131863.0 140472.0 159764.0 176604.0 189252.2 20109.8 21271.1 21599.6 21726.1 22504.1 32519.3 32542.5 32570.7 32593.2 32671.0 10600.2 10837.1 10984.9 11015.3 11157.3 9128.8 9157.9 9182.6 9340.4 9370.5 7961.2 8113.7 8310.0 9586.5 9662.8 3559.3 3706.6 3977.8 4196.5 4268.1 899.9 910.3 926.1 940.6 982.6 19672.4 21962.9 23056.4 23185.7 23222.9 747.3 889.7 951.7 976.3 982.4 9172.0 9464.5 11285.8 11989.6 12039.5 766592.0 772686.0 803191.0 825858.0 828570.0 110729.9 127150.0 158700.0 163233.0 172574.0 1168.0 1201.3 1300.3 1338.9 1342.0 20185.0 20412.5 20943.6 20999.0 21007.5 53430.5 67214.7 67646.7 67678.3 69398.0 94827.1 94847.5 96142.6 96647.5 98091.5 8910.2 11779.9 11789.4 12210.7 12217.3 13267.5 13311.0 13334.5 13412.6 13688.0 9743.7 11245.7 11261.2 11470.2 11616.5 9217.4 10531.0 10664.8 10793.9 10935.8 11474.4 11719.4 11809.9 11839.2 12035.7 11768.3 11847.6 11934.8 12085.2 12180.4 11379.8 11593.5 11595.4 11668.9 11883.0 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:6920.23 Median:2419.45 stddev:4421.75 (182.8%) stddev-sans-first:4416.09 22:29:00 INFO - PROCESS | 1635 | Values: 1600.2 1629.0 1645.7 1649.7 1652.2 8561.0 9745.2 9981.3 11572.6 11669.3 10923.3 11290.0 11465.3 11486.4 11491.9 2365.8 2419.2 2419.7 2424.6 2425.8 2381.3 2406.1 2406.4 2407.1 2408.2 2358.8 2362.8 2372.1 2373.8 2378.7 2334.4 2345.3 2357.9 2376.5 2405.8 2352.3 2373.0 2392.3 2396.2 2396.5 11444.7 11533.2 11564.0 11613.7 11637.5 5743.1 5867.2 5931.3 5948.4 5951.1 2988.3 3482.7 3606.0 3624.7 3661.9 1229.9 1230.2 1266.8 1280.1 1281.6 16243.7 16276.3 16452.9 16475.0 16479.4 4699.6 4862.8 4863.3 4874.0 4981.6 12014.9 12041.5 12096.3 12134.3 12139.8 5301.3 5309.9 5749.7 5750.8 5762.3 4432.6 5266.8 5293.7 5367.7 5389.8 3891.2 4001.7 4014.5 4042.2 4099.2 1046.7 1054.1 1054.8 1062.9 1069.9 909.3 936.6 945.9 961.7 974.3 6651.5 6677.9 6735.7 6881.3 6909.7 955.9 973.6 993.0 1009.8 1053.7 3393.4 4193.8 4253.3 4293.4 4341.4 13820.3 14400.8 14412.6 14413.0 14436.4 13018.8 14313.6 14336.1 14345.7 14353.3 10363.9 10617.4 10618.0 10641.7 10646.0 6334.2 6730.2 6868.8 6994.7 7068.3 10265.0 12322.8 12693.7 12768.3 12922.7 12775.7 12954.9 13102.0 13142.3 13152.9 8394.9 9314.9 9558.7 9691.4 9706.8 10877.9 10927.3 11056.1 11057.4 11113.0 9254.4 9295.0 9305.5 9332.9 9412.5 9003.5 9080.8 9155.9 9163.4 9227.4 8137.4 8205.3 8228.7 8276.3 8294.8 5847.5 5868.3 5922.5 6049.6 6115.3 4991.9 5878.4 6025.2 6208.4 6295.2 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4706.93 Median:5145.31 stddev:1303.51 (25.3%) stddev-sans-first:1264.77 22:29:00 INFO - PROCESS | 1635 | Values: 313.5 322.6 364.7 383.1 385.5 5252.5 5331.7 5341.9 5450.3 5457.0 3985.6 4263.9 5242.8 5315.5 5395.4 4062.4 4304.8 4424.7 4438.6 4452.4 3710.0 3719.6 3888.0 3914.0 4456.3 3704.6 3708.9 4332.7 4384.2 4412.3 3591.3 3917.8 4125.2 4421.4 4467.9 3476.0 3629.8 3954.3 4030.5 4065.0 4117.5 4132.7 4899.6 4965.0 5105.7 4932.6 4964.6 5132.5 5147.7 5216.5 4684.6 4761.8 4959.8 5327.4 5593.3 3658.8 3765.4 4505.2 4572.0 4579.4 5779.7 5888.9 5945.3 6091.2 6128.6 4401.4 5007.0 5087.8 5100.3 5142.9 4877.9 4938.4 5263.7 5269.3 5338.0 4000.5 4476.0 4525.3 5394.6 5439.0 5582.2 5689.6 5691.8 5696.9 5720.8 1670.8 1696.6 1739.7 1769.2 1815.6 1431.9 1446.7 1476.4 1478.9 1501.4 4298.0 4342.9 4997.3 5239.8 5407.4 4563.0 5713.0 5971.8 6012.3 6018.7 4595.3 4693.9 5095.8 5454.5 5487.5 2711.9 2729.5 2908.2 2968.1 3069.9 5487.8 5737.7 5756.4 6074.7 6165.8 5229.9 5392.4 5828.4 5986.3 6097.1 5190.7 5404.7 5416.6 5792.7 6117.4 5255.3 5330.8 5601.4 5949.4 5966.5 4946.9 4955.9 5489.6 5529.8 5775.5 4873.5 5195.5 5476.6 5546.8 5708.7 4701.2 4824.3 5153.4 5174.0 5211.4 5244.7 5702.9 5863.1 5946.8 6000.0 4844.7 4942.4 5346.5 5775.6 5803.5 5343.9 5453.0 5761.1 5830.3 5830.6 5087.8 5295.9 5535.6 5636.5 5682.0 4644.0 4917.7 5392.8 5755.0 5822.8 4941.4 5450.3 5487.3 5583.8 5775.7 22:29:00 INFO - PROCESS | 1635 | 22:29:00 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20598.92 Median:17784.82 stddev:29836.39 (167.8%) stddev-sans-first:29882.50 22:29:00 INFO - PROCESS | 1635 | Values: 652.9 753.9 791.2 857.2 872.8 15497.2 17602.3 17758.8 17759.7 17809.9 15484.6 15607.0 16079.7 16180.2 16303.3 997.9 1026.9 1032.7 1033.3 1033.7 977.4 1029.1 1036.1 1042.2 1043.2 1077.8 1088.2 1088.2 1092.1 1092.2 1079.2 1082.7 1084.8 1085.3 1087.1 806.3 806.7 808.3 809.0 812.4 17279.7 17402.5 17410.6 17725.6 17918.6 14472.9 14702.6 14787.5 14888.0 15140.9 10562.0 11896.8 12165.0 12308.0 12522.9 3844.6 3923.4 4064.2 4073.6 4081.5 80083.0 110638.0 116433.0 116538.0 120990.0 8764.1 10011.5 11354.4 11704.5 11808.4 17397.5 19157.9 21382.7 23660.6 23728.9 8304.6 8323.4 8391.6 8412.0 8495.8 4533.3 4576.4 4672.4 4777.6 4778.2 3482.5 3569.5 3657.8 3668.8 3678.3 575.7 661.3 663.6 664.5 666.2 643.4 711.7 723.6 747.5 754.8 9773.8 9860.4 10603.5 10957.2 11130.9 681.9 728.6 734.2 735.3 737.1 5095.9 5272.8 5381.0 5382.7 5404.1 103967.0 106014.0 106108.5 106941.0 108678.5 72786.0 89668.0 91635.0 92522.5 92576.1 77390.8 78175.2 78944.6 78998.6 80205.6 10001.1 10336.9 10340.0 10360.2 10370.4 41660.8 42859.8 49337.5 51871.0 52753.7 55502.7 66910.7 68137.0 68196.6 69220.0 11579.8 11700.0 11720.5 11922.2 12491.0 11146.5 13736.9 13815.2 13856.7 13971.8 10647.8 12532.2 12752.0 12919.9 12977.4 12203.0 12261.9 12780.9 12809.4 13191.7 12427.4 12513.9 12619.6 12766.7 12932.0 9039.6 9479.2 9709.6 9806.3 9894.1 9035.1 9827.4 10341.6 10581.9 10744.2 22:29:00 INFO - PROCESS | 1635 | -------- Summary: end -------- 22:29:00 INFO - PROCESS | 1635 | 22:29:01 INFO - TEST-INFO | 1635: exit 0 22:29:01 INFO - TEST-OK | dromaeo_css | took 1116063ms 22:29:01 INFO - TEST-START | kraken 22:29:01 INFO - operating with platform_type : mac_ 22:29:01 INFO - Initialising browser for kraken test... 22:29:01 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpP7q1PM/profile http://localhost:49234/getInfo.html) 22:29:03 INFO - PROCESS | 1646 | 2017-05-03 22:29:03.964 plugin-container[1648:9963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 22:29:03 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:03 INFO - PROCESS | 1646 | 2017-05-03 22:29:03.965 plugin-container[1648:9963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 22:29:03 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:03 INFO - PROCESS | 1646 | 2017-05-03 22:29:03.965 plugin-container[1648:9963] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:29:04 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 22:29:04 INFO - PROCESS | 1646 | colorDepth:24 22:29:04 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 22:29:04 INFO - PROCESS | 1646 | __metrics 22:29:04 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 22:29:04 INFO - TEST-INFO | 1646: exit 0 22:29:04 INFO - Browser initialized. 22:29:04 INFO - Running cycle 1/1 for kraken test... 22:29:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpP7q1PM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:06 INFO - PROCESS | 1649 | 2017-05-03 22:29:06.975 plugin-container[1650:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854f, name = 'com.apple.tsm.portname' 22:29:06 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:06 INFO - PROCESS | 1649 | 2017-05-03 22:29:06.976 plugin-container[1650:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 22:29:06 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:06 INFO - PROCESS | 1649 | 2017-05-03 22:29:06.976 plugin-container[1650:10085] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:29:16 INFO - PROCESS | 1649 | RSS: Main: 174534656 22:29:16 INFO - PROCESS | 1649 | 22:29:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 22:29:20 INFO - PROCESS | 1649 | RSS: Main: 187908096 22:29:20 INFO - PROCESS | 1649 | 22:29:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 22:29:23 INFO - PROCESS | 1649 | RSS: Main: 195964928 22:29:23 INFO - PROCESS | 1649 | 22:29:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 22:29:27 INFO - PROCESS | 1649 | RSS: Main: 199720960 22:29:27 INFO - PROCESS | 1649 | 22:29:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 22:29:30 INFO - PROCESS | 1649 | RSS: Main: 200314880 22:29:30 INFO - PROCESS | 1649 | 22:29:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:29:32 INFO - PROCESS | 1649 | RSS: Main: 200163328 22:29:32 INFO - PROCESS | 1649 | 22:29:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:36 INFO - PROCESS | 1649 | RSS: Main: 204046336 22:29:36 INFO - PROCESS | 1649 | 22:29:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:40 INFO - PROCESS | 1649 | RSS: Main: 205746176 22:29:40 INFO - PROCESS | 1649 | 22:29:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 22:29:44 INFO - PROCESS | 1649 | RSS: Main: 204947456 22:29:44 INFO - PROCESS | 1649 | 22:29:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:29:46 INFO - PROCESS | 1649 | RSS: Main: 204300288 22:29:46 INFO - PROCESS | 1649 | 22:29:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:49 INFO - PROCESS | 1649 | RSS: Main: 204341248 22:29:49 INFO - PROCESS | 1649 | 22:29:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:51 INFO - PROCESS | 1649 | RSS: Main: 204378112 22:29:51 INFO - PROCESS | 1649 | 22:29:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:55 INFO - PROCESS | 1649 | RSS: Main: 204742656 22:29:55 INFO - PROCESS | 1649 | 22:29:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:59 INFO - PROCESS | 1649 | RSS: Main: 204500992 22:29:59 INFO - PROCESS | 1649 | 22:30:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 22:30:01 INFO - PROCESS | 1649 | RSS: Main: 206049280 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | __start_tp_report 22:30:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 22:30:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 22:30:01 INFO - PROCESS | 1649 | |i|pagename|runs| 22:30:01 INFO - PROCESS | 1649 | |0;ai-astar;110;174;121;122;124;122;122;123;123;122 22:30:01 INFO - PROCESS | 1649 | |1;audio-beat-detection;135;116;103;127;119;142;138;129;127;113 22:30:01 INFO - PROCESS | 1649 | |2;audio-dft;197;196;197;199;198;195;197;198;200;196 22:30:01 INFO - PROCESS | 1649 | |3;audio-fft;82;59;60;87;62;62;61;73;91;84 22:30:01 INFO - PROCESS | 1649 | |4;audio-oscillator;86;72;117;101;100;75;80;71;71;83 22:30:01 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;89;88;91;88;82;136;133;88;88 22:30:01 INFO - PROCESS | 1649 | |6;imaging-darkroom;97;101;126;119;110;118;90;111;153;95 22:30:01 INFO - PROCESS | 1649 | |7;imaging-desaturate;136;132;128;90;117;90;136;75;84;139 22:30:01 INFO - PROCESS | 1649 | |8;json-parse-financial;67;65;66;68;71;82;100;108;106;76 22:30:01 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;59;86;91;96;59;57;57;57;58;57 22:30:01 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;84;87;88;87;83;82;91;77;85;76 22:30:01 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;137;140;159;145;145;132;136;150;202;172 22:30:01 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;169;164;166;162;176;165;210;195;167;166 22:30:01 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;65;65;75;83;87;81;69;65;63;71 22:30:01 INFO - PROCESS | 1649 | __end_tp_report 22:30:01 INFO - PROCESS | 1649 | __start_cc_report 22:30:01 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,981 22:30:01 INFO - PROCESS | 1649 | __end_cc_report 22:30:01 INFO - PROCESS | 1649 | __startTimestamp1493875801170__endTimestamp 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | ------- Summary: start ------- 22:30:01 INFO - PROCESS | 1649 | Number of tests: 14 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:126.30 Median:122.50 stddev:17.22 (14.1%) stddev-sans-first:17.23 22:30:01 INFO - PROCESS | 1649 | Values: 110.0 174.0 121.0 122.0 124.0 122.0 122.0 123.0 123.0 122.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:128.00 stddev:12.14 (9.5%) stddev-sans-first:12.32 22:30:01 INFO - PROCESS | 1649 | Values: 135.0 116.0 103.0 127.0 119.0 142.0 138.0 129.0 127.0 113.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:197.30 Median:197.50 stddev:1.49 (0.8%) stddev-sans-first:1.58 22:30:01 INFO - PROCESS | 1649 | Values: 197.0 196.0 197.0 199.0 198.0 195.0 197.0 198.0 200.0 196.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.10 Median:77.50 stddev:12.76 (16.5%) stddev-sans-first:13.02 22:30:01 INFO - PROCESS | 1649 | Values: 82.0 59.0 60.0 87.0 62.0 62.0 61.0 73.0 91.0 84.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:73.50 stddev:15.61 (21.2%) stddev-sans-first:16.55 22:30:01 INFO - PROCESS | 1649 | Values: 86.0 72.0 117.0 101.0 100.0 75.0 80.0 71.0 71.0 83.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:88.00 stddev:19.85 (22.6%) stddev-sans-first:20.78 22:30:01 INFO - PROCESS | 1649 | Values: 88.0 89.0 88.0 91.0 88.0 82.0 136.0 133.0 88.0 88.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:139.50 stddev:18.52 (13.3%) stddev-sans-first:18.83 22:30:01 INFO - PROCESS | 1649 | Values: 97.0 101.0 126.0 119.0 110.0 118.0 90.0 111.0 153.0 95.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:112.70 Median:107.00 stddev:25.12 (23.5%) stddev-sans-first:25.19 22:30:01 INFO - PROCESS | 1649 | Values: 136.0 132.0 128.0 90.0 117.0 90.0 136.0 75.0 84.0 139.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:80.90 Median:67.50 stddev:17.28 (25.6%) stddev-sans-first:17.58 22:30:01 INFO - PROCESS | 1649 | Values: 67.0 65.0 66.0 68.0 71.0 82.0 100.0 108.0 106.0 76.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:59.00 stddev:16.27 (27.6%) stddev-sans-first:16.95 22:30:01 INFO - PROCESS | 1649 | Values: 59.0 86.0 91.0 96.0 59.0 57.0 57.0 57.0 58.0 57.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:86.00 stddev:4.74 (5.5%) stddev-sans-first:5.02 22:30:01 INFO - PROCESS | 1649 | Values: 84.0 87.0 88.0 87.0 83.0 82.0 91.0 77.0 85.0 76.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:147.50 stddev:21.28 (14.4%) stddev-sans-first:21.89 22:30:01 INFO - PROCESS | 1649 | Values: 137.0 140.0 159.0 145.0 145.0 132.0 136.0 150.0 202.0 172.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:168.00 stddev:15.87 (9.4%) stddev-sans-first:16.73 22:30:01 INFO - PROCESS | 1649 | Values: 169.0 164.0 166.0 162.0 176.0 165.0 210.0 195.0 167.0 166.0 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:73.00 stddev:8.64 (11.8%) stddev-sans-first:8.74 22:30:01 INFO - PROCESS | 1649 | Values: 65.0 65.0 75.0 83.0 87.0 81.0 69.0 65.0 63.0 71.0 22:30:01 INFO - PROCESS | 1649 | -------- Summary: end -------- 22:30:01 INFO - PROCESS | 1649 | 22:30:01 INFO - TEST-INFO | 1649: exit 0 22:30:02 INFO - TEST-OK | kraken | took 60740ms 22:30:02 INFO - SUITE-END | took 1176s 22:30:02 INFO - Completed test suite (00:19:37) 22:30:02 INFO - javascript benchmark 22:30:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7867.2935609375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3616.8372423240953, 3619.6561326673955, 3619.83710733017, 3637.7453578249524, 3669.8165091745423, 6195.2255968004, 6206.7827626033095, 6219.689015549221, 6225.879341718489, 6237.5945563538335, 6097.634141951475, 6105.175801266828, 6202.1006953991755, 6260.686965651711, 6272.1219029335925, 5533.418991005947, 5601.439856014406, 5624.29696287964, 5635.915461268084, 5677.829665110033, 5659.179418984266, 5659.4340565943485, 5661.433856614347, 5672.17753425753, 5676.375598684142, 5485.506304432601, 5498.890022199554, 5587.888242235153, 5669.773209071632, 5674.2888457131385, 5672.262605861232, 5676.602637815355, 5682.346530149044, 5726.770929162829, 5732.570057245724, 5464.726763661841, 5631.7465714042555, 5631.746571404296, 5645.689487078209, 5670.177824215507, 5572.275604171472, 6034.969825150846, 6225.470834979033, 6260.935640941006, 6288.685565721741, 6399.77600783975, 6403.7438502459845, 6432.903506447407, 6472.093906853044, 6505.121808555832, 5270.7628156733035, 5995.591036106512, 6633.436157926534, 6659.267480577133, 6691.297413771581, 6242.0, 6269.592476489105, 6311.779087731908, 6321.367863213642, 6330.082138089997, 6514.706838192293, 6650.567713098633, 6829.6243706596615, 6874.621895795679, 6883.483738719516, 6373.330800265998, 6400.743970241138, 6447.0, 6478.643674597943, 6482.092507049017, 5714.7428365723645, 6568.737250510022, 6709.5303328767495, 6734.494912881553, 6738.932610673831, 5370.8657283567445, 6587.473002159816, 6624.470042396597, 6640.369164929324, 6690.698918548677, 5965.642061476326, 6128.816135515942, 6322.9051564226575, 6384.3934826191435, 6539.574927629689, 5371.033214021513, 5373.704446255493, 5374.3013408256675, 5378.838634840961, 5398.757055932414, 4777.259524773665, 4795.136875362468, 4799.112164249625, 4812.205986012269, 4850.393700787402, 4893.608511319086, 5414.485623865805, 6112.144299798032, 6221.440070393596, 6245.750169993149, 6636.469082473391, 6694.531382803254, 6710.798676039727, 6715.059891615178, 6726.529142959946, 6285.2457705075685, 6288.245410550671, 6302.401271879165, 6315.652639104802, 6327.525435592349, 5682.431756824367, 5707.600467967202, 5840.970795145997, 5901.881962360729, 5910.468057874812, 6799.1840979081835, 6823.317668233137, 6838.179418469616, 6838.452923765987, 6865.862682746218, 6647.900281495685, 6671.499637527302, 6680.933190668031, 6691.029800678972, 6717.596944183365, 5745.166950792028, 6543.247526534319, 6785.423239024738, 6820.965895170492, 6827.078344423638, 6321.683915804283, 6467.385598368148, 6473.352664733678, 6539.607623542603, 6547.214334279917, 6675.766348177985, 6682.431993280626, 6690.866182676416, 6690.996350547457, 6710.228323742832, 6240.282367527792, 6253.124562561154, 6329.461995730415, 6445.291017987924, 6448.5486015980305, 6331.90502142459, 6516.218053833571, 6518.250401203923, 6524.739010439529, 6538.574992625464, 6160.907586386297, 6292.811215663537, 6351.079093531504, 6377.617342959437, 6378.649174295459, 6471.7734879279, 6475.935240647534, 6495.512836537373, 6497.285298617243, 6516.837079072871, 6018.21763170785, 6184.350643182581, 6207.875842483035, 6257.4681152102585, 6369.777057802955], "value": 6065.636956917943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.2197046894044, 842.5871323051705, 862.0560486267536, 994.5822754443142, 1001.859739636451, 38909.805450972744, 39458.60541394585, 40949.0, 41063.589364106425, 41170.17659646809, 40119.0, 40310.59689403105, 40401.0, 40501.392479112765, 40519.18961620766, 8237.876431853512, 8382.036065852444, 11282.548698052067, 11529.423528823525, 11690.298582085059, 11269.380184089872, 11356.034737047361, 11362.772744545104, 11373.260738052, 11387.08903287735, 11381.658550243465, 11391.974722274994, 11405.828912566318, 11417.486213120388, 11432.0, 11319.20765546412, 11323.547058117665, 11339.319640821535, 11349.489272982735, 11361.886381136166, 11164.0, 11204.103671706243, 11204.607838725606, 11219.0, 11238.66284011481, 35399.64600353989, 35416.82291588551, 35696.46455303173, 35753.0, 35773.64226357729, 3961.742486738353, 3976.880693579197, 3999.5200575930785, 4001.3997900315185, 4041.6564592009713, 25639.0, 25682.87158564195, 25782.0, 26667.733322666718, 27333.04334348288, 3145.276586385144, 3195.1532843796413, 3307.1897385140555, 3383.796972181677, 3396.439587468065, 316142.0, 342839.0, 353094.0, 357544.0, 359673.9999999974, 45191.0, 45228.77385613116, 45258.321125183145, 45268.547314527095, 45361.773191133834, 46064.53935460602, 46090.07819843652, 46132.846678833295, 46133.0, 46247.76876115598, 13508.851747601564, 13763.105398149288, 14817.407303707947, 14821.703565928836, 14938.402463901539, 12681.556145534862, 12767.616971490685, 12813.615591532269, 12817.295048772221, 12839.614811555668, 9726.805463890683, 10888.455577221108, 11458.19792614509, 12076.275423474619, 12298.077644176723, 4546.0, 5704.743286552115, 5710.172025056344, 5834.474897259267, 5835.503982161479, 1082.3722241100143, 1111.6053800900725, 1123.8089524780808, 1179.728662407634, 1199.3523497311528, 23924.0, 28052.0, 28885.133445996653, 29647.0, 30448.0, 1073.0878753059837, 1098.0172745392829, 1140.766142940695, 1144.1590431033185, 1149.6378640728144, 13925.0, 14366.425342986164, 14438.855611443962, 14522.128672279698, 14621.195834229007, 474755.0, 474983.0, 475197.0, 475624.0, 478663.0, 49451.50548494541, 49684.0, 49717.50282497201, 49769.75115124401, 50876.74561627242, 1458.4239225506078, 1480.7334679757532, 1508.1403599948144, 1508.6756347385365, 1512.5084347587078, 27812.443751124458, 27843.721562784922, 28331.57502637421, 28698.56952145678, 28967.5654865173, 43065.78467107644, 43132.78433607812, 43210.13579728451, 43280.0, 43325.78337108294, 501812.0, 503918.0, 506243.0, 506350.0, 507891.0, 31115.377692446476, 31150.0, 31342.843285783427, 31367.372652546364, 31729.8413507931, 39334.40998384969, 39370.0, 39728.60271397365, 39950.80024599859, 39972.00069998157, 27868.442631147667, 28194.43611127807, 28222.44776537228, 28452.715472845008, 29208.123756287347, 28004.719952801028, 28037.57943630888, 28130.0, 28773.712262877103, 28796.568051478826, 31726.841365793025, 32836.83581582077, 33002.504962426065, 33092.503612445355, 33225.335493290484, 27969.0, 28105.016324428547, 28152.0, 28516.287092822844, 28904.7109528902, 18321.816781832546, 21484.140634375075, 22008.339749807532, 23081.769182308635, 23546.52906941886], "value": 18666.86430724476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.01515038435696, 439.8607606299683, 443.36840427227787, 450.1739308369142, 454.56588957545534, 19773.50566235845, 20608.793912060875, 21972.560548789013, 22065.448363790892, 22094.668579971312, 22516.986735596896, 22927.19754808582, 23926.162584309553, 24016.799160042014, 24151.154709585167, 6751.324867513258, 6818.9659051704675, 6880.552764070319, 7112.85774284514, 7120.074390329276, 6929.7574584889535, 6957.269486703899, 6959.408450281732, 6973.302669733037, 6975.965120174392, 6626.105475760759, 6896.069030680844, 6905.067815844847, 6936.79189624312, 6962.1297337832775, 6632.767853125166, 6772.255051944282, 6924.446044316443, 6945.201301850301, 6974.197967233782, 6762.560433571802, 6772.661366931634, 6776.15298087739, 6781.186257649064, 6806.965965170192, 19615.411537653894, 26433.867830660914, 27335.0, 27674.446511069866, 27798.583021254697, 3001.5947847040807, 3026.651935027478, 3061.20408693739, 3071.5699802027734, 3087.1047396255058, 15856.445024424205, 16780.077095759727, 16833.905796123334, 17062.0, 17141.828581714148, 2092.5396412789164, 2124.341454149219, 2151.95696086079, 2158.0504577985635, 2170.240415854454, 131863.0, 140472.0, 159764.0, 176604.0, 189252.16121758323, 20109.798902011084, 21271.149154033974, 21599.56800863974, 21726.13095476195, 22504.099836006702, 32519.34961300761, 32542.511862322084, 32570.674293256765, 32593.185170370456, 32671.0, 10600.151987840954, 10837.078848297826, 10984.890151098387, 11015.339079655087, 11157.27477713946, 9128.813254276902, 9157.862632060524, 9182.63269469227, 9340.365436048663, 9370.531473426301, 7961.203879611992, 8113.6754529818145, 8309.961254843145, 9586.472743999007, 9662.75843103928, 3559.341521818472, 3706.609237042093, 3977.8066580026457, 4196.51740049892, 4268.100695190672, 899.8740176375313, 910.3445519226146, 926.1016813690709, 940.5626383731598, 982.6216906491053, 19672.409827705193, 21962.89018554897, 23056.4235894104, 23185.652215216785, 23222.883885580464, 747.3274053351918, 889.7152911068525, 951.70497145885, 976.272676855747, 982.40564458503, 9172.0, 9464.52677366129, 11285.774284514428, 11989.580364687194, 12039.458224379749, 766592.0, 772686.0, 803191.0, 825858.0, 828570.0, 110729.89270107356, 127150.0, 158700.0, 163233.0, 172574.0, 1167.9852254860298, 1201.3332600406684, 1300.3368282175968, 1338.852726200117, 1342.0, 20184.990750462126, 20412.489687757923, 20943.58112837704, 20999.0, 21007.474813129793, 53430.4656953441, 67214.65570688462, 67646.66176669086, 67678.30804229934, 69398.0, 94827.10345793184, 94847.52576237074, 96142.55786163073, 96647.51676241573, 98091.50954245184, 8910.242629376575, 11779.941100294443, 11789.41052947332, 12210.6947326314, 12217.328046957502, 13267.535636252685, 13311.0, 13334.466621335427, 13412.597622071353, 13687.973401994692, 9743.658971935949, 11245.718857028312, 11261.21171517986, 11470.19708620422, 11616.477258523555, 9217.400868943498, 10530.999555042259, 10664.786704265716, 10793.892061079603, 10935.781284374427, 11474.368909710047, 11719.414029298672, 11809.881901180877, 11839.230450020721, 12035.699107522032, 11768.293902365886, 11847.644570662895, 11934.761304773681, 12085.214461060003, 12180.390980451119, 11379.829302560303, 11593.478293476983, 11595.420228988685, 11668.88331116712, 11883.049356051668], "value": 11824.489140084115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.199900049975, 1629.0307267176033, 1645.7120003999298, 1649.7360422332422, 1652.206940668479, 8560.957195214023, 9745.22038236941, 9981.251406144533, 11572.594959176431, 11669.299842009475, 10923.344599324026, 11290.0, 11465.254758440695, 11486.368249746281, 11491.885081149187, 2365.751596082408, 2419.177479656916, 2419.7217320008162, 2424.648425978232, 2425.7938075263623, 2381.261808839257, 2406.109739396417, 2406.4224586099385, 2407.1093695332665, 2408.157144999254, 2358.84667496613, 2362.7637236276405, 2372.074890792592, 2373.7982271506944, 2378.6550950112223, 2334.4163959010248, 2345.26124270855, 2357.9292621221307, 2376.4652953085506, 2405.783479486837, 2352.282553821084, 2372.9525409491885, 2392.294273189396, 2396.197273913241, 2396.532676128156, 11444.656660300205, 11533.192676512648, 11564.017058550033, 11613.6515904523, 11637.47631356591, 5743.10981797822, 5867.178594996704, 5931.288245410536, 5948.37542058082, 5951.077582974646, 2988.2678743707716, 3482.703970162539, 3605.963940360615, 3624.691901188402, 3661.9450708239397, 1229.8585662648732, 1230.2188110549864, 1266.7909794883833, 1280.1487011137583, 1281.6283277849413, 16243.675126497405, 16276.26756795947, 16452.917735411247, 16475.0, 16479.423220187236, 4699.624030077585, 4862.805487780449, 4863.294822250754, 4874.0, 4981.626378021663, 12014.939925300492, 12041.458134383975, 12096.334701591497, 12134.271943683407, 12139.75720485603, 5301.257823904656, 5309.91146814902, 5749.683767392834, 5750.769969201268, 5762.308522977186, 4432.578905004019, 5266.8156614519075, 5293.708846013506, 5367.704776237267, 5389.838304850782, 3891.1828516011956, 4001.7198796084576, 4014.4781178446615, 4042.171354872244, 4099.1596722672375, 1046.7173863057085, 1054.0671505717496, 1054.752133248686, 1062.8565143705655, 1069.9304545204536, 909.3452714045876, 936.5691781780321, 945.9337846350827, 961.7355227312545, 974.2985050763439, 6651.501137414713, 6677.866442671217, 6735.730570777114, 6881.277465866012, 6909.654517274116, 955.9235261179089, 973.634886917406, 992.9950350248203, 1009.7980403919246, 1053.6522947427331, 3393.423118069934, 4193.790310484464, 4253.340732186516, 4293.441852559147, 4341.39220509129, 13820.308984550733, 14400.783988240184, 14412.567622971328, 14413.0, 14436.422543098368, 13018.804717929239, 14313.57059288223, 14336.139831610137, 14345.713085738227, 14353.282335883165, 10363.94818025905, 10617.416042117758, 10618.0, 10641.73395665099, 10646.0, 6334.176557047738, 6730.163806630369, 6868.828279293058, 6994.720211191699, 7068.3285087916565, 10265.0, 12322.815157772462, 12693.746125077632, 12768.297743624189, 12922.676933076747, 12775.680607984874, 12954.935225323814, 13102.0, 13142.27717475548, 13152.868471315165, 8394.916050839414, 9314.882214134348, 9558.713238602853, 9691.418514889128, 9706.805863882824, 10877.945610271898, 10927.344359338465, 11056.11551075928, 11057.447127643747, 11112.999830015337, 9254.398464099811, 9295.0, 9305.488198149167, 9332.906670933475, 9412.529373531434, 9003.54982250898, 9080.772980675536, 9155.908440915506, 9163.404378715362, 9227.44635321856, 8137.430379873589, 8205.343572514064, 8228.670853166046, 8276.337892968668, 8294.83410331802, 5847.502962248256, 5868.295804503487, 5922.466977972003, 6049.637021778707, 6115.327313995368, 4991.9001619966675, 5878.441548052928, 6025.18659980897, 6208.379162083756, 6295.244570651551], "value": 5238.7448476101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.5171835373525, 322.6241428735523, 364.7209884438405, 383.06340996264146, 385.4738282244736, 5252.50101240382, 5331.667083229193, 5341.893162136755, 5450.345958484977, 5456.972715136424, 3985.641292283694, 4263.868065967016, 5242.7640756165965, 5315.521603055724, 5395.406505284415, 4062.431259623655, 4304.827806887721, 4424.734515929038, 4438.644908407327, 4452.421185245914, 3709.9797555672135, 3719.553653561563, 3887.9805600972095, 3914.0, 4456.2647163218035, 3704.6478035516975, 3708.8701895433665, 4332.740035597874, 4384.18892504886, 4412.294032954728, 3591.317649646559, 3917.764934103963, 4125.195586860534, 4421.38100665907, 4467.910641787179, 3475.9745874966693, 3629.800360980145, 3954.3475326571083, 4030.5163380394542, 4065.0, 4117.505899292074, 4132.73137246078, 4899.583535399495, 4965.0, 5105.693658380509, 4932.630052746058, 4964.6276529260085, 5132.538071573576, 5147.6654017488745, 5216.530512253916, 4684.578387945101, 4761.8095276188915, 4959.82640607577, 5327.413984461706, 5593.300837395325, 3658.835352409148, 3765.435184722286, 4505.189065968125, 4571.959646834851, 4579.358889755437, 5779.653220806723, 5888.939990801654, 5945.286565612111, 6091.177691011745, 6128.63228206309, 4401.361802538614, 5007.0, 5087.796488140507, 5100.336956195671, 5142.948570514322, 4877.853664390074, 4938.4320803107385, 5263.7104959226845, 5269.314989051399, 5337.959097975935, 4000.5199376075057, 4475.977620111878, 4525.253333200051, 5394.649347792381, 5439.0, 5582.162675598693, 5689.601727879009, 5691.800786972436, 5696.943030569724, 5720.8283751487525, 1670.807857096425, 1696.5588946873654, 1739.652069586088, 1769.2077346384951, 1815.5551889787087, 1431.914085154894, 1446.674498237888, 1476.3577843638052, 1478.9038712483655, 1501.4444655477544, 4297.989972356494, 4342.934855977162, 4997.27757269377, 5239.847608015032, 5407.405185429599, 4562.973331000565, 5713.000674645761, 5971.790987315423, 6012.278526576752, 6018.6990650467305, 4595.333676616872, 4693.882652933704, 5095.815115552779, 5454.4545545445135, 5487.451254874561, 2711.945761084767, 2729.4677537879925, 2908.2438565973, 2968.1095671298526, 3069.938601227963, 5487.835364939058, 5737.684427356536, 5756.366799652035, 6074.726637301332, 6165.784197553064, 5229.895402092013, 5392.40683524812, 5828.357276702061, 5986.293906236701, 6097.085437184369, 5190.740462976761, 5404.702741349185, 5416.593755468356, 5792.710364481759, 6117.388261173936, 5255.344566461552, 5330.760115794876, 5601.355844077983, 5949.405059494189, 5966.492848107786, 4946.901061978669, 4955.925661115159, 5489.600151961384, 5529.834104976857, 5775.480206781242, 4873.538862874226, 5195.454477279831, 5476.589255805751, 5546.833594992157, 5708.743106560298, 4701.247800351927, 4824.324594556835, 5153.4073581538605, 5174.0, 5211.400688920671, 5244.685318880879, 5702.857428564319, 5863.120531920246, 5946.762129514944, 6000.039993601003, 4844.685095468924, 4942.406911170682, 5346.492083252085, 5775.566832487664, 5803.506701930383, 5343.866403339947, 5453.045716999589, 5761.135829625589, 5830.271216097988, 5830.62100963436, 5087.820112429731, 5295.947040529545, 5535.612507124462, 5636.492715655447, 5682.0, 4643.9783247685455, 4917.680350777188, 5392.784288628411, 5755.0, 5822.7670893163795, 4941.382327209099, 5450.339959334712, 5487.314085739283, 5583.832485025456, 5775.718591156386], "value": 4338.638663535422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.8890088684923, 753.9132999705035, 791.1811275330031, 857.1942374168282, 872.8297981893529, 15497.22513874306, 17602.29590816369, 17758.82241177588, 17759.73360399592, 17809.910950445246, 15484.612884677888, 15607.0, 16079.678406431864, 16180.19099045046, 16303.347866085342, 997.930144889858, 1026.922980776441, 1032.7314898126504, 1033.3231733214718, 1033.6640591807654, 977.4037836919473, 1029.1406675426028, 1036.1141224253267, 1042.1610603464208, 1043.2332235806675, 1077.7736675298197, 1088.1947358954396, 1088.2273585754124, 1092.1372116028328, 1092.1699508373613, 1079.1744315598573, 1082.7455547946265, 1084.8481212630238, 1085.3108276244564, 1087.0922779479145, 806.2703253555483, 806.6773290683774, 808.296781799838, 808.9887640449438, 812.4231795425255, 17279.654406911915, 17402.47792566225, 17410.564735881704, 17725.645487090314, 17918.641627167515, 14472.862143855888, 14702.632434189123, 14787.482438114614, 14888.0, 15140.94013419061, 10561.996610322007, 11896.762064758743, 12165.0, 12308.015358771278, 12522.937385313106, 3844.6347596978244, 3923.431102490123, 4064.187162567469, 4073.5926407359025, 4081.530623978251, 80083.0, 110638.0, 116433.0, 116538.0, 120990.0, 8764.079771624016, 10011.49942502872, 11354.432278386214, 11704.531818727326, 11808.409579520989, 17397.47807565775, 19157.904210478857, 21382.679259811117, 23660.64509032366, 23728.881355593458, 8304.626291816883, 8323.375746819012, 8391.580420978926, 8411.957940210259, 8495.787605309917, 4533.32000199966, 4576.427946506687, 4672.369230153886, 4777.617790576745, 4778.187708089633, 3482.519929030152, 3569.464580312974, 3657.817109144532, 3668.834902429397, 3678.319510890494, 575.7178982298643, 661.256086902235, 663.6018388966581, 664.4916638771247, 666.2105405094978, 643.4209211709499, 711.669178635954, 723.6345645449014, 747.484235877243, 754.7962050246513, 9773.755656108655, 9860.359076659994, 10603.469826508644, 10957.232993690364, 11130.888691113147, 681.8636272745471, 728.5631446173015, 734.2437289591622, 735.3088097188626, 737.0712901743841, 5095.8980820383385, 5272.762725677277, 5381.0, 5382.703951282717, 5404.108322126884, 103967.0, 106014.0, 106108.46945765222, 106941.0, 108678.45660771804, 72786.0, 89668.0, 91635.0, 92522.53738731264, 92576.07423925675, 77390.84954297572, 78175.2182478168, 78944.60527697325, 78998.6050069746, 80205.59897200477, 10001.14990225839, 10336.948315258376, 10340.0, 10360.222983276133, 10370.429626370622, 41660.79169604133, 42859.7857010713, 49337.51987440383, 51871.0, 52753.73623132013, 55502.72248638893, 66910.66544667441, 68137.0, 68196.63010650151, 69220.0, 11579.768404632028, 11699.965544275565, 11720.531178752753, 11922.225055371373, 12491.0, 11146.49840757184, 13736.862631373559, 13815.240161791198, 13856.722865542833, 13971.816934779898, 10647.787044259227, 12532.248065116122, 12752.0, 12919.935400323315, 12977.351132443151, 12202.975462866216, 12261.877381226073, 12780.936095319465, 12809.359532023547, 13191.67020824487, 12427.378631068592, 12513.874861251272, 12619.558315458915, 12766.744665106831, 12932.03009774252, 9039.59321830506, 9479.241660666992, 9709.56306966174, 9806.264530160124, 9894.06006429388, 9035.096490350912, 9827.4103553787, 10341.586336546754, 10581.853310544759, 10744.24575335101], "value": 7791.160047923879, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 174.0, 121.0, 122.0, 124.0, 122.0, 122.0, 123.0, 123.0, 122.0], "value": 126.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 116.0, 103.0, 127.0, 119.0, 142.0, 138.0, 129.0, 127.0, 113.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 197.0, 199.0, 198.0, 195.0, 197.0, 198.0, 200.0, 196.0], "value": 197.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 60.0, 87.0, 62.0, 62.0, 61.0, 73.0, 91.0, 84.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 117.0, 101.0, 100.0, 75.0, 80.0, 71.0, 71.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 91.0, 88.0, 82.0, 136.0, 133.0, 88.0, 88.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 126.0, 119.0, 110.0, 118.0, 90.0, 111.0, 153.0, 95.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 132.0, 128.0, 90.0, 117.0, 90.0, 136.0, 75.0, 84.0, 139.0], "value": 112.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 68.0, 71.0, 82.0, 100.0, 108.0, 106.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 86.0, 91.0, 96.0, 59.0, 57.0, 57.0, 57.0, 58.0, 57.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 88.0, 87.0, 83.0, 82.0, 91.0, 77.0, 85.0, 76.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 159.0, 145.0, 145.0, 132.0, 136.0, 150.0, 202.0, 172.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 166.0, 162.0, 176.0, 165.0, 210.0, 195.0, 167.0, 166.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 75.0, 83.0, 87.0, 81.0, 69.0, 65.0, 63.0, 71.0], "value": 72.4, "unit": "score"}]}]} 22:30:02 INFO - Return code: 0 22:30:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:30:02 ERROR - # TBPL SUCCESS # 22:30:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:30:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:30:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:30:02 INFO - 2017-05-03 22:30:02.484 screenresolution[1655:10479] starting screenresolution argv=screenresolution get 22:30:02 INFO - 2017-05-03 22:30:02.487 screenresolution[1655:10479] Display 0: 1600x1200x32@60 22:30:02 INFO - 2017-05-03 22:30:02.493 screenresolution[1656:10482] starting screenresolution argv=screenresolution list 22:30:02 INFO - Available Modes on Display 0 22:30:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:30:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:30:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:30:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:30:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:30:02 INFO - Intel Iris: 22:30:02 INFO - Chipset Model: Intel Iris 22:30:02 INFO - Type: GPU 22:30:02 INFO - Bus: Built-In 22:30:02 INFO - VRAM (Dynamic, Max): 1536 MB 22:30:02 INFO - Vendor: Intel (0x8086) 22:30:02 INFO - Device ID: 0x0a2e 22:30:02 INFO - Revision ID: 0x0009 22:30:02 INFO - Displays: 22:30:02 INFO - Display: 22:30:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:30:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:30:02 INFO - Display Serial Number: 1600x1200 60 22:30:02 INFO - Main Display: Yes 22:30:02 INFO - Mirror: Off 22:30:02 INFO - Online: Yes 22:30:02 INFO - Rotation: Supported 22:30:02 INFO - Return code: 0 22:30:02 INFO - Running post-action listener: _resource_record_post_action 22:30:02 INFO - [mozharness: 2017-05-04 05:30:02.727667Z] Finished run-tests step (success) 22:30:02 INFO - Running post-run listener: _resource_record_post_run 22:30:02 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 42612736; Write bytes: 427019264; Read time: 419; Write time: 1619 22:30:02 INFO - TinderboxPrint: CPU usage
54.4% 22:30:02 INFO - TinderboxPrint: I/O read bytes / time
42,612,736 / 419 22:30:02 INFO - TinderboxPrint: I/O write bytes / time
427,019,264 / 1,619 22:30:02 INFO - TinderboxPrint: CPU idle
2,173.3 (45.6%) 22:30:02 INFO - TinderboxPrint: CPU system
959.1 (20.1%) 22:30:02 INFO - TinderboxPrint: CPU user
1,637.1 (34.3%) 22:30:02 INFO - TinderboxPrint: Swap in / out
163,184,640 / 0 22:30:02 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 108679680; Write bytes: 102014976; Read time: 9844; Write time: 195 22:30:02 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 41617408; Write bytes: 304167936; Read time: 404; Write time: 1367 22:30:03 INFO - Running post-run listener: _upload_blobber_files 22:30:03 INFO - Blob upload gear active. 22:30:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:30:03 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 22:30:03 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:30:03 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:30:03 INFO - (blobuploader) - INFO - Open directory for files ... 22:30:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:30:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:05 INFO - (blobuploader) - INFO - Done attempting. 22:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:06 INFO - (blobuploader) - INFO - Done attempting. 22:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:30:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:07 INFO - (blobuploader) - INFO - Done attempting. 22:30:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:30:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:30:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:09 INFO - (blobuploader) - INFO - Done attempting. 22:30:09 INFO - (blobuploader) - INFO - Iteration through files over. 22:30:09 INFO - Return code: 0 22:30:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:30:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:30:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e807cd7ca8fb4f3787ebbc52e7c007337e5f9ab2111589f930061ef4bf09dc991a93ab2c7f7dd41bb0ae6eb5c9e2f789b4c2ed47ae85a328846ec615a031bed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f880d36dbdec0252f6c3e64d79ce79f9a4d28c76201f2c98eb7af988a7825cf688a063dd50727292f2523acab8f5bf91360299dfdf7db93d4634a49ae16ecb24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6e896880e946d629023850037275b5dedcccc2f7e36e0f5e968c4154a4496ed0d1923615d4146af088d7c057c25dba9e42b3796237864d924d221fc7bd5200d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f22943ec8e6dafa4369ce1fc83b91cad8f56bc9e49a4aa2152d0e29774e11fa9e2b65c993e699356c10e1a10e27835bd6b99e272aa74117a2248ab82a9fc8a0"} 22:30:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:30:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:30:09 INFO - Contents: 22:30:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e807cd7ca8fb4f3787ebbc52e7c007337e5f9ab2111589f930061ef4bf09dc991a93ab2c7f7dd41bb0ae6eb5c9e2f789b4c2ed47ae85a328846ec615a031bed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f880d36dbdec0252f6c3e64d79ce79f9a4d28c76201f2c98eb7af988a7825cf688a063dd50727292f2523acab8f5bf91360299dfdf7db93d4634a49ae16ecb24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6e896880e946d629023850037275b5dedcccc2f7e36e0f5e968c4154a4496ed0d1923615d4146af088d7c057c25dba9e42b3796237864d924d221fc7bd5200d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f22943ec8e6dafa4369ce1fc83b91cad8f56bc9e49a4aa2152d0e29774e11fa9e2b65c993e699356c10e1a10e27835bd6b99e272aa74117a2248ab82a9fc8a0"} 22:30:09 INFO - Running post-run listener: copy_logs_to_upload_dir 22:30:09 INFO - Copying logs to upload dir... 22:30:09 INFO - mkdir: /builds/slave/test/build/upload/logs 22:30:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.453013 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-03 22:30:09.682288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 22:30:09.685676) ========= 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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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/Mozilla-Beta/sha512/9e807cd7ca8fb4f3787ebbc52e7c007337e5f9ab2111589f930061ef4bf09dc991a93ab2c7f7dd41bb0ae6eb5c9e2f789b4c2ed47ae85a328846ec615a031bed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f880d36dbdec0252f6c3e64d79ce79f9a4d28c76201f2c98eb7af988a7825cf688a063dd50727292f2523acab8f5bf91360299dfdf7db93d4634a49ae16ecb24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6e896880e946d629023850037275b5dedcccc2f7e36e0f5e968c4154a4496ed0d1923615d4146af088d7c057c25dba9e42b3796237864d924d221fc7bd5200d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f22943ec8e6dafa4369ce1fc83b91cad8f56bc9e49a4aa2152d0e29774e11fa9e2b65c993e699356c10e1a10e27835bd6b99e272aa74117a2248ab82a9fc8a0"} build_url:https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/KgD49UiISxG4mPN8sdigig/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e807cd7ca8fb4f3787ebbc52e7c007337e5f9ab2111589f930061ef4bf09dc991a93ab2c7f7dd41bb0ae6eb5c9e2f789b4c2ed47ae85a328846ec615a031bed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f880d36dbdec0252f6c3e64d79ce79f9a4d28c76201f2c98eb7af988a7825cf688a063dd50727292f2523acab8f5bf91360299dfdf7db93d4634a49ae16ecb24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6e896880e946d629023850037275b5dedcccc2f7e36e0f5e968c4154a4496ed0d1923615d4146af088d7c057c25dba9e42b3796237864d924d221fc7bd5200d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f22943ec8e6dafa4369ce1fc83b91cad8f56bc9e49a4aa2152d0e29774e11fa9e2b65c993e699356c10e1a10e27835bd6b99e272aa74117a2248ab82a9fc8a0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 22:30:09.721264) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:30:09.721603) ========= 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.EBk0GtZvRL/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.8CWkEpkdxf/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005170 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 22:30:09.815002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 22:30:09.815347) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 22:30:10.487666) ========= ========= Total master_lag: 0.61 =========