builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0347 starttime: 1493305725.24 results: success (0) buildid: 20170427060725 builduid: 5db244d2a9ec4495bb9f8ac5b9289363 revision: 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.238234) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.238680) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.238957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.263325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.263642) ========= 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.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-04-27 08:08:45-- 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% 17.4M=0.001s 2017-04-27 08:08:45 (17.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093800 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.382951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.383320) ========= 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.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.026894 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:45.433929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 08:08:45.434349) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-04-27 08:08:45,583 truncating revision to first 12 chars 2017-04-27 08:08:45,583 Setting DEBUG logging. 2017-04-27 08:08:45,584 attempt 1/10 2017-04-27 08:08:45,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1475c20357cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-27 08:08:46,924 unpacking tar archive at: mozilla-beta-1475c20357cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.611863 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 08:08:47.071229) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:47.071804) ========= script_repo_revision: 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:47.072762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:47.073122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-27 08:08:47.094144) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-04-27 08:08:47.094549) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:08:47 INFO - MultiFileLogger online at 20170427 08:08:47 in /builds/slave/test 08:08:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:08:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:47 INFO - {'append_to_log': False, 08:08:47 INFO - 'base_work_dir': '/builds/slave/test', 08:08:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:08:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:47 INFO - 'branch': 'Mozilla-Beta', 08:08:47 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:47 INFO - 'config_files': ('talos/mac_config.py',), 08:08:47 INFO - 'default_actions': ('clobber', 08:08:47 INFO - 'read-buildbot-config', 08:08:47 INFO - 'download-and-extract', 08:08:47 INFO - 'populate-webroot', 08:08:47 INFO - 'create-virtualenv', 08:08:47 INFO - 'install', 08:08:47 INFO - 'run-tests'), 08:08:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:47 INFO - 'download_minidump_stackwalk': True, 08:08:47 INFO - 'download_symbols': 'ondemand', 08:08:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:47 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:47 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:47 INFO - 'gecko_profile': False, 08:08:47 INFO - 'gecko_profile_interval': 0, 08:08:47 INFO - 'installer_path': 'installer.exe', 08:08:47 INFO - 'log_level': 'info', 08:08:47 INFO - 'log_name': 'talos', 08:08:47 INFO - 'log_to_console': True, 08:08:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:47 INFO - 'opt_config_files': (), 08:08:47 INFO - 'pip_index': False, 08:08:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:47 INFO - 'cmd': ('bash', 08:08:47 INFO - '-c', 08:08:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:47 INFO - 'enabled': True, 08:08:47 INFO - 'halt_on_failure': False, 08:08:47 INFO - 'name': 'check_screen_resolution'},), 08:08:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:47 INFO - 'cmd': ('bash', 08:08:47 INFO - '-c', 08:08:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:47 INFO - 'enabled': True, 08:08:47 INFO - 'halt_on_failure': False, 08:08:47 INFO - 'name': 'check_screen_resolution'},), 08:08:47 INFO - 'run_cmd_checks_enabled': True, 08:08:47 INFO - 'suite': 'dromaeojs-e10s', 08:08:47 INFO - 'system_bits': '32', 08:08:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:47 INFO - 'title': 't-yosemite-r7-0347', 08:08:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:47 INFO - 'use_talos_json': True, 08:08:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:47 INFO - 'work_dir': 'build'} 08:08:47 INFO - [mozharness: 2017-04-27 15:08:47.351337Z] Running clobber step. 08:08:47 INFO - Running pre-action listener: _resource_record_pre_action 08:08:47 INFO - Running main action method: clobber 08:08:47 INFO - rmtree: /builds/slave/test/build 08:08:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:49 INFO - Running post-action listener: _resource_record_post_action 08:08:49 INFO - [mozharness: 2017-04-27 15:08:49.790193Z] Finished clobber step (success) 08:08:49 INFO - [mozharness: 2017-04-27 15:08:49.790324Z] Running read-buildbot-config step. 08:08:49 INFO - Running pre-action listener: _resource_record_pre_action 08:08:49 INFO - Running main action method: read_buildbot_config 08:08:49 INFO - Using buildbot properties: 08:08:49 INFO - { 08:08:49 INFO - "project": "", 08:08:49 INFO - "product": "firefox", 08:08:49 INFO - "script_repo_revision": "production", 08:08:49 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:08:49 INFO - "repository": "", 08:08:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:08:49 INFO - "buildid": "20170427060725", 08:08:49 INFO - "pgo_build": "False", 08:08:49 INFO - "basedir": "/builds/slave/test", 08:08:49 INFO - "buildnumber": 15, 08:08:49 INFO - "slavename": "t-yosemite-r7-0347", 08:08:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:08:49 INFO - "platform": "yosemite_r7", 08:08:49 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:08:49 INFO - "branch": "mozilla-beta", 08:08:49 INFO - "repo_path": "releases/mozilla-beta", 08:08:49 INFO - "revision": "1475c20357cf9d509a7cc7ef80fad4deeaac3ba6", 08:08:49 INFO - "stage_platform": "macosx64", 08:08:49 INFO - "builduid": "5db244d2a9ec4495bb9f8ac5b9289363", 08:08:49 INFO - "slavebuilddir": "test" 08:08:49 INFO - } 08:08:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:08:49 INFO - Running post-action listener: _resource_record_post_action 08:08:49 INFO - [mozharness: 2017-04-27 15:08:49.794020Z] Finished read-buildbot-config step (success) 08:08:49 INFO - [mozharness: 2017-04-27 15:08:49.794170Z] Running download-and-extract step. 08:08:49 INFO - Running pre-action listener: _resource_record_pre_action 08:08:49 INFO - Running main action method: download_and_extract 08:08:49 INFO - mkdir: /builds/slave/test/build/tests 08:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:49 INFO - trying https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:08:49 INFO - Downloading https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 08:08:50 INFO - Downloaded 1165 bytes. 08:08:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:08:50 INFO - Using the following test package requirements: 08:08:50 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:08:50 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:08:50 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:08:50 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:08:50 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:08:50 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:08:50 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:08:50 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:08:50 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:08:50 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:08:50 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:08:50 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:08:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:08:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:08:50 INFO - Fetch https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:08:51 INFO - Expected file size: 51225147 08:08:51 INFO - Obtained file size: 51225147 08:09:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:09:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:09:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:09:07 INFO - Fetch https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:09:09 INFO - Expected file size: 51225147 08:09:09 INFO - Obtained file size: 51225147 08:09:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:09:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:09:24 INFO - Fetch https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:09:25 INFO - Expected file size: 14404449 08:09:25 INFO - Obtained file size: 14404449 08:09:26 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')]} 08:09:26 INFO - trying https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:09:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 08:09:27 INFO - Downloaded 59541770 bytes. 08:09:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:09:27 INFO - mkdir: /builds/slave/test/properties 08:09:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:27 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:27 INFO - Contents: 08:09:27 INFO - build_url:https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:09:28 INFO - Running post-action listener: _resource_record_post_action 08:09:28 INFO - Running post-action listener: set_extra_try_arguments 08:09:28 INFO - [mozharness: 2017-04-27 15:09:28.496316Z] Finished download-and-extract step (success) 08:09:28 INFO - [mozharness: 2017-04-27 15:09:28.496419Z] Running populate-webroot step. 08:09:28 INFO - Running pre-action listener: _resource_record_pre_action 08:09:28 INFO - Running main action method: populate_webroot 08:09:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:28 INFO - '--tppagecycles', 08:09:28 INFO - '7'], 08:09:28 INFO - 'tests': ['tsvgm']}}, 08:09:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['damp', 'tps']}, 08:09:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'tests': ['damp', 'tps']}, 08:09:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['dromaeo_dom']}, 08:09:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['basic_compositor_video']}, 08:09:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:09:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['a11yr', 08:09:28 INFO - 'ts_paint', 08:09:28 INFO - 'tpaint', 08:09:28 INFO - 'sessionrestore', 08:09:28 INFO - 'sessionrestore_no_auto_restore', 08:09:28 INFO - 'tabpaint']}, 08:09:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['tsvgx', 08:09:28 INFO - 'tsvgr_opacity', 08:09:28 INFO - 'tart', 08:09:28 INFO - 'tscrollx', 08:09:28 INFO - 'cart', 08:09:28 INFO - 'tsvg_static']}, 08:09:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:28 INFO - 'tsvgr_opacity', 08:09:28 INFO - 'tart', 08:09:28 INFO - 'tscrollx', 08:09:28 INFO - 'cart', 08:09:28 INFO - 'tsvg_static']}, 08:09:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'talos_options': ['--disable-e10s'], 08:09:28 INFO - 'tests': ['tp5o']}, 08:09:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:09:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'talos_options': ['--disable-e10s', 08:09:28 INFO - '--xperf_path', 08:09:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:28 INFO - 'tests': ['tp5n']}, 08:09:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:09:28 INFO - 'talos_options': ['--xperf_path', 08:09:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:28 INFO - 'tests': ['tp5n']}}, 08:09:28 INFO - 'talos.zip': {'path': '', 08:09:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:28 INFO - Running post-action listener: _resource_record_post_action 08:09:28 INFO - [mozharness: 2017-04-27 15:09:28.515709Z] Finished populate-webroot step (success) 08:09:28 INFO - [mozharness: 2017-04-27 15:09:28.515815Z] Running create-virtualenv step. 08:09:28 INFO - Running pre-action listener: _resource_record_pre_action 08:09:28 INFO - Running main action method: create_virtualenv 08:09:28 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:28 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 08:09:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:28 INFO - Using real prefix '/tools/python27' 08:09:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:29 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:32 INFO - Installing pip.................done. 08:09:32 INFO - Return code: 0 08:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:09:32 INFO - Reading from file tmpfile_stdout 08:09:32 INFO - Output received: 08:09:32 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) 08:09:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:32 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:32 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 08:09:32 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 08:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:32 INFO - 'HOME': '/Users/cltbld', 08:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:32 INFO - 'LOGNAME': 'cltbld', 08:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:32 INFO - 'MOZ_NO_REMOTE': '1', 08:09:32 INFO - 'NO_EM_RESTART': '1', 08:09:32 INFO - 'PAGER': '/bin/cat', 08:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:32 INFO - 'PWD': '/builds/slave/test', 08:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:32 INFO - 'SHELL': '/bin/bash', 08:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:32 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:32 INFO - 'USER': 'cltbld', 08:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:32 INFO - 'XPC_FLAGS': '0x0', 08:09:32 INFO - 'XPC_SERVICE_NAME': '0', 08:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:32 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 08:09:32 INFO - Cleaning up... 08:09:32 INFO - Return code: 0 08:09:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:32 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:32 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 08:09:32 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 08:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:32 INFO - 'HOME': '/Users/cltbld', 08:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:32 INFO - 'LOGNAME': 'cltbld', 08:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:32 INFO - 'MOZ_NO_REMOTE': '1', 08:09:32 INFO - 'NO_EM_RESTART': '1', 08:09:32 INFO - 'PAGER': '/bin/cat', 08:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:32 INFO - 'PWD': '/builds/slave/test', 08:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:32 INFO - 'SHELL': '/bin/bash', 08:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:32 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:32 INFO - 'USER': 'cltbld', 08:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:32 INFO - 'XPC_FLAGS': '0x0', 08:09:32 INFO - 'XPC_SERVICE_NAME': '0', 08:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:33 INFO - Downloading/unpacking psutil>=3.1.1 08:09:33 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 08:09:33 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 08:09:33 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 08:09:33 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 08:09:33 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 08:09:33 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 08:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:36 INFO - Installing collected packages: psutil 08:09:36 INFO - Running setup.py install for psutil 08:09:36 INFO - building 'psutil._psutil_osx' extension 08:09:36 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 08:09:37 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 08:09:37 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 08:09:37 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 08:09:37 INFO - building 'psutil._psutil_posix' extension 08:09:37 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 08:09:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:37 INFO - ^ 08:09:37 INFO - 1 warning generated. 08:09:37 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 08:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:37 INFO - Successfully installed psutil 08:09:37 INFO - Cleaning up... 08:09:37 INFO - Return code: 0 08:09:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:37 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:37 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 08:09:37 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 08:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:37 INFO - 'HOME': '/Users/cltbld', 08:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:37 INFO - 'LOGNAME': 'cltbld', 08:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:37 INFO - 'MOZ_NO_REMOTE': '1', 08:09:37 INFO - 'NO_EM_RESTART': '1', 08:09:37 INFO - 'PAGER': '/bin/cat', 08:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:37 INFO - 'PWD': '/builds/slave/test', 08:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:37 INFO - 'SHELL': '/bin/bash', 08:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:37 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:37 INFO - 'USER': 'cltbld', 08:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:37 INFO - 'XPC_FLAGS': '0x0', 08:09:37 INFO - 'XPC_SERVICE_NAME': '0', 08:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:09:37 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 08:09:37 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 08:09:37 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 08:09:37 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 08:09:37 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 08:09:37 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 08:09:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:09:40 INFO - Installing collected packages: mozsystemmonitor 08:09:40 INFO - Running setup.py install for mozsystemmonitor 08:09:40 INFO - Successfully installed mozsystemmonitor 08:09:40 INFO - Cleaning up... 08:09:40 INFO - Return code: 0 08:09:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:40 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:40 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 08:09:40 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 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:41 INFO - Downloading/unpacking jsonschema==2.5.1 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:44 INFO - warning: no files found matching '*.txt' 08:09:44 INFO - no previously-included directories found matching 'build' 08:09:44 INFO - no previously-included directories found matching 'dist' 08:09:44 INFO - no previously-included directories found matching '.git*' 08:09:44 INFO - Installing collected packages: jsonschema, functools32 08:09:44 INFO - Running setup.py install for functools32 08:09:44 INFO - warning: no files found matching '*.txt' 08:09:44 INFO - no previously-included directories found matching 'build' 08:09:44 INFO - no previously-included directories found matching 'dist' 08:09:44 INFO - no previously-included directories found matching '.git*' 08:09:44 INFO - Successfully installed jsonschema functools32 08:09:44 INFO - Cleaning up... 08:09:44 INFO - Return code: 0 08:09:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:09:44 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')]} 08:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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')]} 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:45 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 08:09:45 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 08:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:45 INFO - 'HOME': '/Users/cltbld', 08:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:45 INFO - 'LOGNAME': 'cltbld', 08:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:45 INFO - 'MOZ_NO_REMOTE': '1', 08:09:45 INFO - 'NO_EM_RESTART': '1', 08:09:45 INFO - 'PAGER': '/bin/cat', 08:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:45 INFO - 'PWD': '/builds/slave/test', 08:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:45 INFO - 'SHELL': '/bin/bash', 08:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:45 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:45 INFO - 'USER': 'cltbld', 08:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:45 INFO - 'XPC_FLAGS': '0x0', 08:09:45 INFO - 'XPC_SERVICE_NAME': '0', 08:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:09:45 INFO - Cleaning up... 08:09:45 INFO - Return code: 0 08:09:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:45 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')]} 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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')]} 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:45 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 08:09:45 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 08:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:45 INFO - 'HOME': '/Users/cltbld', 08:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:45 INFO - 'LOGNAME': 'cltbld', 08:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:45 INFO - 'MOZ_NO_REMOTE': '1', 08:09:45 INFO - 'NO_EM_RESTART': '1', 08:09:45 INFO - 'PAGER': '/bin/cat', 08:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:45 INFO - 'PWD': '/builds/slave/test', 08:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:45 INFO - 'SHELL': '/bin/bash', 08:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:45 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:45 INFO - 'USER': 'cltbld', 08:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:45 INFO - 'XPC_FLAGS': '0x0', 08:09:45 INFO - 'XPC_SERVICE_NAME': '0', 08:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:45 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 08:09:45 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 08:09:45 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 08:09:45 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 08:09:45 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 08:09:45 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 08:09:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:48 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 08:09:48 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 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 INFO - Downloading docopt-0.6.1.tar.gz 08:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:49 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:49 INFO - Running setup.py install for blobuploader 08:09:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:49 INFO - Running setup.py install for requests 08:09:50 INFO - Running setup.py install for docopt 08:09:50 INFO - Successfully installed blobuploader requests docopt 08:09:50 INFO - Cleaning up... 08:09:50 INFO - Return code: 0 08:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:50 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:50 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 08:09:50 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 08:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:50 INFO - 'HOME': '/Users/cltbld', 08:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:50 INFO - 'LOGNAME': 'cltbld', 08:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:50 INFO - 'MOZ_NO_REMOTE': '1', 08:09:50 INFO - 'NO_EM_RESTART': '1', 08:09:50 INFO - 'PAGER': '/bin/cat', 08:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:50 INFO - 'PWD': '/builds/slave/test', 08:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:50 INFO - 'SHELL': '/bin/bash', 08:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:50 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:50 INFO - 'USER': 'cltbld', 08:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:50 INFO - 'XPC_FLAGS': '0x0', 08:09:50 INFO - 'XPC_SERVICE_NAME': '0', 08:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:50 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-CBokTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:50 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-OaFfVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:50 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-GJwhcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-zDlXpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-CEbx3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-Kz94KS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-300l7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-veh2r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-e4Jmo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-R6zLNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-ZMzrWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-O2MYPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:51 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-Pfw5ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:52 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-DaYUS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:52 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-faJlFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:52 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-FJYwF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:52 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-xS_CD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:52 INFO - Running setup.py install for manifestparser 08:09:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:52 INFO - Running setup.py install for mozcrash 08:09:52 INFO - Running setup.py install for mozdebug 08:09:52 INFO - Running setup.py install for mozdevice 08:09:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozfile 08:09:53 INFO - Running setup.py install for mozhttpd 08:09:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozinfo 08:09:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozInstall 08:09:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozleak 08:09:53 INFO - Running setup.py install for mozlog 08:09:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for moznetwork 08:09:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozprocess 08:09:54 INFO - Running setup.py install for mozprofile 08:09:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozrunner 08:09:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozscreenshot 08:09:54 INFO - Running setup.py install for moztest 08:09:54 INFO - Running setup.py install for mozversion 08:09:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:55 INFO - Cleaning up... 08:09:55 INFO - Return code: 0 08:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:09:55 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 08:09:55 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 08:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:55 INFO - 'HOME': '/Users/cltbld', 08:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:55 INFO - 'LOGNAME': 'cltbld', 08:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:55 INFO - 'MOZ_NO_REMOTE': '1', 08:09:55 INFO - 'NO_EM_RESTART': '1', 08:09:55 INFO - 'PAGER': '/bin/cat', 08:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:55 INFO - 'PWD': '/builds/slave/test', 08:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:55 INFO - 'SHELL': '/bin/bash', 08:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:09:55 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:55 INFO - 'USER': 'cltbld', 08:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:55 INFO - 'XPC_FLAGS': '0x0', 08:09:55 INFO - 'XPC_SERVICE_NAME': '0', 08:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-gVt0Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-xoAXba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-a6Yy6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-hcO4bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-bjbG5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-WxCs53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:55 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)) 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:55 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-qzd64e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-gB4uVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-cHde5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-7MdHAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-sP2lZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-pbVJze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-jdiQD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-k3j2HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-pYAoHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:56 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-5qCwST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:57 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-jMsn7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:59 INFO - Downloading blessings-1.6.tar.gz 08:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:59 INFO - Installing collected packages: blessings 08:09:59 INFO - Running setup.py install for blessings 08:09:59 INFO - Successfully installed blessings 08:09:59 INFO - Cleaning up... 08:10:00 INFO - Return code: 0 08:10:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:00 INFO - Reading from file tmpfile_stdout 08:10:00 INFO - Current package versions: 08:10:00 INFO - blessings == 1.6 08:10:00 INFO - blobuploader == 1.2.4 08:10:00 INFO - docopt == 0.6.1 08:10:00 INFO - functools32 == 3.2.3-2 08:10:00 INFO - jsonschema == 2.5.1 08:10:00 INFO - manifestparser == 1.1 08:10:00 INFO - mozInstall == 1.12 08:10:00 INFO - mozcrash == 1.0 08:10:00 INFO - mozdebug == 0.1 08:10:00 INFO - mozdevice == 0.49 08:10:00 INFO - mozfile == 1.2 08:10:00 INFO - mozhttpd == 0.7 08:10:00 INFO - mozinfo == 0.9 08:10:00 INFO - mozleak == 0.1 08:10:00 INFO - mozlog == 3.4 08:10:00 INFO - moznetwork == 0.27 08:10:00 INFO - mozprocess == 0.25 08:10:00 INFO - mozprofile == 0.28 08:10:00 INFO - mozrunner == 6.13 08:10:00 INFO - mozscreenshot == 0.1 08:10:00 INFO - mozsystemmonitor == 0.3 08:10:00 INFO - moztest == 0.8 08:10:00 INFO - mozversion == 1.4 08:10:00 INFO - psutil == 3.1.1 08:10:00 INFO - requests == 1.2.3 08:10:00 INFO - wsgiref == 0.1.2 08:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 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 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:10:00 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 08:10:00 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 08:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:00 INFO - 'HOME': '/Users/cltbld', 08:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:00 INFO - 'LOGNAME': 'cltbld', 08:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:00 INFO - 'MOZ_NO_REMOTE': '1', 08:10:00 INFO - 'NO_EM_RESTART': '1', 08:10:00 INFO - 'PAGER': '/bin/cat', 08:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:00 INFO - 'PWD': '/builds/slave/test', 08:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:00 INFO - 'SHELL': '/bin/bash', 08:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:10:00 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:00 INFO - 'USER': 'cltbld', 08:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:00 INFO - 'XPC_FLAGS': '0x0', 08:10:00 INFO - 'XPC_SERVICE_NAME': '0', 08:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 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)) 08:10:00 INFO - Cleaning up... 08:10:00 INFO - Return code: 0 08:10:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3aac85850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, '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.lkaKohuGQC/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.YYHhkpsFr4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 08:10:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:00 INFO - 'HOME': '/Users/cltbld', 08:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:00 INFO - 'LOGNAME': 'cltbld', 08:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:00 INFO - 'MOZ_NO_REMOTE': '1', 08:10:00 INFO - 'NO_EM_RESTART': '1', 08:10:00 INFO - 'PAGER': '/bin/cat', 08:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:00 INFO - 'PWD': '/builds/slave/test', 08:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:00 INFO - 'SHELL': '/bin/bash', 08:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:10:00 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:00 INFO - 'USER': 'cltbld', 08:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:00 INFO - 'XPC_FLAGS': '0x0', 08:10:00 INFO - 'XPC_SERVICE_NAME': '0', 08:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:10:00 INFO - Cleaning up... 08:10:00 INFO - Return code: 0 08:10:00 INFO - Running post-action listener: _resource_record_post_action 08:10:00 INFO - Running post-action listener: _start_resource_monitoring 08:10:00 INFO - Starting resource monitoring. 08:10:00 INFO - [mozharness: 2017-04-27 15:10:00.925224Z] Finished create-virtualenv step (success) 08:10:00 INFO - [mozharness: 2017-04-27 15:10:00.926243Z] Running install step. 08:10:00 INFO - Running pre-action listener: _resource_record_pre_action 08:10:00 INFO - Running main action method: install 08:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:01 INFO - Reading from file tmpfile_stdout 08:10:01 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:01 INFO - Reading from file tmpfile_stdout 08:10:01 INFO - Output received: 08:10:01 INFO - Usage: mozinstall [options] installer 08:10:01 INFO - Options: 08:10:01 INFO - -h, --help show this help message and exit 08:10:01 INFO - -d DEST, --destination=DEST 08:10:01 INFO - Directory to install application into. [default: 08:10:01 INFO - "/builds/slave/test"] 08:10:01 INFO - --app=APP Application being installed. [default: firefox] 08:10:01 INFO - mkdir: /builds/slave/test/build/application 08:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:10:15 INFO - Reading from file tmpfile_stdout 08:10:15 INFO - Output received: 08:10:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:10:15 INFO - Running post-action listener: _resource_record_post_action 08:10:15 INFO - [mozharness: 2017-04-27 15:10:15.976305Z] Finished install step (success) 08:10:15 INFO - [mozharness: 2017-04-27 15:10:15.976450Z] Running run-tests step. 08:10:15 INFO - Running pre-action listener: _resource_record_pre_action 08:10:15 INFO - Running main action method: run_tests 08:10:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:15 INFO - 2017-04-27 08:10:15.990 screenresolution[1626:7538] starting screenresolution argv=screenresolution get 08:10:15 INFO - 2017-04-27 08:10:15.993 screenresolution[1626:7538] Display 0: 1600x1200x32@60 08:10:16 INFO - 2017-04-27 08:10:15.999 screenresolution[1627:7540] starting screenresolution argv=screenresolution list 08:10:16 INFO - Available Modes on Display 0 08:10:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:16 INFO - Intel Iris: 08:10:16 INFO - Chipset Model: Intel Iris 08:10:16 INFO - Type: GPU 08:10:16 INFO - Bus: Built-In 08:10:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:16 INFO - Vendor: Intel (0x8086) 08:10:16 INFO - Device ID: 0x0a2e 08:10:16 INFO - Revision ID: 0x0009 08:10:16 INFO - Displays: 08:10:16 INFO - Display: 08:10:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:16 INFO - Display Serial Number: 1600x1200 60 08:10:16 INFO - Main Display: Yes 08:10:16 INFO - Mirror: Off 08:10:16 INFO - Online: Yes 08:10:16 INFO - Rotation: Supported 08:10:16 INFO - Return code: 0 08:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:16 INFO - Python 2.7.3 08:10:16 INFO - Return code: 0 08:10:16 INFO - grabbing minidump binary from tooltool 08:10:16 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')]} 08:10:16 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 0x103fded30>, '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 0x103f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fd3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:16 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 08:10:16 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 08:10:16 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 08:10:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:16 INFO - Return code: 0 08:10:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:16 INFO - ENV: RUST_BACKTRACE is now 1 08:10:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 08:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0347 --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 08:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkaKohuGQC/Render', 08:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:16 INFO - 'HOME': '/Users/cltbld', 08:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:16 INFO - 'LOGNAME': 'cltbld', 08:10:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:16 INFO - 'MOZ_NO_REMOTE': '1', 08:10:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:16 INFO - 'NO_EM_RESTART': '1', 08:10:16 INFO - 'PAGER': '/bin/cat', 08:10:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:16 INFO - 'PWD': '/builds/slave/test', 08:10:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:16 INFO - 'RUST_BACKTRACE': '1', 08:10:16 INFO - 'SHELL': '/bin/bash', 08:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YYHhkpsFr4/Listeners', 08:10:16 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 08:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:16 INFO - 'USER': 'cltbld', 08:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:16 INFO - 'XPC_FLAGS': '0x0', 08:10:16 INFO - 'XPC_SERVICE_NAME': '0', 08:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 08:10:16 INFO - mozversion application_buildid: 20170427060725 08:10:16 INFO - mozversion application_changeset: 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 08:10:16 INFO - mozversion application_display_name: Firefox 08:10:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:16 INFO - mozversion application_name: Firefox 08:10:16 INFO - mozversion application_remotingname: firefox 08:10:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:10:16 INFO - mozversion application_vendor: Mozilla 08:10:16 INFO - mozversion application_version: 54.0 08:10:16 INFO - mozversion platform_buildid: 20170427060725 08:10:16 INFO - mozversion platform_changeset: 1475c20357cf9d509a7cc7ef80fad4deeaac3ba6 08:10:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:10:16 INFO - mozversion platform_version: 54.0 08:10:16 INFO - using testdate: 1493305816 08:10:16 INFO - actual date: 1493305816 08:10:16 INFO - starting webserver on 'localhost:49242' 08:10:16 INFO - SUITE-START | Running 2 tests 08:10:16 INFO - TEST-START | dromaeo_css 08:10:16 INFO - operating with platform_type : mac_ 08:10:16 INFO - Initialising browser for dromaeo_css test... 08:10:16 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpnqQrGz/profile http://localhost:49242/getInfo.html) 08:10:19 INFO - PROCESS | 1637 | 2017-04-27 08:10:19.376 plugin-container[1640:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.tsm.portname' 08:10:19 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:19 INFO - PROCESS | 1637 | 2017-04-27 08:10:19.376 plugin-container[1640:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 08:10:19 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:19 INFO - PROCESS | 1637 | 2017-04-27 08:10:19.376 plugin-container[1640:7712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:10:19 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 08:10:19 INFO - PROCESS | 1637 | colorDepth:24 08:10:19 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 08:10:19 INFO - PROCESS | 1637 | __metrics 08:10:19 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:10:20 INFO - TEST-INFO | 1637: exit 0 08:10:20 INFO - Browser initialized. 08:10:20 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:20 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpnqQrGz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:22 INFO - PROCESS | 1641 | 2017-04-27 08:10:22.254 plugin-container[1642:7845] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:10:22 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:22 INFO - PROCESS | 1641 | 2017-04-27 08:10:22.255 plugin-container[1642:7845] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 08:10:22 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:10:22 INFO - PROCESS | 1641 | 2017-04-27 08:10:22.255 plugin-container[1642:7845] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:10:32 INFO - PROCESS | 1641 | RSS: Main: 174231552 08:10:32 INFO - PROCESS | 1641 | 08:13:34 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:13:36 INFO - PROCESS | 1641 | RSS: Main: 193097728 08:13:36 INFO - PROCESS | 1641 | 08:16:38 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 08:16:39 INFO - PROCESS | 1641 | RSS: Main: 201760768 08:16:39 INFO - PROCESS | 1641 | 08:19:41 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 08:19:41 INFO - PROCESS | 1641 | RSS: Main: 203608064 08:19:41 INFO - PROCESS | 1641 | 08:22:43 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 08:22:44 INFO - PROCESS | 1641 | RSS: Main: 207802368 08:22:44 INFO - PROCESS | 1641 | 08:25:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 08:25:47 INFO - PROCESS | 1641 | RSS: Main: 210731008 08:25:47 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 08:28:50 INFO - PROCESS | 1641 | RSS: Main: 208384000 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | __start_tp_report 08:28:50 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:28:50 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:28:50 INFO - PROCESS | 1641 | |i|pagename|runs| 08:28:50 INFO - PROCESS | 1641 | |0;dojo.html;3296.6318977624287;3621.330053940021;3642.708583313335;3652.4886515887774;3697.4268988306803;5648.802291919784;5977.432143946324;6224.626522408653;6294.874102517947;6296.622202667837;6188.443040126388;6254.249490061187;6296.181496405472;6305.6216627002295;6316.9684151579295;4613.63090952723;5153.304303918979;5649.830505084834;5696.914546281809;5780.161876527901;5746.051901436258;5769.624974376673;5774.942250577504;5779.537636989052;5802.535797136219;5540.196671482632;5544.140658197985;5759.16492108644;5772.422757724236;5821.970890145543;5668.943310566883;5691.174779656926;5698.373178950312;5736.368999410061;5775.942240577582;5635.126555383922;5711.942880571182;5770;5770.711464426804;5864.38423965486;4543.931841022388;5357.758900849432;6058.515318774487;6096.908546371808;6224.813255602339;6472;6599.208095028579;6611.636360000198;6737.3262673732725;6765.289644587297;6786.59280443175;6856.657167141673;6861;6866.725330986755;6873.347032031949;5483.985462689415;6347.270063942613;6362.491000719931;6376.681165941731;6377.808665740035;6521.11964884744;6629.502787290972;6753.831154221184;6810.148731408574;6862.245153033163;6644.534882558174;6657.700403481855;6676.432503237182;6685.097511835937;6687;6095.4818840398175;6712.798616041527;6793.864122717518;6804.4216241620015;6830.385265326145;6333.556651034383;6357.713902874381;6480.611163330215;6512.967435162794;6699.296573859772;6653.534252602271;6699.799006029827;6723.159605049369;6727.529072964846;6728.125343705385;5220.216967454912;5254.710990895461;5315;5331.440908364007;5357.892842143213;3908.1495714009343;4631.606313463393;4806.279058141306;4821.590164836029;4826.831060912851;6121.6358891611635;6182;6269.028300613412;6346.048092786026;6397.8720425592155;6662.766803161963;6767.661616919135;6773.45812335012;6811.352921472452;6812.14848143982;6370.299267080617;6415.037744338387;6443.420092191632;6443.7744678936015;6444.065610486453;5689.374168841424;5746.2529871116485;5967.433093856163;5986.700664966821;5994.700264986734;5710.457506536873;6786.558873673195;6847.349501797321;6875.553089049396;6883.896741548981;6663.533552651268;6754.493412993947;6763;6774.762883299061;6779.661016949231;6959.338862808025;6981.79054628362;7004;7006.9649651751415;7013;6322.49420046386;6357.93642063592;6445.774397896051;6452.322506136976;6626.635535045619;6734.696938637675;6747.966260168668;6752.020956961115;6756.8648627026205;6766.560173588701;6459.483241340776;6538.8365290868105;6549.6070235786;6552.082708420729;6554.573952693059;6117.614269788275;6315.873682526232;6385.553011289165;6430.614163150226;6552.737890484518;6344.71448784815;6405.295417503978;6425;6432.388923052303;6447.290798012309;6214.968925155526;6558.213014438107;6580.177477815273;6587.670616469253;6648.90026649591;6252.5935814171935;6358.968205158944;6407.519436042408;6421.614703117828;6441.871162576816 08:28:50 INFO - PROCESS | 1641 | |1;ext.html;844.8437039147757;908.6728777640051;921.2814005076043;993.0367543482828;1002.1181360402844;36983.81508092459;38979;39076.60923390765;39205;39215.41176882349;33940.151496212544;34702.8264858676;37378.62621373792;37680;37857.810710946476;7799.844003119934;7952.324052455549;8056.637451314705;8212.630431630561;8218.137095604965;10073.848892266622;11366.090712743002;11739.413029348541;11785.469653865597;11785.528578856836;9918.05778451046;11686.649400517997;11815.409229538489;11841.526338946433;11849;11731;11769.646910592695;11800.940995295012;11804.763904721944;11825.113116516295;11540.538378464851;11544.422778861024;11568.826467602992;11631.825522617168;11688.941555292255;39700.80149599262;39792.801035994926;39990.80004599987;40035.399469007985;40036.999075023356;3959.1487830116375;3979.263836190314;3983.9203215935518;4073.368627862686;4192.413062171298;27668.44663106747;28389.716102838913;28554;28562;29503.114906552837;3179.8887038953526;3271.8200498972546;3303.80177189367;3380.4422270325367;3435.862565497377;346214;346469;346673.26663366775;347787;348467;52200;52218.95562088737;52322.47677523252;52565.2115218272;52605.684857878856;54814.45185548173;54843.725781370835;54847.451525485034;54879.725601371734;54889.451105489235;15229.314680839389;15691.450799221973;15704.979176353501;15711;15717.371305147666;13232.073754837069;13285.66785830362;13290.20258784476;13337.866621333857;13366.33168341579;12216.877831221751;12593;12627.5580354689;12666.683332916751;12725.109242353132;5752.36723960364;5772.855678608068;5792.710364481759;5807.448292412214;5825.592208545361;1067;1146.6846617180342;1192.356127691037;1212.42409855318;1262.5391732017931;26115;27277.318067048483;27480.779457003955;27665.446691066067;27974.580381294298;1015.7308313296983;1316.1576590981779;1328.14334754085;1352.3981828086407;1376.6695992961618;14195.361208745631;14352.138871667734;14455.349509272108;14468.902009208816;14473.276336183359;475940;477386;478232.6088369536;478962;479029;45204.547954520036;54754;55116.72441637766;55174.17238741422;55418.16872746912;1334.1794796200268;1525.3974680001559;1552.6661767719995;1553.5339398180502;1556.144120733592;22334.77665223327;30728.231794205323;31892.5216121763;31906;32590.51114233241;41682.58317416787;52811.73594132158;52812.735936320074;53145.202821958475;53276.73361633167;499254;499817;503041;503079;503608;35466.64533354634;36268.09329766777;36368.454473183454;36402.635973639924;36448;36332.81833590815;36599.08502287464;44164.89587760332;45002;46116.30825537687;27565;31237.375252495276;31428.685713142575;31969.200769980936;32178.83910580432;31026.379472410877;31299.53050704287;31480.42597870143;31658.683413165574;32007.839960800047;36711.816440917624;36793.63206367902;36991.445128323634;37444.62555374411;37851.810740946115;30870;31216;31243.375132497677;31283.687163128077;31808.68191318057;23999;24892.62661060122;25147.874260629313;25313.60775157384;25474.61788073143 08:28:50 INFO - PROCESS | 1641 | |2;jquery.html;388.46004054364437;425.2303330970943;427.5489358726546;438.9846355377562;466.2912373192747;20179.39461816146;21372.57254854902;22311.219107331246;22480.100795968163;22513.874306284662;19208;22607;23357.649635255442;23710;24080.87959560204;6322.43098121169;6887.621180835052;7204.783856484314;7227.168875579297;7227.747028853991;6959.199692035405;7094.71621135154;7130.073090498254;7140;7151.32062454066;7096.929030709704;7151.749688760894;7401.962990185041;7733.419993500482;7909.169537198569;7571.772846814604;7774.378049756006;7797.142314345418;7814.413918956073;7863.449558530905;7599.658015389321;7612.885806712904;7614.8096297593;7617.314441700219;7646.311831935152;26813.731862681318;27730.445391092064;28003;28048.719512804815;28207.71792282092;2738.8493632850186;3048.0855743277148;3052.9084127476212;3057.4038062577806;3074.1392410124977;15996.76004859928;16249.431269905615;16948.237329320087;17317.48047558575;17432.738508922375;2138.828893688501;2165.6534954407375;2194.714687090684;2224.4216503709154;2264.615015447378;147114;156525;162150;163411.18294408452;179963.40073198592;21077.78922210789;21429.49993500471;21511.78488215098;21605.351839444527;22028.559428811335;32408.83795581007;32938.341233175684;33016.33967320688;33257.3348533028;33346.832860849754;10637.149028077734;10805.189610779224;11004.119670426508;11028.50371733274;11172.273802202832;9176.95411522938;9186.356955013218;9189.356745027917;9346.57940392684;9363.25093992479;9801.11789938909;9915.900840991642;9922.80154396908;9956.80086398268;10020.599176033023;3595.3528364894046;3614.8734794282072;3624.456331550236;4344.218040752632;4369.470685260134;891.5185799668106;909.8453262945183;1044.7910417916446;1049.5801679328329;1062.2245760594808;21610.35168944934;21937.122515099218;21940.561188776133;22016.88991555064;22115.673059616474;872.7905302727427;909.6315992023241;1094.2285688589525;1106.7731115121542;1109.6393672056613;10788;11339.92270734279;11591.362475063785;11607.76784464306;11630.185886987829;751589;765090;774121;782861;797643;159918.20040899722;161684;164392;164628.17685911735;166677.33322666862;1228.269179837995;1246.7693476706838;1269.955551555691;1279.405076639367;1279.8272233248579;14472.421103155755;16090.954087984244;18572.814271857107;19274;19287.51781205481;48254.75872620755;57404.42595574158;63396.366036339045;63448;64556;66432;87651.56174219088;88523;89748.55125724329;90446.54776726074;9796.755081123029;11863.228890122115;12159.696007599881;12261.38693065361;12299.93850030744;13263.204207747565;13588.25264610456;13603.863961360259;13768.518101866386;14044.016918815994;8971.685990990283;9644.448413761522;10138.341007834468;11522.078233741448;11578.36319002463;9840.458774767456;9931.255155863484;10567.996040376152;10850;11028.779424411307;11826.231294965799;11836.349000805038;12207;12242.081843861568;12275.815862762245;11850.762984740084;12044.156909016285;12168.452419641315;12179.75640487203;12260.87739122633;11609.245399049034;11656.941715291368;11727.706807329543;11737.765244694887;11965.222260553037 08:28:50 INFO - PROCESS | 1641 | |3;mootools.html;1611.2427159235156;1641.7865677461932;1649.9670006599877;1653.198198873546;1674.330267892843;11071.778564428707;11322.26405283656;11670.124740644444;11705.122115841314;11740.471678774444;11111.611093611726;11219.495122719476;11336.263142895727;11374.886251137506;11392.71518212043;2426.951460970784;2456.029868202068;2459.0286836699493;2465.408302007514;2466.6300054991716;2351.318117745852;2366.6095094309417;2465.963010554834;2482.5283196192754;2493.0526399968076;2401.6277476991095;2454.349597356702;2459;2466.0135945621714;2468.950620987579;2383.2373640435067;2399.3401814501067;2414.5774489464275;2418.4074901649033;2469.1481438903547;2426.8422552534207;2435.3789783605066;2451.816113791455;2454.16558370155;2458.7172475165225;11418.828717569244;11637.825432618518;11645;11646.883531164665;11663.183577149603;5963.314218864843;6122.142899994004;6124.203853499017;6130.111133885606;6134.9079763803575;3559.911002224965;3591.2099338145567;3665.6884164845756;3726.4782930389765;3775.2449510097817;1038.9220808439322;1226.1478272600546;1274.7769140400392;1276.7551637153795;1284.4220100954608;14335.92832035833;14635.92682036583;15175.165365904872;15297.847021529753;15708.057516549045;4776.785044672998;4883.218685010382;4924.556789888856;4936.703797772145;4955.876103097379;12312.384380780924;12334.50661973511;12348.506059757688;12359.567415140607;12381.195222310522;5772.971135144381;6052.455279024909;6065.4541091302;6087.1173679816175;6089.2997305310455;4323.567643235651;5303.602229832701;5334.546563542142;5445.727713614383;5451.427600102012;3968.001589983095;4049.230646177217;4051.716379853441;4153.833846646161;4158.397032430281;907.9636814527345;1057.2652006855162;1061.8407238914224;1062.6599488163713;1075.8924107589335;967.970960871175;990.1286867556504;996.2329006664828;1002.3434650304063;1007.9546420411099;6741.898871516931;6800.863982720318;6926.272741362185;6944.201416837129;6971.79084627462;993.8459538771502;1043.400044974143;1064.8509208710786;1074.7796701676139;1103.542030057533;3741.4574886641285;4371.213181627274;4380.211561918885;4391.516933137416;4523.276275795857;13969.58091257264;14558.199299038655;14586.41654333836;14587.41650333996;14592.197429141286;11348.205625606293;13346.46614135443;14038.929805350908;14061.578152655436;14467.49363772263;10395.688129356131;10616.150707943347;10628.84056739165;10632;10636.734081647866;6905.413039891666;7069.71721131149;7152.320529549685;7213.20654727969;7274.527155734861;12612.49550017989;12658.98728101768;12887.613371598492;12902;13149.737005260033;9284.58219380116;12356.505739770308;12656.810147847606;12769.106162568902;13027.674308142372;9504.287178461782;9521.476318802532;9569.569369378256;9571.90428095738;9619.759506012406;10549.367037977747;10575.259731818698;10575.36547807134;10624.681259562225;10699.89300106989;9113.3165012623;9156;9187.402818816756;9245.768855778659;9368.4378937264;8824.205821476098;8912.108789121035;8922.973858006413;8997.505137217517;9043.728688139367;8350.540720260202;8388.622511986854;8427.115152908857;8454.83090338202;8565.400421970651;6055.273367195965;6156.015037593964;6265.624062556261;6299.685015749286;6310.5898116622275;5017.397912250407;5204.219367094966;6026.698665066817;6133;6164.352742961924 08:28:50 INFO - PROCESS | 1641 | |4;prototype.html;344.4437233867304;385.5758665467986;398.9980050099749;402.7986006996502;404.8785364390683;4387.03485233249;4806.447258565262;5077;5448.673079615226;5507.311586051744;4888.948875991661;5284.8150314739;5293.247511294135;5320.866978325544;5372.462753724625;3726.5341832270965;4402.415130552999;4441.712518053205;4445.355423463604;4471.530489298626;4180.414741936132;4423.911521769563;4464.642828573723;4480.708753930984;4514.864554063383;3752.3058234226755;4113.03343714227;4186.665066794649;4364.978175109136;4415.006623509701;4460.241758900995;4465.397171381855;4491.730496170224;4497;4523.235937984189;3640.1445660269815;3650.908727281813;3751.5873253942045;4152.688548358855;4158.313878210091;5120.92318615221;5141.562967147797;5177.948220517822;5199.972010915746;5215.608829337815;4233.534311225793;5116.820911268125;5164.92252616211;5174.896502069975;5188.896222075575;4845.878853028667;5378.542823859937;5652.463016013472;5747.005830947493;5765.855853603652;4633.32816741574;4739.573438390527;4889.779959901777;4924.507549245082;4970.751462426901;5614.494695477427;5806.506446952014;5809.489532721523;5985.947166944889;6073.331933487313;4276.935845962313;4350.12891491614;4680.46174689915;4704.788284527204;5026.597872170218;4462.620677242471;4661;5163.225516172604;5199.766010529535;5201.479852014768;4470.8435204767675;5335.66608347913;5376.14522989568;5391.894661594364;5396.64921780083;5739.512141468022;5747.034546163134;5762.135679648002;5771.567132465082;5772.682502462321;1479.5857159995219;1519.566923426818;1528.9297491755815;1669.472432724068;1769.398404542436;1207.9698007549882;1335.419092694681;1412.6892083741561;1423.181670539444;1444.407792804966;4744.478107408182;4922.384701912261;5323.220513435427;5355.544778693855;5389.784408623611;4765.7140571565815;5175.611829112831;5476.864023031021;5690.5447564194765;5706.457886500776;4296.884758550089;4922.72924989129;5058.4435712071645;5278.261043453919;5340.012097761926;2784.3874347643487;2886.610307608488;2905.402028884105;3104.301532155292;3112.813231206135;5315.750798562335;5392.973035134799;5609.03683710698;5873.358517718956;6164.270616277087;4980.701157930535;5201.453847345974;5416.702081385482;6049.364816694272;6085.96538588441;5327.920081198863;5400.73169268908;5549.833504994856;5576.498115169654;5748.281464816898;5193.11717008102;5434.934724804549;5893.5874488785375;5925.585209035498;5988.610740301867;5249.842504724865;5757.3379061406795;5781.248437703071;5827.970860145672;5833.82498525045;5329.774151944996;5660.433956604306;6011.519078473799;6076.90884636722;6117.480014198843;4239.650675966076;4999.800007999639;5038.647294689336;5055;5069;5122.359705036871;5251.396089449762;5487.4238204987905;5557.749901254372;5719.542436604978;4729.787159577896;4998.575121114746;5173.715445650525;5497.202905578589;5658.575606829422;4831.043427411903;5315.176147697113;5641.843422098542;5729.879944420591;5740.770369185186;5048.798048078036;5137.381724756689;5238.6379541318765;5527.419620939743;5713.457221563945;4774.76126193682;5756.136579512939;5783.479486846204;5834.591578589628;5837.3870743571315;5257.58045327767;5656.218291238247;5687.715614219355;5714.457126572969;5731.283589551306 08:28:50 INFO - PROCESS | 1641 | |5;yui.html;684.3567046975845;781.5506084001701;805.6947744653659;885.3713863157158;891.7101941868892;13556.389962451689;17600.20799064042;17637.294508219657;17696.734548981774;17850.28598856046;16180.433684821033;16355.754663680025;16391.83608163918;17737.733933990967;18506.352277670285;999.3004896572392;1013.7262939006464;1019.2406657040515;1022.8465730140501;1028.2699283508719;1012.6860673191334;1017.2218253036433;1020.2297265564525;1020.5305559442628;1022.355915773062;911.4485736129645;1066.3921564708119;1069.8662667166604;1070.5664205996584;1075.1184029096144;1067.2849191041983;1068.716790050641;1069.2408390043079;1069.3049517813406;1069.7486090768664;652.6769249221618;787.4251796188757;788.755485799404;789.9723509677134;790.5572879187616;17710.29158833645;18178.36375726843;18336.81663183378;18370.816291837178;18427;14116.858831411657;14566.417343306255;14593.41626334956;14617.707645847024;14867.628309292246;11875.643730688007;11976.820347694791;12318.81521777174;12342.938285308606;12724.491020359175;3321.534985102088;3969.960300396988;3973.3642617181113;4033.233685599756;4138.255114079465;116434.41782791117;117700.82299176985;121323;121432.57134857253;123452.38273808663;11266.493007814668;11521.769564608829;11536.538538458368;11632.418379081013;11977.520899163936;22391.888040559694;22764;22991.655125173136;23325.41686457852;23404.414889627897;8273.089960104384;8346.874796878052;8350.4154709171;8351.624176912053;8379.790505237417;4601.194790911577;4650.813967441332;4703.459102203222;4800.375951126401;4951.232558953368;3636.6908812751212;3670.889873303805;3690.9261814763554;3735.4396840474146;3740.925181496355;626.6302881300055;629.4869681209799;630.8359826445158;632.1181951178081;638.7796210307437;705.0235423937883;722;736.37408203027;745.8471013442231;779.3764988009569;10699.572017119228;10838.295510791773;11178.944105279421;11270.661880143609;11341.319520828776;588.4729127913145;612.3631423319787;617.2623714661026;627.6140173793176;633.6071635585967;4829.492903245179;5210.47895210476;5370.038578437021;5391.191321301758;5405.702686352289;92568.53715731378;98004;99882.50293742714;101607;104824;85820;86787;89024;89105.66341504882;89652.5517372409;78721.60639196882;80843;82587;83485;83518.16481835104;10016.098551130433;10479.790404192026;10486.47567621631;10536.420496872439;10548.314359566604;35973.46039809353;47644.2853357193;50995.745021274655;51352.74323628358;52825.20762188493;66086;66966;67984;68399.65800170967;69053.65473172603;11540.365279909685;11770.234934729559;11864.136660192104;11972.880271197177;12015.399230038638;11780;13379.397927093109;13532.797008045085;13883.583492495241;13972.091814032055;10809.35143891369;12384.380780961095;12839.422225999666;12998.675033124247;13273.469061237442;12284.201526900722;12788.936055319664;12795;12851;12867.613971580868;12572.937135314265;12581.874181258436;12666.809997849856;12811.359432028175;13095.214287142802;9446.905530944878;9513.66702165421;9616.278779091735;9960.601575937171;10053.095221430107;10224.182065434896;10856.565737370416;10881.347119172875;10927.45362731876;10997.560097596006 08:28:50 INFO - PROCESS | 1641 | __end_tp_report 08:28:50 INFO - PROCESS | 1641 | __start_cc_report 08:28:50 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,397 08:28:50 INFO - PROCESS | 1641 | __end_cc_report 08:28:50 INFO - PROCESS | 1641 | __startTimestamp1493306930826__endTimestamp 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:28:50 INFO - PROCESS | 1641 | Number of tests: 6 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6174.87 Median:6385.55 stddev:727.41 (11.4%) stddev-sans-first:693.72 08:28:50 INFO - PROCESS | 1641 | Values: 3296.6 3621.3 3642.7 3652.5 3697.4 5648.8 5977.4 6224.6 6294.9 6296.6 6188.4 6254.2 6296.2 6305.6 6317.0 4613.6 5153.3 5649.8 5696.9 5780.2 5746.1 5769.6 5774.9 5779.5 5802.5 5540.2 5544.1 5759.2 5772.4 5822.0 5668.9 5691.2 5698.4 5736.4 5775.9 5635.1 5711.9 5770.0 5770.7 5864.4 4543.9 5357.8 6058.5 6096.9 6224.8 6472.0 6599.2 6611.6 6737.3 6765.3 6786.6 6856.7 6861.0 6866.7 6873.3 5484.0 6347.3 6362.5 6376.7 6377.8 6521.1 6629.5 6753.8 6810.1 6862.2 6644.5 6657.7 6676.4 6685.1 6687.0 6095.5 6712.8 6793.9 6804.4 6830.4 6333.6 6357.7 6480.6 6513.0 6699.3 6653.5 6699.8 6723.2 6727.5 6728.1 5220.2 5254.7 5315.0 5331.4 5357.9 3908.1 4631.6 4806.3 4821.6 4826.8 6121.6 6182.0 6269.0 6346.0 6397.9 6662.8 6767.7 6773.5 6811.4 6812.1 6370.3 6415.0 6443.4 6443.8 6444.1 5689.4 5746.3 5967.4 5986.7 5994.7 5710.5 6786.6 6847.3 6875.6 6883.9 6663.5 6754.5 6763.0 6774.8 6779.7 6959.3 6981.8 7004.0 7007.0 7013.0 6322.5 6357.9 6445.8 6452.3 6626.6 6734.7 6748.0 6752.0 6756.9 6766.6 6459.5 6538.8 6549.6 6552.1 6554.6 6117.6 6315.9 6385.6 6430.6 6552.7 6344.7 6405.3 6425.0 6432.4 6447.3 6215.0 6558.2 6580.2 6587.7 6648.9 6252.6 6359.0 6407.5 6421.6 6441.9 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:58340.80 Median:17931.17 stddev:118844.73 (662.8%) stddev-sans-first:119099.72 08:28:50 INFO - PROCESS | 1641 | Values: 844.8 908.7 921.3 993.0 1002.1 36983.8 38979.0 39076.6 39205.0 39215.4 33940.2 34702.8 37378.6 37680.0 37857.8 7799.8 7952.3 8056.6 8212.6 8218.1 10073.8 11366.1 11739.4 11785.5 11785.5 9918.1 11686.6 11815.4 11841.5 11849.0 11731.0 11769.6 11800.9 11804.8 11825.1 11540.5 11544.4 11568.8 11631.8 11688.9 39700.8 39792.8 39990.8 40035.4 40037.0 3959.1 3979.3 3983.9 4073.4 4192.4 27668.4 28389.7 28554.0 28562.0 29503.1 3179.9 3271.8 3303.8 3380.4 3435.9 346214.0 346469.0 346673.3 347787.0 348467.0 52200.0 52219.0 52322.5 52565.2 52605.7 54814.5 54843.7 54847.5 54879.7 54889.5 15229.3 15691.5 15705.0 15711.0 15717.4 13232.1 13285.7 13290.2 13337.9 13366.3 12216.9 12593.0 12627.6 12666.7 12725.1 5752.4 5772.9 5792.7 5807.4 5825.6 1067.0 1146.7 1192.4 1212.4 1262.5 26115.0 27277.3 27480.8 27665.4 27974.6 1015.7 1316.2 1328.1 1352.4 1376.7 14195.4 14352.1 14455.3 14468.9 14473.3 475940.0 477386.0 478232.6 478962.0 479029.0 45204.5 54754.0 55116.7 55174.2 55418.2 1334.2 1525.4 1552.7 1553.5 1556.1 22334.8 30728.2 31892.5 31906.0 32590.5 41682.6 52811.7 52812.7 53145.2 53276.7 499254.0 499817.0 503041.0 503079.0 503608.0 35466.6 36268.1 36368.5 36402.6 36448.0 36332.8 36599.1 44164.9 45002.0 46116.3 27565.0 31237.4 31428.7 31969.2 32178.8 31026.4 31299.5 31480.4 31658.7 32007.8 36711.8 36793.6 36991.4 37444.6 37851.8 30870.0 31216.0 31243.4 31283.7 31808.7 23999.0 24892.6 25147.9 25313.6 25474.6 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:44436.06 Median:22496.99 stddev:129304.89 (574.8%) stddev-sans-first:129625.32 08:28:50 INFO - PROCESS | 1641 | Values: 388.5 425.2 427.5 439.0 466.3 20179.4 21372.6 22311.2 22480.1 22513.9 19208.0 22607.0 23357.6 23710.0 24080.9 6322.4 6887.6 7204.8 7227.2 7227.7 6959.2 7094.7 7130.1 7140.0 7151.3 7096.9 7151.7 7402.0 7733.4 7909.2 7571.8 7774.4 7797.1 7814.4 7863.4 7599.7 7612.9 7614.8 7617.3 7646.3 26813.7 27730.4 28003.0 28048.7 28207.7 2738.8 3048.1 3052.9 3057.4 3074.1 15996.8 16249.4 16948.2 17317.5 17432.7 2138.8 2165.7 2194.7 2224.4 2264.6 147114.0 156525.0 162150.0 163411.2 179963.4 21077.8 21429.5 21511.8 21605.4 22028.6 32408.8 32938.3 33016.3 33257.3 33346.8 10637.1 10805.2 11004.1 11028.5 11172.3 9177.0 9186.4 9189.4 9346.6 9363.3 9801.1 9915.9 9922.8 9956.8 10020.6 3595.4 3614.9 3624.5 4344.2 4369.5 891.5 909.8 1044.8 1049.6 1062.2 21610.4 21937.1 21940.6 22016.9 22115.7 872.8 909.6 1094.2 1106.8 1109.6 10788.0 11339.9 11591.4 11607.8 11630.2 751589.0 765090.0 774121.0 782861.0 797643.0 159918.2 161684.0 164392.0 164628.2 166677.3 1228.3 1246.8 1270.0 1279.4 1279.8 14472.4 16091.0 18572.8 19274.0 19287.5 48254.8 57404.4 63396.4 63448.0 64556.0 66432.0 87651.6 88523.0 89748.6 90446.5 9796.8 11863.2 12159.7 12261.4 12299.9 13263.2 13588.3 13603.9 13768.5 14044.0 8971.7 9644.4 10138.3 11522.1 11578.4 9840.5 9931.3 10568.0 10850.0 11028.8 11826.2 11836.3 12207.0 12242.1 12275.8 11850.8 12044.2 12168.5 12179.8 12260.9 11609.2 11656.9 11727.7 11737.8 11965.2 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6948.57 Median:2462.22 stddev:4321.94 (175.5%) stddev-sans-first:4315.45 08:28:50 INFO - PROCESS | 1641 | Values: 1611.2 1641.8 1650.0 1653.2 1674.3 11071.8 11322.3 11670.1 11705.1 11740.5 11111.6 11219.5 11336.3 11374.9 11392.7 2427.0 2456.0 2459.0 2465.4 2466.6 2351.3 2366.6 2466.0 2482.5 2493.1 2401.6 2454.3 2459.0 2466.0 2469.0 2383.2 2399.3 2414.6 2418.4 2469.1 2426.8 2435.4 2451.8 2454.2 2458.7 11418.8 11637.8 11645.0 11646.9 11663.2 5963.3 6122.1 6124.2 6130.1 6134.9 3559.9 3591.2 3665.7 3726.5 3775.2 1038.9 1226.1 1274.8 1276.8 1284.4 14335.9 14635.9 15175.2 15297.8 15708.1 4776.8 4883.2 4924.6 4936.7 4955.9 12312.4 12334.5 12348.5 12359.6 12381.2 5773.0 6052.5 6065.5 6087.1 6089.3 4323.6 5303.6 5334.5 5445.7 5451.4 3968.0 4049.2 4051.7 4153.8 4158.4 908.0 1057.3 1061.8 1062.7 1075.9 968.0 990.1 996.2 1002.3 1008.0 6741.9 6800.9 6926.3 6944.2 6971.8 993.8 1043.4 1064.9 1074.8 1103.5 3741.5 4371.2 4380.2 4391.5 4523.3 13969.6 14558.2 14586.4 14587.4 14592.2 11348.2 13346.5 14038.9 14061.6 14467.5 10395.7 10616.2 10628.8 10632.0 10636.7 6905.4 7069.7 7152.3 7213.2 7274.5 12612.5 12659.0 12887.6 12902.0 13149.7 9284.6 12356.5 12656.8 12769.1 13027.7 9504.3 9521.5 9569.6 9571.9 9619.8 10549.4 10575.3 10575.4 10624.7 10699.9 9113.3 9156.0 9187.4 9245.8 9368.4 8824.2 8912.1 8923.0 8997.5 9043.7 8350.5 8388.6 8427.1 8454.8 8565.4 6055.3 6156.0 6265.6 6299.7 6310.6 5017.4 5204.2 6026.7 6133.0 6164.4 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4766.25 Median:5176.78 stddev:1293.72 (25.0%) stddev-sans-first:1254.06 08:28:50 INFO - PROCESS | 1641 | Values: 344.4 385.6 399.0 402.8 404.9 4387.0 4806.4 5077.0 5448.7 5507.3 4888.9 5284.8 5293.2 5320.9 5372.5 3726.5 4402.4 4441.7 4445.4 4471.5 4180.4 4423.9 4464.6 4480.7 4514.9 3752.3 4113.0 4186.7 4365.0 4415.0 4460.2 4465.4 4491.7 4497.0 4523.2 3640.1 3650.9 3751.6 4152.7 4158.3 5120.9 5141.6 5177.9 5200.0 5215.6 4233.5 5116.8 5164.9 5174.9 5188.9 4845.9 5378.5 5652.5 5747.0 5765.9 4633.3 4739.6 4889.8 4924.5 4970.8 5614.5 5806.5 5809.5 5985.9 6073.3 4276.9 4350.1 4680.5 4704.8 5026.6 4462.6 4661.0 5163.2 5199.8 5201.5 4470.8 5335.7 5376.1 5391.9 5396.6 5739.5 5747.0 5762.1 5771.6 5772.7 1479.6 1519.6 1528.9 1669.5 1769.4 1208.0 1335.4 1412.7 1423.2 1444.4 4744.5 4922.4 5323.2 5355.5 5389.8 4765.7 5175.6 5476.9 5690.5 5706.5 4296.9 4922.7 5058.4 5278.3 5340.0 2784.4 2886.6 2905.4 3104.3 3112.8 5315.8 5393.0 5609.0 5873.4 6164.3 4980.7 5201.5 5416.7 6049.4 6086.0 5327.9 5400.7 5549.8 5576.5 5748.3 5193.1 5434.9 5893.6 5925.6 5988.6 5249.8 5757.3 5781.2 5828.0 5833.8 5329.8 5660.4 6011.5 6076.9 6117.5 4239.7 4999.8 5038.6 5055.0 5069.0 5122.4 5251.4 5487.4 5557.7 5719.5 4729.8 4998.6 5173.7 5497.2 5658.6 4831.0 5315.2 5641.8 5729.9 5740.8 5048.8 5137.4 5238.6 5527.4 5713.5 4774.8 5756.1 5783.5 5834.6 5837.4 5257.6 5656.2 5687.7 5714.5 5731.3 08:28:50 INFO - PROCESS | 1641 | 08:28:50 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:21025.74 Median:18014.32 stddev:30403.29 (168.8%) stddev-sans-first:30450.21 08:28:50 INFO - PROCESS | 1641 | Values: 684.4 781.6 805.7 885.4 891.7 13556.4 17600.2 17637.3 17696.7 17850.3 16180.4 16355.8 16391.8 17737.7 18506.4 999.3 1013.7 1019.2 1022.8 1028.3 1012.7 1017.2 1020.2 1020.5 1022.4 911.4 1066.4 1069.9 1070.6 1075.1 1067.3 1068.7 1069.2 1069.3 1069.7 652.7 787.4 788.8 790.0 790.6 17710.3 18178.4 18336.8 18370.8 18427.0 14116.9 14566.4 14593.4 14617.7 14867.6 11875.6 11976.8 12318.8 12342.9 12724.5 3321.5 3970.0 3973.4 4033.2 4138.3 116434.4 117700.8 121323.0 121432.6 123452.4 11266.5 11521.8 11536.5 11632.4 11977.5 22391.9 22764.0 22991.7 23325.4 23404.4 8273.1 8346.9 8350.4 8351.6 8379.8 4601.2 4650.8 4703.5 4800.4 4951.2 3636.7 3670.9 3690.9 3735.4 3740.9 626.6 629.5 630.8 632.1 638.8 705.0 722.0 736.4 745.8 779.4 10699.6 10838.3 11178.9 11270.7 11341.3 588.5 612.4 617.3 627.6 633.6 4829.5 5210.5 5370.0 5391.2 5405.7 92568.5 98004.0 99882.5 101607.0 104824.0 85820.0 86787.0 89024.0 89105.7 89652.6 78721.6 80843.0 82587.0 83485.0 83518.2 10016.1 10479.8 10486.5 10536.4 10548.3 35973.5 47644.3 50995.7 51352.7 52825.2 66086.0 66966.0 67984.0 68399.7 69053.7 11540.4 11770.2 11864.1 11972.9 12015.4 11780.0 13379.4 13532.8 13883.6 13972.1 10809.4 12384.4 12839.4 12998.7 13273.5 12284.2 12788.9 12795.0 12851.0 12867.6 12572.9 12581.9 12666.8 12811.4 13095.2 9446.9 9513.7 9616.3 9960.6 10053.1 10224.2 10856.6 10881.3 10927.5 10997.6 08:28:50 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:28:50 INFO - PROCESS | 1641 | 08:28:51 INFO - TEST-INFO | 1641: exit 0 08:28:51 INFO - TEST-OK | dromaeo_css | took 1114823ms 08:28:51 INFO - TEST-START | kraken 08:28:51 INFO - operating with platform_type : mac_ 08:28:51 INFO - Initialising browser for kraken test... 08:28:51 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpvw5dof/profile http://localhost:49242/getInfo.html) 08:28:54 INFO - PROCESS | 1653 | 2017-04-27 08:28:54.010 plugin-container[1655:10439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 08:28:54 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:54 INFO - PROCESS | 1653 | 2017-04-27 08:28:54.010 plugin-container[1655:10439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 08:28:54 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:54 INFO - PROCESS | 1653 | 2017-04-27 08:28:54.010 plugin-container[1655:10439] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:28:54 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:28:54 INFO - PROCESS | 1653 | colorDepth:24 08:28:54 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:28:54 INFO - PROCESS | 1653 | __metrics 08:28:54 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:28:54 INFO - TEST-INFO | 1653: exit 0 08:28:54 INFO - Browser initialized. 08:28:54 INFO - Running cycle 1/1 for kraken test... 08:28:54 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpvw5dof/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:57 INFO - PROCESS | 1656 | 2017-04-27 08:28:57.236 plugin-container[1657:10565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 08:28:57 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:57 INFO - PROCESS | 1656 | 2017-04-27 08:28:57.237 plugin-container[1657:10565] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860b, name = 'com.apple.CFPasteboardClient' 08:28:57 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:57 INFO - PROCESS | 1656 | 2017-04-27 08:28:57.237 plugin-container[1657:10565] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:06 INFO - PROCESS | 1656 | RSS: Main: 174407680 08:29:06 INFO - PROCESS | 1656 | 08:29:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:10 INFO - PROCESS | 1656 | RSS: Main: 187891712 08:29:10 INFO - PROCESS | 1656 | 08:29:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 08:29:14 INFO - PROCESS | 1656 | RSS: Main: 195870720 08:29:14 INFO - PROCESS | 1656 | 08:29:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 08:29:18 INFO - PROCESS | 1656 | RSS: Main: 199557120 08:29:18 INFO - PROCESS | 1656 | 08:29:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 08:29:20 INFO - PROCESS | 1656 | RSS: Main: 200982528 08:29:20 INFO - PROCESS | 1656 | 08:29:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:23 INFO - PROCESS | 1656 | RSS: Main: 201342976 08:29:23 INFO - PROCESS | 1656 | 08:29:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:27 INFO - PROCESS | 1656 | RSS: Main: 205008896 08:29:27 INFO - PROCESS | 1656 | 08:29:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:30 INFO - PROCESS | 1656 | RSS: Main: 204263424 08:29:30 INFO - PROCESS | 1656 | 08:29:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 08:29:34 INFO - PROCESS | 1656 | RSS: Main: 204431360 08:29:34 INFO - PROCESS | 1656 | 08:29:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:29:37 INFO - PROCESS | 1656 | RSS: Main: 203935744 08:29:37 INFO - PROCESS | 1656 | 08:29:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:39 INFO - PROCESS | 1656 | RSS: Main: 203968512 08:29:39 INFO - PROCESS | 1656 | 08:29:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:42 INFO - PROCESS | 1656 | RSS: Main: 204193792 08:29:42 INFO - PROCESS | 1656 | 08:29:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:46 INFO - PROCESS | 1656 | RSS: Main: 204562432 08:29:46 INFO - PROCESS | 1656 | 08:29:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:49 INFO - PROCESS | 1656 | RSS: Main: 204279808 08:29:49 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 08:29:51 INFO - PROCESS | 1656 | RSS: Main: 206102528 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | __start_tp_report 08:29:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:29:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:29:51 INFO - PROCESS | 1656 | |i|pagename|runs| 08:29:51 INFO - PROCESS | 1656 | |0;ai-astar;107;116;111;119;117;117;118;116;117;117 08:29:51 INFO - PROCESS | 1656 | |1;audio-beat-detection;139;118;141;137;141;146;144;121;130;132 08:29:51 INFO - PROCESS | 1656 | |2;audio-dft;197;193;200;200;194;199;198;214;242;195 08:29:51 INFO - PROCESS | 1656 | |3;audio-fft;84;62;60;89;64;58;60;59;70;63 08:29:51 INFO - PROCESS | 1656 | |4;audio-oscillator;91;70;84;93;103;72;80;70;69;82 08:29:51 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;100;103;90;118;84;130;137;99;98;136 08:29:51 INFO - PROCESS | 1656 | |6;imaging-darkroom;103;94;154;153;95;95;123;109;153;143 08:29:51 INFO - PROCESS | 1656 | |7;imaging-desaturate;144;145;91;92;91;90;95;107;148;77 08:29:51 INFO - PROCESS | 1656 | |8;json-parse-financial;56;88;55;59;71;57;92;102;104;106 08:29:51 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;56;63;70;83;63;69;88;88;58;55 08:29:51 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;86;84;81;81;84;85;86;88;104 08:29:51 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;128;153;145;147;139;133;133;137;136;134 08:29:51 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;163;163;161;160;161;166;161;162;158;165 08:29:51 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;64;64;65;62;65;65;62;66;88;84 08:29:51 INFO - PROCESS | 1656 | __end_tp_report 08:29:51 INFO - PROCESS | 1656 | __start_cc_report 08:29:51 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,982 08:29:51 INFO - PROCESS | 1656 | __end_cc_report 08:29:51 INFO - PROCESS | 1656 | __startTimestamp1493306991386__endTimestamp 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:29:51 INFO - PROCESS | 1656 | Number of tests: 14 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:115.50 Median:117.00 stddev:3.66 (3.1%) stddev-sans-first:2.24 08:29:51 INFO - PROCESS | 1656 | Values: 107.0 116.0 111.0 119.0 117.0 117.0 118.0 116.0 117.0 117.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:140.00 stddev:9.50 (6.8%) stddev-sans-first:9.96 08:29:51 INFO - PROCESS | 1656 | Values: 139.0 118.0 141.0 137.0 141.0 146.0 144.0 121.0 130.0 132.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:203.20 Median:199.50 stddev:14.84 (7.4%) stddev-sans-first:15.57 08:29:51 INFO - PROCESS | 1656 | Values: 197.0 193.0 200.0 200.0 194.0 199.0 198.0 214.0 242.0 195.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.90 Median:63.50 stddev:10.93 (17.2%) stddev-sans-first:9.68 08:29:51 INFO - PROCESS | 1656 | Values: 84.0 62.0 60.0 89.0 64.0 58.0 60.0 59.0 70.0 63.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:81.00 stddev:11.57 (14.3%) stddev-sans-first:11.74 08:29:51 INFO - PROCESS | 1656 | Values: 91.0 70.0 84.0 93.0 103.0 72.0 80.0 70.0 69.0 82.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.50 stddev:19.31 (17.5%) stddev-sans-first:20.17 08:29:51 INFO - PROCESS | 1656 | Values: 100.0 103.0 90.0 118.0 84.0 130.0 137.0 99.0 98.0 136.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:122.20 Median:153.50 stddev:26.16 (17.0%) stddev-sans-first:26.81 08:29:51 INFO - PROCESS | 1656 | Values: 103.0 94.0 154.0 153.0 95.0 95.0 123.0 109.0 153.0 143.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:108.00 Median:90.50 stddev:26.99 (29.8%) stddev-sans-first:25.28 08:29:51 INFO - PROCESS | 1656 | Values: 144.0 145.0 91.0 92.0 91.0 90.0 95.0 107.0 148.0 77.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:79.00 Median:58.00 stddev:21.57 (37.2%) stddev-sans-first:21.21 08:29:51 INFO - PROCESS | 1656 | Values: 56.0 88.0 55.0 59.0 71.0 57.0 92.0 102.0 104.0 106.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.50 stddev:12.81 (18.4%) stddev-sans-first:12.65 08:29:51 INFO - PROCESS | 1656 | Values: 56.0 63.0 70.0 83.0 63.0 69.0 88.0 88.0 58.0 55.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.00 stddev:6.55 (7.7%) stddev-sans-first:6.93 08:29:51 INFO - PROCESS | 1656 | Values: 85.0 86.0 84.0 81.0 81.0 84.0 85.0 86.0 88.0 104.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:138.00 stddev:7.63 (5.5%) stddev-sans-first:7.09 08:29:51 INFO - PROCESS | 1656 | Values: 128.0 153.0 145.0 147.0 139.0 133.0 133.0 137.0 136.0 134.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.00 Median:162.50 stddev:2.36 (1.5%) stddev-sans-first:2.47 08:29:51 INFO - PROCESS | 1656 | Values: 163.0 163.0 161.0 160.0 161.0 166.0 161.0 162.0 158.0 165.0 08:29:51 INFO - PROCESS | 1656 | 08:29:51 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:65.00 stddev:9.36 (14.4%) stddev-sans-first:9.79 08:29:51 INFO - PROCESS | 1656 | Values: 64.0 64.0 65.0 62.0 65.0 65.0 62.0 66.0 88.0 84.0 08:29:51 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:29:51 INFO - PROCESS | 1656 | 08:29:52 INFO - TEST-INFO | 1656: exit 0 08:29:52 INFO - TEST-OK | kraken | took 60804ms 08:29:52 INFO - SUITE-END | took 1175s 08:29:52 INFO - Completed test suite (00:19:35) 08:29:52 INFO - javascript benchmark 08:29:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8003.714620601453, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.6318977624287, 3621.330053940021, 3642.708583313335, 3652.4886515887774, 3697.4268988306803, 5648.802291919784, 5977.432143946324, 6224.626522408653, 6294.874102517947, 6296.622202667837, 6188.443040126388, 6254.249490061187, 6296.181496405472, 6305.6216627002295, 6316.9684151579295, 4613.63090952723, 5153.304303918979, 5649.830505084834, 5696.914546281809, 5780.161876527901, 5746.051901436258, 5769.624974376673, 5774.942250577504, 5779.537636989052, 5802.535797136219, 5540.196671482632, 5544.140658197985, 5759.16492108644, 5772.422757724236, 5821.970890145543, 5668.943310566883, 5691.174779656926, 5698.373178950312, 5736.368999410061, 5775.942240577582, 5635.126555383922, 5711.942880571182, 5770.0, 5770.711464426804, 5864.38423965486, 4543.931841022388, 5357.758900849432, 6058.515318774487, 6096.908546371808, 6224.813255602339, 6472.0, 6599.208095028579, 6611.636360000198, 6737.3262673732725, 6765.289644587297, 6786.59280443175, 6856.657167141673, 6861.0, 6866.725330986755, 6873.347032031949, 5483.985462689415, 6347.270063942613, 6362.491000719931, 6376.681165941731, 6377.808665740035, 6521.11964884744, 6629.502787290972, 6753.831154221184, 6810.148731408574, 6862.245153033163, 6644.534882558174, 6657.700403481855, 6676.432503237182, 6685.097511835937, 6687.0, 6095.4818840398175, 6712.798616041527, 6793.864122717518, 6804.4216241620015, 6830.385265326145, 6333.556651034383, 6357.713902874381, 6480.611163330215, 6512.967435162794, 6699.296573859772, 6653.534252602271, 6699.799006029827, 6723.159605049369, 6727.529072964846, 6728.125343705385, 5220.216967454912, 5254.710990895461, 5315.0, 5331.440908364007, 5357.892842143213, 3908.1495714009343, 4631.606313463393, 4806.279058141306, 4821.590164836029, 4826.831060912851, 6121.6358891611635, 6182.0, 6269.028300613412, 6346.048092786026, 6397.8720425592155, 6662.766803161963, 6767.661616919135, 6773.45812335012, 6811.352921472452, 6812.14848143982, 6370.299267080617, 6415.037744338387, 6443.420092191632, 6443.7744678936015, 6444.065610486453, 5689.374168841424, 5746.2529871116485, 5967.433093856163, 5986.700664966821, 5994.700264986734, 5710.457506536873, 6786.558873673195, 6847.349501797321, 6875.553089049396, 6883.896741548981, 6663.533552651268, 6754.493412993947, 6763.0, 6774.762883299061, 6779.661016949231, 6959.338862808025, 6981.79054628362, 7004.0, 7006.9649651751415, 7013.0, 6322.49420046386, 6357.93642063592, 6445.774397896051, 6452.322506136976, 6626.635535045619, 6734.696938637675, 6747.966260168668, 6752.020956961115, 6756.8648627026205, 6766.560173588701, 6459.483241340776, 6538.8365290868105, 6549.6070235786, 6552.082708420729, 6554.573952693059, 6117.614269788275, 6315.873682526232, 6385.553011289165, 6430.614163150226, 6552.737890484518, 6344.71448784815, 6405.295417503978, 6425.0, 6432.388923052303, 6447.290798012309, 6214.968925155526, 6558.213014438107, 6580.177477815273, 6587.670616469253, 6648.90026649591, 6252.5935814171935, 6358.968205158944, 6407.519436042408, 6421.614703117828, 6441.871162576816], "value": 6127.830816561935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.8437039147757, 908.6728777640051, 921.2814005076043, 993.0367543482828, 1002.1181360402844, 36983.81508092459, 38979.0, 39076.60923390765, 39205.0, 39215.41176882349, 33940.151496212544, 34702.8264858676, 37378.62621373792, 37680.0, 37857.810710946476, 7799.844003119934, 7952.324052455549, 8056.637451314705, 8212.630431630561, 8218.137095604965, 10073.848892266622, 11366.090712743002, 11739.413029348541, 11785.469653865597, 11785.528578856836, 9918.05778451046, 11686.649400517997, 11815.409229538489, 11841.526338946433, 11849.0, 11731.0, 11769.646910592695, 11800.940995295012, 11804.763904721944, 11825.113116516295, 11540.538378464851, 11544.422778861024, 11568.826467602992, 11631.825522617168, 11688.941555292255, 39700.80149599262, 39792.801035994926, 39990.80004599987, 40035.399469007985, 40036.999075023356, 3959.1487830116375, 3979.263836190314, 3983.9203215935518, 4073.368627862686, 4192.413062171298, 27668.44663106747, 28389.716102838913, 28554.0, 28562.0, 29503.114906552837, 3179.8887038953526, 3271.8200498972546, 3303.80177189367, 3380.4422270325367, 3435.862565497377, 346214.0, 346469.0, 346673.26663366775, 347787.0, 348467.0, 52200.0, 52218.95562088737, 52322.47677523252, 52565.2115218272, 52605.684857878856, 54814.45185548173, 54843.725781370835, 54847.451525485034, 54879.725601371734, 54889.451105489235, 15229.314680839389, 15691.450799221973, 15704.979176353501, 15711.0, 15717.371305147666, 13232.073754837069, 13285.66785830362, 13290.20258784476, 13337.866621333857, 13366.33168341579, 12216.877831221751, 12593.0, 12627.5580354689, 12666.683332916751, 12725.109242353132, 5752.36723960364, 5772.855678608068, 5792.710364481759, 5807.448292412214, 5825.592208545361, 1067.0, 1146.6846617180342, 1192.356127691037, 1212.42409855318, 1262.5391732017931, 26115.0, 27277.318067048483, 27480.779457003955, 27665.446691066067, 27974.580381294298, 1015.7308313296983, 1316.1576590981779, 1328.14334754085, 1352.3981828086407, 1376.6695992961618, 14195.361208745631, 14352.138871667734, 14455.349509272108, 14468.902009208816, 14473.276336183359, 475940.0, 477386.0, 478232.6088369536, 478962.0, 479029.0, 45204.547954520036, 54754.0, 55116.72441637766, 55174.17238741422, 55418.16872746912, 1334.1794796200268, 1525.3974680001559, 1552.6661767719995, 1553.5339398180502, 1556.144120733592, 22334.77665223327, 30728.231794205323, 31892.5216121763, 31906.0, 32590.51114233241, 41682.58317416787, 52811.73594132158, 52812.735936320074, 53145.202821958475, 53276.73361633167, 499254.0, 499817.0, 503041.0, 503079.0, 503608.0, 35466.64533354634, 36268.09329766777, 36368.454473183454, 36402.635973639924, 36448.0, 36332.81833590815, 36599.08502287464, 44164.89587760332, 45002.0, 46116.30825537687, 27565.0, 31237.375252495276, 31428.685713142575, 31969.200769980936, 32178.83910580432, 31026.379472410877, 31299.53050704287, 31480.42597870143, 31658.683413165574, 32007.839960800047, 36711.816440917624, 36793.63206367902, 36991.445128323634, 37444.62555374411, 37851.810740946115, 30870.0, 31216.0, 31243.375132497677, 31283.687163128077, 31808.68191318057, 23999.0, 24892.62661060122, 25147.874260629313, 25313.60775157384, 25474.61788073143], "value": 19571.09455064112, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.46004054364437, 425.2303330970943, 427.5489358726546, 438.9846355377562, 466.2912373192747, 20179.39461816146, 21372.57254854902, 22311.219107331246, 22480.100795968163, 22513.874306284662, 19208.0, 22607.0, 23357.649635255442, 23710.0, 24080.87959560204, 6322.43098121169, 6887.621180835052, 7204.783856484314, 7227.168875579297, 7227.747028853991, 6959.199692035405, 7094.71621135154, 7130.073090498254, 7140.0, 7151.32062454066, 7096.929030709704, 7151.749688760894, 7401.962990185041, 7733.419993500482, 7909.169537198569, 7571.772846814604, 7774.378049756006, 7797.142314345418, 7814.413918956073, 7863.449558530905, 7599.658015389321, 7612.885806712904, 7614.8096297593, 7617.314441700219, 7646.311831935152, 26813.731862681318, 27730.445391092064, 28003.0, 28048.719512804815, 28207.71792282092, 2738.8493632850186, 3048.0855743277148, 3052.9084127476212, 3057.4038062577806, 3074.1392410124977, 15996.76004859928, 16249.431269905615, 16948.237329320087, 17317.48047558575, 17432.738508922375, 2138.828893688501, 2165.6534954407375, 2194.714687090684, 2224.4216503709154, 2264.615015447378, 147114.0, 156525.0, 162150.0, 163411.18294408452, 179963.40073198592, 21077.78922210789, 21429.49993500471, 21511.78488215098, 21605.351839444527, 22028.559428811335, 32408.83795581007, 32938.341233175684, 33016.33967320688, 33257.3348533028, 33346.832860849754, 10637.149028077734, 10805.189610779224, 11004.119670426508, 11028.50371733274, 11172.273802202832, 9176.95411522938, 9186.356955013218, 9189.356745027917, 9346.57940392684, 9363.25093992479, 9801.11789938909, 9915.900840991642, 9922.80154396908, 9956.80086398268, 10020.599176033023, 3595.3528364894046, 3614.8734794282072, 3624.456331550236, 4344.218040752632, 4369.470685260134, 891.5185799668106, 909.8453262945183, 1044.7910417916446, 1049.5801679328329, 1062.2245760594808, 21610.35168944934, 21937.122515099218, 21940.561188776133, 22016.88991555064, 22115.673059616474, 872.7905302727427, 909.6315992023241, 1094.2285688589525, 1106.7731115121542, 1109.6393672056613, 10788.0, 11339.92270734279, 11591.362475063785, 11607.76784464306, 11630.185886987829, 751589.0, 765090.0, 774121.0, 782861.0, 797643.0, 159918.20040899722, 161684.0, 164392.0, 164628.17685911735, 166677.33322666862, 1228.269179837995, 1246.7693476706838, 1269.955551555691, 1279.405076639367, 1279.8272233248579, 14472.421103155755, 16090.954087984244, 18572.814271857107, 19274.0, 19287.51781205481, 48254.75872620755, 57404.42595574158, 63396.366036339045, 63448.0, 64556.0, 66432.0, 87651.56174219088, 88523.0, 89748.55125724329, 90446.54776726074, 9796.755081123029, 11863.228890122115, 12159.696007599881, 12261.38693065361, 12299.93850030744, 13263.204207747565, 13588.25264610456, 13603.863961360259, 13768.518101866386, 14044.016918815994, 8971.685990990283, 9644.448413761522, 10138.341007834468, 11522.078233741448, 11578.36319002463, 9840.458774767456, 9931.255155863484, 10567.996040376152, 10850.0, 11028.779424411307, 11826.231294965799, 11836.349000805038, 12207.0, 12242.081843861568, 12275.815862762245, 11850.762984740084, 12044.156909016285, 12168.452419641315, 12179.75640487203, 12260.87739122633, 11609.245399049034, 11656.941715291368, 11727.706807329543, 11737.765244694887, 11965.222260553037], "value": 11983.006973735794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1611.2427159235156, 1641.7865677461932, 1649.9670006599877, 1653.198198873546, 1674.330267892843, 11071.778564428707, 11322.26405283656, 11670.124740644444, 11705.122115841314, 11740.471678774444, 11111.611093611726, 11219.495122719476, 11336.263142895727, 11374.886251137506, 11392.71518212043, 2426.951460970784, 2456.029868202068, 2459.0286836699493, 2465.408302007514, 2466.6300054991716, 2351.318117745852, 2366.6095094309417, 2465.963010554834, 2482.5283196192754, 2493.0526399968076, 2401.6277476991095, 2454.349597356702, 2459.0, 2466.0135945621714, 2468.950620987579, 2383.2373640435067, 2399.3401814501067, 2414.5774489464275, 2418.4074901649033, 2469.1481438903547, 2426.8422552534207, 2435.3789783605066, 2451.816113791455, 2454.16558370155, 2458.7172475165225, 11418.828717569244, 11637.825432618518, 11645.0, 11646.883531164665, 11663.183577149603, 5963.314218864843, 6122.142899994004, 6124.203853499017, 6130.111133885606, 6134.9079763803575, 3559.911002224965, 3591.2099338145567, 3665.6884164845756, 3726.4782930389765, 3775.2449510097817, 1038.9220808439322, 1226.1478272600546, 1274.7769140400392, 1276.7551637153795, 1284.4220100954608, 14335.92832035833, 14635.92682036583, 15175.165365904872, 15297.847021529753, 15708.057516549045, 4776.785044672998, 4883.218685010382, 4924.556789888856, 4936.703797772145, 4955.876103097379, 12312.384380780924, 12334.50661973511, 12348.506059757688, 12359.567415140607, 12381.195222310522, 5772.971135144381, 6052.455279024909, 6065.4541091302, 6087.1173679816175, 6089.2997305310455, 4323.567643235651, 5303.602229832701, 5334.546563542142, 5445.727713614383, 5451.427600102012, 3968.001589983095, 4049.230646177217, 4051.716379853441, 4153.833846646161, 4158.397032430281, 907.9636814527345, 1057.2652006855162, 1061.8407238914224, 1062.6599488163713, 1075.8924107589335, 967.970960871175, 990.1286867556504, 996.2329006664828, 1002.3434650304063, 1007.9546420411099, 6741.898871516931, 6800.863982720318, 6926.272741362185, 6944.201416837129, 6971.79084627462, 993.8459538771502, 1043.400044974143, 1064.8509208710786, 1074.7796701676139, 1103.542030057533, 3741.4574886641285, 4371.213181627274, 4380.211561918885, 4391.516933137416, 4523.276275795857, 13969.58091257264, 14558.199299038655, 14586.41654333836, 14587.41650333996, 14592.197429141286, 11348.205625606293, 13346.46614135443, 14038.929805350908, 14061.578152655436, 14467.49363772263, 10395.688129356131, 10616.150707943347, 10628.84056739165, 10632.0, 10636.734081647866, 6905.413039891666, 7069.71721131149, 7152.320529549685, 7213.20654727969, 7274.527155734861, 12612.49550017989, 12658.98728101768, 12887.613371598492, 12902.0, 13149.737005260033, 9284.58219380116, 12356.505739770308, 12656.810147847606, 12769.106162568902, 13027.674308142372, 9504.287178461782, 9521.476318802532, 9569.569369378256, 9571.90428095738, 9619.759506012406, 10549.367037977747, 10575.259731818698, 10575.36547807134, 10624.681259562225, 10699.89300106989, 9113.3165012623, 9156.0, 9187.402818816756, 9245.768855778659, 9368.4378937264, 8824.205821476098, 8912.108789121035, 8922.973858006413, 8997.505137217517, 9043.728688139367, 8350.540720260202, 8388.622511986854, 8427.115152908857, 8454.83090338202, 8565.400421970651, 6055.273367195965, 6156.015037593964, 6265.624062556261, 6299.685015749286, 6310.5898116622275, 5017.397912250407, 5204.219367094966, 6026.698665066817, 6133.0, 6164.352742961924], "value": 5303.946418626042, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.4437233867304, 385.5758665467986, 398.9980050099749, 402.7986006996502, 404.8785364390683, 4387.03485233249, 4806.447258565262, 5077.0, 5448.673079615226, 5507.311586051744, 4888.948875991661, 5284.8150314739, 5293.247511294135, 5320.866978325544, 5372.462753724625, 3726.5341832270965, 4402.415130552999, 4441.712518053205, 4445.355423463604, 4471.530489298626, 4180.414741936132, 4423.911521769563, 4464.642828573723, 4480.708753930984, 4514.864554063383, 3752.3058234226755, 4113.03343714227, 4186.665066794649, 4364.978175109136, 4415.006623509701, 4460.241758900995, 4465.397171381855, 4491.730496170224, 4497.0, 4523.235937984189, 3640.1445660269815, 3650.908727281813, 3751.5873253942045, 4152.688548358855, 4158.313878210091, 5120.92318615221, 5141.562967147797, 5177.948220517822, 5199.972010915746, 5215.608829337815, 4233.534311225793, 5116.820911268125, 5164.92252616211, 5174.896502069975, 5188.896222075575, 4845.878853028667, 5378.542823859937, 5652.463016013472, 5747.005830947493, 5765.855853603652, 4633.32816741574, 4739.573438390527, 4889.779959901777, 4924.507549245082, 4970.751462426901, 5614.494695477427, 5806.506446952014, 5809.489532721523, 5985.947166944889, 6073.331933487313, 4276.935845962313, 4350.12891491614, 4680.46174689915, 4704.788284527204, 5026.597872170218, 4462.620677242471, 4661.0, 5163.225516172604, 5199.766010529535, 5201.479852014768, 4470.8435204767675, 5335.66608347913, 5376.14522989568, 5391.894661594364, 5396.64921780083, 5739.512141468022, 5747.034546163134, 5762.135679648002, 5771.567132465082, 5772.682502462321, 1479.5857159995219, 1519.566923426818, 1528.9297491755815, 1669.472432724068, 1769.398404542436, 1207.9698007549882, 1335.419092694681, 1412.6892083741561, 1423.181670539444, 1444.407792804966, 4744.478107408182, 4922.384701912261, 5323.220513435427, 5355.544778693855, 5389.784408623611, 4765.7140571565815, 5175.611829112831, 5476.864023031021, 5690.5447564194765, 5706.457886500776, 4296.884758550089, 4922.72924989129, 5058.4435712071645, 5278.261043453919, 5340.012097761926, 2784.3874347643487, 2886.610307608488, 2905.402028884105, 3104.301532155292, 3112.813231206135, 5315.750798562335, 5392.973035134799, 5609.03683710698, 5873.358517718956, 6164.270616277087, 4980.701157930535, 5201.453847345974, 5416.702081385482, 6049.364816694272, 6085.96538588441, 5327.920081198863, 5400.73169268908, 5549.833504994856, 5576.498115169654, 5748.281464816898, 5193.11717008102, 5434.934724804549, 5893.5874488785375, 5925.585209035498, 5988.610740301867, 5249.842504724865, 5757.3379061406795, 5781.248437703071, 5827.970860145672, 5833.82498525045, 5329.774151944996, 5660.433956604306, 6011.519078473799, 6076.90884636722, 6117.480014198843, 4239.650675966076, 4999.800007999639, 5038.647294689336, 5055.0, 5069.0, 5122.359705036871, 5251.396089449762, 5487.4238204987905, 5557.749901254372, 5719.542436604978, 4729.787159577896, 4998.575121114746, 5173.715445650525, 5497.202905578589, 5658.575606829422, 4831.043427411903, 5315.176147697113, 5641.843422098542, 5729.879944420591, 5740.770369185186, 5048.798048078036, 5137.381724756689, 5238.6379541318765, 5527.419620939743, 5713.457221563945, 4774.76126193682, 5756.136579512939, 5783.479486846204, 5834.591578589628, 5837.3870743571315, 5257.58045327767, 5656.218291238247, 5687.715614219355, 5714.457126572969, 5731.283589551306], "value": 4394.590198297004, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.3567046975845, 781.5506084001701, 805.6947744653659, 885.3713863157158, 891.7101941868892, 13556.389962451689, 17600.20799064042, 17637.294508219657, 17696.734548981774, 17850.28598856046, 16180.433684821033, 16355.754663680025, 16391.83608163918, 17737.733933990967, 18506.352277670285, 999.3004896572392, 1013.7262939006464, 1019.2406657040515, 1022.8465730140501, 1028.2699283508719, 1012.6860673191334, 1017.2218253036433, 1020.2297265564525, 1020.5305559442628, 1022.355915773062, 911.4485736129645, 1066.3921564708119, 1069.8662667166604, 1070.5664205996584, 1075.1184029096144, 1067.2849191041983, 1068.716790050641, 1069.2408390043079, 1069.3049517813406, 1069.7486090768664, 652.6769249221618, 787.4251796188757, 788.755485799404, 789.9723509677134, 790.5572879187616, 17710.29158833645, 18178.36375726843, 18336.81663183378, 18370.816291837178, 18427.0, 14116.858831411657, 14566.417343306255, 14593.41626334956, 14617.707645847024, 14867.628309292246, 11875.643730688007, 11976.820347694791, 12318.81521777174, 12342.938285308606, 12724.491020359175, 3321.534985102088, 3969.960300396988, 3973.3642617181113, 4033.233685599756, 4138.255114079465, 116434.41782791117, 117700.82299176985, 121323.0, 121432.57134857253, 123452.38273808663, 11266.493007814668, 11521.769564608829, 11536.538538458368, 11632.418379081013, 11977.520899163936, 22391.888040559694, 22764.0, 22991.655125173136, 23325.41686457852, 23404.414889627897, 8273.089960104384, 8346.874796878052, 8350.4154709171, 8351.624176912053, 8379.790505237417, 4601.194790911577, 4650.813967441332, 4703.459102203222, 4800.375951126401, 4951.232558953368, 3636.6908812751212, 3670.889873303805, 3690.9261814763554, 3735.4396840474146, 3740.925181496355, 626.6302881300055, 629.4869681209799, 630.8359826445158, 632.1181951178081, 638.7796210307437, 705.0235423937883, 722.0, 736.37408203027, 745.8471013442231, 779.3764988009569, 10699.572017119228, 10838.295510791773, 11178.944105279421, 11270.661880143609, 11341.319520828776, 588.4729127913145, 612.3631423319787, 617.2623714661026, 627.6140173793176, 633.6071635585967, 4829.492903245179, 5210.47895210476, 5370.038578437021, 5391.191321301758, 5405.702686352289, 92568.53715731378, 98004.0, 99882.50293742714, 101607.0, 104824.0, 85820.0, 86787.0, 89024.0, 89105.66341504882, 89652.5517372409, 78721.60639196882, 80843.0, 82587.0, 83485.0, 83518.16481835104, 10016.098551130433, 10479.790404192026, 10486.47567621631, 10536.420496872439, 10548.314359566604, 35973.46039809353, 47644.2853357193, 50995.745021274655, 51352.74323628358, 52825.20762188493, 66086.0, 66966.0, 67984.0, 68399.65800170967, 69053.65473172603, 11540.365279909685, 11770.234934729559, 11864.136660192104, 11972.880271197177, 12015.399230038638, 11780.0, 13379.397927093109, 13532.797008045085, 13883.583492495241, 13972.091814032055, 10809.35143891369, 12384.380780961095, 12839.422225999666, 12998.675033124247, 13273.469061237442, 12284.201526900722, 12788.936055319664, 12795.0, 12851.0, 12867.613971580868, 12572.937135314265, 12581.874181258436, 12666.809997849856, 12811.359432028175, 13095.214287142802, 9446.905530944878, 9513.66702165421, 9616.278779091735, 9960.601575937171, 10053.095221430107, 10224.182065434896, 10856.565737370416, 10881.347119172875, 10927.45362731876, 10997.560097596006], "value": 7847.016918883905, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 111.0, 119.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 118.0, 141.0, 137.0, 141.0, 146.0, 144.0, 121.0, 130.0, 132.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 193.0, 200.0, 200.0, 194.0, 199.0, 198.0, 214.0, 242.0, 195.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 60.0, 89.0, 64.0, 58.0, 60.0, 59.0, 70.0, 63.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 84.0, 93.0, 103.0, 72.0, 80.0, 70.0, 69.0, 82.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 103.0, 90.0, 118.0, 84.0, 130.0, 137.0, 99.0, 98.0, 136.0], "value": 109.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 94.0, 154.0, 153.0, 95.0, 95.0, 123.0, 109.0, 153.0, 143.0], "value": 122.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 91.0, 92.0, 91.0, 90.0, 95.0, 107.0, 148.0, 77.0], "value": 108.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 88.0, 55.0, 59.0, 71.0, 57.0, 92.0, 102.0, 104.0, 106.0], "value": 79.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 70.0, 83.0, 63.0, 69.0, 88.0, 88.0, 58.0, 55.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 81.0, 81.0, 84.0, 85.0, 86.0, 88.0, 104.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 153.0, 145.0, 147.0, 139.0, 133.0, 133.0, 137.0, 136.0, 134.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 160.0, 161.0, 166.0, 161.0, 162.0, 158.0, 165.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 62.0, 65.0, 65.0, 62.0, 66.0, 88.0, 84.0], "value": 68.5, "unit": "score"}]}]} 08:29:52 INFO - Return code: 0 08:29:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:29:52 ERROR - # TBPL SUCCESS # 08:29:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:52 INFO - 2017-04-27 08:29:52.684 screenresolution[1661:10946] starting screenresolution argv=screenresolution get 08:29:52 INFO - 2017-04-27 08:29:52.687 screenresolution[1661:10946] Display 0: 1600x1200x32@60 08:29:52 INFO - 2017-04-27 08:29:52.693 screenresolution[1662:10949] starting screenresolution argv=screenresolution list 08:29:52 INFO - Available Modes on Display 0 08:29:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:52 INFO - Intel Iris: 08:29:52 INFO - Chipset Model: Intel Iris 08:29:52 INFO - Type: GPU 08:29:52 INFO - Bus: Built-In 08:29:52 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:52 INFO - Vendor: Intel (0x8086) 08:29:52 INFO - Device ID: 0x0a2e 08:29:52 INFO - Revision ID: 0x0009 08:29:52 INFO - Displays: 08:29:52 INFO - Display: 08:29:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:52 INFO - Display Serial Number: 1600x1200 60 08:29:52 INFO - Main Display: Yes 08:29:52 INFO - Mirror: Off 08:29:52 INFO - Online: Yes 08:29:52 INFO - Rotation: Supported 08:29:52 INFO - Return code: 0 08:29:52 INFO - Running post-action listener: _resource_record_post_action 08:29:52 INFO - [mozharness: 2017-04-27 15:29:52.928543Z] Finished run-tests step (success) 08:29:52 INFO - Running post-run listener: _resource_record_post_run 08:29:52 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 38950912; Write bytes: 426204160; Read time: 247; Write time: 1434 08:29:52 INFO - TinderboxPrint: CPU usage
54.5% 08:29:52 INFO - TinderboxPrint: I/O read bytes / time
38,950,912 / 247 08:29:52 INFO - TinderboxPrint: I/O write bytes / time
426,204,160 / 1,434 08:29:52 INFO - TinderboxPrint: CPU idle
2,170.1 (45.5%) 08:29:52 INFO - TinderboxPrint: CPU system
957.0 (20.1%) 08:29:52 INFO - TinderboxPrint: CPU user
1,638.5 (34.4%) 08:29:52 INFO - TinderboxPrint: Swap in / out
161,464,320 / 0 08:29:52 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123054592; Write bytes: 125630464; Read time: 11240; Write time: 291 08:29:53 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35760128; Write bytes: 299189248; Read time: 223; Write time: 1138 08:29:53 INFO - Running post-run listener: _upload_blobber_files 08:29:53 INFO - Blob upload gear active. 08:29:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:53 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 08:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:29:53 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:29:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:29:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:54 INFO - (blobuploader) - INFO - Done attempting. 08:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:55 INFO - (blobuploader) - INFO - Done attempting. 08:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:56 INFO - (blobuploader) - INFO - Done attempting. 08:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:58 INFO - (blobuploader) - INFO - Done attempting. 08:29:58 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:58 INFO - Return code: 0 08:29:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28264dbfd5be7dca0bbed71adff96ea5cdff3eace1b1508e9bb74ae41c485fb25d57f860a3d8965930526b47d5d02502456e2b12f80caa321f4f2255e9befcfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/043e55ceceac121f32e102c16f468ccd1124e6de8b5b3dcccc1d2bf60abfc73efce45f073eb14dd80dda21659cf08ddf934ff751d76e81968bc78bee30e3df1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5817bef8919a430a0e672de7219ae14e1abe81a3b7ee71f0dc268e853322e38fa9c91838732ee65bf395a7adae997860b3f51070fa98bf0a2332c49a5a39013e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba263404c703c26c09cb6a6d3e13b90c097335dd0350bdb3a7759055e3ecc04160f7857b8d2545d1da563785a56b4e0c7c184221ce8525e6753a645756a6d17b"} 08:29:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:58 INFO - Contents: 08:29:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28264dbfd5be7dca0bbed71adff96ea5cdff3eace1b1508e9bb74ae41c485fb25d57f860a3d8965930526b47d5d02502456e2b12f80caa321f4f2255e9befcfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/043e55ceceac121f32e102c16f468ccd1124e6de8b5b3dcccc1d2bf60abfc73efce45f073eb14dd80dda21659cf08ddf934ff751d76e81968bc78bee30e3df1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5817bef8919a430a0e672de7219ae14e1abe81a3b7ee71f0dc268e853322e38fa9c91838732ee65bf395a7adae997860b3f51070fa98bf0a2332c49a5a39013e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba263404c703c26c09cb6a6d3e13b90c097335dd0350bdb3a7759055e3ecc04160f7857b8d2545d1da563785a56b4e0c7c184221ce8525e6753a645756a6d17b"} 08:29:58 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:58 INFO - Copying logs to upload dir... 08:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.147849 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-04-27 08:29:58.304052) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 08:29:58.307645) ========= 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.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28264dbfd5be7dca0bbed71adff96ea5cdff3eace1b1508e9bb74ae41c485fb25d57f860a3d8965930526b47d5d02502456e2b12f80caa321f4f2255e9befcfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/043e55ceceac121f32e102c16f468ccd1124e6de8b5b3dcccc1d2bf60abfc73efce45f073eb14dd80dda21659cf08ddf934ff751d76e81968bc78bee30e3df1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5817bef8919a430a0e672de7219ae14e1abe81a3b7ee71f0dc268e853322e38fa9c91838732ee65bf395a7adae997860b3f51070fa98bf0a2332c49a5a39013e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba263404c703c26c09cb6a6d3e13b90c097335dd0350bdb3a7759055e3ecc04160f7857b8d2545d1da563785a56b4e0c7c184221ce8525e6753a645756a6d17b"} build_url:https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011083 build_url: 'https://queue.taskcluster.net/v1/task/dG5BuG09QDWDWq3YyyPCtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28264dbfd5be7dca0bbed71adff96ea5cdff3eace1b1508e9bb74ae41c485fb25d57f860a3d8965930526b47d5d02502456e2b12f80caa321f4f2255e9befcfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/043e55ceceac121f32e102c16f468ccd1124e6de8b5b3dcccc1d2bf60abfc73efce45f073eb14dd80dda21659cf08ddf934ff751d76e81968bc78bee30e3df1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5817bef8919a430a0e672de7219ae14e1abe81a3b7ee71f0dc268e853322e38fa9c91838732ee65bf395a7adae997860b3f51070fa98bf0a2332c49a5a39013e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba263404c703c26c09cb6a6d3e13b90c097335dd0350bdb3a7759055e3ecc04160f7857b8d2545d1da563785a56b4e0c7c184221ce8525e6753a645756a6d17b"}' ========= master_lag: 0.13 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 08:29:58.449085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:29:58.449467) ========= 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.lkaKohuGQC/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.YYHhkpsFr4/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.005286 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 08:29:58.478147) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 08:29:58.478484) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 08:29:58.478817) ========= ========= Total master_lag: 0.29 =========