builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1490160626.8 results: success (0) buildid: 20170321205435 builduid: af339c5cc49f4134aa8a9a913df4bede revision: 9ac2fe545f73719d862d85d87764f7728bb34cfc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.797488) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.797951) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.798228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.823016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.823371) ========= 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.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 22:30:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 129M=0s 2017-03-21 22:30:26 (129 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114391 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.961061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:26.961390) ========= 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.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.020588 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:27.009575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-21 22:30:27.009948) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9ac2fe545f73719d862d85d87764f7728bb34cfc --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-aurora --rev 9ac2fe545f73719d862d85d87764f7728bb34cfc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 22:30:27,097 truncating revision to first 12 chars 2017-03-21 22:30:27,097 Setting DEBUG logging. 2017-03-21 22:30:27,098 attempt 1/10 2017-03-21 22:30:27,098 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9ac2fe545f73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 22:30:28,079 unpacking tar archive at: mozilla-aurora-9ac2fe545f73/testing/mozharness/ program finished with exit code 0 elapsedTime=1.237108 ========= master_lag: 1.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-21 22:30:29.825518) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:29.826106) ========= script_repo_revision: 9ac2fe545f73719d862d85d87764f7728bb34cfc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:29.826794) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:29.827264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 22:30:29.918624) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-03-21 22:30:29.919014) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:30:30 INFO - MultiFileLogger online at 20170321 22:30:30 in /builds/slave/test 22:30:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:30:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:30 INFO - {'append_to_log': False, 22:30:30 INFO - 'base_work_dir': '/builds/slave/test', 22:30:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:30:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:30 INFO - 'branch': 'Mozilla-Aurora', 22:30:30 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:30 INFO - 'config_files': ('talos/mac_config.py',), 22:30:30 INFO - 'default_actions': ('clobber', 22:30:30 INFO - 'read-buildbot-config', 22:30:30 INFO - 'download-and-extract', 22:30:30 INFO - 'populate-webroot', 22:30:30 INFO - 'create-virtualenv', 22:30:30 INFO - 'install', 22:30:30 INFO - 'run-tests'), 22:30:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:30 INFO - 'download_minidump_stackwalk': True, 22:30:30 INFO - 'download_symbols': 'ondemand', 22:30:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:30 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:30 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:30 INFO - 'gecko_profile': False, 22:30:30 INFO - 'gecko_profile_interval': 0, 22:30:30 INFO - 'installer_path': 'installer.exe', 22:30:30 INFO - 'log_level': 'info', 22:30:30 INFO - 'log_name': 'talos', 22:30:30 INFO - 'log_to_console': True, 22:30:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:30:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:30:30 INFO - 'opt_config_files': (), 22:30:30 INFO - 'pip_index': False, 22:30:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:30:30 INFO - 'cmd': ('bash', 22:30:30 INFO - '-c', 22:30:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:30:30 INFO - 'enabled': True, 22:30:30 INFO - 'halt_on_failure': False, 22:30:30 INFO - 'name': 'check_screen_resolution'},), 22:30:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:30:30 INFO - 'cmd': ('bash', 22:30:30 INFO - '-c', 22:30:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:30:30 INFO - 'enabled': True, 22:30:30 INFO - 'halt_on_failure': False, 22:30:30 INFO - 'name': 'check_screen_resolution'},), 22:30:30 INFO - 'run_cmd_checks_enabled': True, 22:30:30 INFO - 'suite': 'dromaeojs-e10s', 22:30:30 INFO - 'system_bits': '32', 22:30:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:30 INFO - 'title': 't-yosemite-r7-0390', 22:30:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:30 INFO - 'use_talos_json': True, 22:30:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:30 INFO - 'work_dir': 'build'} 22:30:30 INFO - [mozharness: 2017-03-22 05:30:30.123647Z] Running clobber step. 22:30:30 INFO - Running pre-action listener: _resource_record_pre_action 22:30:30 INFO - Running main action method: clobber 22:30:30 INFO - rmtree: /builds/slave/test/build 22:30:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:30 INFO - Running post-action listener: _resource_record_post_action 22:30:30 INFO - [mozharness: 2017-03-22 05:30:30.283864Z] Finished clobber step (success) 22:30:30 INFO - [mozharness: 2017-03-22 05:30:30.283999Z] Running read-buildbot-config step. 22:30:30 INFO - Running pre-action listener: _resource_record_pre_action 22:30:30 INFO - Running main action method: read_buildbot_config 22:30:30 INFO - Using buildbot properties: 22:30:30 INFO - { 22:30:30 INFO - "project": "", 22:30:30 INFO - "product": "firefox", 22:30:30 INFO - "script_repo_revision": "production", 22:30:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 22:30:30 INFO - "repository": "", 22:30:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 22:30:30 INFO - "buildid": "20170321205435", 22:30:30 INFO - "pgo_build": "False", 22:30:30 INFO - "basedir": "/builds/slave/test", 22:30:30 INFO - "buildnumber": 30, 22:30:30 INFO - "slavename": "t-yosemite-r7-0390", 22:30:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:30:30 INFO - "platform": "yosemite_r7", 22:30:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:30:30 INFO - "branch": "mozilla-aurora", 22:30:30 INFO - "repo_path": "releases/mozilla-aurora", 22:30:30 INFO - "revision": "9ac2fe545f73719d862d85d87764f7728bb34cfc", 22:30:30 INFO - "stage_platform": "macosx64", 22:30:30 INFO - "builduid": "af339c5cc49f4134aa8a9a913df4bede", 22:30:30 INFO - "slavebuilddir": "test" 22:30:30 INFO - } 22:30:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 22:30:30 INFO - Running post-action listener: _resource_record_post_action 22:30:30 INFO - [mozharness: 2017-03-22 05:30:30.287459Z] Finished read-buildbot-config step (success) 22:30:30 INFO - [mozharness: 2017-03-22 05:30:30.287558Z] Running download-and-extract step. 22:30:30 INFO - Running pre-action listener: _resource_record_pre_action 22:30:30 INFO - Running main action method: download_and_extract 22:30:30 INFO - mkdir: /builds/slave/test/build/tests 22:30:30 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:30:30 INFO - trying https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 22:30:30 INFO - Downloading https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 22:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 22:30:31 INFO - Downloaded 1199 bytes. 22:30:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 22:30:31 INFO - Using the following test package requirements: 22:30:31 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 22:30:31 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 22:30:31 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 22:30:31 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'jsshell-mac.zip'], 22:30:31 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 22:30:31 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 22:30:31 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 22:30:31 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 22:30:31 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 22:30:31 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:30:31 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 22:30:31 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:30:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 22:30:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:30:31 INFO - Fetch https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 22:30:33 INFO - Expected file size: 51785221 22:30:33 INFO - Obtained file size: 51785221 22:30:49 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:30:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 22:30:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:30:49 INFO - Fetch https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 22:30:51 INFO - Expected file size: 51785221 22:30:51 INFO - Obtained file size: 51785221 22:31:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 22:31:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:31:06 INFO - Fetch https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 22:31:07 INFO - Expected file size: 14404438 22:31:07 INFO - Obtained file size: 14404438 22:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:07 INFO - trying https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:31:07 INFO - Downloading https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 22:31:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 22:31:09 INFO - Downloaded 74693305 bytes. 22:31:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:31:09 INFO - mkdir: /builds/slave/test/properties 22:31:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:31:09 INFO - Writing to file /builds/slave/test/properties/build_url 22:31:09 INFO - Contents: 22:31:09 INFO - build_url:https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:31:10 INFO - Running post-action listener: _resource_record_post_action 22:31:10 INFO - Running post-action listener: set_extra_try_arguments 22:31:10 INFO - [mozharness: 2017-03-22 05:31:10.477217Z] Finished download-and-extract step (success) 22:31:10 INFO - [mozharness: 2017-03-22 05:31:10.477353Z] Running populate-webroot step. 22:31:10 INFO - Running pre-action listener: _resource_record_pre_action 22:31:10 INFO - Running main action method: populate_webroot 22:31:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:31:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:31:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:31:10 INFO - '--tppagecycles', 22:31:10 INFO - '7'], 22:31:10 INFO - 'tests': ['tsvgm']}}, 22:31:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:31:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:31:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:31:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:31:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['damp', 'tps']}, 22:31:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'tests': ['damp', 'tps']}, 22:31:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['dromaeo_dom']}, 22:31:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:31:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['basic_compositor_video']}, 22:31:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:31:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'other-e10s': {'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['a11yr', 22:31:10 INFO - 'ts_paint', 22:31:10 INFO - 'tpaint', 22:31:10 INFO - 'sessionrestore', 22:31:10 INFO - 'sessionrestore_no_auto_restore', 22:31:10 INFO - 'tabpaint']}, 22:31:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['tsvgx', 22:31:10 INFO - 'tsvgr_opacity', 22:31:10 INFO - 'tart', 22:31:10 INFO - 'tscrollx', 22:31:10 INFO - 'cart', 22:31:10 INFO - 'tsvg_static']}, 22:31:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:31:10 INFO - 'tsvgr_opacity', 22:31:10 INFO - 'tart', 22:31:10 INFO - 'tscrollx', 22:31:10 INFO - 'cart', 22:31:10 INFO - 'tsvg_static']}, 22:31:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'talos_options': ['--disable-e10s'], 22:31:10 INFO - 'tests': ['tp5o']}, 22:31:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:31:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'talos_options': ['--disable-e10s', 22:31:10 INFO - '--xperf_path', 22:31:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:10 INFO - 'tests': ['tp5n']}, 22:31:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:31:10 INFO - 'talos_options': ['--xperf_path', 22:31:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:10 INFO - 'tests': ['tp5n']}}, 22:31:10 INFO - 'talos.zip': {'path': '', 22:31:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:31:10 INFO - Running post-action listener: _resource_record_post_action 22:31:10 INFO - [mozharness: 2017-03-22 05:31:10.496469Z] Finished populate-webroot step (success) 22:31:10 INFO - [mozharness: 2017-03-22 05:31:10.496605Z] Running create-virtualenv step. 22:31:10 INFO - Running pre-action listener: _resource_record_pre_action 22:31:10 INFO - Running main action method: create_virtualenv 22:31:10 INFO - Creating virtualenv /builds/slave/test/build/venv 22:31:10 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:31:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:31:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:31:10 INFO - Using real prefix '/tools/python27' 22:31:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:31:11 INFO - Installing distribute.............................................................................................................................................................................................done. 22:31:14 INFO - Installing pip.................done. 22:31:14 INFO - Return code: 0 22:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:31:14 INFO - Reading from file tmpfile_stdout 22:31:14 INFO - Output received: 22:31:14 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:31:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:31:14 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:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:14 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:31:14 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:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:14 INFO - 'HOME': '/Users/cltbld', 22:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:14 INFO - 'LOGNAME': 'cltbld', 22:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:14 INFO - 'MOZ_NO_REMOTE': '1', 22:31:14 INFO - 'NO_EM_RESTART': '1', 22:31:14 INFO - 'PAGER': '/bin/cat', 22:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:14 INFO - 'PWD': '/builds/slave/test', 22:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:14 INFO - 'SHELL': '/bin/bash', 22:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:14 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:14 INFO - 'USER': 'cltbld', 22:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:14 INFO - 'XPC_FLAGS': '0x0', 22:31:14 INFO - 'XPC_SERVICE_NAME': '0', 22:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:14 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:31:14 INFO - Cleaning up... 22:31:14 INFO - Return code: 0 22:31:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:31:14 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:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:14 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:31:14 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:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:14 INFO - 'HOME': '/Users/cltbld', 22:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:14 INFO - 'LOGNAME': 'cltbld', 22:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:14 INFO - 'MOZ_NO_REMOTE': '1', 22:31:14 INFO - 'NO_EM_RESTART': '1', 22:31:14 INFO - 'PAGER': '/bin/cat', 22:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:14 INFO - 'PWD': '/builds/slave/test', 22:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:14 INFO - 'SHELL': '/bin/bash', 22:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:14 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:14 INFO - 'USER': 'cltbld', 22:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:14 INFO - 'XPC_FLAGS': '0x0', 22:31:14 INFO - 'XPC_SERVICE_NAME': '0', 22:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:14 INFO - Downloading/unpacking psutil>=3.1.1 22:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:31:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:18 INFO - Installing collected packages: psutil 22:31:18 INFO - Running setup.py install for psutil 22:31:18 INFO - building 'psutil._psutil_osx' extension 22:31:18 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:31:18 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:31:18 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:31:18 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:31:19 INFO - building 'psutil._psutil_posix' extension 22:31:19 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:31:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:31:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:31:19 INFO - ^ 22:31:19 INFO - 1 warning generated. 22:31:19 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:31:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:19 INFO - Successfully installed psutil 22:31:19 INFO - Cleaning up... 22:31:19 INFO - Return code: 0 22:31:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:31:19 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:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:19 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:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:19 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:19 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:31:19 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:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:19 INFO - 'HOME': '/Users/cltbld', 22:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:19 INFO - 'LOGNAME': 'cltbld', 22:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:19 INFO - 'MOZ_NO_REMOTE': '1', 22:31:19 INFO - 'NO_EM_RESTART': '1', 22:31:19 INFO - 'PAGER': '/bin/cat', 22:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:19 INFO - 'PWD': '/builds/slave/test', 22:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:19 INFO - 'SHELL': '/bin/bash', 22:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:19 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:19 INFO - 'USER': 'cltbld', 22:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:19 INFO - 'XPC_FLAGS': '0x0', 22:31:19 INFO - 'XPC_SERVICE_NAME': '0', 22:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:31:19 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:31:19 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:31:19 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:31:19 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:31:19 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:31:19 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:31:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:31:22 INFO - Installing collected packages: mozsystemmonitor 22:31:22 INFO - Running setup.py install for mozsystemmonitor 22:31:22 INFO - Successfully installed mozsystemmonitor 22:31:22 INFO - Cleaning up... 22:31:22 INFO - Return code: 0 22:31:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:31:22 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:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:23 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:23 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:31:23 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:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:23 INFO - 'HOME': '/Users/cltbld', 22:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:23 INFO - 'LOGNAME': 'cltbld', 22:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:23 INFO - 'MOZ_NO_REMOTE': '1', 22:31:23 INFO - 'NO_EM_RESTART': '1', 22:31:23 INFO - 'PAGER': '/bin/cat', 22:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:23 INFO - 'PWD': '/builds/slave/test', 22:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:23 INFO - 'SHELL': '/bin/bash', 22:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:23 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:23 INFO - 'USER': 'cltbld', 22:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:23 INFO - 'XPC_FLAGS': '0x0', 22:31:23 INFO - 'XPC_SERVICE_NAME': '0', 22:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:24 INFO - Downloading/unpacking jsonschema==2.5.1 22:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:31:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:27 INFO - Downloading functools32-3.2.3-2.tar.gz 22:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:31:27 INFO - warning: no files found matching '*.txt' 22:31:27 INFO - no previously-included directories found matching 'build' 22:31:27 INFO - no previously-included directories found matching 'dist' 22:31:27 INFO - no previously-included directories found matching '.git*' 22:31:27 INFO - Installing collected packages: jsonschema, functools32 22:31:27 INFO - Running setup.py install for functools32 22:31:27 INFO - warning: no files found matching '*.txt' 22:31:27 INFO - no previously-included directories found matching 'build' 22:31:27 INFO - no previously-included directories found matching 'dist' 22:31:27 INFO - no previously-included directories found matching '.git*' 22:31:27 INFO - Successfully installed jsonschema functools32 22:31:27 INFO - Cleaning up... 22:31:27 INFO - Return code: 0 22:31:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:27 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:27 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:31:27 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:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:27 INFO - 'HOME': '/Users/cltbld', 22:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:27 INFO - 'LOGNAME': 'cltbld', 22:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:27 INFO - 'MOZ_NO_REMOTE': '1', 22:31:27 INFO - 'NO_EM_RESTART': '1', 22:31:27 INFO - 'PAGER': '/bin/cat', 22:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:27 INFO - 'PWD': '/builds/slave/test', 22:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:27 INFO - 'SHELL': '/bin/bash', 22:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:27 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:27 INFO - 'USER': 'cltbld', 22:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:27 INFO - 'XPC_FLAGS': '0x0', 22:31:27 INFO - 'XPC_SERVICE_NAME': '0', 22:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:31:27 INFO - Cleaning up... 22:31:27 INFO - Return code: 0 22:31:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:27 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:27 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:31:27 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:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:27 INFO - 'HOME': '/Users/cltbld', 22:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:27 INFO - 'LOGNAME': 'cltbld', 22:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:27 INFO - 'MOZ_NO_REMOTE': '1', 22:31:27 INFO - 'NO_EM_RESTART': '1', 22:31:27 INFO - 'PAGER': '/bin/cat', 22:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:27 INFO - 'PWD': '/builds/slave/test', 22:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:27 INFO - 'SHELL': '/bin/bash', 22:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:27 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:27 INFO - 'USER': 'cltbld', 22:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:27 INFO - 'XPC_FLAGS': '0x0', 22:31:27 INFO - 'XPC_SERVICE_NAME': '0', 22:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:28 INFO - Downloading/unpacking blobuploader==1.2.4 22:31:28 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:31:28 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:31:28 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:31:28 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:31:28 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:31:28 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:31:30 INFO - Downloading blobuploader-1.2.4.tar.gz 22:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:31:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:31:30 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:31:30 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:31:30 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:31:30 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:31:30 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:31:30 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:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:31:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:31 INFO - Downloading docopt-0.6.1.tar.gz 22:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:31:32 INFO - Installing collected packages: blobuploader, requests, docopt 22:31:32 INFO - Running setup.py install for blobuploader 22:31:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:31:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:31:32 INFO - Running setup.py install for requests 22:31:32 INFO - Running setup.py install for docopt 22:31:32 INFO - Successfully installed blobuploader requests docopt 22:31:32 INFO - Cleaning up... 22:31:32 INFO - Return code: 0 22:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:32 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:32 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:31:32 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:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:32 INFO - 'HOME': '/Users/cltbld', 22:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:32 INFO - 'LOGNAME': 'cltbld', 22:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:32 INFO - 'MOZ_NO_REMOTE': '1', 22:31:32 INFO - 'NO_EM_RESTART': '1', 22:31:32 INFO - 'PAGER': '/bin/cat', 22:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:32 INFO - 'PWD': '/builds/slave/test', 22:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:32 INFO - 'SHELL': '/bin/bash', 22:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:32 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:32 INFO - 'USER': 'cltbld', 22:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:32 INFO - 'XPC_FLAGS': '0x0', 22:31:32 INFO - 'XPC_SERVICE_NAME': '0', 22:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4JpRVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-dMS6wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-71XBoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-EzJssj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-pG530t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-lLta39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-hJ1nnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:33 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-pp25K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-QSsGgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-LQPVKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HvCqTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-jjk9dS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YzGKxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YZGpqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-R0sJqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DSBQZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:34 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GwQJoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:31:34 INFO - Running setup.py install for manifestparser 22:31:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:31:35 INFO - Running setup.py install for mozcrash 22:31:35 INFO - Running setup.py install for mozdebug 22:31:35 INFO - Running setup.py install for mozdevice 22:31:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:31:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:31:35 INFO - Running setup.py install for mozfile 22:31:35 INFO - Running setup.py install for mozhttpd 22:31:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:31:35 INFO - Running setup.py install for mozinfo 22:31:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Running setup.py install for mozInstall 22:31:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Running setup.py install for mozleak 22:31:36 INFO - Running setup.py install for mozlog 22:31:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Running setup.py install for moznetwork 22:31:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Running setup.py install for mozprocess 22:31:36 INFO - Running setup.py install for mozprofile 22:31:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:31:36 INFO - Running setup.py install for mozrunner 22:31:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:31:37 INFO - Running setup.py install for mozscreenshot 22:31:37 INFO - Running setup.py install for moztest 22:31:37 INFO - Running setup.py install for mozversion 22:31:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:31:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:31:37 INFO - Cleaning up... 22:31:37 INFO - Return code: 0 22:31:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:37 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:37 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:31:37 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:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:37 INFO - 'HOME': '/Users/cltbld', 22:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:37 INFO - 'LOGNAME': 'cltbld', 22:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:37 INFO - 'MOZ_NO_REMOTE': '1', 22:31:37 INFO - 'NO_EM_RESTART': '1', 22:31:37 INFO - 'PAGER': '/bin/cat', 22:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:37 INFO - 'PWD': '/builds/slave/test', 22:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:37 INFO - 'SHELL': '/bin/bash', 22:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:37 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:37 INFO - 'USER': 'cltbld', 22:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:37 INFO - 'XPC_FLAGS': '0x0', 22:31:37 INFO - 'XPC_SERVICE_NAME': '0', 22:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:37 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4KWLbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:37 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:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:37 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Hp4QH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-J806lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-h7EP8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-I3ssz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-tnGyCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-s42ArC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-NfihTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-m6E1Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_oXezO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:38 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:31:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:38 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-H_j4_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-djX46K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:39 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:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_vIfDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-H9AOc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:39 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:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CfNd3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9JzTdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:39 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:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:39 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-FI5uvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:39 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:31:42 INFO - Downloading blessings-1.6.tar.gz 22:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:31:42 INFO - Installing collected packages: blessings 22:31:42 INFO - Running setup.py install for blessings 22:31:42 INFO - Successfully installed blessings 22:31:42 INFO - Cleaning up... 22:31:42 INFO - Return code: 0 22:31:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:31:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:42 INFO - Reading from file tmpfile_stdout 22:31:42 INFO - Current package versions: 22:31:42 INFO - blessings == 1.6 22:31:42 INFO - blobuploader == 1.2.4 22:31:42 INFO - docopt == 0.6.1 22:31:42 INFO - functools32 == 3.2.3-2 22:31:42 INFO - jsonschema == 2.5.1 22:31:42 INFO - manifestparser == 1.1 22:31:42 INFO - mozInstall == 1.12 22:31:42 INFO - mozcrash == 1.0 22:31:42 INFO - mozdebug == 0.1 22:31:42 INFO - mozdevice == 0.49 22:31:42 INFO - mozfile == 1.2 22:31:42 INFO - mozhttpd == 0.7 22:31:42 INFO - mozinfo == 0.9 22:31:42 INFO - mozleak == 0.1 22:31:42 INFO - mozlog == 3.4 22:31:42 INFO - moznetwork == 0.27 22:31:42 INFO - mozprocess == 0.25 22:31:42 INFO - mozprofile == 0.28 22:31:42 INFO - mozrunner == 6.13 22:31:42 INFO - mozscreenshot == 0.1 22:31:42 INFO - mozsystemmonitor == 0.3 22:31:42 INFO - moztest == 0.8 22:31:42 INFO - mozversion == 1.4 22:31:42 INFO - psutil == 3.1.1 22:31:42 INFO - requests == 1.2.3 22:31:42 INFO - wsgiref == 0.1.2 22:31:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:42 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 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:42 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:31:42 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:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:42 INFO - 'HOME': '/Users/cltbld', 22:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:42 INFO - 'LOGNAME': 'cltbld', 22:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:42 INFO - 'MOZ_NO_REMOTE': '1', 22:31:42 INFO - 'NO_EM_RESTART': '1', 22:31:42 INFO - 'PAGER': '/bin/cat', 22:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:42 INFO - 'PWD': '/builds/slave/test', 22:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:42 INFO - 'SHELL': '/bin/bash', 22:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:42 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:42 INFO - 'USER': 'cltbld', 22:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:42 INFO - 'XPC_FLAGS': '0x0', 22:31:42 INFO - 'XPC_SERVICE_NAME': '0', 22:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:31:43 INFO - Cleaning up... 22:31:43 INFO - Return code: 0 22:31:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a825b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a828768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2b496200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, '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.ieF5pmA5iv/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.oZmjjwajwl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:43 INFO - 'HOME': '/Users/cltbld', 22:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:43 INFO - 'LOGNAME': 'cltbld', 22:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:43 INFO - 'MOZ_NO_REMOTE': '1', 22:31:43 INFO - 'NO_EM_RESTART': '1', 22:31:43 INFO - 'PAGER': '/bin/cat', 22:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:43 INFO - 'PWD': '/builds/slave/test', 22:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:43 INFO - 'SHELL': '/bin/bash', 22:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:31:43 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:43 INFO - 'USER': 'cltbld', 22:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:43 INFO - 'XPC_FLAGS': '0x0', 22:31:43 INFO - 'XPC_SERVICE_NAME': '0', 22:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:31:43 INFO - Cleaning up... 22:31:43 INFO - Return code: 0 22:31:43 INFO - Running post-action listener: _resource_record_post_action 22:31:43 INFO - Running post-action listener: _start_resource_monitoring 22:31:43 INFO - Starting resource monitoring. 22:31:43 INFO - [mozharness: 2017-03-22 05:31:43.453554Z] Finished create-virtualenv step (success) 22:31:43 INFO - [mozharness: 2017-03-22 05:31:43.454549Z] Running install step. 22:31:43 INFO - Running pre-action listener: _resource_record_pre_action 22:31:43 INFO - Running main action method: install 22:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:43 INFO - Reading from file tmpfile_stdout 22:31:43 INFO - Detecting whether we're running mozinstall >=1.0... 22:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:31:43 INFO - Reading from file tmpfile_stdout 22:31:43 INFO - Output received: 22:31:43 INFO - Usage: mozinstall [options] installer 22:31:43 INFO - Options: 22:31:43 INFO - -h, --help show this help message and exit 22:31:43 INFO - -d DEST, --destination=DEST 22:31:43 INFO - Directory to install application into. [default: 22:31:43 INFO - "/builds/slave/test"] 22:31:43 INFO - --app=APP Application being installed. [default: firefox] 22:31:43 INFO - mkdir: /builds/slave/test/build/application 22:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:32:03 INFO - Reading from file tmpfile_stdout 22:32:03 INFO - Output received: 22:32:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:32:03 INFO - Running post-action listener: _resource_record_post_action 22:32:03 INFO - [mozharness: 2017-03-22 05:32:03.786782Z] Finished install step (success) 22:32:03 INFO - [mozharness: 2017-03-22 05:32:03.786921Z] Running run-tests step. 22:32:03 INFO - Running pre-action listener: _resource_record_pre_action 22:32:03 INFO - Running main action method: run_tests 22:32:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:32:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:32:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:32:03 INFO - 2017-03-21 22:32:03.801 screenresolution[84817:713894] starting screenresolution argv=screenresolution get 22:32:03 INFO - 2017-03-21 22:32:03.803 screenresolution[84817:713894] Display 0: 1600x1200x32@60 22:32:03 INFO - 2017-03-21 22:32:03.809 screenresolution[84818:713896] starting screenresolution argv=screenresolution list 22:32:03 INFO - Available Modes on Display 0 22:32:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:32:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:32:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:32:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:32:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:32:04 INFO - Intel Iris: 22:32:04 INFO - Chipset Model: Intel Iris 22:32:04 INFO - Type: GPU 22:32:04 INFO - Bus: Built-In 22:32:04 INFO - VRAM (Dynamic, Max): 1536 MB 22:32:04 INFO - Vendor: Intel (0x8086) 22:32:04 INFO - Device ID: 0x0a2e 22:32:04 INFO - Revision ID: 0x0009 22:32:04 INFO - Displays: 22:32:04 INFO - Display: 22:32:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:32:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:32:04 INFO - Display Serial Number: 1600x1200 60 22:32:04 INFO - Main Display: Yes 22:32:04 INFO - Mirror: Off 22:32:04 INFO - Online: Yes 22:32:04 INFO - Rotation: Supported 22:32:04 INFO - Return code: 0 22:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:32:04 INFO - Python 2.7.3 22:32:04 INFO - Return code: 0 22:32:04 INFO - grabbing minidump binary from tooltool 22:32: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:32:04 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 0x10a603d30>, '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 0x10a52adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5f8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:32:04 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:32:04 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:32:04 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:32:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:32:04 INFO - Return code: 0 22:32:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:32:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:32:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:32:04 INFO - ENV: RUST_BACKTRACE is now 1 22:32:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:32:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ieF5pmA5iv/Render', 22:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:04 INFO - 'HOME': '/Users/cltbld', 22:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:04 INFO - 'LOGNAME': 'cltbld', 22:32:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:32:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:04 INFO - 'MOZ_NO_REMOTE': '1', 22:32:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:32:04 INFO - 'NO_EM_RESTART': '1', 22:32:04 INFO - 'PAGER': '/bin/cat', 22:32:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:04 INFO - 'PWD': '/builds/slave/test', 22:32:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:04 INFO - 'RUST_BACKTRACE': '1', 22:32:04 INFO - 'SHELL': '/bin/bash', 22:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZmjjwajwl/Listeners', 22:32:04 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:04 INFO - 'USER': 'cltbld', 22:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:04 INFO - 'XPC_FLAGS': '0x0', 22:32:04 INFO - 'XPC_SERVICE_NAME': '0', 22:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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:32:04 INFO - mozversion application_buildid: 20170321205435 22:32:04 INFO - mozversion application_changeset: 9ac2fe545f73719d862d85d87764f7728bb34cfc 22:32:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:32:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:32:04 INFO - mozversion application_name: Firefox 22:32:04 INFO - mozversion application_remotingname: firefox-dev 22:32:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:32:04 INFO - mozversion application_vendor: Mozilla 22:32:04 INFO - mozversion application_version: 54.0a2 22:32:04 INFO - mozversion platform_buildid: 20170321205435 22:32:04 INFO - mozversion platform_changeset: 9ac2fe545f73719d862d85d87764f7728bb34cfc 22:32:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:32:04 INFO - mozversion platform_version: 54.0a2 22:32:04 INFO - using testdate: 1490160724 22:32:04 INFO - actual date: 1490160724 22:32:04 INFO - starting webserver on 'localhost:49447' 22:32:04 INFO - SUITE-START | Running 2 tests 22:32:04 INFO - TEST-START | dromaeo_css 22:32:04 INFO - operating with platform_type : mac_ 22:32:04 INFO - Initialising browser for dromaeo_css test... 22:32:04 INFO - TEST-INFO | started process 84828 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmptrX9sS/profile http://localhost:49447/getInfo.html) 22:32:07 INFO - PROCESS | 84828 | 2017-03-21 22:32:07.114 plugin-container[84831:714074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 22:32:07 INFO - PROCESS | 84828 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:07 INFO - PROCESS | 84828 | 2017-03-21 22:32:07.116 plugin-container[84831:714074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 22:32:07 INFO - PROCESS | 84828 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:07 INFO - PROCESS | 84828 | 2017-03-21 22:32:07.116 plugin-container[84831:714074] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:32:07 INFO - PROCESS | 84828 | __metrics Screen width/height:1600/1200 22:32:07 INFO - PROCESS | 84828 | colorDepth:24 22:32:07 INFO - PROCESS | 84828 | Browser inner width/height: 1024/689 22:32:07 INFO - PROCESS | 84828 | __metrics 22:32:07 ERROR - PROCESS | 84828 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 22:32:07 INFO - TEST-INFO | 84828: exit 0 22:32:07 INFO - Browser initialized. 22:32:07 INFO - Running cycle 1/1 for dromaeo_css test... 22:32:07 INFO - TEST-INFO | started process 84832 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmptrX9sS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:10 INFO - PROCESS | 84832 | 2017-03-21 22:32:10.262 plugin-container[84833:714203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 22:32:10 INFO - PROCESS | 84832 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:10 INFO - PROCESS | 84832 | 2017-03-21 22:32:10.263 plugin-container[84833:714203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 22:32:10 INFO - PROCESS | 84832 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:10 INFO - PROCESS | 84832 | 2017-03-21 22:32:10.263 plugin-container[84833:714203] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:32:20 INFO - PROCESS | 84832 | RSS: Main: 220508160 22:32:20 INFO - PROCESS | 84832 | 22:35:23 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49447/tests/dromaeo/cssquery-ext.html) 22:35:24 INFO - PROCESS | 84832 | RSS: Main: 238284800 22:35:24 INFO - PROCESS | 84832 | 22:37:08 INFO - PROCESS | 84832 | ************************* 22:37:08 INFO - PROCESS | 84832 | A coding exception was thrown and uncaught in a Task. 22:37:08 INFO - PROCESS | 84832 | 22:37:08 ERROR - PROCESS | 84832 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:37:08 INFO - PROCESS | 84832 | Full stack: 22:37:08 INFO - PROCESS | 84832 | ************************* 22:38:27 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-ext.html (next: http://localhost:49447/tests/dromaeo/cssquery-jquery.html) 22:38:27 INFO - PROCESS | 84832 | RSS: Main: 246063104 22:38:27 INFO - PROCESS | 84832 | 22:41:29 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49447/tests/dromaeo/cssquery-mootools.html) 22:41:30 INFO - PROCESS | 84832 | RSS: Main: 256745472 22:41:30 INFO - PROCESS | 84832 | 22:44:32 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49447/tests/dromaeo/cssquery-prototype.html) 22:44:32 INFO - PROCESS | 84832 | RSS: Main: 253128704 22:44:32 INFO - PROCESS | 84832 | 22:47:35 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49447/tests/dromaeo/cssquery-yui.html) 22:47:36 INFO - PROCESS | 84832 | RSS: Main: 257916928 22:47:36 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | Cycle 1(1): loaded http://localhost:49447/tests/dromaeo/cssquery-yui.html (next: http://localhost:49447/tests/dromaeo/cssquery-dojo.html) 22:50:39 INFO - PROCESS | 84832 | RSS: Main: 256233472 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | __start_tp_report 22:50:39 INFO - PROCESS | 84832 | _x_x_mozilla_page_load 22:50:39 INFO - PROCESS | 84832 | _x_x_mozilla_page_load_details 22:50:39 INFO - PROCESS | 84832 | |i|pagename|runs| 22:50:39 INFO - PROCESS | 84832 | |0;dojo.html;2841.4317136572686;3444.500547420624;3472.253465504919;3479.565054368204;3527.347440723466;4804.327394164815;5952.107183922414;6078.240219972504;6080.665563394017;6120.632762034281;6063.484603808682;6078.088286756989;6121.449069583737;6130.0804879268135;6138.109974053771;4638.420197475316;5439.211314359415;5479.53423958962;5487.478689524509;5514.200440936062;5535.557155427577;5542.251796007527;5545.251391062216;5550.361708403545;5557.055300598888;5341.65279256847;5345.786168553253;5535.169724541311;5540.168974653794;5591;5448.155535891943;5455.372632147294;5464.961657285143;5493.121100623881;5517.44825517449;5376.865578360573;5472.097103977839;5476.2333273341765;5478.671279723189;5479.260299859548;5805.36141024487;6027.246594175728;6058.515318774487;6158.414950579688;6179.134921111048;6270.31026587075;6273.466755325803;6278.591891527082;6303.5902666327;6313.242410910674;6425.22897252328;6457.903131453032;6472.676366181672;6475.676216189218;6476.6114033158165;6196.1635179251125;6226.470749986257;6230.190075290183;6233.252009758768;6252.2809876864285;6196.008638617799;6320.115183874262;6545.37818907203;6554.836129096715;6570.8028759137305;6336.1762970814425;6351.523635727339;6361.936380636134;6362.713677884413;6376.872462550723;6449.838754031187;6456;6484.157059582224;6514.9674251629385;6546.279909209983;6239.812805615838;6280.214973128359;6282.968585157045;6293.559450838489;6402.167718196698;6254.468370188494;6378.298387177407;6396.136521569621;6405.775797847146;6411.294757576663;5065.8733531662;5134.589232861362;5164.225366195025;5188.63679542436;5189.040027594907;4572.611328037087;4591.989762252299;4618.769061546909;4626.651221294695;4682.461516925598;5939.881202375928;5987.970060149672;6027.035674292092;6227.501799856001;6230.532710046674;5635.154726790932;6258.968705156445;6339.175907132136;6357.777477788255;6377.96811015942;5181.3782346118705;5823.737931793079;6199.473044791243;6214;6220.564560480723;5561.360443548962;5715.8571035724435;5717.056685646905;5717.171010203498;5729.598928074995;6436.388543088399;6518;6553.279139294578;6559.4752419807255;6588.934110659024;5447.809326673548;6434.453071488976;6464.612123272619;6470.126532918184;6477.805665830033;6542.771003014872;6698.363655452919;6714.395704386433;6735.73057077731;6744.966275168593;4885.609151267819;5454.372747134131;6200.348963358782;6265.310815810349;6281.52888533371;5343.305370301836;6361.586496877688;6389.7763578274535;6391.201099862517;6401.551891367746;6164.876702466016;6299.244090708961;6307.621542707452;6319.210098737658;6325.810225693236;6129.817282374446;6177.752889884354;6186.721597528212;6189.133521307111;6227.034809604518;5773.8556536086935;6167.753289868535;6195.2255968004;6286.717097730704;6316.052592111037;6106.297775755845;6244.313125556276;6304.905426418516;6312.4634406076;6312.7159277833525;6095.817125486243;6113.052476866093;6149.877002459836;6206.658633775005;6221.346758590283 22:50:39 INFO - PROCESS | 84832 | |1;ext.html;685.2838783471273;749.3817600479606;836.4186890111371;972.6838777397343;975.9512024398779;39428;39937.40093898594;41629.95925101874;41646.58353416465;41743.53897613584;39839.40240896389;40004.799976000155;40065;40134.19731605366;40287.395689064615;7893.605319734004;10632.681019569425;10924.890751092467;11055.72360690985;11077.50151243192;9198.4480931144;10841.620543280947;10845.295055821385;10945;10945.56217751289;8777.561121943909;9216.631334746602;10913.290636108668;10927.562897484091;10953.123750099972;10921.890781092166;10954.014138727553;10965.177611679155;10969.83545246822;10972;10628.521716522775;10683.519241634145;10694.144468442506;10698.090662293715;10715.732106697316;34822.47766283508;34906.65093349085;35120.473192901874;35147.64852351495;35250;3269.5095735639607;3692.8892133236045;3748.475213470116;3758.962410375916;3943.211357728466;24605.63091553628;25043.49913001748;25467.981280748747;26174.86912565444;26270.343241418926;3044.8782048718026;3045.7867949243564;3075.5232938894505;3077.292222788749;3094.8452577371268;327361;335394.3230283833;336559;345040;365983;44209;44310.778446107564;44375.778121109186;44451.777741111735;44620.10759784851;44899.102017959456;45332.54667453349;45351.77324113358;45373.546264537596;45380.77309613431;12741.490340386466;12813.039022073383;12816.48734050646;12819.102662813513;12835.743285134246;10935.781284374427;11045.889541104647;11051.502682379312;11060.557577696964;11062.502187401586;11760.58837940685;11896.88103118975;11897.881021189678;11946.761064778655;11980.520779168735;5611.9438805612235;5647;5652.123920792284;5662.263905692316;5750.539956803445;865.1262225152516;876.9298456123495;893.2764460786783;916.422653728147;936.180841763457;25365;27267.45465090687;27991.860040699667;28427;28578.571321430194;768.6541056524586;864.8270345930839;935.8362286599818;998.7902540466438;1009.0514915978956;12381.814272785916;13503.797443038362;14947;14993.550193494213;15183.089014658935;402588;407619;408539;409256;409533;55771.44228557744;56305.43694563084;56310;56495;56720.71639641775;1426.2369632246698;1457.0092337210588;1460.1385182742022;1467.8972471926863;1472.6171195489035;24995.75004249934;25915.315504492148;28205;28771.856140719163;28787.568186477638;40393.39409908913;40401.79799100986;40453.595464044985;40494.59505405026;40562;375637;377600;377614.22385776887;379949;381852;26509.33726656849;26980;29663.406731865674;30246.697533024388;31915.680843191272;36968.26063478769;44193.77903110464;44525.55474445214;45596.54403455923;45872.08255834931;35745;37195.44206836846;37704.245915080995;38257.80871095627;38354.0411489715;36617.45073823948;37551.83512562667;38074.61925380822;38127;38312.61687383202;36160;36489.696921502495;36633.45049824308;36963.07592310214;37105.25789484249;31169.68830311668;33004.1748956278;33239.33521329512;33358.83320583381;33697.494537581464;22250.888745556167;24625.63061554114;24660.753392466562;24709.011639534936;24951.62572561377 22:50:39 INFO - PROCESS | 84832 | |2;jquery.html;399.4308110941908;412.9318662420699;413.7434790429934;430.4210836425007;457.9931301030485;19081.52296192594;19854.305099321526;20940;21026.89486552565;21074.578508429822;21359.893200534014;21763.891180544113;22055.889720551415;22347.776522234813;22353;6195.597286176407;6575.441087507567;6600.273969863311;6604.2405123410945;6605.900911486331;6217.751289948396;6402.93597064028;6463.676816159197;6481.157449531578;6529.804105876831;6218.502519798428;6462.870742585145;6521.28265890754;6529.608223506605;6534.771283005096;5733.999999999958;6449.516286278525;6556.27880933097;6567.277599464055;6573.112629794989;5919.585629005971;5996.610220335664;6269.279032911228;6332.715027823759;6397.904031439532;26370.340741481425;26542.203733888015;26634.93460261587;26703;26959.32601684954;2962.481565725989;2976.3749612581382;3064.5096784514594;3089.5365695145683;3102.8448577571257;15944;16501.58746031347;16561.917190414093;17158.313667453287;17439.65120697579;1982.5539253667953;2018.8687735297158;2024.5647185855112;2047.692846073086;2048.979510204894;114852;140909;156502.21748891295;176454.99999999872;177006;24499.510009799706;26299.868500657376;27093.1872043839;27319.45361092767;27352.58971115435;43545.564544354784;44451;44551.88620284454;44649.330260046125;44654.77672611681;10348.896511034793;10752.247342685936;10813.351198928089;10896.237263391486;11112.499937502831;8914.598843052077;8923.286137109015;8930.732078037669;8975.685850995184;9115.90884091164;9791.853122203172;9827;10109.848352274721;10232.948835255776;10255.487225638837;4035.6771458283265;4149;4151.066010147753;4163.355474587521;4171.770552619635;866.9653213871501;882.7288704265836;903.9774005649912;928.6007016982627;941.7975135345933;20273.18907243723;20845.270415535688;20850.687239691415;20973.79026209749;21313.040913158944;828.188375392119;921.3044151665449;924.408378637659;949.240607513986;955.9952200239094;9698.75753106178;10730.946345268223;10735.57057717698;10765.30025548337;10772.946135269274;716541;721091;725025;732850;740129;153678.6948195778;160934.19532902495;161341;164366;168252;964.5948701545227;1132.7904337697553;1153.135148638521;1188.1504724122294;1219.061322781471;15309.77035344493;15911.363545458053;16513.33946642169;16841;17254.533364664145;43025;52562.211566827296;60536.697316513135;62376;63947;89037;90544;91819.54090229506;92092.07907920836;92113;10137.746556336151;11257.493412796608;11303.31506263874;11551.942240288745;11787.174897757417;13368.131071480322;13441.193528388329;13467.326633668474;13704.451821927012;13814.86185138176;9818.75453113678;10010.44942528168;11558.075353971493;11695.415229238333;11854.051675865738;9586.28102892272;10280;11494.367809770543;11511.424428778695;11950.880491194977;11180.273282236629;11766.882331176921;11866.169368144147;12065.758684826429;12193.573224937085;11654.41727913584;11672.70818229551;11907.868752468501;12006.75986480248;12034.458449369975;11653.126015548698;11669.533218671158;11818.468168932246;11826.467808948446;11978.281303121841 22:50:39 INFO - PROCESS | 84832 | |3;mootools.html;1581.7231984402727;1582.5727053695502;1606.799150106236;1618.6034421566717;1627.414130912871;9307.813843723121;9505.334626576143;10941.28881622694;11434.599789007387;11461.770764584713;10425.843612345801;10765.08496777773;10963.122950163988;10970.396628185446;10974.396408197566;2330.359151233416;2365.6215005599106;2369.30105618842;2371.312319427364;2374.893129809163;2339.8011169050565;2368.8815559222057;2371.976280237193;2373.750756170603;2378.7502312257225;2328.499372634883;2339.555484457956;2345.542619189259;2346.6480027995767;2347.495288512969;1918.414883460544;2261.973686837432;2285.02886273334;2299.609066458706;2301.7122859642545;2305.1931823861514;2368.017272831773;2371.34787933318;2371.845830021043;2377.690900182965;11247.60633377836;11291.943540282246;11294.435278236138;11310.886891131147;11398.1451391146;5565.1374037024325;5728.026235539968;5741.425857414267;5752.050911599581;5765.077587586008;3237.2392487765355;3272.8363581820813;3505.8247087645773;3546.8935931922083;3550.3609350316933;1061.0503599278602;1127.774445110973;1195.384377045822;1196.4436537010245;1203.8615559210716;15426.537203883901;15490.845091549052;15494.225288735519;15588.844111558967;15596.220188990505;4708.22736957863;4770.499097594772;4780.904381912343;4843.491433399442;4901.5588597026435;10988.725281868017;13094.410751516205;13101;13102.868971310356;13133.080684352004;5774;5781.132830075522;5857.560682948796;5880.911786323209;5895.380984996514;4415.161119387306;4925.408950925912;5117.130087885069;5132.101882170605;5244.370675518962;3456.879009234715;3608.386574282378;3824.8661296854475;4087.2847251730827;4189.392538081961;1090.4929207918356;1092.4100985467917;1093.912487001038;1100.9119270458343;1106.3791545386048;931.9394239374554;939.8120375924842;939.9154076133044;948.6537413843897;986.5807032011367;6760.7295708172105;6785.253622101565;6841.794746157623;6885.070515480446;7102.183248926336;966.9129778319985;989.3569180033036;1006.4464544500496;1023.2121266624657;1026.6765968719828;4424.269995450779;4699.436067671902;4702.764861756898;4759.309900064541;4828.720389096892;12027.759444811054;13170.802437963439;14250.643733906736;14268.858491320669;14357.138571685524;13698.52055178064;13702.725945481034;13749.31253437324;13767.449302028008;13799.586012419644;9996.450195239186;10276.794464110675;10293.3823970562;10311;10326.22553308505;5738.368779434176;5870.647761134345;5943.851403714769;6652.900206497004;6784.168274565938;11942.402879856147;12346.691332716753;12367.69080772952;12530.686732831753;12598.433070511666;12322.445489953154;12375.504979800708;12407.565735199225;12549.309787961749;12630.494780209056;8218.137095605114;9358.204552613104;9384.906150938676;9499.287553433382;9564.856527151958;9183.403078799856;10182.643607473703;10642.8403573948;10724.356538607708;10726.731831703957;9279.72160835176;9340.579673914553;9401.200897923753;9411.811763764823;9464.242860571;7785.961070194613;8936.86594701093;9028.59371328305;9104.226140778108;9234.630614775602;7991;8018.518888866687;8022.117567067735;8116.228958248957;8141.9185808141165;5838.2702162229725;5839.591228613956;5880.852978675567;6056.606320589148;6080.513558915364;5915.402841232929;5957.582969192115;5978.970105149447;6030.517558595389;6200.410960958882 22:50:39 INFO - PROCESS | 84832 | |4;prototype.html;372.7279086267025;391.9353306704394;392.93123703351915;393.97045221608386;399.8280739282109;4488.528704486031;4868.3690963527215;5203.505666961642;5453.91819122713;5480.342358916925;4356.821589205398;4522.977385113069;5357.928414317133;5364.088105022146;5402.00251813658;3597.9100522486888;3715.8327875245545;4692.624590032806;4693.718376897397;4734.479207287196;4191.706580539363;4295.892602684942;4549.24937385331;4627.722336659811;4727.716337019772;3929.607039296062;4321.740695558261;4569.862904112865;4603.263477843546;4622.054401790086;3912.943505253594;4153.7300075494995;4500.189965806154;4634.397528321313;4636.724900652314;3484.6689564491335;4283.87148385549;4450.933236001462;4460.397846290742;4463.799129039201;4819;5027.271045698353;5115.16362727954;5145.552479838517;5213.973930130325;4870.756462176877;5179.64909476042;5216.921746173811;5335.359756829205;5349.438308977579;5306.522412982811;5508.394076651565;5755.4244575542525;5782.768689252425;5800.651960882361;3887.786171760552;4071.2468193384025;4650.953490465085;4846.297286893416;4866.296796121364;5684.658920464744;5741.511971482429;5776.740046697909;5861.648301101947;5976.981624279754;5014.774335154927;5051.217061355495;5071.275766239496;5092.337996060563;5101.387833460009;4301.806418711166;4467.553244675506;4573.199690054227;5123.462036486189;5276.868078297996;4674.672772905864;4707.529247075265;4912.803487860446;5299.735013249322;5402.513773760302;5556.916401301707;5576.498115169654;5670.461306175906;5784.066834552461;5799.869025540063;1524.024624240487;1667.3247334829414;1690.9408170713966;1737.5751883455698;1783.3669047488215;1106.856108705863;1116.6426743442182;1150.6950658075616;1200.2798321007501;1226.993865030687;4937.8271760488215;4940.654154209171;5158.509941555546;5219.693752654076;5301.68189908599;4885.780139893643;5093.8726531836255;5252.97566974444;5295.987524740651;5777.480026797524;4184.2787272736305;4570.65720070996;4791.921817591084;4827.903441931142;5138.845834624967;2915.7521610662907;2970.2673540342935;3001.639803223627;3023.5335862106863;3060.1180274131248;5486.835394938158;5519.006578815772;5546.861328466821;6056.697165141725;6116.26604807426;4775.689580177277;5400.729963501809;5491.478309560585;5728.312602487645;6150.354212807681;5233.712145831863;5594.6293158175695;5738.368779434259;6031.09533569968;6094.512439004869;4733.952660473351;5583.454506388392;5757.107172594287;5901.734421951109;5948.37542058095;5352.059214272233;5481.725084090058;5545.445455454423;5718.399568045295;5975.87664511503;5136.9743151284;6000.699965001645;6046.606970546901;6130.816075517741;6131.84670383244;4663.253879379418;4669.112868554964;4676.55572720591;4700.45944716362;4723.504032076583;5631.63394379364;5834.299884013945;5880.323762767165;5916.467517923407;5948.613340132878;4961.305417241661;5156.4070131935305;5295.655782374133;5463.508284254436;5724.599278050497;5033.874153146201;5231.639150531814;5720;5742.4733117428195;5758.654480731169;5202.3698907971175;5546.251256080377;5550.368243664961;5552.139418390156;5573.693446860461;4933.777979990838;5400.783968641211;5706.144078388275;5781.884362312814;5801.82594522165;4593.425821772278;4597.195490789165;4971.9502804972935;5517.034518959246;5561.916571251515 22:50:39 INFO - PROCESS | 84832 | |5;yui.html;723.0744646852031;723.8697034533782;775.6587101675266;805.37583372886;866.1987661413193;14868.107913525182;15403;15923.124228167446;16085.437009704663;16131.83868161318;15095.54713358601;15107.62230944227;15209.771853422208;15270.007449515773;15313.387464501406;900.9053999390738;942.4486675294944;960.4717405427023;960.6781728121088;962.9277804164681;909.2771246858763;961.6441916490908;964.0263334032608;973.897740737223;977.9951100244489;1000.4697510319543;1006.2302338710855;1010.6311196413293;1014.1987829614633;1018.4093225928981;992.28059656749;1002.8997100289913;1005.607812952949;1007.9445630490284;1009.470028235175;754.8150703077727;754.8263899303137;755.3579457461169;755.6863901480879;758.1584441270186;11624.535018599247;14440.98913076085;14502.347394367172;14508.709825803426;14662.193579353132;11835.704107397298;12513.061520386007;12690.048246381466;12805.615831525069;13130.934345328213;10412;12360.752784944341;12741.426635801412;12952.352382380937;13340.799888001688;4371.23503386906;4380.693351465398;4390.100029493978;4506.639468842484;4665.836695715634;109977;110067;111827;113422;121083.3945830274;10572.682819515427;10752.731181720519;10895.346279223113;11220.214584978972;11299.717507062389;21099.367018989455;21407.357779266647;21475.140994360052;21605.67591486129;21613.567728645652;7689.50018248812;7690.346320562815;7723.884141737878;7772.611369431505;7797.415193860483;4254.276772948606;4267.338562522814;4274.1451709658195;4315.4605674290715;4406.052698669801;3172.682731726809;3207.139858881843;3212.2932954749917;3226.3224722808004;3246.318273162662;611.260374946311;612.2866860108013;619.2754477261615;621.4500167351927;627.2253766598247;634.926983396906;640.4652115483531;654.201873714068;664.4153145232165;679.5107522583731;9571.856422153673;9669.129778319984;9699.806003879883;9940.403575785476;10106.898931010743;635.2821311917596;654.4175683641563;665.1818263535878;666.5436022291212;689.917209934811;4631.722096674211;4821.63837712173;4830.492798256203;4834.540718631724;4867.294242334841;106955.46522267339;108450;108904;110289;110733;70955.64522177356;71443;91271.54364228137;94396.52801735948;96059.51970240104;76464;77998;78453;79046.60476697695;80004.59997699974;7499.700011999677;9046.638134474548;9346.485943273185;9368.859467107863;9464.19554337889;45377;50667.74666126646;50776.74611626918;51045.48954510407;52065;64551;65876.34123658702;66782.66434671376;66913.33086669071;67174.66412667905;10574.8413773795;10664;10807.891921080689;10849;11035.889641103486;10234.795304094025;11489.540418383505;11965.820512692491;12061.276323420623;12387.876121238673;10606.886276941119;11189.664310070712;11609.535618575163;11932.343721095423;12245.938770306091;10710.732231693959;11513.194076414906;11886.524539018343;11903.107266955187;12272.509099636272;11548.191626586058;11592.362420066976;12123.515059397525;12152.40794043208;12229.93885030569;9356.906430935876;9699.5150242489;9725.221982241545;9811.313208075366;9891.35706179096;8868.290536756915;9658.179054780423;9661.13049825519;9665.806683866424;9675.22598192157 22:50:39 INFO - PROCESS | 84832 | __end_tp_report 22:50:39 INFO - PROCESS | 84832 | __start_cc_report 22:50:39 INFO - PROCESS | 84832 | _x_x_mozilla_cycle_collect,419 22:50:39 INFO - PROCESS | 84832 | __end_cc_report 22:50:39 INFO - PROCESS | 84832 | __startTimestamp1490161839626__endTimestamp 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | ------- Summary: start ------- 22:50:39 INFO - PROCESS | 84832 | Number of tests: 6 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#0] dojo.html Cycles:165 Average:5929.20 Median:6196.01 stddev:674.48 (10.9%) stddev-sans-first:631.56 22:50:39 INFO - PROCESS | 84832 | Values: 2841.4 3444.5 3472.3 3479.6 3527.3 4804.3 5952.1 6078.2 6080.7 6120.6 6063.5 6078.1 6121.4 6130.1 6138.1 4638.4 5439.2 5479.5 5487.5 5514.2 5535.6 5542.3 5545.3 5550.4 5557.1 5341.7 5345.8 5535.2 5540.2 5591.0 5448.2 5455.4 5465.0 5493.1 5517.4 5376.9 5472.1 5476.2 5478.7 5479.3 5805.4 6027.2 6058.5 6158.4 6179.1 6270.3 6273.5 6278.6 6303.6 6313.2 6425.2 6457.9 6472.7 6475.7 6476.6 6196.2 6226.5 6230.2 6233.3 6252.3 6196.0 6320.1 6545.4 6554.8 6570.8 6336.2 6351.5 6361.9 6362.7 6376.9 6449.8 6456.0 6484.2 6515.0 6546.3 6239.8 6280.2 6283.0 6293.6 6402.2 6254.5 6378.3 6396.1 6405.8 6411.3 5065.9 5134.6 5164.2 5188.6 5189.0 4572.6 4592.0 4618.8 4626.7 4682.5 5939.9 5988.0 6027.0 6227.5 6230.5 5635.2 6259.0 6339.2 6357.8 6378.0 5181.4 5823.7 6199.5 6214.0 6220.6 5561.4 5715.9 5717.1 5717.2 5729.6 6436.4 6518.0 6553.3 6559.5 6588.9 5447.8 6434.5 6464.6 6470.1 6477.8 6542.8 6698.4 6714.4 6735.7 6745.0 4885.6 5454.4 6200.3 6265.3 6281.5 5343.3 6361.6 6389.8 6391.2 6401.6 6164.9 6299.2 6307.6 6319.2 6325.8 6129.8 6177.8 6186.7 6189.1 6227.0 5773.9 6167.8 6195.2 6286.7 6316.1 6106.3 6244.3 6304.9 6312.5 6312.7 6095.8 6113.1 6149.9 6206.7 6221.3 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#1] ext.html Cycles:180 Average:51987.05 Median:35198.82 stddev:99521.23 (282.7%) stddev-sans-first:99725.87 22:50:39 INFO - PROCESS | 84832 | Values: 685.3 749.4 836.4 972.7 976.0 39428.0 39937.4 41630.0 41646.6 41743.5 39839.4 40004.8 40065.0 40134.2 40287.4 7893.6 10632.7 10924.9 11055.7 11077.5 9198.4 10841.6 10845.3 10945.0 10945.6 8777.6 9216.6 10913.3 10927.6 10953.1 10921.9 10954.0 10965.2 10969.8 10972.0 10628.5 10683.5 10694.1 10698.1 10715.7 34822.5 34906.7 35120.5 35147.6 35250.0 3269.5 3692.9 3748.5 3759.0 3943.2 24605.6 25043.5 25468.0 26174.9 26270.3 3044.9 3045.8 3075.5 3077.3 3094.8 327361.0 335394.3 336559.0 345040.0 365983.0 44209.0 44310.8 44375.8 44451.8 44620.1 44899.1 45332.5 45351.8 45373.5 45380.8 12741.5 12813.0 12816.5 12819.1 12835.7 10935.8 11045.9 11051.5 11060.6 11062.5 11760.6 11896.9 11897.9 11946.8 11980.5 5611.9 5647.0 5652.1 5662.3 5750.5 865.1 876.9 893.3 916.4 936.2 25365.0 27267.5 27991.9 28427.0 28578.6 768.7 864.8 935.8 998.8 1009.1 12381.8 13503.8 14947.0 14993.6 15183.1 402588.0 407619.0 408539.0 409256.0 409533.0 55771.4 56305.4 56310.0 56495.0 56720.7 1426.2 1457.0 1460.1 1467.9 1472.6 24995.8 25915.3 28205.0 28771.9 28787.6 40393.4 40401.8 40453.6 40494.6 40562.0 375637.0 377600.0 377614.2 379949.0 381852.0 26509.3 26980.0 29663.4 30246.7 31915.7 36968.3 44193.8 44525.6 45596.5 45872.1 35745.0 37195.4 37704.2 38257.8 38354.0 36617.5 37551.8 38074.6 38127.0 38312.6 36160.0 36489.7 36633.5 36963.1 37105.3 31169.7 33004.2 33239.3 33358.8 33697.5 22250.9 24625.6 24660.8 24709.0 24951.6 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#2] jquery.html Cycles:180 Average:43068.65 Median:26335.10 stddev:121745.28 (462.3%) stddev-sans-first:122044.66 22:50:39 INFO - PROCESS | 84832 | Values: 399.4 412.9 413.7 430.4 458.0 19081.5 19854.3 20940.0 21026.9 21074.6 21359.9 21763.9 22055.9 22347.8 22353.0 6195.6 6575.4 6600.3 6604.2 6605.9 6217.8 6402.9 6463.7 6481.2 6529.8 6218.5 6462.9 6521.3 6529.6 6534.8 5734.0 6449.5 6556.3 6567.3 6573.1 5919.6 5996.6 6269.3 6332.7 6397.9 26370.3 26542.2 26634.9 26703.0 26959.3 2962.5 2976.4 3064.5 3089.5 3102.8 15944.0 16501.6 16561.9 17158.3 17439.7 1982.6 2018.9 2024.6 2047.7 2049.0 114852.0 140909.0 156502.2 176455.0 177006.0 24499.5 26299.9 27093.2 27319.5 27352.6 43545.6 44451.0 44551.9 44649.3 44654.8 10348.9 10752.2 10813.4 10896.2 11112.5 8914.6 8923.3 8930.7 8975.7 9115.9 9791.9 9827.0 10109.8 10232.9 10255.5 4035.7 4149.0 4151.1 4163.4 4171.8 867.0 882.7 904.0 928.6 941.8 20273.2 20845.3 20850.7 20973.8 21313.0 828.2 921.3 924.4 949.2 956.0 9698.8 10730.9 10735.6 10765.3 10772.9 716541.0 721091.0 725025.0 732850.0 740129.0 153678.7 160934.2 161341.0 164366.0 168252.0 964.6 1132.8 1153.1 1188.2 1219.1 15309.8 15911.4 16513.3 16841.0 17254.5 43025.0 52562.2 60536.7 62376.0 63947.0 89037.0 90544.0 91819.5 92092.1 92113.0 10137.7 11257.5 11303.3 11551.9 11787.2 13368.1 13441.2 13467.3 13704.5 13814.9 9818.8 10010.4 11558.1 11695.4 11854.1 9586.3 10280.0 11494.4 11511.4 11950.9 11180.3 11766.9 11866.2 12065.8 12193.6 11654.4 11672.7 11907.9 12006.8 12034.5 11653.1 11669.5 11818.5 11826.5 11978.3 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#3] mootools.html Cycles:180 Average:6798.98 Median:2374.32 stddev:4292.71 (180.8%) stddev-sans-first:4286.85 22:50:39 INFO - PROCESS | 84832 | Values: 1581.7 1582.6 1606.8 1618.6 1627.4 9307.8 9505.3 10941.3 11434.6 11461.8 10425.8 10765.1 10963.1 10970.4 10974.4 2330.4 2365.6 2369.3 2371.3 2374.9 2339.8 2368.9 2372.0 2373.8 2378.8 2328.5 2339.6 2345.5 2346.6 2347.5 1918.4 2262.0 2285.0 2299.6 2301.7 2305.2 2368.0 2371.3 2371.8 2377.7 11247.6 11291.9 11294.4 11310.9 11398.1 5565.1 5728.0 5741.4 5752.1 5765.1 3237.2 3272.8 3505.8 3546.9 3550.4 1061.1 1127.8 1195.4 1196.4 1203.9 15426.5 15490.8 15494.2 15588.8 15596.2 4708.2 4770.5 4780.9 4843.5 4901.6 10988.7 13094.4 13101.0 13102.9 13133.1 5774.0 5781.1 5857.6 5880.9 5895.4 4415.2 4925.4 5117.1 5132.1 5244.4 3456.9 3608.4 3824.9 4087.3 4189.4 1090.5 1092.4 1093.9 1100.9 1106.4 931.9 939.8 939.9 948.7 986.6 6760.7 6785.3 6841.8 6885.1 7102.2 966.9 989.4 1006.4 1023.2 1026.7 4424.3 4699.4 4702.8 4759.3 4828.7 12027.8 13170.8 14250.6 14268.9 14357.1 13698.5 13702.7 13749.3 13767.4 13799.6 9996.5 10276.8 10293.4 10311.0 10326.2 5738.4 5870.6 5943.9 6652.9 6784.2 11942.4 12346.7 12367.7 12530.7 12598.4 12322.4 12375.5 12407.6 12549.3 12630.5 8218.1 9358.2 9384.9 9499.3 9564.9 9183.4 10182.6 10642.8 10724.4 10726.7 9279.7 9340.6 9401.2 9411.8 9464.2 7786.0 8936.9 9028.6 9104.2 9234.6 7991.0 8018.5 8022.1 8116.2 8141.9 5838.3 5839.6 5880.9 6056.6 6080.5 5915.4 5957.6 5979.0 6030.5 6200.4 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#4] prototype.html Cycles:180 Average:4758.56 Median:5130.22 stddev:1301.27 (25.4%) stddev-sans-first:1262.60 22:50:39 INFO - PROCESS | 84832 | Values: 372.7 391.9 392.9 394.0 399.8 4488.5 4868.4 5203.5 5453.9 5480.3 4356.8 4523.0 5357.9 5364.1 5402.0 3597.9 3715.8 4692.6 4693.7 4734.5 4191.7 4295.9 4549.2 4627.7 4727.7 3929.6 4321.7 4569.9 4603.3 4622.1 3912.9 4153.7 4500.2 4634.4 4636.7 3484.7 4283.9 4450.9 4460.4 4463.8 4819.0 5027.3 5115.2 5145.6 5214.0 4870.8 5179.6 5216.9 5335.4 5349.4 5306.5 5508.4 5755.4 5782.8 5800.7 3887.8 4071.2 4651.0 4846.3 4866.3 5684.7 5741.5 5776.7 5861.6 5977.0 5014.8 5051.2 5071.3 5092.3 5101.4 4301.8 4467.6 4573.2 5123.5 5276.9 4674.7 4707.5 4912.8 5299.7 5402.5 5556.9 5576.5 5670.5 5784.1 5799.9 1524.0 1667.3 1690.9 1737.6 1783.4 1106.9 1116.6 1150.7 1200.3 1227.0 4937.8 4940.7 5158.5 5219.7 5301.7 4885.8 5093.9 5253.0 5296.0 5777.5 4184.3 4570.7 4791.9 4827.9 5138.8 2915.8 2970.3 3001.6 3023.5 3060.1 5486.8 5519.0 5546.9 6056.7 6116.3 4775.7 5400.7 5491.5 5728.3 6150.4 5233.7 5594.6 5738.4 6031.1 6094.5 4734.0 5583.5 5757.1 5901.7 5948.4 5352.1 5481.7 5545.4 5718.4 5975.9 5137.0 6000.7 6046.6 6130.8 6131.8 4663.3 4669.1 4676.6 4700.5 4723.5 5631.6 5834.3 5880.3 5916.5 5948.6 4961.3 5156.4 5295.7 5463.5 5724.6 5033.9 5231.6 5720.0 5742.5 5758.7 5202.4 5546.3 5550.4 5552.1 5573.7 4933.8 5400.8 5706.1 5781.9 5801.8 4593.4 4597.2 4972.0 5517.0 5561.9 22:50:39 INFO - PROCESS | 84832 | 22:50:39 INFO - PROCESS | 84832 | [#5] yui.html Cycles:180 Average:20315.02 Median:4385.40 stddev:30351.82 (692.1%) stddev-sans-first:30401.32 22:50:39 INFO - PROCESS | 84832 | Values: 723.1 723.9 775.7 805.4 866.2 14868.1 15403.0 15923.1 16085.4 16131.8 15095.5 15107.6 15209.8 15270.0 15313.4 900.9 942.4 960.5 960.7 962.9 909.3 961.6 964.0 973.9 978.0 1000.5 1006.2 1010.6 1014.2 1018.4 992.3 1002.9 1005.6 1007.9 1009.5 754.8 754.8 755.4 755.7 758.2 11624.5 14441.0 14502.3 14508.7 14662.2 11835.7 12513.1 12690.0 12805.6 13130.9 10412.0 12360.8 12741.4 12952.4 13340.8 4371.2 4380.7 4390.1 4506.6 4665.8 109977.0 110067.0 111827.0 113422.0 121083.4 10572.7 10752.7 10895.3 11220.2 11299.7 21099.4 21407.4 21475.1 21605.7 21613.6 7689.5 7690.3 7723.9 7772.6 7797.4 4254.3 4267.3 4274.1 4315.5 4406.1 3172.7 3207.1 3212.3 3226.3 3246.3 611.3 612.3 619.3 621.5 627.2 634.9 640.5 654.2 664.4 679.5 9571.9 9669.1 9699.8 9940.4 10106.9 635.3 654.4 665.2 666.5 689.9 4631.7 4821.6 4830.5 4834.5 4867.3 106955.5 108450.0 108904.0 110289.0 110733.0 70955.6 71443.0 91271.5 94396.5 96059.5 76464.0 77998.0 78453.0 79046.6 80004.6 7499.7 9046.6 9346.5 9368.9 9464.2 45377.0 50667.7 50776.7 51045.5 52065.0 64551.0 65876.3 66782.7 66913.3 67174.7 10574.8 10664.0 10807.9 10849.0 11035.9 10234.8 11489.5 11965.8 12061.3 12387.9 10606.9 11189.7 11609.5 11932.3 12245.9 10710.7 11513.2 11886.5 11903.1 12272.5 11548.2 11592.4 12123.5 12152.4 12229.9 9356.9 9699.5 9725.2 9811.3 9891.4 8868.3 9658.2 9661.1 9665.8 9675.2 22:50:39 INFO - PROCESS | 84832 | -------- Summary: end -------- 22:50:39 INFO - PROCESS | 84832 | 22:50:40 INFO - TEST-INFO | 84832: exit 0 22:50:40 INFO - TEST-OK | dromaeo_css | took 1115950ms 22:50:40 INFO - TEST-START | kraken 22:50:40 INFO - operating with platform_type : mac_ 22:50:40 INFO - Initialising browser for kraken test... 22:50:40 INFO - TEST-INFO | started process 84838 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpFSj3IP/profile http://localhost:49447/getInfo.html) 22:50:43 INFO - PROCESS | 84838 | 2017-03-21 22:50:43.069 plugin-container[84839:716315] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 22:50:43 INFO - PROCESS | 84838 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:43 INFO - PROCESS | 84838 | 2017-03-21 22:50:43.072 plugin-container[84839:716315] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 22:50:43 INFO - PROCESS | 84838 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:43 INFO - PROCESS | 84838 | 2017-03-21 22:50:43.072 plugin-container[84839:716315] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:43 INFO - PROCESS | 84838 | __metrics Screen width/height:1600/1200 22:50:43 INFO - PROCESS | 84838 | colorDepth:24 22:50:43 INFO - PROCESS | 84838 | Browser inner width/height: 1024/689 22:50:43 INFO - PROCESS | 84838 | __metrics 22:50:43 ERROR - PROCESS | 84838 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 22:50:44 INFO - TEST-INFO | 84838: exit 0 22:50:44 INFO - Browser initialized. 22:50:44 INFO - Running cycle 1/1 for kraken test... 22:50:44 INFO - TEST-INFO | started process 84840 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpFSj3IP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:46 INFO - PROCESS | 84840 | 2017-03-21 22:50:46.594 plugin-container[84841:716447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 22:50:46 INFO - PROCESS | 84840 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:46 INFO - PROCESS | 84840 | 2017-03-21 22:50:46.594 plugin-container[84841:716447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 22:50:46 INFO - PROCESS | 84840 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:46 INFO - PROCESS | 84840 | 2017-03-21 22:50:46.595 plugin-container[84841:716447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:56 INFO - PROCESS | 84840 | RSS: Main: 220577792 22:50:56 INFO - PROCESS | 84840 | 22:50:59 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49447/tests/kraken/driver.html?testName=audio-beat-detection) 22:51:00 INFO - PROCESS | 84840 | RSS: Main: 235261952 22:51:00 INFO - PROCESS | 84840 | 22:51:03 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49447/tests/kraken/driver.html?testName=audio-dft) 22:51:03 INFO - PROCESS | 84840 | RSS: Main: 243527680 22:51:03 INFO - PROCESS | 84840 | 22:51:07 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49447/tests/kraken/driver.html?testName=audio-fft) 22:51:07 INFO - PROCESS | 84840 | RSS: Main: 247148544 22:51:07 INFO - PROCESS | 84840 | 22:51:09 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49447/tests/kraken/driver.html?testName=audio-oscillator) 22:51:10 INFO - PROCESS | 84840 | RSS: Main: 247648256 22:51:10 INFO - PROCESS | 84840 | 22:51:12 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:51:13 INFO - PROCESS | 84840 | RSS: Main: 248922112 22:51:13 INFO - PROCESS | 84840 | 22:51:16 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49447/tests/kraken/driver.html?testName=imaging-darkroom) 22:51:16 INFO - PROCESS | 84840 | RSS: Main: 253128704 22:51:16 INFO - PROCESS | 84840 | 22:51:20 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49447/tests/kraken/driver.html?testName=imaging-desaturate) 22:51:20 INFO - PROCESS | 84840 | RSS: Main: 252182528 22:51:20 INFO - PROCESS | 84840 | 22:51:23 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49447/tests/kraken/driver.html?testName=json-parse-financial) 22:51:24 INFO - PROCESS | 84840 | RSS: Main: 252297216 22:51:24 INFO - PROCESS | 84840 | 22:51:26 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:51:26 INFO - PROCESS | 84840 | RSS: Main: 251973632 22:51:26 INFO - PROCESS | 84840 | 22:51:28 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:51:29 INFO - PROCESS | 84840 | RSS: Main: 251949056 22:51:29 INFO - PROCESS | 84840 | 22:51:31 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:51:32 INFO - PROCESS | 84840 | RSS: Main: 251994112 22:51:32 INFO - PROCESS | 84840 | 22:51:35 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:51:36 INFO - PROCESS | 84840 | RSS: Main: 252465152 22:51:36 INFO - PROCESS | 84840 | 22:51:38 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:51:39 INFO - PROCESS | 84840 | RSS: Main: 252366848 22:51:39 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | Cycle 1(1): loaded http://localhost:49447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49447/tests/kraken/driver.html?testName=ai-astar) 22:51:41 INFO - PROCESS | 84840 | RSS: Main: 254447616 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | __start_tp_report 22:51:41 INFO - PROCESS | 84840 | _x_x_mozilla_page_load 22:51:41 INFO - PROCESS | 84840 | _x_x_mozilla_page_load_details 22:51:41 INFO - PROCESS | 84840 | |i|pagename|runs| 22:51:41 INFO - PROCESS | 84840 | |0;ai-astar;108;118;121;117;118;118;120;119;119;120 22:51:41 INFO - PROCESS | 84840 | |1;audio-beat-detection;144;102;139;180;140;101;141;141;155;118 22:51:41 INFO - PROCESS | 84840 | |2;audio-dft;280;240;191;197;199;202;201;203;198;195 22:51:41 INFO - PROCESS | 84840 | |3;audio-fft;84;60;61;91;64;63;62;84;76;64 22:51:41 INFO - PROCESS | 84840 | |4;audio-oscillator;92;78;118;90;72;72;83;72;72;92 22:51:41 INFO - PROCESS | 84840 | |5;imaging-gaussian-blur;89;89;89;113;88;89;98;136;135;88 22:51:41 INFO - PROCESS | 84840 | |6;imaging-darkroom;145;122;103;110;135;153;136;97;96;96 22:51:41 INFO - PROCESS | 84840 | |7;imaging-desaturate;127;96;128;144;103;89;89;83;94;91 22:51:41 INFO - PROCESS | 84840 | |8;json-parse-financial;67;67;69;68;66;66;69;71;71;70 22:51:41 INFO - PROCESS | 84840 | |9;json-stringify-tinderbox;61;66;69;67;61;79;57;68;89;89 22:51:41 INFO - PROCESS | 84840 | |10;stanford-crypto-aes;83;81;77;70;89;83;99;77;84;102 22:51:41 INFO - PROCESS | 84840 | |11;stanford-crypto-ccm;145;151;129;137;168;152;158;178;209;165 22:51:41 INFO - PROCESS | 84840 | |12;stanford-crypto-pbkdf2;169;177;169;163;173;167;168;171;169;163 22:51:41 INFO - PROCESS | 84840 | |13;stanford-crypto-sha256-iterative;66;67;66;68;65;67;65;67;66;66 22:51:41 INFO - PROCESS | 84840 | __end_tp_report 22:51:41 INFO - PROCESS | 84840 | __start_cc_report 22:51:41 INFO - PROCESS | 84840 | _x_x_mozilla_cycle_collect,1009 22:51:41 INFO - PROCESS | 84840 | __end_cc_report 22:51:41 INFO - PROCESS | 84840 | __startTimestamp1490161901460__endTimestamp 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | ------- Summary: start ------- 22:51:41 INFO - PROCESS | 84840 | Number of tests: 14 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:3.65 (3.1%) stddev-sans-first:1.27 22:51:41 INFO - PROCESS | 84840 | Values: 108.0 118.0 121.0 117.0 118.0 118.0 120.0 119.0 119.0 120.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:141.00 stddev:23.90 (17.0%) stddev-sans-first:25.18 22:51:41 INFO - PROCESS | 84840 | Values: 144.0 102.0 139.0 180.0 140.0 101.0 141.0 141.0 155.0 118.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#2] audio-dft Cycles:10 Average:210.60 Median:201.50 stddev:27.91 (13.9%) stddev-sans-first:14.40 22:51:41 INFO - PROCESS | 84840 | Values: 280.0 240.0 191.0 197.0 199.0 202.0 201.0 203.0 198.0 195.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#3] audio-fft Cycles:10 Average:70.90 Median:70.00 stddev:11.68 (16.7%) stddev-sans-first:11.38 22:51:41 INFO - PROCESS | 84840 | Values: 84.0 60.0 61.0 91.0 64.0 63.0 62.0 84.0 76.0 64.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:80.50 stddev:14.65 (18.2%) stddev-sans-first:15.26 22:51:41 INFO - PROCESS | 84840 | Values: 92.0 78.0 118.0 90.0 72.0 72.0 83.0 72.0 72.0 92.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#5] imaging-gaussian-blur Cycles:10 Average:101.40 Median:89.00 stddev:19.57 (22.0%) stddev-sans-first:20.23 22:51:41 INFO - PROCESS | 84840 | Values: 89.0 89.0 89.0 113.0 88.0 89.0 98.0 136.0 135.0 88.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#6] imaging-darkroom Cycles:10 Average:119.30 Median:149.00 stddev:21.77 (14.6%) stddev-sans-first:21.01 22:51:41 INFO - PROCESS | 84840 | Values: 145.0 122.0 103.0 110.0 135.0 153.0 136.0 97.0 96.0 96.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#7] imaging-desaturate Cycles:10 Average:104.40 Median:89.00 stddev:20.89 (23.5%) stddev-sans-first:20.50 22:51:41 INFO - PROCESS | 84840 | Values: 127.0 96.0 128.0 144.0 103.0 89.0 89.0 83.0 94.0 91.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:69.00 stddev:1.90 (2.7%) stddev-sans-first:1.94 22:51:41 INFO - PROCESS | 84840 | Values: 67.0 67.0 69.0 68.0 66.0 66.0 69.0 71.0 71.0 70.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:68.50 stddev:11.35 (16.6%) stddev-sans-first:11.50 22:51:41 INFO - PROCESS | 84840 | Values: 61.0 66.0 69.0 67.0 61.0 79.0 57.0 68.0 89.0 89.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:83.00 stddev:9.87 (11.9%) stddev-sans-first:10.45 22:51:41 INFO - PROCESS | 84840 | Values: 83.0 81.0 77.0 70.0 89.0 83.0 99.0 77.0 84.0 102.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#11] stanford-crypto-ccm Cycles:10 Average:159.20 Median:161.50 stddev:22.78 (14.1%) stddev-sans-first:23.58 22:51:41 INFO - PROCESS | 84840 | Values: 145.0 151.0 129.0 137.0 168.0 152.0 158.0 178.0 209.0 165.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.00 stddev:4.23 (2.5%) stddev-sans-first:4.48 22:51:41 INFO - PROCESS | 84840 | Values: 169.0 177.0 169.0 163.0 173.0 167.0 168.0 171.0 169.0 163.0 22:51:41 INFO - PROCESS | 84840 | 22:51:41 INFO - PROCESS | 84840 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 22:51:41 INFO - PROCESS | 84840 | Values: 66.0 67.0 66.0 68.0 65.0 67.0 65.0 67.0 66.0 66.0 22:51:41 INFO - PROCESS | 84840 | -------- Summary: end -------- 22:51:41 INFO - PROCESS | 84840 | 22:51:42 INFO - TEST-INFO | 84840: exit 0 22:51:42 INFO - TEST-OK | kraken | took 62194ms 22:51:42 INFO - SUITE-END | took 1178s 22:51:42 INFO - Completed test suite (00:19:38) 22:51:42 INFO - javascript benchmark 22:51:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7707.533583735359, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2841.4317136572686, 3444.500547420624, 3472.253465504919, 3479.565054368204, 3527.347440723466, 4804.327394164815, 5952.107183922414, 6078.240219972504, 6080.665563394017, 6120.632762034281, 6063.484603808682, 6078.088286756989, 6121.449069583737, 6130.0804879268135, 6138.109974053771, 4638.420197475316, 5439.211314359415, 5479.53423958962, 5487.478689524509, 5514.200440936062, 5535.557155427577, 5542.251796007527, 5545.251391062216, 5550.361708403545, 5557.055300598888, 5341.65279256847, 5345.786168553253, 5535.169724541311, 5540.168974653794, 5591.0, 5448.155535891943, 5455.372632147294, 5464.961657285143, 5493.121100623881, 5517.44825517449, 5376.865578360573, 5472.097103977839, 5476.2333273341765, 5478.671279723189, 5479.260299859548, 5805.36141024487, 6027.246594175728, 6058.515318774487, 6158.414950579688, 6179.134921111048, 6270.31026587075, 6273.466755325803, 6278.591891527082, 6303.5902666327, 6313.242410910674, 6425.22897252328, 6457.903131453032, 6472.676366181672, 6475.676216189218, 6476.6114033158165, 6196.1635179251125, 6226.470749986257, 6230.190075290183, 6233.252009758768, 6252.2809876864285, 6196.008638617799, 6320.115183874262, 6545.37818907203, 6554.836129096715, 6570.8028759137305, 6336.1762970814425, 6351.523635727339, 6361.936380636134, 6362.713677884413, 6376.872462550723, 6449.838754031187, 6456.0, 6484.157059582224, 6514.9674251629385, 6546.279909209983, 6239.812805615838, 6280.214973128359, 6282.968585157045, 6293.559450838489, 6402.167718196698, 6254.468370188494, 6378.298387177407, 6396.136521569621, 6405.775797847146, 6411.294757576663, 5065.8733531662, 5134.589232861362, 5164.225366195025, 5188.63679542436, 5189.040027594907, 4572.611328037087, 4591.989762252299, 4618.769061546909, 4626.651221294695, 4682.461516925598, 5939.881202375928, 5987.970060149672, 6027.035674292092, 6227.501799856001, 6230.532710046674, 5635.154726790932, 6258.968705156445, 6339.175907132136, 6357.777477788255, 6377.96811015942, 5181.3782346118705, 5823.737931793079, 6199.473044791243, 6214.0, 6220.564560480723, 5561.360443548962, 5715.8571035724435, 5717.056685646905, 5717.171010203498, 5729.598928074995, 6436.388543088399, 6518.0, 6553.279139294578, 6559.4752419807255, 6588.934110659024, 5447.809326673548, 6434.453071488976, 6464.612123272619, 6470.126532918184, 6477.805665830033, 6542.771003014872, 6698.363655452919, 6714.395704386433, 6735.73057077731, 6744.966275168593, 4885.609151267819, 5454.372747134131, 6200.348963358782, 6265.310815810349, 6281.52888533371, 5343.305370301836, 6361.586496877688, 6389.7763578274535, 6391.201099862517, 6401.551891367746, 6164.876702466016, 6299.244090708961, 6307.621542707452, 6319.210098737658, 6325.810225693236, 6129.817282374446, 6177.752889884354, 6186.721597528212, 6189.133521307111, 6227.034809604518, 5773.8556536086935, 6167.753289868535, 6195.2255968004, 6286.717097730704, 6316.052592111037, 6106.297775755845, 6244.313125556276, 6304.905426418516, 6312.4634406076, 6312.7159277833525, 6095.817125486243, 6113.052476866093, 6149.877002459836, 6206.658633775005, 6221.346758590283], "value": 5886.913415635952, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.2838783471273, 749.3817600479606, 836.4186890111371, 972.6838777397343, 975.9512024398779, 39428.0, 39937.40093898594, 41629.95925101874, 41646.58353416465, 41743.53897613584, 39839.40240896389, 40004.799976000155, 40065.0, 40134.19731605366, 40287.395689064615, 7893.605319734004, 10632.681019569425, 10924.890751092467, 11055.72360690985, 11077.50151243192, 9198.4480931144, 10841.620543280947, 10845.295055821385, 10945.0, 10945.56217751289, 8777.561121943909, 9216.631334746602, 10913.290636108668, 10927.562897484091, 10953.123750099972, 10921.890781092166, 10954.014138727553, 10965.177611679155, 10969.83545246822, 10972.0, 10628.521716522775, 10683.519241634145, 10694.144468442506, 10698.090662293715, 10715.732106697316, 34822.47766283508, 34906.65093349085, 35120.473192901874, 35147.64852351495, 35250.0, 3269.5095735639607, 3692.8892133236045, 3748.475213470116, 3758.962410375916, 3943.211357728466, 24605.63091553628, 25043.49913001748, 25467.981280748747, 26174.86912565444, 26270.343241418926, 3044.8782048718026, 3045.7867949243564, 3075.5232938894505, 3077.292222788749, 3094.8452577371268, 327361.0, 335394.3230283833, 336559.0, 345040.0, 365983.0, 44209.0, 44310.778446107564, 44375.778121109186, 44451.777741111735, 44620.10759784851, 44899.102017959456, 45332.54667453349, 45351.77324113358, 45373.546264537596, 45380.77309613431, 12741.490340386466, 12813.039022073383, 12816.48734050646, 12819.102662813513, 12835.743285134246, 10935.781284374427, 11045.889541104647, 11051.502682379312, 11060.557577696964, 11062.502187401586, 11760.58837940685, 11896.88103118975, 11897.881021189678, 11946.761064778655, 11980.520779168735, 5611.9438805612235, 5647.0, 5652.123920792284, 5662.263905692316, 5750.539956803445, 865.1262225152516, 876.9298456123495, 893.2764460786783, 916.422653728147, 936.180841763457, 25365.0, 27267.45465090687, 27991.860040699667, 28427.0, 28578.571321430194, 768.6541056524586, 864.8270345930839, 935.8362286599818, 998.7902540466438, 1009.0514915978956, 12381.814272785916, 13503.797443038362, 14947.0, 14993.550193494213, 15183.089014658935, 402588.0, 407619.0, 408539.0, 409256.0, 409533.0, 55771.44228557744, 56305.43694563084, 56310.0, 56495.0, 56720.71639641775, 1426.2369632246698, 1457.0092337210588, 1460.1385182742022, 1467.8972471926863, 1472.6171195489035, 24995.75004249934, 25915.315504492148, 28205.0, 28771.856140719163, 28787.568186477638, 40393.39409908913, 40401.79799100986, 40453.595464044985, 40494.59505405026, 40562.0, 375637.0, 377600.0, 377614.22385776887, 379949.0, 381852.0, 26509.33726656849, 26980.0, 29663.406731865674, 30246.697533024388, 31915.680843191272, 36968.26063478769, 44193.77903110464, 44525.55474445214, 45596.54403455923, 45872.08255834931, 35745.0, 37195.44206836846, 37704.245915080995, 38257.80871095627, 38354.0411489715, 36617.45073823948, 37551.83512562667, 38074.61925380822, 38127.0, 38312.61687383202, 36160.0, 36489.696921502495, 36633.45049824308, 36963.07592310214, 37105.25789484249, 31169.68830311668, 33004.1748956278, 33239.33521329512, 33358.83320583381, 33697.494537581464, 22250.888745556167, 24625.63061554114, 24660.753392466562, 24709.011639534936, 24951.62572561377], "value": 18296.211685820195, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.4308110941908, 412.9318662420699, 413.7434790429934, 430.4210836425007, 457.9931301030485, 19081.52296192594, 19854.305099321526, 20940.0, 21026.89486552565, 21074.578508429822, 21359.893200534014, 21763.891180544113, 22055.889720551415, 22347.776522234813, 22353.0, 6195.597286176407, 6575.441087507567, 6600.273969863311, 6604.2405123410945, 6605.900911486331, 6217.751289948396, 6402.93597064028, 6463.676816159197, 6481.157449531578, 6529.804105876831, 6218.502519798428, 6462.870742585145, 6521.28265890754, 6529.608223506605, 6534.771283005096, 5733.999999999958, 6449.516286278525, 6556.27880933097, 6567.277599464055, 6573.112629794989, 5919.585629005971, 5996.610220335664, 6269.279032911228, 6332.715027823759, 6397.904031439532, 26370.340741481425, 26542.203733888015, 26634.93460261587, 26703.0, 26959.32601684954, 2962.481565725989, 2976.3749612581382, 3064.5096784514594, 3089.5365695145683, 3102.8448577571257, 15944.0, 16501.58746031347, 16561.917190414093, 17158.313667453287, 17439.65120697579, 1982.5539253667953, 2018.8687735297158, 2024.5647185855112, 2047.692846073086, 2048.979510204894, 114852.0, 140909.0, 156502.21748891295, 176454.99999999872, 177006.0, 24499.510009799706, 26299.868500657376, 27093.1872043839, 27319.45361092767, 27352.58971115435, 43545.564544354784, 44451.0, 44551.88620284454, 44649.330260046125, 44654.77672611681, 10348.896511034793, 10752.247342685936, 10813.351198928089, 10896.237263391486, 11112.499937502831, 8914.598843052077, 8923.286137109015, 8930.732078037669, 8975.685850995184, 9115.90884091164, 9791.853122203172, 9827.0, 10109.848352274721, 10232.948835255776, 10255.487225638837, 4035.6771458283265, 4149.0, 4151.066010147753, 4163.355474587521, 4171.770552619635, 866.9653213871501, 882.7288704265836, 903.9774005649912, 928.6007016982627, 941.7975135345933, 20273.18907243723, 20845.270415535688, 20850.687239691415, 20973.79026209749, 21313.040913158944, 828.188375392119, 921.3044151665449, 924.408378637659, 949.240607513986, 955.9952200239094, 9698.75753106178, 10730.946345268223, 10735.57057717698, 10765.30025548337, 10772.946135269274, 716541.0, 721091.0, 725025.0, 732850.0, 740129.0, 153678.6948195778, 160934.19532902495, 161341.0, 164366.0, 168252.0, 964.5948701545227, 1132.7904337697553, 1153.135148638521, 1188.1504724122294, 1219.061322781471, 15309.77035344493, 15911.363545458053, 16513.33946642169, 16841.0, 17254.533364664145, 43025.0, 52562.211566827296, 60536.697316513135, 62376.0, 63947.0, 89037.0, 90544.0, 91819.54090229506, 92092.07907920836, 92113.0, 10137.746556336151, 11257.493412796608, 11303.31506263874, 11551.942240288745, 11787.174897757417, 13368.131071480322, 13441.193528388329, 13467.326633668474, 13704.451821927012, 13814.86185138176, 9818.75453113678, 10010.44942528168, 11558.075353971493, 11695.415229238333, 11854.051675865738, 9586.28102892272, 10280.0, 11494.367809770543, 11511.424428778695, 11950.880491194977, 11180.273282236629, 11766.882331176921, 11866.169368144147, 12065.758684826429, 12193.573224937085, 11654.41727913584, 11672.70818229551, 11907.868752468501, 12006.75986480248, 12034.458449369975, 11653.126015548698, 11669.533218671158, 11818.468168932246, 11826.467808948446, 11978.281303121841], "value": 11655.481694402444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1581.7231984402727, 1582.5727053695502, 1606.799150106236, 1618.6034421566717, 1627.414130912871, 9307.813843723121, 9505.334626576143, 10941.28881622694, 11434.599789007387, 11461.770764584713, 10425.843612345801, 10765.08496777773, 10963.122950163988, 10970.396628185446, 10974.396408197566, 2330.359151233416, 2365.6215005599106, 2369.30105618842, 2371.312319427364, 2374.893129809163, 2339.8011169050565, 2368.8815559222057, 2371.976280237193, 2373.750756170603, 2378.7502312257225, 2328.499372634883, 2339.555484457956, 2345.542619189259, 2346.6480027995767, 2347.495288512969, 1918.414883460544, 2261.973686837432, 2285.02886273334, 2299.609066458706, 2301.7122859642545, 2305.1931823861514, 2368.017272831773, 2371.34787933318, 2371.845830021043, 2377.690900182965, 11247.60633377836, 11291.943540282246, 11294.435278236138, 11310.886891131147, 11398.1451391146, 5565.1374037024325, 5728.026235539968, 5741.425857414267, 5752.050911599581, 5765.077587586008, 3237.2392487765355, 3272.8363581820813, 3505.8247087645773, 3546.8935931922083, 3550.3609350316933, 1061.0503599278602, 1127.774445110973, 1195.384377045822, 1196.4436537010245, 1203.8615559210716, 15426.537203883901, 15490.845091549052, 15494.225288735519, 15588.844111558967, 15596.220188990505, 4708.22736957863, 4770.499097594772, 4780.904381912343, 4843.491433399442, 4901.5588597026435, 10988.725281868017, 13094.410751516205, 13101.0, 13102.868971310356, 13133.080684352004, 5774.0, 5781.132830075522, 5857.560682948796, 5880.911786323209, 5895.380984996514, 4415.161119387306, 4925.408950925912, 5117.130087885069, 5132.101882170605, 5244.370675518962, 3456.879009234715, 3608.386574282378, 3824.8661296854475, 4087.2847251730827, 4189.392538081961, 1090.4929207918356, 1092.4100985467917, 1093.912487001038, 1100.9119270458343, 1106.3791545386048, 931.9394239374554, 939.8120375924842, 939.9154076133044, 948.6537413843897, 986.5807032011367, 6760.7295708172105, 6785.253622101565, 6841.794746157623, 6885.070515480446, 7102.183248926336, 966.9129778319985, 989.3569180033036, 1006.4464544500496, 1023.2121266624657, 1026.6765968719828, 4424.269995450779, 4699.436067671902, 4702.764861756898, 4759.309900064541, 4828.720389096892, 12027.759444811054, 13170.802437963439, 14250.643733906736, 14268.858491320669, 14357.138571685524, 13698.52055178064, 13702.725945481034, 13749.31253437324, 13767.449302028008, 13799.586012419644, 9996.450195239186, 10276.794464110675, 10293.3823970562, 10311.0, 10326.22553308505, 5738.368779434176, 5870.647761134345, 5943.851403714769, 6652.900206497004, 6784.168274565938, 11942.402879856147, 12346.691332716753, 12367.69080772952, 12530.686732831753, 12598.433070511666, 12322.445489953154, 12375.504979800708, 12407.565735199225, 12549.309787961749, 12630.494780209056, 8218.137095605114, 9358.204552613104, 9384.906150938676, 9499.287553433382, 9564.856527151958, 9183.403078799856, 10182.643607473703, 10642.8403573948, 10724.356538607708, 10726.731831703957, 9279.72160835176, 9340.579673914553, 9401.200897923753, 9411.811763764823, 9464.242860571, 7785.961070194613, 8936.86594701093, 9028.59371328305, 9104.226140778108, 9234.630614775602, 7991.0, 8018.518888866687, 8022.117567067735, 8116.228958248957, 8141.9185808141165, 5838.2702162229725, 5839.591228613956, 5880.852978675567, 6056.606320589148, 6080.513558915364, 5915.402841232929, 5957.582969192115, 5978.970105149447, 6030.517558595389, 6200.410960958882], "value": 5164.412561905462, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7279086267025, 391.9353306704394, 392.93123703351915, 393.97045221608386, 399.8280739282109, 4488.528704486031, 4868.3690963527215, 5203.505666961642, 5453.91819122713, 5480.342358916925, 4356.821589205398, 4522.977385113069, 5357.928414317133, 5364.088105022146, 5402.00251813658, 3597.9100522486888, 3715.8327875245545, 4692.624590032806, 4693.718376897397, 4734.479207287196, 4191.706580539363, 4295.892602684942, 4549.24937385331, 4627.722336659811, 4727.716337019772, 3929.607039296062, 4321.740695558261, 4569.862904112865, 4603.263477843546, 4622.054401790086, 3912.943505253594, 4153.7300075494995, 4500.189965806154, 4634.397528321313, 4636.724900652314, 3484.6689564491335, 4283.87148385549, 4450.933236001462, 4460.397846290742, 4463.799129039201, 4819.0, 5027.271045698353, 5115.16362727954, 5145.552479838517, 5213.973930130325, 4870.756462176877, 5179.64909476042, 5216.921746173811, 5335.359756829205, 5349.438308977579, 5306.522412982811, 5508.394076651565, 5755.4244575542525, 5782.768689252425, 5800.651960882361, 3887.786171760552, 4071.2468193384025, 4650.953490465085, 4846.297286893416, 4866.296796121364, 5684.658920464744, 5741.511971482429, 5776.740046697909, 5861.648301101947, 5976.981624279754, 5014.774335154927, 5051.217061355495, 5071.275766239496, 5092.337996060563, 5101.387833460009, 4301.806418711166, 4467.553244675506, 4573.199690054227, 5123.462036486189, 5276.868078297996, 4674.672772905864, 4707.529247075265, 4912.803487860446, 5299.735013249322, 5402.513773760302, 5556.916401301707, 5576.498115169654, 5670.461306175906, 5784.066834552461, 5799.869025540063, 1524.024624240487, 1667.3247334829414, 1690.9408170713966, 1737.5751883455698, 1783.3669047488215, 1106.856108705863, 1116.6426743442182, 1150.6950658075616, 1200.2798321007501, 1226.993865030687, 4937.8271760488215, 4940.654154209171, 5158.509941555546, 5219.693752654076, 5301.68189908599, 4885.780139893643, 5093.8726531836255, 5252.97566974444, 5295.987524740651, 5777.480026797524, 4184.2787272736305, 4570.65720070996, 4791.921817591084, 4827.903441931142, 5138.845834624967, 2915.7521610662907, 2970.2673540342935, 3001.639803223627, 3023.5335862106863, 3060.1180274131248, 5486.835394938158, 5519.006578815772, 5546.861328466821, 6056.697165141725, 6116.26604807426, 4775.689580177277, 5400.729963501809, 5491.478309560585, 5728.312602487645, 6150.354212807681, 5233.712145831863, 5594.6293158175695, 5738.368779434259, 6031.09533569968, 6094.512439004869, 4733.952660473351, 5583.454506388392, 5757.107172594287, 5901.734421951109, 5948.37542058095, 5352.059214272233, 5481.725084090058, 5545.445455454423, 5718.399568045295, 5975.87664511503, 5136.9743151284, 6000.699965001645, 6046.606970546901, 6130.816075517741, 6131.84670383244, 4663.253879379418, 4669.112868554964, 4676.55572720591, 4700.45944716362, 4723.504032076583, 5631.63394379364, 5834.299884013945, 5880.323762767165, 5916.467517923407, 5948.613340132878, 4961.305417241661, 5156.4070131935305, 5295.655782374133, 5463.508284254436, 5724.599278050497, 5033.874153146201, 5231.639150531814, 5720.0, 5742.4733117428195, 5758.654480731169, 5202.3698907971175, 5546.251256080377, 5550.368243664961, 5552.139418390156, 5573.693446860461, 4933.777979990838, 5400.783968641211, 5706.144078388275, 5781.884362312814, 5801.82594522165, 4593.425821772278, 4597.195490789165, 4971.9502804972935, 5517.034518959246, 5561.916571251515], "value": 4380.139910671805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.0744646852031, 723.8697034533782, 775.6587101675266, 805.37583372886, 866.1987661413193, 14868.107913525182, 15403.0, 15923.124228167446, 16085.437009704663, 16131.83868161318, 15095.54713358601, 15107.62230944227, 15209.771853422208, 15270.007449515773, 15313.387464501406, 900.9053999390738, 942.4486675294944, 960.4717405427023, 960.6781728121088, 962.9277804164681, 909.2771246858763, 961.6441916490908, 964.0263334032608, 973.897740737223, 977.9951100244489, 1000.4697510319543, 1006.2302338710855, 1010.6311196413293, 1014.1987829614633, 1018.4093225928981, 992.28059656749, 1002.8997100289913, 1005.607812952949, 1007.9445630490284, 1009.470028235175, 754.8150703077727, 754.8263899303137, 755.3579457461169, 755.6863901480879, 758.1584441270186, 11624.535018599247, 14440.98913076085, 14502.347394367172, 14508.709825803426, 14662.193579353132, 11835.704107397298, 12513.061520386007, 12690.048246381466, 12805.615831525069, 13130.934345328213, 10412.0, 12360.752784944341, 12741.426635801412, 12952.352382380937, 13340.799888001688, 4371.23503386906, 4380.693351465398, 4390.100029493978, 4506.639468842484, 4665.836695715634, 109977.0, 110067.0, 111827.0, 113422.0, 121083.3945830274, 10572.682819515427, 10752.731181720519, 10895.346279223113, 11220.214584978972, 11299.717507062389, 21099.367018989455, 21407.357779266647, 21475.140994360052, 21605.67591486129, 21613.567728645652, 7689.50018248812, 7690.346320562815, 7723.884141737878, 7772.611369431505, 7797.415193860483, 4254.276772948606, 4267.338562522814, 4274.1451709658195, 4315.4605674290715, 4406.052698669801, 3172.682731726809, 3207.139858881843, 3212.2932954749917, 3226.3224722808004, 3246.318273162662, 611.260374946311, 612.2866860108013, 619.2754477261615, 621.4500167351927, 627.2253766598247, 634.926983396906, 640.4652115483531, 654.201873714068, 664.4153145232165, 679.5107522583731, 9571.856422153673, 9669.129778319984, 9699.806003879883, 9940.403575785476, 10106.898931010743, 635.2821311917596, 654.4175683641563, 665.1818263535878, 666.5436022291212, 689.917209934811, 4631.722096674211, 4821.63837712173, 4830.492798256203, 4834.540718631724, 4867.294242334841, 106955.46522267339, 108450.0, 108904.0, 110289.0, 110733.0, 70955.64522177356, 71443.0, 91271.54364228137, 94396.52801735948, 96059.51970240104, 76464.0, 77998.0, 78453.0, 79046.60476697695, 80004.59997699974, 7499.700011999677, 9046.638134474548, 9346.485943273185, 9368.859467107863, 9464.19554337889, 45377.0, 50667.74666126646, 50776.74611626918, 51045.48954510407, 52065.0, 64551.0, 65876.34123658702, 66782.66434671376, 66913.33086669071, 67174.66412667905, 10574.8413773795, 10664.0, 10807.891921080689, 10849.0, 11035.889641103486, 10234.795304094025, 11489.540418383505, 11965.820512692491, 12061.276323420623, 12387.876121238673, 10606.886276941119, 11189.664310070712, 11609.535618575163, 11932.343721095423, 12245.938770306091, 10710.732231693959, 11513.194076414906, 11886.524539018343, 11903.107266955187, 12272.509099636272, 11548.191626586058, 11592.362420066976, 12123.515059397525, 12152.40794043208, 12229.93885030569, 9356.906430935876, 9699.5150242489, 9725.221982241545, 9811.313208075366, 9891.35706179096, 8868.290536756915, 9658.179054780423, 9661.13049825519, 9665.806683866424, 9675.22598192157], "value": 7381.860503717039, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 121.0, 117.0, 118.0, 118.0, 120.0, 119.0, 119.0, 120.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 102.0, 139.0, 180.0, 140.0, 101.0, 141.0, 141.0, 155.0, 118.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [280.0, 240.0, 191.0, 197.0, 199.0, 202.0, 201.0, 203.0, 198.0, 195.0], "value": 210.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 61.0, 91.0, 64.0, 63.0, 62.0, 84.0, 76.0, 64.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 78.0, 118.0, 90.0, 72.0, 72.0, 83.0, 72.0, 72.0, 92.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 113.0, 88.0, 89.0, 98.0, 136.0, 135.0, 88.0], "value": 101.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 122.0, 103.0, 110.0, 135.0, 153.0, 136.0, 97.0, 96.0, 96.0], "value": 119.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 96.0, 128.0, 144.0, 103.0, 89.0, 89.0, 83.0, 94.0, 91.0], "value": 104.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 68.0, 66.0, 66.0, 69.0, 71.0, 71.0, 70.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 69.0, 67.0, 61.0, 79.0, 57.0, 68.0, 89.0, 89.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 77.0, 70.0, 89.0, 83.0, 99.0, 77.0, 84.0, 102.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 151.0, 129.0, 137.0, 168.0, 152.0, 158.0, 178.0, 209.0, 165.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 177.0, 169.0, 163.0, 173.0, 167.0, 168.0, 171.0, 169.0, 163.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 68.0, 65.0, 67.0, 65.0, 67.0, 66.0, 66.0], "value": 66.3, "unit": "score"}]}]} 22:51:42 INFO - Return code: 0 22:51:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:51:42 ERROR - # TBPL SUCCESS # 22:51:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:51:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:51:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:51:42 INFO - 2017-03-21 22:51:42.773 screenresolution[84845:716830] starting screenresolution argv=screenresolution get 22:51:42 INFO - 2017-03-21 22:51:42.776 screenresolution[84845:716830] Display 0: 1600x1200x32@60 22:51:42 INFO - 2017-03-21 22:51:42.782 screenresolution[84846:716833] starting screenresolution argv=screenresolution list 22:51:42 INFO - Available Modes on Display 0 22:51:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:51:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:51:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:51:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:51:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:51:43 INFO - Intel Iris: 22:51:43 INFO - Chipset Model: Intel Iris 22:51:43 INFO - Type: GPU 22:51:43 INFO - Bus: Built-In 22:51:43 INFO - VRAM (Dynamic, Max): 1536 MB 22:51:43 INFO - Vendor: Intel (0x8086) 22:51:43 INFO - Device ID: 0x0a2e 22:51:43 INFO - Revision ID: 0x0009 22:51:43 INFO - Displays: 22:51:43 INFO - Display: 22:51:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:51:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:51:43 INFO - Display Serial Number: 1600x1200 60 22:51:43 INFO - Main Display: Yes 22:51:43 INFO - Mirror: Off 22:51:43 INFO - Online: Yes 22:51:43 INFO - Rotation: Supported 22:51:43 INFO - Return code: 0 22:51:43 INFO - Running post-action listener: _resource_record_post_action 22:51:43 INFO - [mozharness: 2017-03-22 05:51:43.035371Z] Finished run-tests step (success) 22:51:43 INFO - Running post-run listener: _resource_record_post_run 22:51:43 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 1402368; Write bytes: 514354688; Read time: 12; Write time: 1602 22:51:43 INFO - TinderboxPrint: CPU usage
54.7% 22:51:43 INFO - TinderboxPrint: I/O read bytes / time
1,402,368 / 12 22:51:43 INFO - TinderboxPrint: I/O write bytes / time
514,354,688 / 1,602 22:51:43 INFO - TinderboxPrint: CPU idle
2,175.4 (45.3%) 22:51:43 INFO - TinderboxPrint: CPU system
966.8 (20.2%) 22:51:43 INFO - TinderboxPrint: CPU user
1,655.7 (34.5%) 22:51:43 INFO - TinderboxPrint: Swap in / out
210,575,360 / 0 22:51:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206258688; Write bytes: 199798784; Read time: 15807; Write time: 411 22:51:43 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 1361408; Write bytes: 303312384; Read time: 12; Write time: 1149 22:51:43 INFO - Running post-run listener: _upload_blobber_files 22:51:43 INFO - Blob upload gear active. 22:51:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:51:43 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:51:43 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:51:43 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:51:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:44 INFO - (blobuploader) - INFO - Done attempting. 22:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:51:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:45 INFO - (blobuploader) - INFO - Done attempting. 22:51:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:51:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:46 INFO - (blobuploader) - INFO - Done attempting. 22:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:51:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:48 INFO - (blobuploader) - INFO - Done attempting. 22:51:48 INFO - (blobuploader) - INFO - Iteration through files over. 22:51:48 INFO - Return code: 0 22:51:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:51:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:51:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/016fe282827cfbab808cec8e1c70e227ec36b495cfa9b7bd5c0f1f9a72a63371ae661d2919fc7ce6b0dcc9255a2759b2a186e383e8ad19a9c2ac203286415cfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/341dc9e9f806669df4308fa5b9e962d3d8ef6eb458a5e1e8045864cfc918bf06edeb802c1f5ff23331ea74f7d1becb1f47463e2205d7b7389fab5d3aa01fcbaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc0777ed02e4fea2bc20ee3f40ec173319ef68bfb03b60b1dbf938a91b667bff47ab75c1ac2b097b9176a43f3235701fc9290b19696335a172ee2d167d225a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bef54fc9f2070c090d16dd3ffccbdcdd0d4477196ce1d3d0b8f007d7c2691038a45363f907c5a01133d9c3afbf068ebad665bef8a4fee73ffc2e1036f79c3b8e"} 22:51:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:51:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:51:48 INFO - Contents: 22:51:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/016fe282827cfbab808cec8e1c70e227ec36b495cfa9b7bd5c0f1f9a72a63371ae661d2919fc7ce6b0dcc9255a2759b2a186e383e8ad19a9c2ac203286415cfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/341dc9e9f806669df4308fa5b9e962d3d8ef6eb458a5e1e8045864cfc918bf06edeb802c1f5ff23331ea74f7d1becb1f47463e2205d7b7389fab5d3aa01fcbaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc0777ed02e4fea2bc20ee3f40ec173319ef68bfb03b60b1dbf938a91b667bff47ab75c1ac2b097b9176a43f3235701fc9290b19696335a172ee2d167d225a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bef54fc9f2070c090d16dd3ffccbdcdd0d4477196ce1d3d0b8f007d7c2691038a45363f907c5a01133d9c3afbf068ebad665bef8a4fee73ffc2e1036f79c3b8e"} 22:51:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:51:48 INFO - Copying logs to upload dir... 22:51:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:51:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.739117 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-03-21 22:51:50.167778) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 22:51:50.175024) ========= 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.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-Aurora/sha512/016fe282827cfbab808cec8e1c70e227ec36b495cfa9b7bd5c0f1f9a72a63371ae661d2919fc7ce6b0dcc9255a2759b2a186e383e8ad19a9c2ac203286415cfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/341dc9e9f806669df4308fa5b9e962d3d8ef6eb458a5e1e8045864cfc918bf06edeb802c1f5ff23331ea74f7d1becb1f47463e2205d7b7389fab5d3aa01fcbaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc0777ed02e4fea2bc20ee3f40ec173319ef68bfb03b60b1dbf938a91b667bff47ab75c1ac2b097b9176a43f3235701fc9290b19696335a172ee2d167d225a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bef54fc9f2070c090d16dd3ffccbdcdd0d4477196ce1d3d0b8f007d7c2691038a45363f907c5a01133d9c3afbf068ebad665bef8a4fee73ffc2e1036f79c3b8e"} build_url:https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/druDds56Sx-lLlmKZb9MMQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/016fe282827cfbab808cec8e1c70e227ec36b495cfa9b7bd5c0f1f9a72a63371ae661d2919fc7ce6b0dcc9255a2759b2a186e383e8ad19a9c2ac203286415cfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/341dc9e9f806669df4308fa5b9e962d3d8ef6eb458a5e1e8045864cfc918bf06edeb802c1f5ff23331ea74f7d1becb1f47463e2205d7b7389fab5d3aa01fcbaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc0777ed02e4fea2bc20ee3f40ec173319ef68bfb03b60b1dbf938a91b667bff47ab75c1ac2b097b9176a43f3235701fc9290b19696335a172ee2d167d225a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bef54fc9f2070c090d16dd3ffccbdcdd0d4477196ce1d3d0b8f007d7c2691038a45363f907c5a01133d9c3afbf068ebad665bef8a4fee73ffc2e1036f79c3b8e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 22:51:50.212475) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 22:51:50.215814) ========= 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.ieF5pmA5iv/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.oZmjjwajwl/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005242 ========= master_lag: 1.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 22:51:51.991633) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 22:51:51.991956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 22:51:51.994371) ========= ========= Total master_lag: 4.94 =========