builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0365 starttime: 1485718769.31 results: success (0) buildid: 20170129110043 builduid: df7cfc53ab174be3a7f9cc2f2b990e11 revision: 71224049c0b52ab190564d3ea0eab089a159a4cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.306639) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.307035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.307612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.358649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.359010) ========= 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.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-01-29 11:39:29-- 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% 72.6M=0s 2017-01-29 11:39:29 (72.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214343 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.603241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.603561) ========= 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.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.024969 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:29.656546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 11:39:29.656895) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 71224049c0b52ab190564d3ea0eab089a159a4cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 71224049c0b52ab190564d3ea0eab089a159a4cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-01-29 11:39:29,765 truncating revision to first 12 chars 2017-01-29 11:39:29,765 Setting DEBUG logging. 2017-01-29 11:39:29,765 attempt 1/10 2017-01-29 11:39:29,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/71224049c0b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-29 11:39:30,970 unpacking tar archive at: mozilla-central-71224049c0b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 11:39:31.173939) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:31.174425) ========= script_repo_revision: 71224049c0b52ab190564d3ea0eab089a159a4cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:31.175039) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:31.175468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 11:39:31.201680) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-29 11:39:31.202097) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:39:31 INFO - MultiFileLogger online at 20170129 11:39:31 in /builds/slave/test 11:39:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:39:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:39:31 INFO - {'append_to_log': False, 11:39:31 INFO - 'base_work_dir': '/builds/slave/test', 11:39:31 INFO - 'blob_upload_branch': 'Firefox', 11:39:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:39:31 INFO - 'branch': 'Firefox', 11:39:31 INFO - 'buildbot_json_path': 'buildprops.json', 11:39:31 INFO - 'config_files': ('talos/mac_config.py',), 11:39:31 INFO - 'default_actions': ('clobber', 11:39:31 INFO - 'read-buildbot-config', 11:39:31 INFO - 'download-and-extract', 11:39:31 INFO - 'populate-webroot', 11:39:31 INFO - 'create-virtualenv', 11:39:31 INFO - 'install', 11:39:31 INFO - 'run-tests'), 11:39:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:39:31 INFO - 'download_minidump_stackwalk': True, 11:39:31 INFO - 'download_symbols': 'ondemand', 11:39:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:39:31 INFO - 'tooltool.py': '/tools/tooltool.py', 11:39:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:39:31 INFO - '/tools/misc-python/virtualenv.py')}, 11:39:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:39:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:39:31 INFO - 'installer_path': 'installer.exe', 11:39:31 INFO - 'log_level': 'info', 11:39:31 INFO - 'log_name': 'talos', 11:39:31 INFO - 'log_to_console': True, 11:39:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:39:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:39:31 INFO - 'opt_config_files': (), 11:39:31 INFO - 'pip_index': False, 11:39:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:39:31 INFO - 'cmd': ('bash', 11:39:31 INFO - '-c', 11:39:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:39:31 INFO - 'enabled': True, 11:39:31 INFO - 'halt_on_failure': False, 11:39:31 INFO - 'name': 'check_screen_resolution'},), 11:39:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:39:31 INFO - 'cmd': ('bash', 11:39:31 INFO - '-c', 11:39:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:39:31 INFO - 'enabled': True, 11:39:31 INFO - 'halt_on_failure': False, 11:39:31 INFO - 'name': 'check_screen_resolution'},), 11:39:31 INFO - 'run_cmd_checks_enabled': True, 11:39:31 INFO - 'sps_profile': False, 11:39:31 INFO - 'sps_profile_interval': 0, 11:39:31 INFO - 'suite': 'dromaeojs', 11:39:31 INFO - 'system_bits': '32', 11:39:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:39:31 INFO - 'title': 't-yosemite-r7-0365', 11:39:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:39:31 INFO - 'use_talos_json': True, 11:39:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:39:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:39:31 INFO - 'work_dir': 'build'} 11:39:31 INFO - [mozharness: 2017-01-29 19:39:31.419725Z] Running clobber step. 11:39:31 INFO - Running pre-action listener: _resource_record_pre_action 11:39:31 INFO - Running main action method: clobber 11:39:31 INFO - rmtree: /builds/slave/test/build 11:39:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:39:33 INFO - Running post-action listener: _resource_record_post_action 11:39:33 INFO - [mozharness: 2017-01-29 19:39:33.860586Z] Finished clobber step (success) 11:39:33 INFO - [mozharness: 2017-01-29 19:39:33.860662Z] Running read-buildbot-config step. 11:39:33 INFO - Running pre-action listener: _resource_record_pre_action 11:39:33 INFO - Running main action method: read_buildbot_config 11:39:33 INFO - Using buildbot properties: 11:39:33 INFO - { 11:39:33 INFO - "project": "", 11:39:33 INFO - "product": "firefox", 11:39:33 INFO - "script_repo_revision": "production", 11:39:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:39:33 INFO - "repository": "", 11:39:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:39:33 INFO - "buildid": "20170129110043", 11:39:33 INFO - "pgo_build": "False", 11:39:33 INFO - "basedir": "/builds/slave/test", 11:39:33 INFO - "buildnumber": 42, 11:39:33 INFO - "slavename": "t-yosemite-r7-0365", 11:39:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:39:33 INFO - "platform": "yosemite_r7", 11:39:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:39:33 INFO - "branch": "mozilla-central", 11:39:33 INFO - "repo_path": "mozilla-central", 11:39:33 INFO - "revision": "71224049c0b52ab190564d3ea0eab089a159a4cf", 11:39:33 INFO - "stage_platform": "macosx64", 11:39:33 INFO - "builduid": "df7cfc53ab174be3a7f9cc2f2b990e11", 11:39:33 INFO - "slavebuilddir": "test" 11:39:33 INFO - } 11:39:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 11:39:33 INFO - Running post-action listener: _resource_record_post_action 11:39:33 INFO - [mozharness: 2017-01-29 19:39:33.863921Z] Finished read-buildbot-config step (success) 11:39:33 INFO - [mozharness: 2017-01-29 19:39:33.864054Z] Running download-and-extract step. 11:39:33 INFO - Running pre-action listener: _resource_record_pre_action 11:39:33 INFO - Running main action method: download_and_extract 11:39:33 INFO - mkdir: /builds/slave/test/build/tests 11:39:33 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')]} 11:39:33 INFO - trying https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 11:39:33 INFO - Downloading https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 11:39:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 11:39:35 INFO - Downloaded 1199 bytes. 11:39:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 11:39:35 INFO - Using the following test package requirements: 11:39:35 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 11:39:35 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 11:39:35 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 11:39:35 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'jsshell-mac.zip'], 11:39:35 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 11:39:35 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 11:39:35 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 11:39:35 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 11:39:35 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 11:39:35 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:39:35 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 11:39:35 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:39:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 11:39:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:39:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 11:39:36 INFO - Expected file size: 16364573 11:39:36 INFO - Obtained file size: 16364573 11:39:43 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:39:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 11:39:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:39:43 INFO - Fetch https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 11:39:43 INFO - Expected file size: 16364573 11:39:43 INFO - Obtained file size: 16364573 11:39:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 11:39:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:39:50 INFO - Fetch https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 11:39:51 INFO - Expected file size: 14403454 11:39:51 INFO - Obtained file size: 14403454 11:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:51 INFO - trying https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 11:39:51 INFO - Downloading https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 11:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 11:39:54 INFO - Downloaded 70144187 bytes. 11:39:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 11:39:54 INFO - mkdir: /builds/slave/test/properties 11:39:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:54 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:54 INFO - Contents: 11:39:54 INFO - build_url:https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 11:39:55 INFO - Running post-action listener: _resource_record_post_action 11:39:55 INFO - Running post-action listener: set_extra_try_arguments 11:39:55 INFO - [mozharness: 2017-01-29 19:39:55.057123Z] Finished download-and-extract step (success) 11:39:55 INFO - [mozharness: 2017-01-29 19:39:55.057227Z] Running populate-webroot step. 11:39:55 INFO - Running pre-action listener: _resource_record_pre_action 11:39:55 INFO - Running main action method: populate_webroot 11:39:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:39:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:39:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:39:55 INFO - '--tppagecycles', 11:39:55 INFO - '7'], 11:39:55 INFO - 'tests': ['tsvgm']}}, 11:39:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:39:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['damp', 'tps']}, 11:39:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'tests': ['damp', 'tps']}, 11:39:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['dromaeo_dom']}, 11:39:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['basic_compositor_video']}, 11:39:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:39:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['a11yr', 11:39:55 INFO - 'ts_paint', 11:39:55 INFO - 'tpaint', 11:39:55 INFO - 'sessionrestore', 11:39:55 INFO - 'sessionrestore_no_auto_restore', 11:39:55 INFO - 'tabpaint']}, 11:39:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['tsvgx', 11:39:55 INFO - 'tsvgr_opacity', 11:39:55 INFO - 'tart', 11:39:55 INFO - 'tscrollx', 11:39:55 INFO - 'cart', 11:39:55 INFO - 'tsvg_static']}, 11:39:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:55 INFO - 'tsvgr_opacity', 11:39:55 INFO - 'tart', 11:39:55 INFO - 'tscrollx', 11:39:55 INFO - 'cart', 11:39:55 INFO - 'tsvg_static']}, 11:39:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'talos_options': ['--disable-e10s'], 11:39:55 INFO - 'tests': ['tp5o']}, 11:39:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:39:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'talos_options': ['--disable-e10s', 11:39:55 INFO - '--xperf_path', 11:39:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:55 INFO - 'tests': ['tp5n']}, 11:39:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:39:55 INFO - 'talos_options': ['--xperf_path', 11:39:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:55 INFO - 'tests': ['tp5n']}}, 11:39:55 INFO - 'talos.zip': {'path': '', 11:39:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:39:55 INFO - Running post-action listener: _resource_record_post_action 11:39:55 INFO - [mozharness: 2017-01-29 19:39:55.075404Z] Finished populate-webroot step (success) 11:39:55 INFO - [mozharness: 2017-01-29 19:39:55.075519Z] Running create-virtualenv step. 11:39:55 INFO - Running pre-action listener: _resource_record_pre_action 11:39:55 INFO - Running main action method: create_virtualenv 11:39:55 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:55 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 11:39:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:39:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:55 INFO - Using real prefix '/tools/python27' 11:39:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:55 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:58 INFO - Installing pip.................done. 11:39:58 INFO - Return code: 0 11:39:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:39:59 INFO - Reading from file tmpfile_stdout 11:39:59 INFO - Output received: 11:39:59 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) 11:39:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:59 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')]} 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:59 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')]} 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:59 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:39:59 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 11:39:59 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 11:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:59 INFO - 'HOME': '/Users/cltbld', 11:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:59 INFO - 'LOGNAME': 'cltbld', 11:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:59 INFO - 'MOZ_NO_REMOTE': '1', 11:39:59 INFO - 'NO_EM_RESTART': '1', 11:39:59 INFO - 'PAGER': '/bin/cat', 11:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:59 INFO - 'PWD': '/builds/slave/test', 11:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:59 INFO - 'SHELL': '/bin/bash', 11:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:39:59 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:59 INFO - 'USER': 'cltbld', 11:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:59 INFO - 'XPC_FLAGS': '0x0', 11:39:59 INFO - 'XPC_SERVICE_NAME': '0', 11:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:59 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 11:39:59 INFO - Cleaning up... 11:39:59 INFO - Return code: 0 11:39:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:59 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')]} 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:59 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')]} 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:59 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:39:59 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 11:39:59 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 11:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:59 INFO - 'HOME': '/Users/cltbld', 11:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:59 INFO - 'LOGNAME': 'cltbld', 11:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:59 INFO - 'MOZ_NO_REMOTE': '1', 11:39:59 INFO - 'NO_EM_RESTART': '1', 11:39:59 INFO - 'PAGER': '/bin/cat', 11:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:59 INFO - 'PWD': '/builds/slave/test', 11:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:59 INFO - 'SHELL': '/bin/bash', 11:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:39:59 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:59 INFO - 'USER': 'cltbld', 11:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:59 INFO - 'XPC_FLAGS': '0x0', 11:39:59 INFO - 'XPC_SERVICE_NAME': '0', 11:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:59 INFO - Downloading/unpacking psutil>=3.1.1 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:40:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:40:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:40:03 INFO - Installing collected packages: psutil 11:40:03 INFO - Running setup.py install for psutil 11:40:03 INFO - building 'psutil._psutil_osx' extension 11:40:03 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 11:40:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:40:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:40:04 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 11:40:04 INFO - building 'psutil._psutil_posix' extension 11:40:04 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 11:40:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:40:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:40:04 INFO - ^ 11:40:04 INFO - 1 warning generated. 11:40:04 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 11:40:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:40:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:40:04 INFO - Successfully installed psutil 11:40:04 INFO - Cleaning up... 11:40:04 INFO - Return code: 0 11:40:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:40:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:05 INFO - 'HOME': '/Users/cltbld', 11:40:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:05 INFO - 'LOGNAME': 'cltbld', 11:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:05 INFO - 'MOZ_NO_REMOTE': '1', 11:40:05 INFO - 'NO_EM_RESTART': '1', 11:40:05 INFO - 'PAGER': '/bin/cat', 11:40:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:05 INFO - 'PWD': '/builds/slave/test', 11:40:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:05 INFO - 'SHELL': '/bin/bash', 11:40:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:05 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:05 INFO - 'USER': 'cltbld', 11:40:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:05 INFO - 'XPC_FLAGS': '0x0', 11:40:05 INFO - 'XPC_SERVICE_NAME': '0', 11:40:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:40:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:40:07 INFO - Installing collected packages: mozsystemmonitor 11:40:07 INFO - Running setup.py install for mozsystemmonitor 11:40:08 INFO - Successfully installed mozsystemmonitor 11:40:08 INFO - Cleaning up... 11:40:08 INFO - Return code: 0 11:40:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:08 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:08 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 11:40:08 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 11:40:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:08 INFO - 'HOME': '/Users/cltbld', 11:40:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:08 INFO - 'LOGNAME': 'cltbld', 11:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:08 INFO - 'MOZ_NO_REMOTE': '1', 11:40:08 INFO - 'NO_EM_RESTART': '1', 11:40:08 INFO - 'PAGER': '/bin/cat', 11:40:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:08 INFO - 'PWD': '/builds/slave/test', 11:40:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:08 INFO - 'SHELL': '/bin/bash', 11:40:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:08 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:08 INFO - 'USER': 'cltbld', 11:40:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:08 INFO - 'XPC_FLAGS': '0x0', 11:40:08 INFO - 'XPC_SERVICE_NAME': '0', 11:40:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:08 INFO - Downloading/unpacking jsonschema==2.5.1 11:40:08 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 11:40:08 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 11:40:08 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 11:40:08 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 11:40:08 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 11:40:08 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 11:40:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:40:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:40:11 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 11:40:11 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 11:40:11 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 11:40:11 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 11:40:11 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 11:40:11 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 11:40:11 INFO - Downloading functools32-3.2.3-2.tar.gz 11:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:40:11 INFO - warning: no files found matching '*.txt' 11:40:11 INFO - no previously-included directories found matching 'build' 11:40:11 INFO - no previously-included directories found matching 'dist' 11:40:11 INFO - no previously-included directories found matching '.git*' 11:40:11 INFO - Installing collected packages: jsonschema, functools32 11:40:11 INFO - Running setup.py install for functools32 11:40:11 INFO - warning: no files found matching '*.txt' 11:40:11 INFO - no previously-included directories found matching 'build' 11:40:11 INFO - no previously-included directories found matching 'dist' 11:40:11 INFO - no previously-included directories found matching '.git*' 11:40:11 INFO - Successfully installed jsonschema functools32 11:40:11 INFO - Cleaning up... 11:40:11 INFO - Return code: 0 11:40:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:40:11 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')]} 11:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:11 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')]} 11:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:11 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:11 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 11:40:11 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 11:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:11 INFO - 'HOME': '/Users/cltbld', 11:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:11 INFO - 'LOGNAME': 'cltbld', 11:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:11 INFO - 'MOZ_NO_REMOTE': '1', 11:40:11 INFO - 'NO_EM_RESTART': '1', 11:40:11 INFO - 'PAGER': '/bin/cat', 11:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:11 INFO - 'PWD': '/builds/slave/test', 11:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:11 INFO - 'SHELL': '/bin/bash', 11:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:11 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:11 INFO - 'USER': 'cltbld', 11:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:11 INFO - 'XPC_FLAGS': '0x0', 11:40:11 INFO - 'XPC_SERVICE_NAME': '0', 11:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:40:12 INFO - Cleaning up... 11:40:12 INFO - Return code: 0 11:40:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:12 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:12 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 11:40:12 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 11:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:12 INFO - 'HOME': '/Users/cltbld', 11:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:12 INFO - 'LOGNAME': 'cltbld', 11:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:12 INFO - 'MOZ_NO_REMOTE': '1', 11:40:12 INFO - 'NO_EM_RESTART': '1', 11:40:12 INFO - 'PAGER': '/bin/cat', 11:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:12 INFO - 'PWD': '/builds/slave/test', 11:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:12 INFO - 'SHELL': '/bin/bash', 11:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:12 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:12 INFO - 'USER': 'cltbld', 11:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:12 INFO - 'XPC_FLAGS': '0x0', 11:40:12 INFO - 'XPC_SERVICE_NAME': '0', 11:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:12 INFO - Downloading/unpacking blobuploader==1.2.4 11:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:40:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:40:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:16 INFO - Downloading docopt-0.6.1.tar.gz 11:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:40:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:40:16 INFO - Running setup.py install for blobuploader 11:40:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:40:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:40:16 INFO - Running setup.py install for requests 11:40:17 INFO - Running setup.py install for docopt 11:40:17 INFO - Successfully installed blobuploader requests docopt 11:40:17 INFO - Cleaning up... 11:40:17 INFO - Return code: 0 11:40:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:17 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:17 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 11:40:17 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 11:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:17 INFO - 'HOME': '/Users/cltbld', 11:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:17 INFO - 'LOGNAME': 'cltbld', 11:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:17 INFO - 'MOZ_NO_REMOTE': '1', 11:40:17 INFO - 'NO_EM_RESTART': '1', 11:40:17 INFO - 'PAGER': '/bin/cat', 11:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:17 INFO - 'PWD': '/builds/slave/test', 11:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:17 INFO - 'SHELL': '/bin/bash', 11:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:17 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:17 INFO - 'USER': 'cltbld', 11:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:17 INFO - 'XPC_FLAGS': '0x0', 11:40:17 INFO - 'XPC_SERVICE_NAME': '0', 11:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:40:17 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-SeWOuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:40:17 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Rn9wUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:40:17 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5p2Gy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:40:17 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Hc_1Oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:40:17 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7A6SSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-jKf_F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-HUDC1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DSv83p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-JXMp4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Q7YO8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tlf6aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-CoAdij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-PGJ1gL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8l9V4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:18 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ooR0pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:40:19 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-QB9pBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:40:19 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-3ae0Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:40:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:40:19 INFO - Running setup.py install for manifestparser 11:40:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:40:19 INFO - Running setup.py install for mozcrash 11:40:19 INFO - Running setup.py install for mozdebug 11:40:19 INFO - Running setup.py install for mozdevice 11:40:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:40:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:40:19 INFO - Running setup.py install for mozfile 11:40:20 INFO - Running setup.py install for mozhttpd 11:40:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Running setup.py install for mozinfo 11:40:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Running setup.py install for mozInstall 11:40:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Running setup.py install for mozleak 11:40:20 INFO - Running setup.py install for mozlog 11:40:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Running setup.py install for moznetwork 11:40:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:40:20 INFO - Running setup.py install for mozprocess 11:40:21 INFO - Running setup.py install for mozprofile 11:40:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:40:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:40:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:40:21 INFO - Running setup.py install for mozrunner 11:40:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:40:21 INFO - Running setup.py install for mozscreenshot 11:40:21 INFO - Running setup.py install for moztest 11:40:21 INFO - Running setup.py install for mozversion 11:40:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:40:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:40:21 INFO - Cleaning up... 11:40:21 INFO - Return code: 0 11:40:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:21 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')]} 11:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:21 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')]} 11:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:21 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:21 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 11:40:21 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 11:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:21 INFO - 'HOME': '/Users/cltbld', 11:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:21 INFO - 'LOGNAME': 'cltbld', 11:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:21 INFO - 'MOZ_NO_REMOTE': '1', 11:40:21 INFO - 'NO_EM_RESTART': '1', 11:40:21 INFO - 'PAGER': '/bin/cat', 11:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:21 INFO - 'PWD': '/builds/slave/test', 11:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:21 INFO - 'SHELL': '/bin/bash', 11:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:21 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:21 INFO - 'USER': 'cltbld', 11:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:21 INFO - 'XPC_FLAGS': '0x0', 11:40:21 INFO - 'XPC_SERVICE_NAME': '0', 11:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-9abpTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-SuqldZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-0rh8yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-GnqyET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-4V6DtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-skges6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-gBa5m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-EaZ2pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:40:22 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)) 11:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:40:22 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-PCba5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-UioSYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-4KbOkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Aj_mQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:40:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-3YBiQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-v1nZQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-LUxFzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-lNKIKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:40:23 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)) 11:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:40:23 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-s2rHM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:40:23 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)) 11:40:23 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)) 11:40:23 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)) 11:40:23 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)) 11:40:23 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)) 11:40:23 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)) 11:40:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:40:23 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 11:40:23 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 11:40:23 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 11:40:23 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 11:40:23 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 11:40:23 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 11:40:26 INFO - Downloading blessings-1.6.tar.gz 11:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:40:26 INFO - Installing collected packages: blessings 11:40:26 INFO - Running setup.py install for blessings 11:40:26 INFO - Successfully installed blessings 11:40:26 INFO - Cleaning up... 11:40:26 INFO - Return code: 0 11:40:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:40:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:27 INFO - Reading from file tmpfile_stdout 11:40:27 INFO - Current package versions: 11:40:27 INFO - blessings == 1.6 11:40:27 INFO - blobuploader == 1.2.4 11:40:27 INFO - docopt == 0.6.1 11:40:27 INFO - functools32 == 3.2.3-2 11:40:27 INFO - jsonschema == 2.5.1 11:40:27 INFO - manifestparser == 1.1 11:40:27 INFO - mozInstall == 1.12 11:40:27 INFO - mozcrash == 1.0 11:40:27 INFO - mozdebug == 0.1 11:40:27 INFO - mozdevice == 0.49 11:40:27 INFO - mozfile == 1.2 11:40:27 INFO - mozhttpd == 0.7 11:40:27 INFO - mozinfo == 0.9 11:40:27 INFO - mozleak == 0.1 11:40:27 INFO - mozlog == 3.4 11:40:27 INFO - moznetwork == 0.27 11:40:27 INFO - mozprocess == 0.24 11:40:27 INFO - mozprofile == 0.28 11:40:27 INFO - mozrunner == 6.13 11:40:27 INFO - mozscreenshot == 0.1 11:40:27 INFO - mozsystemmonitor == 0.3 11:40:27 INFO - moztest == 0.8 11:40:27 INFO - mozversion == 1.4 11:40:27 INFO - psutil == 3.1.1 11:40:27 INFO - requests == 1.2.3 11:40:27 INFO - wsgiref == 0.1.2 11:40:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:27 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 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:27 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 11:40:27 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 11:40:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:27 INFO - 'HOME': '/Users/cltbld', 11:40:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:27 INFO - 'LOGNAME': 'cltbld', 11:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:27 INFO - 'MOZ_NO_REMOTE': '1', 11:40:27 INFO - 'NO_EM_RESTART': '1', 11:40:27 INFO - 'PAGER': '/bin/cat', 11:40:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:27 INFO - 'PWD': '/builds/slave/test', 11:40:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:27 INFO - 'SHELL': '/bin/bash', 11:40:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:27 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:27 INFO - 'USER': 'cltbld', 11:40:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:27 INFO - 'XPC_FLAGS': '0x0', 11:40:27 INFO - 'XPC_SERVICE_NAME': '0', 11:40:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 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)) 11:40:27 INFO - Cleaning up... 11:40:27 INFO - Return code: 0 11:40:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1224646f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, '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.JuI2CCDCQQ/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.uL189FwOZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 11:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:40:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:27 INFO - 'HOME': '/Users/cltbld', 11:40:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:27 INFO - 'LOGNAME': 'cltbld', 11:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:27 INFO - 'MOZ_NO_REMOTE': '1', 11:40:27 INFO - 'NO_EM_RESTART': '1', 11:40:27 INFO - 'PAGER': '/bin/cat', 11:40:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:27 INFO - 'PWD': '/builds/slave/test', 11:40:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:27 INFO - 'SHELL': '/bin/bash', 11:40:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:27 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:27 INFO - 'USER': 'cltbld', 11:40:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:27 INFO - 'XPC_FLAGS': '0x0', 11:40:27 INFO - 'XPC_SERVICE_NAME': '0', 11:40:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:40:27 INFO - Cleaning up... 11:40:27 INFO - Return code: 0 11:40:27 INFO - Running post-action listener: _resource_record_post_action 11:40:27 INFO - Running post-action listener: _start_resource_monitoring 11:40:27 INFO - Starting resource monitoring. 11:40:27 INFO - [mozharness: 2017-01-29 19:40:27.801842Z] Finished create-virtualenv step (success) 11:40:27 INFO - [mozharness: 2017-01-29 19:40:27.802766Z] Running install step. 11:40:27 INFO - Running pre-action listener: _resource_record_pre_action 11:40:27 INFO - Running main action method: install 11:40:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:28 INFO - Reading from file tmpfile_stdout 11:40:28 INFO - Detecting whether we're running mozinstall >=1.0... 11:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:40:28 INFO - Reading from file tmpfile_stdout 11:40:28 INFO - Output received: 11:40:28 INFO - Usage: mozinstall [options] installer 11:40:28 INFO - Options: 11:40:28 INFO - -h, --help show this help message and exit 11:40:28 INFO - -d DEST, --destination=DEST 11:40:28 INFO - Directory to install application into. [default: 11:40:28 INFO - "/builds/slave/test"] 11:40:28 INFO - --app=APP Application being installed. [default: firefox] 11:40:28 INFO - mkdir: /builds/slave/test/build/application 11:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:40:50 INFO - Reading from file tmpfile_stdout 11:40:50 INFO - Output received: 11:40:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:40:50 INFO - Running post-action listener: _resource_record_post_action 11:40:50 INFO - [mozharness: 2017-01-29 19:40:50.127237Z] Finished install step (success) 11:40:50 INFO - [mozharness: 2017-01-29 19:40:50.127385Z] Running run-tests step. 11:40:50 INFO - Running pre-action listener: _resource_record_pre_action 11:40:50 INFO - Running main action method: run_tests 11:40:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:40:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:40:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:40:50 INFO - 2017-01-29 11:40:50.141 screenresolution[1636:8423] starting screenresolution argv=screenresolution get 11:40:50 INFO - 2017-01-29 11:40:50.144 screenresolution[1636:8423] Display 0: 1600x1200x32@60 11:40:50 INFO - 2017-01-29 11:40:50.150 screenresolution[1637:8426] starting screenresolution argv=screenresolution list 11:40:50 INFO - Available Modes on Display 0 11:40:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:40:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:40:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:40:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:40:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:40:50 INFO - Intel Iris: 11:40:50 INFO - Chipset Model: Intel Iris 11:40:50 INFO - Type: GPU 11:40:50 INFO - Bus: Built-In 11:40:50 INFO - VRAM (Dynamic, Max): 1536 MB 11:40:50 INFO - Vendor: Intel (0x8086) 11:40:50 INFO - Device ID: 0x0a2e 11:40:50 INFO - Revision ID: 0x0009 11:40:50 INFO - Displays: 11:40:50 INFO - Display: 11:40:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:40:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:40:50 INFO - Display Serial Number: 1600x1200 60 11:40:50 INFO - Main Display: Yes 11:40:50 INFO - Mirror: Off 11:40:50 INFO - Online: Yes 11:40:50 INFO - Rotation: Supported 11:40:50 INFO - Return code: 0 11:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:50 INFO - Python 2.7.3 11:40:50 INFO - Return code: 0 11:40:50 INFO - grabbing minidump binary from tooltool 11:40: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')]} 11:40:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f084d30>, '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 0x10efabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f079be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:40:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:40:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:40:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:50 INFO - Return code: 0 11:40:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:40:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:40:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:40:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JuI2CCDCQQ/Render', 11:40:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:50 INFO - 'HOME': '/Users/cltbld', 11:40:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:50 INFO - 'LOGNAME': 'cltbld', 11:40:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:40:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:50 INFO - 'MOZ_NO_REMOTE': '1', 11:40:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:50 INFO - 'NO_EM_RESTART': '1', 11:40:50 INFO - 'PAGER': '/bin/cat', 11:40:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:50 INFO - 'PWD': '/builds/slave/test', 11:40:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:50 INFO - 'SHELL': '/bin/bash', 11:40:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uL189FwOZD/Listeners', 11:40:50 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 11:40:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:50 INFO - 'USER': 'cltbld', 11:40:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:50 INFO - 'XPC_FLAGS': '0x0', 11:40:50 INFO - 'XPC_SERVICE_NAME': '0', 11:40:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:40:50 INFO - mozversion application_buildid: 20170129110043 11:40:50 INFO - mozversion application_changeset: 71224049c0b52ab190564d3ea0eab089a159a4cf 11:40:50 INFO - mozversion application_display_name: Nightly 11:40:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:50 INFO - mozversion application_name: Firefox 11:40:50 INFO - mozversion application_remotingname: firefox 11:40:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:40:50 INFO - mozversion application_vendor: Mozilla 11:40:50 INFO - mozversion application_version: 54.0a1 11:40:50 INFO - mozversion platform_buildid: 20170129110043 11:40:50 INFO - mozversion platform_changeset: 71224049c0b52ab190564d3ea0eab089a159a4cf 11:40:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:40:50 INFO - mozversion platform_version: 54.0a1 11:40:50 INFO - using testdate: 1485718850 11:40:50 INFO - actual date: 1485718850 11:40:50 INFO - starting webserver on 'localhost:49242' 11:40:50 INFO - SUITE-START | Running 2 tests 11:40:50 INFO - TEST-START | dromaeo_css 11:40:50 INFO - operating with platform_type : mac_ 11:40:50 INFO - Initialising browser for dromaeo_css test... 11:40:50 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpbU5QE_/profile http://localhost:49242/getInfo.html) 11:40:52 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 11:40:53 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 11:40:53 INFO - PROCESS | 1647 | colorDepth:24 11:40:53 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 11:40:53 INFO - PROCESS | 1647 | __metrics 11:40:53 INFO - PROCESS | 1647 | 1485718853202 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:40:53 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:40:53 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:40:53 INFO - TEST-INFO | 1647: exit 0 11:40:53 INFO - Browser initialized. 11:40:53 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:53 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpbU5QE_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:41:06 INFO - PROCESS | 1650 | RSS: Main: 236933120 11:41:06 INFO - PROCESS | 1650 | 11:44:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 11:44:11 INFO - PROCESS | 1650 | RSS: Main: 355659776 11:44:11 INFO - PROCESS | 1650 | 11:45:55 INFO - PROCESS | 1650 | ************************* 11:45:55 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 11:45:55 INFO - PROCESS | 1650 | 11:45:55 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:45:55 INFO - PROCESS | 1650 | Full stack: 11:45:55 INFO - PROCESS | 1650 | ************************* 11:47:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 11:47:15 INFO - PROCESS | 1650 | RSS: Main: 369029120 11:47:15 INFO - PROCESS | 1650 | 11:50:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 11:50:18 INFO - PROCESS | 1650 | RSS: Main: 337027072 11:50:18 INFO - PROCESS | 1650 | 11:53:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 11:53:21 INFO - PROCESS | 1650 | RSS: Main: 352882688 11:53:21 INFO - PROCESS | 1650 | 11:56:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 11:56:25 INFO - PROCESS | 1650 | RSS: Main: 326934528 11:56:25 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 11:59:28 INFO - PROCESS | 1650 | RSS: Main: 598556672 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | __start_tp_report 11:59:28 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 11:59:28 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 11:59:28 INFO - PROCESS | 1650 | |i|pagename|runs| 11:59:28 INFO - PROCESS | 1650 | |0;dojo.html;3283.802971821692;3285.244393789428;3291.3417316536693;3299.3071454994433;3343.364760695468;5468.398476167628;5549.805756798512;5589.329280486342;5608.915866262004;5652.434756524351;5518.172274158878;5637.3517045539775;5696.6866822324755;5698;5740.942590574093;5065.189569668853;5070.974645126769;5075.959428317205;5115.616328775338;5162.096633089213;5183.118869792125;5231.764570594313;5232.529072383485;5236;5287.894242115175;4758.976205118969;5070.6957582545165;5185.611079169077;5203.531682148587;5231.686098834063;5099.490050994871;5107.284980102751;5123.180291153435;5141.794328226866;5143.922841157386;5067.037262920034;5092.79628814847;5110.259012443212;5128.1282182029145;5154.969006198738;5587.944120558824;5614.073677843151;5615.578831587606;5635.126555383922;5653.208550802891;6049.576529642927;6076.5442591805795;6086.513078953673;6134.693265336716;6155.599886007395;6215;6233.065040243954;6271.435570798604;6290.842728931813;6301.7164227609865;5765.250517432707;5769.913451298234;5770.307563092456;5773.884522309572;5796.710164491758;6178.382161783786;6230.096635987757;6232.750689972441;6274.121622972787;6346.428821406095;6042.637441753421;6042.758289668364;6064.787732429344;6109.040306147876;6124.330384950791;6076.878462430726;6215.06773983906;6237.563370564017;6238.750449982041;6261.373862613793;5953.583249172603;6026.306974697965;6041.728122234422;6098.847528811815;6108.8778224436155;6050.848728781815;6129.400675281419;6150.169727086786;6157.784477543264;6169.259688837368;4886.682365646222;4913.140200464904;4914.975425122851;4916.680415772963;4921.089598424303;4344.304911214191;4375.190589740908;4380.386745855582;4401.22978478765;4467.262901621261;5704.629199102045;5710.486056254874;5715.771369145187;5717.285339332583;5724.198612194296;6240.31356550779;6252.343503932113;6253.7185826637915;6260.624362538262;6273.0904018917;5545.500904918576;5577.8047768327915;5602.2717046783655;5653.349864765522;5710.885782284331;5377.220303056035;5388.245645609618;5390.299261096033;5417.187421886749;5431.67409955396;6122.602030867979;6215.098810672427;6229.470495007975;6243.063540468966;6261.248650161828;6101.359357267601;6108.190302030417;6116.457510225689;6252.5935814171935;6258.249010118815;6181.072839073995;6203.534734894811;6227.1282020516255;6256.593321434092;6265.592736472115;6133.049377346519;6137.355577664281;6151.815445536641;6152.0156774915795;6154.138420621027;5872.823815285548;5898.528117750656;6177.320494745664;6235.095911092957;6279.937200627935;5863.2670916135485;5928.229330187048;5952.255968003999;6002.639841609517;6056.636601803906;6029.78895738647;6048.516118710405;6054.757809687563;6064.727087280995;6090.086487026982;5743.741531631003;5747.080467125407;5801.274840644919;5801.36185019639;5816.796412125555;4747.738874361943;5677.744501497526;6023.337432882292;6037.124616930432;6040.637561746309;5750.741216645178;5796.478316951495;5821.970890145692;5826.271716035496;5861.32594751609 11:59:28 INFO - PROCESS | 1650 | |1;ext.html;450.2840483631027;492.22228878372175;495.06432841928785;499.69019208090975;520.8541608349661;26368.86815565922;39361.60638393615;41720.58279417205;41869.79065104678;42041.369379459255;43013.7849310753;43426.131477370436;43427.34858977118;43558;43630;8381.161883811601;9354.859677104849;9558.474283914382;9563.378380405251;9567.42595444272;8456.365772567051;11318.32090074594;11620.883791162107;11631.883681163206;11643;11288.096952243843;11319.886801132006;11323.150763692714;11364.545418183263;11365.545378184905;8454.070052294242;8713.302935765123;11314.208005439581;11402.087832973342;11421.371824549606;10971.23201375904;11281.830772538418;11328.207025508218;11505.022073123795;11529.365884876328;41218.587814121776;41687;41803.1639367214;41825.58174418248;41845.1630967382;4015.357542793139;4027.6684175379587;4049.53430355507;4049.53430355507;4192.517860446057;31126.68873311283;31263.905763298404;31309.686903130903;31316.53025204624;31348.37303253945;2907.7237662422035;2939.720726530976;3056.488321767019;3111.859966301522;3138.8901388451523;468066;468460;468953;469171.99999999657;469394;61898.69050654718;62263.37736622666;62291.688541557;62363.37636623666;62494.0625890612;64666.67666661637;64895.67552162209;64912.67543662346;64958.02562961559;65031.34968650253;15624.140672263135;15790.131542765259;15819.52541423759;15820.446284379992;15824.525264242091;11105.222634415675;11392.20254582188;12140.392980350947;12316.445759940823;12441.066919981038;17056.2324695389;17207.483775486755;17234.224459899335;17249.22378492971;17258.395956141474;6525.836854078686;6632;7590.924090759022;7618.542887426772;7721.7683469496005;836.5110104014864;841.5119230846094;857.237059017475;859.2696208223085;879.6965047058754;25561.744382555935;29323.706762932525;29988.85005575002;30731.539026914616;30833.691663083082;1023.764534157148;1074.897884700952;1076.4402510694395;1077.5851297250458;1099.1701265544464;15173.696526069416;15699.450519231772;15793.526194214191;15874.285657145456;16183.919080404523;217383;217855;218085.90957045113;218158;218160;61750.38249617536;61815;61955.380446195864;61983.690081549306;62188.68905655443;1398.9510367137102;1413.7737961926043;1431.6564024634013;1436.8132142821366;1438.3527412664344;27914.86042569774;29488.852555737085;29574.260643484085;30432.23919401944;30483.695163048087;51547.742261288455;51874.48125518696;51925.7403712979;52006.21990670219;52022;528066;531246;534357;535012;535312;40103;40808.591914080476;40832;40922.59077409307;41147.7942610285;46552.53447465482;46915.76542117268;47518;47537;47809;38650.6134938647;38786.80606596949;39209.8039509812;40027.599724002386;40568.797156015215;26209.606855897557;37934.24131517409;38325.61674383332;38398.040048999;39515.604843951194;37225.81387093047;37680.43479347757;37930.43104353382;38159.618403815606;38171.61828381681;32176.678233217368;32799.50800737943;34091;34110.48834267438;34346.82826585951;21737.565248695253;24052.037918483064;24821.774467320847;24992.625110623718;25109 11:59:28 INFO - PROCESS | 1650 | |2;jquery.html;372.46923134533284;380.8457574682254;389.204077661183;390.28772490205375;425.8828822073929;12243.449044792984;15548.84451155488;17695.64608707825;20824.791752082474;21136.626119302233;20444.386668399933;21035.68446473304;21831.01760420781;22049.669254961187;22225.777742222574;5337.1994200869985;5461.754221060062;5497.587680923927;5643.181738647893;5649.689267090308;5434.266374039514;5535.778568857241;5537.058700020986;5539.612227144082;5544.77820887164;4016.377461493473;4209.936850947238;5902.3507414184405;6151.692415379235;6200.379962003786;6124.663143527104;6237.532185086114;6244.000959846426;6247.9062814057825;6846.4522838172825;6107.7556897724035;6164.907526387108;6166.784162554289;6167.537434692416;6169.537284703665;13412.932935335359;19776.604467910704;25130.37174070663;25607.359816004562;25870.223893283233;2599.9610005849927;2609.712931577525;2618.4763047390597;2651.907183248577;2667.8132530722855;10098.242631802645;10124.240681948882;10176.592936282539;10648.14814814813;10657.147428205802;1785.5089850291145;1829.5060333709957;1835.0824587706147;1843.9446816595523;1853.1753369750465;91676.08323916742;112512;125367.74632253652;132182;134692.979605306;9905.900940990497;11353.716157095974;11459.427028648533;11534.711632209262;11916.285022898654;15541.300641471162;15567.61080972962;15581.844181558266;15589;15625;8431.241188292961;8648.1784230498;8677.093173786852;8830.42602230853;8833.69082082124;8370.958145209357;8371.455855369382;8392.076871544125;8548.059713431518;8562.100979397073;4719.551642593948;4780.330753694486;4802.327674125625;4837.540433658797;4911.582515486152;1731.4113201511295;1947.7565304336958;1965.5675751334684;2008.2327551754363;2013.808688174621;786.9769299910093;810.4124509730516;851.9914800851913;871.8997315308694;883.4301875290402;18210.817891821178;21057.684134737992;21824.563508729738;21879;22017.449563761034;841.2050612171562;867.8154319354036;872.895252569683;883.5184824270788;890.225503811688;10490;11131.332120072822;11133;11143.832842507369;11356.829647555292;518565;591881;736040;740495.2975235089;753972;105912;127359.72640273663;128995.35502322616;153592;164097;841.2722994609619;981.7790997025755;1014.4876837197169;1036;1036.4921188617523;19861.801381986574;20249.69625455649;20363.694544581547;20373.49066273296;20381.79618203799;61233.46916327127;61338.69330653318;62347.376526234155;62468.68765656143;62577.06134408079;91161;91204;91960;92654;94041.58937615804;8112.716054938049;8149.877751833608;8246.876296855671;8648.481091134552;8718.476891386537;12806.935965320114;13229.206247625174;13238.735225295633;13298.335083245605;13591.592252232449;11267.661970140909;11536.134789890624;11559.595414160463;11686.357250351311;11692.94153529227;11040.723981900517;11049.844406835206;11228.157888158257;11392.25950313227;11509.194356394972;11297.491612877277;11432.428378580871;11504.884951150381;11698.005669518187;11750.353730544903;7152.213256541881;7445.9255407445235;7510.286522780327;8308;8419.368547359096;8254.463459875089;10293.53679084428;11151.163662725166;11599.478023488795;11750.941245294061 11:59:28 INFO - PROCESS | 1650 | |3;mootools.html;1432.0262221689254;1438.654722866512;1450.5358285348693;1451.6733734909644;1457.3951809998844;7932.801679958004;8389.958050209756;8399.908011958452;8403.915960840397;8408.327333813295;7323.670434830432;7534.321911028006;7538.660760265788;7939.285464308211;8180.018397792258;2225.354647152324;2285.131649973008;2289.027163455533;2290.3815969688176;2290.610596198642;2207.7792220777956;2306.4003359126664;2312.6531020346997;2316.6177580699164;2324.744278129413;1986.433866348091;2062.6279778254975;2064.0814837397365;2189.770074142216;2273.761255068219;2261.0729600863565;2274.431392151962;2277.4420267034516;2278.464560828204;2282.6690129931235;2212.3849589799374;2264.62633665445;2283.7373702024315;2285.6914316567218;2286.8056215221727;8186.54973976431;8290.129536398712;8295.958520207421;8308.667653293862;8312.709055183039;4990.600751939836;5157.8452646420665;5160.432352441228;5160.845174644766;5164.45773193813;2937.089502254308;3091.984540077285;3391.8812841550675;3442.707369873564;3449.4308439107517;1122.1135303110614;1131.4682099413344;1149.0520320735427;1166.0147175636573;1213.1811027556364;10549.261551691385;10599.993000665001;10624.628138015132;10646.361218326925;10649.361038337725;4471.329300604935;4649.697769644962;4658.86023419298;4661.463931647904;4699.718016918996;8552.61513231906;8571.357148213909;8591.398602097917;8600.39797214189;8605.440646357967;5056.26684130799;5060.114479965991;5062.7468626568525;5064.468230835783;5068.493150684902;4510.120526497367;4665.230237010923;4750.691205071659;4753.120672675566;4755.262934245197;3526.0479670488826;3596.6762991330907;3637.4725664778457;3670.46778217157;3680.35593771089;1155.6706338693432;1159.9942000289946;1170.3504554972071;1170.3738499902681;1170.4323403149465;776.2780614028894;841.474078700812;870.9564521773885;904.1500989070253;931.9161275485238;5051.873703157377;5294.2852714883;5380.246765452762;5489.58828087895;5598.972005139948;456.8720758187713;515.1628603519281;567.6594043573823;571.6427232979388;573.6156774960787;3148.6536480987074;3419.196488825124;3636.2000359920776;3724.385476396364;3734.8879533614036;9529.904700952902;9909.554070066864;9911.702648920544;9911.80176396468;9914.45470499115;9793.804123917482;9855.655052073143;9886.406815591088;9889.258305627107;9891.703248902544;7195.784126476214;7520.849583008309;7645.4648174628355;7730.265624765639;7843.333316668147;4438.800253988514;5397.59518036141;5432.402435731988;5457.645253058538;5477.863053423696;8672.349573782118;8870.068642792672;9077.909220907706;9114.635414583343;9176.44941303524;9202.953985230031;9222.631094756134;9266.11971862672;9309.627614895327;9325.720228393158;6739.831504212238;6995.930040699528;7023.68393422287;7085.220625731268;7141.285871412817;7850.528968261922;7899.526028438312;7911.5253084815095;7913.089994650459;7929.603519823871;5955.910661339996;6978;7013.579185248901;7041.4014808739785;7124.857502850017;6879.862402752017;6904.344087311697;6908.274631163656;6945.131858517685;6957.130358705162;6167.969160154171;6210.130581718653;6214.720337584729;6236.282827474898;6316.905246421215;4691.929621055755;4695.3191787191345;4792.9281060784815;4798.760061996816;4807.975960120177;4734.71591704485;4828.179209534317;4832.251001094836;4838.491958344464;4889.364382630235 11:59:28 INFO - PROCESS | 1650 | |4;prototype.html;324.24774523106373;350.61082198759374;351.5728390006142;359.81289729340733;369.6857670979666;4212.978935105324;4412.889677758054;4554.544545545452;5044.495550444949;5286.471352864712;4409.536998615147;4654.767261636913;5022.92007218448;5181.80273978078;5235.3299817093275;3825.699262250834;4097.44684467596;4097.467329247193;4106.029106029107;4144.48193975753;3406.863725450979;3475.7487304570336;3529.976306871017;3920.7451515651533;4121.608447197512;3390.610079840825;3979.86070487533;4001.8799436016966;4079.3269110596716;4123.855665051724;3304.958937934555;3739.5886452490204;3743.839409782976;4053.43251944728;4086.6117718816663;3306.6032076151014;3595.209054008114;3627.3470775260444;3641.799701016443;3661.340958627446;4821.397325334333;4874.488908438396;4987.351644286256;5051.974740126313;5057.2161314995865;4344.45694288214;4702.177119004161;4767.356406885061;4777.498362671904;4809.8635173750545;4519.728816271035;5345.3585569731495;5573.247611572427;5575.918583399101;5590.596043701207;3668.596454390015;4010;4040.9595904040875;4054.6958978076764;4065.410515475269;5211;5415.377231618375;5587.608867379285;5646.378898321182;5768.682722450264;3808.676262517717;3907.1794923065904;4034.7377420467574;4103.302438585448;4111.568285330057;3590.0486371111674;3851.730378873452;3926.7251292409255;3928.8035598219976;4161.56303588125;3937.6062393760394;3982.940255896106;4411.029573493826;4423.363355558399;4506.662000349987;5094.872628184325;5258.843054527998;5548.77804887808;5606.962711898312;5633.126865335879;1202.927824330543;1229.3668760588305;1241.5530409052733;1264.4815625593462;1266.3098611256887;741.5402450480628;777.9883301750478;861.586438509519;864.7059999600166;875.0767939823547;4918.5573298403315;4918.680285781413;4934.6545741798445;4970.304157417964;4976.477469865685;4503.68474206809;4667.346571479996;5143.919776846829;5205.817796377109;5380.461953804588;4096.897577560585;4511.924514723199;4880.633952453581;4909.631777616693;4969.279454479152;2430.347363792638;2573.2794817451145;2768.529350010503;2791.371941313229;2809.524999375344;5380;5416.179600155864;5485.396606373296;5511.58663100269;5525.668459892419;4858.659893807399;4958.677685950402;5057.016360347339;5452.454754524595;5565.2486914266865;4826.565609095198;5039.193729003342;5556.305461817192;5596.58025648078;5636.549076073904;4241.818636022983;4625.421822272216;5031.823886163967;5206.349206349207;5328.176066684663;4984.675996060245;5320.733963301896;5325.7337133143965;5443.237946687546;5444.945550544602;4564.226253788894;4865.878353041202;4913.803447862046;5119.846404607868;5480.780768769205;2968.8960886368873;3303.3888730584918;3636.5999740028046;3656.488091667222;3682.8710995115043;4736;5021.297018417352;5188.533032027136;5276.815311464234;5469.945300546943;4944.48082951285;5124.7950081996305;5375.623706340519;5390.466480754039;5584.748686309044;4710.929336059893;4923.950760492493;5581.748821303132;5584.3857175711455;5598.832035038955;5074.340335756328;5166.48257808346;5277.788888444573;5402.324709411324;5455.781768729206;4404.515503294571;4652.418177819558;5540.556755459473;5548.445155484419;5591.552675786009;4410.536893626123;5294.23233631129;5455.8363249102595;5465.169168328654;5505.779768809202 11:59:28 INFO - PROCESS | 1650 | |5;yui.html;701.7824474412929;725.3435640745121;760.0347558600578;816.2694388522273;823.2220551578758;11912.46393912274;15730.606734831636;15764.211789410512;15765.921170394146;15797.526074217763;14355.569332920002;14494.202818844959;14517.564473065799;14548.781768273484;14562.927185364058;676.2763842688325;698.4272896225098;702.3152426384286;840.7225615546855;853.1766844994579;860.9225169734723;864.0754392799707;867.1675191815864;875.7810547363159;878.5782824244367;934.9298802589799;936.1247233836351;937.2736129499624;938.1837801113041;939.4269495607674;717.4834119433999;932.9253659707241;936.9927328122228;937.3157594955652;939.173527295982;547.7179252684871;666.516775337881;685.5543896467286;688.5662032919229;696.4010950582496;14941.70116597662;15010.399584016626;15020.924895375454;15022.248887555688;15025.624359390993;13529.797053044213;13558;13599.252041137734;13607.523736669169;13609.591712248648;12378.319192444413;12416.56542021034;12430.937845310715;12453.564125255663;12791.04067194964;4390.1219756048595;4404.207242696313;4450.710703804242;4483.193025255453;4558.931616025729;97797.51101244519;100507;101174;105195.47402263017;105938;10165.237607179492;10502.212334074975;10800.94599527013;10875.564977400974;11446.255993360404;21472;21671.241506547194;21872.671909921362;21943.451413714785;21974.01116949741;7471.3649339806725;7503.512271702322;7545.132309784335;7616.0099187105325;7766.339861111755;3828.73198876076;4219.240536703362;4259.403683484315;4260.552641972548;4261.808218630169;3384.1708781348725;3485.128717820545;3490.0925759302763;3494.4583589543663;3501.8599256029984;625.956183067181;629.8866204083309;637.7959053103056;644.716324817079;646.5021933111572;634.6192284629278;637.7130291368902;640.3148630964823;647.9676016199171;649.9715142057455;8701.347398945105;9486;9491.810163796823;9512.90487095134;9579.568919398644;611.651358725531;613.9508839292846;633.2021652717609;640.7853369121303;669.0900375489331;4416.968194630506;4552.1806074906835;4579.931301030487;4587.151376995266;4591.058832939239;84667.57666211629;86268.56865715756;87047;87504.56247718721;107673;83744.58127709445;84343;84459;86026;86326.13673863182;67578.32421675819;68012.6599367;69730.65134674395;69886.30113698798;76055;9140.497272650067;10935.726606834893;11043.066767407592;11106.222564420414;11226.943865280948;41364;43750.78124609357;47457.762711187606;47696.7615161922;51156;67403.3259667397;68154;69227.30772692208;70299.29700702928;71474;12584.18202816814;12762.17045892014;12928.353582320658;13044.347782611021;13130.671733206746;10060.69817905464;11515.30908145514;12367.319797411228;14556.854431455973;14650.459259702335;13305.933470332586;13695.657608559866;13899.722005560034;14232.64418389507;14279.143251404948;13258.270795106362;13575.456981720621;13827.101238419471;13912.165270083828;14301.856981430468;11425.37160456183;13346.933265333611;13572.979229695613;13653;13754.381052852446;10849.240553161202;11173.162012849232;11176.10591152722;11251.324920504796;11530;9547.618095276111;10419.94790026045;10759.946200269262;10871;10886.782264354826 11:59:28 INFO - PROCESS | 1650 | __end_tp_report 11:59:28 INFO - PROCESS | 1650 | __start_cc_report 11:59:28 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1199 11:59:28 INFO - PROCESS | 1650 | __end_cc_report 11:59:28 INFO - PROCESS | 1650 | __startTimestamp1485719968793__endTimestamp 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | ------- Summary: start ------- 11:59:28 INFO - PROCESS | 1650 | Number of tests: 6 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5670.24 Median:5801.36 stddev:636.75 (11.0%) stddev-sans-first:610.56 11:59:28 INFO - PROCESS | 1650 | Values: 3283.8 3285.2 3291.3 3299.3 3343.4 5468.4 5549.8 5589.3 5608.9 5652.4 5518.2 5637.4 5696.7 5698.0 5740.9 5065.2 5071.0 5076.0 5115.6 5162.1 5183.1 5231.8 5232.5 5236.0 5287.9 4759.0 5070.7 5185.6 5203.5 5231.7 5099.5 5107.3 5123.2 5141.8 5143.9 5067.0 5092.8 5110.3 5128.1 5155.0 5587.9 5614.1 5615.6 5635.1 5653.2 6049.6 6076.5 6086.5 6134.7 6155.6 6215.0 6233.1 6271.4 6290.8 6301.7 5765.3 5769.9 5770.3 5773.9 5796.7 6178.4 6230.1 6232.8 6274.1 6346.4 6042.6 6042.8 6064.8 6109.0 6124.3 6076.9 6215.1 6237.6 6238.8 6261.4 5953.6 6026.3 6041.7 6098.8 6108.9 6050.8 6129.4 6150.2 6157.8 6169.3 4886.7 4913.1 4915.0 4916.7 4921.1 4344.3 4375.2 4380.4 4401.2 4467.3 5704.6 5710.5 5715.8 5717.3 5724.2 6240.3 6252.3 6253.7 6260.6 6273.1 5545.5 5577.8 5602.3 5653.3 5710.9 5377.2 5388.2 5390.3 5417.2 5431.7 6122.6 6215.1 6229.5 6243.1 6261.2 6101.4 6108.2 6116.5 6252.6 6258.2 6181.1 6203.5 6227.1 6256.6 6265.6 6133.0 6137.4 6151.8 6152.0 6154.1 5872.8 5898.5 6177.3 6235.1 6279.9 5863.3 5928.2 5952.3 6002.6 6056.6 6029.8 6048.5 6054.8 6064.7 6090.1 5743.7 5747.1 5801.3 5801.4 5816.8 4747.7 5677.7 6023.3 6037.1 6040.6 5750.7 5796.5 5822.0 5826.3 5861.3 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:57359.24 Median:38361.83 stddev:114173.40 (297.6%) stddev-sans-first:114413.74 11:59:28 INFO - PROCESS | 1650 | Values: 450.3 492.2 495.1 499.7 520.9 26368.9 39361.6 41720.6 41869.8 42041.4 43013.8 43426.1 43427.3 43558.0 43630.0 8381.2 9354.9 9558.5 9563.4 9567.4 8456.4 11318.3 11620.9 11631.9 11643.0 11288.1 11319.9 11323.2 11364.5 11365.5 8454.1 8713.3 11314.2 11402.1 11421.4 10971.2 11281.8 11328.2 11505.0 11529.4 41218.6 41687.0 41803.2 41825.6 41845.2 4015.4 4027.7 4049.5 4049.5 4192.5 31126.7 31263.9 31309.7 31316.5 31348.4 2907.7 2939.7 3056.5 3111.9 3138.9 468066.0 468460.0 468953.0 469172.0 469394.0 61898.7 62263.4 62291.7 62363.4 62494.1 64666.7 64895.7 64912.7 64958.0 65031.3 15624.1 15790.1 15819.5 15820.4 15824.5 11105.2 11392.2 12140.4 12316.4 12441.1 17056.2 17207.5 17234.2 17249.2 17258.4 6525.8 6632.0 7590.9 7618.5 7721.8 836.5 841.5 857.2 859.3 879.7 25561.7 29323.7 29988.9 30731.5 30833.7 1023.8 1074.9 1076.4 1077.6 1099.2 15173.7 15699.5 15793.5 15874.3 16183.9 217383.0 217855.0 218085.9 218158.0 218160.0 61750.4 61815.0 61955.4 61983.7 62188.7 1399.0 1413.8 1431.7 1436.8 1438.4 27914.9 29488.9 29574.3 30432.2 30483.7 51547.7 51874.5 51925.7 52006.2 52022.0 528066.0 531246.0 534357.0 535012.0 535312.0 40103.0 40808.6 40832.0 40922.6 41147.8 46552.5 46915.8 47518.0 47537.0 47809.0 38650.6 38786.8 39209.8 40027.6 40568.8 26209.6 37934.2 38325.6 38398.0 39515.6 37225.8 37680.4 37930.4 38159.6 38171.6 32176.7 32799.5 34091.0 34110.5 34346.8 21737.6 24052.0 24821.8 24992.6 25109.0 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37851.29 Median:2604.84 stddev:112566.65 (4321.4%) stddev-sans-first:112847.25 11:59:28 INFO - PROCESS | 1650 | Values: 372.5 380.8 389.2 390.3 425.9 12243.4 15548.8 17695.6 20824.8 21136.6 20444.4 21035.7 21831.0 22049.7 22225.8 5337.2 5461.8 5497.6 5643.2 5649.7 5434.3 5535.8 5537.1 5539.6 5544.8 4016.4 4209.9 5902.4 6151.7 6200.4 6124.7 6237.5 6244.0 6247.9 6846.5 6107.8 6164.9 6166.8 6167.5 6169.5 13412.9 19776.6 25130.4 25607.4 25870.2 2600.0 2609.7 2618.5 2651.9 2667.8 10098.2 10124.2 10176.6 10648.1 10657.1 1785.5 1829.5 1835.1 1843.9 1853.2 91676.1 112512.0 125367.7 132182.0 134693.0 9905.9 11353.7 11459.4 11534.7 11916.3 15541.3 15567.6 15581.8 15589.0 15625.0 8431.2 8648.2 8677.1 8830.4 8833.7 8371.0 8371.5 8392.1 8548.1 8562.1 4719.6 4780.3 4802.3 4837.5 4911.6 1731.4 1947.8 1965.6 2008.2 2013.8 787.0 810.4 852.0 871.9 883.4 18210.8 21057.7 21824.6 21879.0 22017.4 841.2 867.8 872.9 883.5 890.2 10490.0 11131.3 11133.0 11143.8 11356.8 518565.0 591881.0 736040.0 740495.3 753972.0 105912.0 127359.7 128995.4 153592.0 164097.0 841.3 981.8 1014.5 1036.0 1036.5 19861.8 20249.7 20363.7 20373.5 20381.8 61233.5 61338.7 62347.4 62468.7 62577.1 91161.0 91204.0 91960.0 92654.0 94041.6 8112.7 8149.9 8246.9 8648.5 8718.5 12806.9 13229.2 13238.7 13298.3 13591.6 11267.7 11536.1 11559.6 11686.4 11692.9 11040.7 11049.8 11228.2 11392.3 11509.2 11297.5 11432.4 11504.9 11698.0 11750.4 7152.2 7445.9 7510.3 8308.0 8419.4 8254.5 10293.5 11151.2 11599.5 11750.9 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5288.71 Median:5159.14 stddev:2916.66 (56.5%) stddev-sans-first:2910.44 11:59:28 INFO - PROCESS | 1650 | Values: 1432.0 1438.7 1450.5 1451.7 1457.4 7932.8 8390.0 8399.9 8403.9 8408.3 7323.7 7534.3 7538.7 7939.3 8180.0 2225.4 2285.1 2289.0 2290.4 2290.6 2207.8 2306.4 2312.7 2316.6 2324.7 1986.4 2062.6 2064.1 2189.8 2273.8 2261.1 2274.4 2277.4 2278.5 2282.7 2212.4 2264.6 2283.7 2285.7 2286.8 8186.5 8290.1 8296.0 8308.7 8312.7 4990.6 5157.8 5160.4 5160.8 5164.5 2937.1 3092.0 3391.9 3442.7 3449.4 1122.1 1131.5 1149.1 1166.0 1213.2 10549.3 10600.0 10624.6 10646.4 10649.4 4471.3 4649.7 4658.9 4661.5 4699.7 8552.6 8571.4 8591.4 8600.4 8605.4 5056.3 5060.1 5062.7 5064.5 5068.5 4510.1 4665.2 4750.7 4753.1 4755.3 3526.0 3596.7 3637.5 3670.5 3680.4 1155.7 1160.0 1170.4 1170.4 1170.4 776.3 841.5 871.0 904.2 931.9 5051.9 5294.3 5380.2 5489.6 5599.0 456.9 515.2 567.7 571.6 573.6 3148.7 3419.2 3636.2 3724.4 3734.9 9529.9 9909.6 9911.7 9911.8 9914.5 9793.8 9855.7 9886.4 9889.3 9891.7 7195.8 7520.8 7645.5 7730.3 7843.3 4438.8 5397.6 5432.4 5457.6 5477.9 8672.3 8870.1 9077.9 9114.6 9176.4 9203.0 9222.6 9266.1 9309.6 9325.7 6739.8 6995.9 7023.7 7085.2 7141.3 7850.5 7899.5 7911.5 7913.1 7929.6 5955.9 6978.0 7013.6 7041.4 7124.9 6879.9 6904.3 6908.3 6945.1 6957.1 6168.0 6210.1 6214.7 6236.3 6316.9 4691.9 4695.3 4792.9 4798.8 4808.0 4734.7 4828.2 4832.3 4838.5 4889.4 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4341.04 Median:4862.27 stddev:1301.64 (26.8%) stddev-sans-first:1269.90 11:59:28 INFO - PROCESS | 1650 | Values: 324.2 350.6 351.6 359.8 369.7 4213.0 4412.9 4554.5 5044.5 5286.5 4409.5 4654.8 5022.9 5181.8 5235.3 3825.7 4097.4 4097.5 4106.0 4144.5 3406.9 3475.7 3530.0 3920.7 4121.6 3390.6 3979.9 4001.9 4079.3 4123.9 3305.0 3739.6 3743.8 4053.4 4086.6 3306.6 3595.2 3627.3 3641.8 3661.3 4821.4 4874.5 4987.4 5052.0 5057.2 4344.5 4702.2 4767.4 4777.5 4809.9 4519.7 5345.4 5573.2 5575.9 5590.6 3668.6 4010.0 4041.0 4054.7 4065.4 5211.0 5415.4 5587.6 5646.4 5768.7 3808.7 3907.2 4034.7 4103.3 4111.6 3590.0 3851.7 3926.7 3928.8 4161.6 3937.6 3982.9 4411.0 4423.4 4506.7 5094.9 5258.8 5548.8 5607.0 5633.1 1202.9 1229.4 1241.6 1264.5 1266.3 741.5 778.0 861.6 864.7 875.1 4918.6 4918.7 4934.7 4970.3 4976.5 4503.7 4667.3 5143.9 5205.8 5380.5 4096.9 4511.9 4880.6 4909.6 4969.3 2430.3 2573.3 2768.5 2791.4 2809.5 5380.0 5416.2 5485.4 5511.6 5525.7 4858.7 4958.7 5057.0 5452.5 5565.2 4826.6 5039.2 5556.3 5596.6 5636.5 4241.8 4625.4 5031.8 5206.3 5328.2 4984.7 5320.7 5325.7 5443.2 5444.9 4564.2 4865.9 4913.8 5119.8 5480.8 2968.9 3303.4 3636.6 3656.5 3682.9 4736.0 5021.3 5188.5 5276.8 5469.9 4944.5 5124.8 5375.6 5390.5 5584.7 4710.9 4924.0 5581.7 5584.4 5598.8 5074.3 5166.5 5277.8 5402.3 5455.8 4404.5 4652.4 5540.6 5548.4 5591.6 4410.5 5294.2 5455.8 5465.2 5505.8 11:59:28 INFO - PROCESS | 1650 | 11:59:28 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19604.79 Median:4397.16 stddev:27496.43 (625.3%) stddev-sans-first:27536.93 11:59:28 INFO - PROCESS | 1650 | Values: 701.8 725.3 760.0 816.3 823.2 11912.5 15730.6 15764.2 15765.9 15797.5 14355.6 14494.2 14517.6 14548.8 14562.9 676.3 698.4 702.3 840.7 853.2 860.9 864.1 867.2 875.8 878.6 934.9 936.1 937.3 938.2 939.4 717.5 932.9 937.0 937.3 939.2 547.7 666.5 685.6 688.6 696.4 14941.7 15010.4 15020.9 15022.2 15025.6 13529.8 13558.0 13599.3 13607.5 13609.6 12378.3 12416.6 12430.9 12453.6 12791.0 4390.1 4404.2 4450.7 4483.2 4558.9 97797.5 100507.0 101174.0 105195.5 105938.0 10165.2 10502.2 10800.9 10875.6 11446.3 21472.0 21671.2 21872.7 21943.5 21974.0 7471.4 7503.5 7545.1 7616.0 7766.3 3828.7 4219.2 4259.4 4260.6 4261.8 3384.2 3485.1 3490.1 3494.5 3501.9 626.0 629.9 637.8 644.7 646.5 634.6 637.7 640.3 648.0 650.0 8701.3 9486.0 9491.8 9512.9 9579.6 611.7 614.0 633.2 640.8 669.1 4417.0 4552.2 4579.9 4587.2 4591.1 84667.6 86268.6 87047.0 87504.6 107673.0 83744.6 84343.0 84459.0 86026.0 86326.1 67578.3 68012.7 69730.7 69886.3 76055.0 9140.5 10935.7 11043.1 11106.2 11226.9 41364.0 43750.8 47457.8 47696.8 51156.0 67403.3 68154.0 69227.3 70299.3 71474.0 12584.2 12762.2 12928.4 13044.3 13130.7 10060.7 11515.3 12367.3 14556.9 14650.5 13305.9 13695.7 13899.7 14232.6 14279.1 13258.3 13575.5 13827.1 13912.2 14301.9 11425.4 13346.9 13573.0 13653.0 13754.4 10849.2 11173.2 11176.1 11251.3 11530.0 9547.6 10419.9 10759.9 10871.0 10886.8 11:59:28 INFO - PROCESS | 1650 | -------- Summary: end -------- 11:59:28 INFO - PROCESS | 1650 | 11:59:29 INFO - TEST-INFO | 1650: exit 0 11:59:29 INFO - TEST-OK | dromaeo_css | took 1118854ms 11:59:29 INFO - TEST-START | kraken 11:59:29 INFO - operating with platform_type : mac_ 11:59:29 INFO - Initialising browser for kraken test... 11:59:29 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpLi5iZB/profile http://localhost:49242/getInfo.html) 11:59:31 INFO - PROCESS | 1658 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 11:59:31 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 11:59:31 INFO - PROCESS | 1658 | colorDepth:24 11:59:31 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 11:59:31 INFO - PROCESS | 1658 | __metrics 11:59:31 INFO - PROCESS | 1658 | 1485719971851 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:59:31 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:59:31 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:59:32 INFO - TEST-INFO | 1658: exit 0 11:59:32 INFO - Browser initialized. 11:59:32 INFO - Running cycle 1/1 for kraken test... 11:59:32 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpLi5iZB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:44 INFO - PROCESS | 1659 | RSS: Main: 236769280 11:59:44 INFO - PROCESS | 1659 | 11:59:47 INFO - PROCESS | 1659 | 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) 11:59:48 INFO - PROCESS | 1659 | RSS: Main: 289050624 11:59:48 INFO - PROCESS | 1659 | 11:59:50 INFO - PROCESS | 1659 | 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) 11:59:51 INFO - PROCESS | 1659 | RSS: Main: 290000896 11:59:51 INFO - PROCESS | 1659 | 11:59:54 INFO - PROCESS | 1659 | 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) 11:59:55 INFO - PROCESS | 1659 | RSS: Main: 378425344 11:59:55 INFO - PROCESS | 1659 | 11:59:57 INFO - PROCESS | 1659 | 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) 11:59:58 INFO - PROCESS | 1659 | RSS: Main: 282488832 11:59:58 INFO - PROCESS | 1659 | 12:00:00 INFO - PROCESS | 1659 | 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) 12:00:00 INFO - PROCESS | 1659 | RSS: Main: 286306304 12:00:00 INFO - PROCESS | 1659 | 12:00:03 INFO - PROCESS | 1659 | 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) 12:00:04 INFO - PROCESS | 1659 | RSS: Main: 328224768 12:00:04 INFO - PROCESS | 1659 | 12:00:07 INFO - PROCESS | 1659 | 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) 12:00:07 INFO - PROCESS | 1659 | RSS: Main: 330473472 12:00:07 INFO - PROCESS | 1659 | 12:00:10 INFO - PROCESS | 1659 | 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) 12:00:11 INFO - PROCESS | 1659 | RSS: Main: 327876608 12:00:11 INFO - PROCESS | 1659 | 12:00:12 INFO - PROCESS | 1659 | 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) 12:00:13 INFO - PROCESS | 1659 | RSS: Main: 304390144 12:00:13 INFO - PROCESS | 1659 | 12:00:15 INFO - PROCESS | 1659 | 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) 12:00:16 INFO - PROCESS | 1659 | RSS: Main: 308531200 12:00:16 INFO - PROCESS | 1659 | 12:00:18 INFO - PROCESS | 1659 | 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) 12:00:18 INFO - PROCESS | 1659 | RSS: Main: 309878784 12:00:18 INFO - PROCESS | 1659 | 12:00:21 INFO - PROCESS | 1659 | 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) 12:00:22 INFO - PROCESS | 1659 | RSS: Main: 327467008 12:00:22 INFO - PROCESS | 1659 | 12:00:25 INFO - PROCESS | 1659 | 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) 12:00:26 INFO - PROCESS | 1659 | RSS: Main: 321953792 12:00:26 INFO - PROCESS | 1659 | 12:00:27 INFO - PROCESS | 1659 | 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) 12:00:28 INFO - PROCESS | 1659 | RSS: Main: 366882816 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | __start_tp_report 12:00:28 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 12:00:28 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 12:00:28 INFO - PROCESS | 1659 | |i|pagename|runs| 12:00:28 INFO - PROCESS | 1659 | |0;ai-astar;112;112;112;113;112;112;111;112;112;112 12:00:28 INFO - PROCESS | 1659 | |1;audio-beat-detection;141;112;109;145;110;114;135;130;131;108 12:00:28 INFO - PROCESS | 1659 | |2;audio-dft;184;182;184;185;184;185;182;185;183;185 12:00:28 INFO - PROCESS | 1659 | |3;audio-fft;79;59;59;88;59;59;58;71;74;58 12:00:28 INFO - PROCESS | 1659 | |4;audio-oscillator;87;69;85;70;68;69;84;69;70;84 12:00:28 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;88;85;88;86;88;84;86;84;87;85 12:00:28 INFO - PROCESS | 1659 | |6;imaging-darkroom;96;89;130;149;93;121;103;133;148;148 12:00:28 INFO - PROCESS | 1659 | |7;imaging-desaturate;87;87;88;85;86;87;88;86;86;80 12:00:28 INFO - PROCESS | 1659 | |8;json-parse-financial;56;54;54;54;55;54;56;54;54;54 12:00:28 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;69;77;69;79;74;71;70;72;73;72 12:00:28 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;98;108;90;90;85;84;82;86;87;88 12:00:28 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;143;143;146;150;139;140;139;130;204;169 12:00:28 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;175;230;213;180;177;169;175;175;171;176 12:00:28 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;66;66;65;68;67;67;66;65;67 12:00:28 INFO - PROCESS | 1659 | __end_tp_report 12:00:28 INFO - PROCESS | 1659 | __start_cc_report 12:00:28 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1947 12:00:28 INFO - PROCESS | 1659 | __end_cc_report 12:00:28 INFO - PROCESS | 1659 | __startTimestamp1485720028219__endTimestamp 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | ------- Summary: start ------- 12:00:28 INFO - PROCESS | 1659 | Number of tests: 14 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 12:00:28 INFO - PROCESS | 1659 | Values: 112.0 112.0 112.0 113.0 112.0 112.0 111.0 112.0 112.0 112.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:130.50 stddev:14.35 (11.0%) stddev-sans-first:13.76 12:00:28 INFO - PROCESS | 1659 | Values: 141.0 112.0 109.0 145.0 110.0 114.0 135.0 130.0 131.0 108.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.20 (0.6%) stddev-sans-first:1.27 12:00:28 INFO - PROCESS | 1659 | Values: 184.0 182.0 184.0 185.0 184.0 185.0 182.0 185.0 183.0 185.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:66.40 Median:65.00 stddev:10.88 (16.7%) stddev-sans-first:10.54 12:00:28 INFO - PROCESS | 1659 | Values: 79.0 59.0 59.0 88.0 59.0 59.0 58.0 71.0 74.0 58.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:77.00 stddev:8.24 (10.7%) stddev-sans-first:7.61 12:00:28 INFO - PROCESS | 1659 | Values: 87.0 69.0 85.0 70.0 68.0 69.0 84.0 69.0 70.0 84.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.54 12:00:28 INFO - PROCESS | 1659 | Values: 88.0 85.0 88.0 86.0 88.0 84.0 86.0 84.0 87.0 85.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:121.00 Median:148.50 stddev:24.09 (16.2%) stddev-sans-first:23.79 12:00:28 INFO - PROCESS | 1659 | Values: 96.0 89.0 130.0 149.0 93.0 121.0 103.0 133.0 148.0 148.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:87.00 stddev:2.31 (2.7%) stddev-sans-first:2.42 12:00:28 INFO - PROCESS | 1659 | Values: 87.0 87.0 88.0 85.0 86.0 87.0 88.0 86.0 86.0 80.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.00 stddev:0.85 (1.6%) stddev-sans-first:0.71 12:00:28 INFO - PROCESS | 1659 | Values: 56.0 54.0 54.0 54.0 55.0 54.0 56.0 54.0 54.0 54.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.50 stddev:3.31 (4.6%) stddev-sans-first:3.24 12:00:28 INFO - PROCESS | 1659 | Values: 69.0 77.0 69.0 79.0 74.0 71.0 70.0 72.0 73.0 72.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:87.50 stddev:7.76 (8.9%) stddev-sans-first:7.64 12:00:28 INFO - PROCESS | 1659 | Values: 98.0 108.0 90.0 90.0 85.0 84.0 82.0 86.0 87.0 88.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:144.50 stddev:21.43 (14.8%) stddev-sans-first:22.56 12:00:28 INFO - PROCESS | 1659 | Values: 143.0 143.0 146.0 150.0 139.0 140.0 139.0 130.0 204.0 169.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:176.50 stddev:20.34 (11.5%) stddev-sans-first:21.30 12:00:28 INFO - PROCESS | 1659 | Values: 175.0 230.0 213.0 180.0 177.0 169.0 175.0 175.0 171.0 176.0 12:00:28 INFO - PROCESS | 1659 | 12:00:28 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.00 12:00:28 INFO - PROCESS | 1659 | Values: 67.0 66.0 66.0 65.0 68.0 67.0 67.0 66.0 65.0 67.0 12:00:28 INFO - PROCESS | 1659 | -------- Summary: end -------- 12:00:28 INFO - PROCESS | 1659 | 12:00:29 INFO - TEST-INFO | 1659: exit 0 12:00:29 INFO - TEST-OK | kraken | took 59614ms 12:00:29 INFO - SUITE-END | took 1178s 12:00:29 INFO - Completed test suite (00:19:38) 12:00:29 INFO - javascript benchmark 12:00:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7143.915164433039, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.802971821692, 3285.244393789428, 3291.3417316536693, 3299.3071454994433, 3343.364760695468, 5468.398476167628, 5549.805756798512, 5589.329280486342, 5608.915866262004, 5652.434756524351, 5518.172274158878, 5637.3517045539775, 5696.6866822324755, 5698.0, 5740.942590574093, 5065.189569668853, 5070.974645126769, 5075.959428317205, 5115.616328775338, 5162.096633089213, 5183.118869792125, 5231.764570594313, 5232.529072383485, 5236.0, 5287.894242115175, 4758.976205118969, 5070.6957582545165, 5185.611079169077, 5203.531682148587, 5231.686098834063, 5099.490050994871, 5107.284980102751, 5123.180291153435, 5141.794328226866, 5143.922841157386, 5067.037262920034, 5092.79628814847, 5110.259012443212, 5128.1282182029145, 5154.969006198738, 5587.944120558824, 5614.073677843151, 5615.578831587606, 5635.126555383922, 5653.208550802891, 6049.576529642927, 6076.5442591805795, 6086.513078953673, 6134.693265336716, 6155.599886007395, 6215.0, 6233.065040243954, 6271.435570798604, 6290.842728931813, 6301.7164227609865, 5765.250517432707, 5769.913451298234, 5770.307563092456, 5773.884522309572, 5796.710164491758, 6178.382161783786, 6230.096635987757, 6232.750689972441, 6274.121622972787, 6346.428821406095, 6042.637441753421, 6042.758289668364, 6064.787732429344, 6109.040306147876, 6124.330384950791, 6076.878462430726, 6215.06773983906, 6237.563370564017, 6238.750449982041, 6261.373862613793, 5953.583249172603, 6026.306974697965, 6041.728122234422, 6098.847528811815, 6108.8778224436155, 6050.848728781815, 6129.400675281419, 6150.169727086786, 6157.784477543264, 6169.259688837368, 4886.682365646222, 4913.140200464904, 4914.975425122851, 4916.680415772963, 4921.089598424303, 4344.304911214191, 4375.190589740908, 4380.386745855582, 4401.22978478765, 4467.262901621261, 5704.629199102045, 5710.486056254874, 5715.771369145187, 5717.285339332583, 5724.198612194296, 6240.31356550779, 6252.343503932113, 6253.7185826637915, 6260.624362538262, 6273.0904018917, 5545.500904918576, 5577.8047768327915, 5602.2717046783655, 5653.349864765522, 5710.885782284331, 5377.220303056035, 5388.245645609618, 5390.299261096033, 5417.187421886749, 5431.67409955396, 6122.602030867979, 6215.098810672427, 6229.470495007975, 6243.063540468966, 6261.248650161828, 6101.359357267601, 6108.190302030417, 6116.457510225689, 6252.5935814171935, 6258.249010118815, 6181.072839073995, 6203.534734894811, 6227.1282020516255, 6256.593321434092, 6265.592736472115, 6133.049377346519, 6137.355577664281, 6151.815445536641, 6152.0156774915795, 6154.138420621027, 5872.823815285548, 5898.528117750656, 6177.320494745664, 6235.095911092957, 6279.937200627935, 5863.2670916135485, 5928.229330187048, 5952.255968003999, 6002.639841609517, 6056.636601803906, 6029.78895738647, 6048.516118710405, 6054.757809687563, 6064.727087280995, 6090.086487026982, 5743.741531631003, 5747.080467125407, 5801.274840644919, 5801.36185019639, 5816.796412125555, 4747.738874361943, 5677.744501497526, 6023.337432882292, 6037.124616930432, 6040.637561746309, 5750.741216645178, 5796.478316951495, 5821.970890145692, 5826.271716035496, 5861.32594751609], "value": 5628.9614388729715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.2840483631027, 492.22228878372175, 495.06432841928785, 499.69019208090975, 520.8541608349661, 26368.86815565922, 39361.60638393615, 41720.58279417205, 41869.79065104678, 42041.369379459255, 43013.7849310753, 43426.131477370436, 43427.34858977118, 43558.0, 43630.0, 8381.161883811601, 9354.859677104849, 9558.474283914382, 9563.378380405251, 9567.42595444272, 8456.365772567051, 11318.32090074594, 11620.883791162107, 11631.883681163206, 11643.0, 11288.096952243843, 11319.886801132006, 11323.150763692714, 11364.545418183263, 11365.545378184905, 8454.070052294242, 8713.302935765123, 11314.208005439581, 11402.087832973342, 11421.371824549606, 10971.23201375904, 11281.830772538418, 11328.207025508218, 11505.022073123795, 11529.365884876328, 41218.587814121776, 41687.0, 41803.1639367214, 41825.58174418248, 41845.1630967382, 4015.357542793139, 4027.6684175379587, 4049.53430355507, 4049.53430355507, 4192.517860446057, 31126.68873311283, 31263.905763298404, 31309.686903130903, 31316.53025204624, 31348.37303253945, 2907.7237662422035, 2939.720726530976, 3056.488321767019, 3111.859966301522, 3138.8901388451523, 468066.0, 468460.0, 468953.0, 469171.99999999657, 469394.0, 61898.69050654718, 62263.37736622666, 62291.688541557, 62363.37636623666, 62494.0625890612, 64666.67666661637, 64895.67552162209, 64912.67543662346, 64958.02562961559, 65031.34968650253, 15624.140672263135, 15790.131542765259, 15819.52541423759, 15820.446284379992, 15824.525264242091, 11105.222634415675, 11392.20254582188, 12140.392980350947, 12316.445759940823, 12441.066919981038, 17056.2324695389, 17207.483775486755, 17234.224459899335, 17249.22378492971, 17258.395956141474, 6525.836854078686, 6632.0, 7590.924090759022, 7618.542887426772, 7721.7683469496005, 836.5110104014864, 841.5119230846094, 857.237059017475, 859.2696208223085, 879.6965047058754, 25561.744382555935, 29323.706762932525, 29988.85005575002, 30731.539026914616, 30833.691663083082, 1023.764534157148, 1074.897884700952, 1076.4402510694395, 1077.5851297250458, 1099.1701265544464, 15173.696526069416, 15699.450519231772, 15793.526194214191, 15874.285657145456, 16183.919080404523, 217383.0, 217855.0, 218085.90957045113, 218158.0, 218160.0, 61750.38249617536, 61815.0, 61955.380446195864, 61983.690081549306, 62188.68905655443, 1398.9510367137102, 1413.7737961926043, 1431.6564024634013, 1436.8132142821366, 1438.3527412664344, 27914.86042569774, 29488.852555737085, 29574.260643484085, 30432.23919401944, 30483.695163048087, 51547.742261288455, 51874.48125518696, 51925.7403712979, 52006.21990670219, 52022.0, 528066.0, 531246.0, 534357.0, 535012.0, 535312.0, 40103.0, 40808.591914080476, 40832.0, 40922.59077409307, 41147.7942610285, 46552.53447465482, 46915.76542117268, 47518.0, 47537.0, 47809.0, 38650.6134938647, 38786.80606596949, 39209.8039509812, 40027.599724002386, 40568.797156015215, 26209.606855897557, 37934.24131517409, 38325.61674383332, 38398.040048999, 39515.604843951194, 37225.81387093047, 37680.43479347757, 37930.43104353382, 38159.618403815606, 38171.61828381681, 32176.678233217368, 32799.50800737943, 34091.0, 34110.48834267438, 34346.82826585951, 21737.565248695253, 24052.037918483064, 24821.774467320847, 24992.625110623718, 25109.0], "value": 19744.04760933055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.46923134533284, 380.8457574682254, 389.204077661183, 390.28772490205375, 425.8828822073929, 12243.449044792984, 15548.84451155488, 17695.64608707825, 20824.791752082474, 21136.626119302233, 20444.386668399933, 21035.68446473304, 21831.01760420781, 22049.669254961187, 22225.777742222574, 5337.1994200869985, 5461.754221060062, 5497.587680923927, 5643.181738647893, 5649.689267090308, 5434.266374039514, 5535.778568857241, 5537.058700020986, 5539.612227144082, 5544.77820887164, 4016.377461493473, 4209.936850947238, 5902.3507414184405, 6151.692415379235, 6200.379962003786, 6124.663143527104, 6237.532185086114, 6244.000959846426, 6247.9062814057825, 6846.4522838172825, 6107.7556897724035, 6164.907526387108, 6166.784162554289, 6167.537434692416, 6169.537284703665, 13412.932935335359, 19776.604467910704, 25130.37174070663, 25607.359816004562, 25870.223893283233, 2599.9610005849927, 2609.712931577525, 2618.4763047390597, 2651.907183248577, 2667.8132530722855, 10098.242631802645, 10124.240681948882, 10176.592936282539, 10648.14814814813, 10657.147428205802, 1785.5089850291145, 1829.5060333709957, 1835.0824587706147, 1843.9446816595523, 1853.1753369750465, 91676.08323916742, 112512.0, 125367.74632253652, 132182.0, 134692.979605306, 9905.900940990497, 11353.716157095974, 11459.427028648533, 11534.711632209262, 11916.285022898654, 15541.300641471162, 15567.61080972962, 15581.844181558266, 15589.0, 15625.0, 8431.241188292961, 8648.1784230498, 8677.093173786852, 8830.42602230853, 8833.69082082124, 8370.958145209357, 8371.455855369382, 8392.076871544125, 8548.059713431518, 8562.100979397073, 4719.551642593948, 4780.330753694486, 4802.327674125625, 4837.540433658797, 4911.582515486152, 1731.4113201511295, 1947.7565304336958, 1965.5675751334684, 2008.2327551754363, 2013.808688174621, 786.9769299910093, 810.4124509730516, 851.9914800851913, 871.8997315308694, 883.4301875290402, 18210.817891821178, 21057.684134737992, 21824.563508729738, 21879.0, 22017.449563761034, 841.2050612171562, 867.8154319354036, 872.895252569683, 883.5184824270788, 890.225503811688, 10490.0, 11131.332120072822, 11133.0, 11143.832842507369, 11356.829647555292, 518565.0, 591881.0, 736040.0, 740495.2975235089, 753972.0, 105912.0, 127359.72640273663, 128995.35502322616, 153592.0, 164097.0, 841.2722994609619, 981.7790997025755, 1014.4876837197169, 1036.0, 1036.4921188617523, 19861.801381986574, 20249.69625455649, 20363.694544581547, 20373.49066273296, 20381.79618203799, 61233.46916327127, 61338.69330653318, 62347.376526234155, 62468.68765656143, 62577.06134408079, 91161.0, 91204.0, 91960.0, 92654.0, 94041.58937615804, 8112.716054938049, 8149.877751833608, 8246.876296855671, 8648.481091134552, 8718.476891386537, 12806.935965320114, 13229.206247625174, 13238.735225295633, 13298.335083245605, 13591.592252232449, 11267.661970140909, 11536.134789890624, 11559.595414160463, 11686.357250351311, 11692.94153529227, 11040.723981900517, 11049.844406835206, 11228.157888158257, 11392.25950313227, 11509.194356394972, 11297.491612877277, 11432.428378580871, 11504.884951150381, 11698.005669518187, 11750.353730544903, 7152.213256541881, 7445.9255407445235, 7510.286522780327, 8308.0, 8419.368547359096, 8254.463459875089, 10293.53679084428, 11151.163662725166, 11599.478023488795, 11750.941245294061], "value": 9711.826528947684, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.0262221689254, 1438.654722866512, 1450.5358285348693, 1451.6733734909644, 1457.3951809998844, 7932.801679958004, 8389.958050209756, 8399.908011958452, 8403.915960840397, 8408.327333813295, 7323.670434830432, 7534.321911028006, 7538.660760265788, 7939.285464308211, 8180.018397792258, 2225.354647152324, 2285.131649973008, 2289.027163455533, 2290.3815969688176, 2290.610596198642, 2207.7792220777956, 2306.4003359126664, 2312.6531020346997, 2316.6177580699164, 2324.744278129413, 1986.433866348091, 2062.6279778254975, 2064.0814837397365, 2189.770074142216, 2273.761255068219, 2261.0729600863565, 2274.431392151962, 2277.4420267034516, 2278.464560828204, 2282.6690129931235, 2212.3849589799374, 2264.62633665445, 2283.7373702024315, 2285.6914316567218, 2286.8056215221727, 8186.54973976431, 8290.129536398712, 8295.958520207421, 8308.667653293862, 8312.709055183039, 4990.600751939836, 5157.8452646420665, 5160.432352441228, 5160.845174644766, 5164.45773193813, 2937.089502254308, 3091.984540077285, 3391.8812841550675, 3442.707369873564, 3449.4308439107517, 1122.1135303110614, 1131.4682099413344, 1149.0520320735427, 1166.0147175636573, 1213.1811027556364, 10549.261551691385, 10599.993000665001, 10624.628138015132, 10646.361218326925, 10649.361038337725, 4471.329300604935, 4649.697769644962, 4658.86023419298, 4661.463931647904, 4699.718016918996, 8552.61513231906, 8571.357148213909, 8591.398602097917, 8600.39797214189, 8605.440646357967, 5056.26684130799, 5060.114479965991, 5062.7468626568525, 5064.468230835783, 5068.493150684902, 4510.120526497367, 4665.230237010923, 4750.691205071659, 4753.120672675566, 4755.262934245197, 3526.0479670488826, 3596.6762991330907, 3637.4725664778457, 3670.46778217157, 3680.35593771089, 1155.6706338693432, 1159.9942000289946, 1170.3504554972071, 1170.3738499902681, 1170.4323403149465, 776.2780614028894, 841.474078700812, 870.9564521773885, 904.1500989070253, 931.9161275485238, 5051.873703157377, 5294.2852714883, 5380.246765452762, 5489.58828087895, 5598.972005139948, 456.8720758187713, 515.1628603519281, 567.6594043573823, 571.6427232979388, 573.6156774960787, 3148.6536480987074, 3419.196488825124, 3636.2000359920776, 3724.385476396364, 3734.8879533614036, 9529.904700952902, 9909.554070066864, 9911.702648920544, 9911.80176396468, 9914.45470499115, 9793.804123917482, 9855.655052073143, 9886.406815591088, 9889.258305627107, 9891.703248902544, 7195.784126476214, 7520.849583008309, 7645.4648174628355, 7730.265624765639, 7843.333316668147, 4438.800253988514, 5397.59518036141, 5432.402435731988, 5457.645253058538, 5477.863053423696, 8672.349573782118, 8870.068642792672, 9077.909220907706, 9114.635414583343, 9176.44941303524, 9202.953985230031, 9222.631094756134, 9266.11971862672, 9309.627614895327, 9325.720228393158, 6739.831504212238, 6995.930040699528, 7023.68393422287, 7085.220625731268, 7141.285871412817, 7850.528968261922, 7899.526028438312, 7911.5253084815095, 7913.089994650459, 7929.603519823871, 5955.910661339996, 6978.0, 7013.579185248901, 7041.4014808739785, 7124.857502850017, 6879.862402752017, 6904.344087311697, 6908.274631163656, 6945.131858517685, 6957.130358705162, 6167.969160154171, 6210.130581718653, 6214.720337584729, 6236.282827474898, 6316.905246421215, 4691.929621055755, 4695.3191787191345, 4792.9281060784815, 4798.760061996816, 4807.975960120177, 4734.71591704485, 4828.179209534317, 4832.251001094836, 4838.491958344464, 4889.364382630235], "value": 4237.588298143773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.24774523106373, 350.61082198759374, 351.5728390006142, 359.81289729340733, 369.6857670979666, 4212.978935105324, 4412.889677758054, 4554.544545545452, 5044.495550444949, 5286.471352864712, 4409.536998615147, 4654.767261636913, 5022.92007218448, 5181.80273978078, 5235.3299817093275, 3825.699262250834, 4097.44684467596, 4097.467329247193, 4106.029106029107, 4144.48193975753, 3406.863725450979, 3475.7487304570336, 3529.976306871017, 3920.7451515651533, 4121.608447197512, 3390.610079840825, 3979.86070487533, 4001.8799436016966, 4079.3269110596716, 4123.855665051724, 3304.958937934555, 3739.5886452490204, 3743.839409782976, 4053.43251944728, 4086.6117718816663, 3306.6032076151014, 3595.209054008114, 3627.3470775260444, 3641.799701016443, 3661.340958627446, 4821.397325334333, 4874.488908438396, 4987.351644286256, 5051.974740126313, 5057.2161314995865, 4344.45694288214, 4702.177119004161, 4767.356406885061, 4777.498362671904, 4809.8635173750545, 4519.728816271035, 5345.3585569731495, 5573.247611572427, 5575.918583399101, 5590.596043701207, 3668.596454390015, 4010.0, 4040.9595904040875, 4054.6958978076764, 4065.410515475269, 5211.0, 5415.377231618375, 5587.608867379285, 5646.378898321182, 5768.682722450264, 3808.676262517717, 3907.1794923065904, 4034.7377420467574, 4103.302438585448, 4111.568285330057, 3590.0486371111674, 3851.730378873452, 3926.7251292409255, 3928.8035598219976, 4161.56303588125, 3937.6062393760394, 3982.940255896106, 4411.029573493826, 4423.363355558399, 4506.662000349987, 5094.872628184325, 5258.843054527998, 5548.77804887808, 5606.962711898312, 5633.126865335879, 1202.927824330543, 1229.3668760588305, 1241.5530409052733, 1264.4815625593462, 1266.3098611256887, 741.5402450480628, 777.9883301750478, 861.586438509519, 864.7059999600166, 875.0767939823547, 4918.5573298403315, 4918.680285781413, 4934.6545741798445, 4970.304157417964, 4976.477469865685, 4503.68474206809, 4667.346571479996, 5143.919776846829, 5205.817796377109, 5380.461953804588, 4096.897577560585, 4511.924514723199, 4880.633952453581, 4909.631777616693, 4969.279454479152, 2430.347363792638, 2573.2794817451145, 2768.529350010503, 2791.371941313229, 2809.524999375344, 5380.0, 5416.179600155864, 5485.396606373296, 5511.58663100269, 5525.668459892419, 4858.659893807399, 4958.677685950402, 5057.016360347339, 5452.454754524595, 5565.2486914266865, 4826.565609095198, 5039.193729003342, 5556.305461817192, 5596.58025648078, 5636.549076073904, 4241.818636022983, 4625.421822272216, 5031.823886163967, 5206.349206349207, 5328.176066684663, 4984.675996060245, 5320.733963301896, 5325.7337133143965, 5443.237946687546, 5444.945550544602, 4564.226253788894, 4865.878353041202, 4913.803447862046, 5119.846404607868, 5480.780768769205, 2968.8960886368873, 3303.3888730584918, 3636.5999740028046, 3656.488091667222, 3682.8710995115043, 4736.0, 5021.297018417352, 5188.533032027136, 5276.815311464234, 5469.945300546943, 4944.48082951285, 5124.7950081996305, 5375.623706340519, 5390.466480754039, 5584.748686309044, 4710.929336059893, 4923.950760492493, 5581.748821303132, 5584.3857175711455, 5598.832035038955, 5074.340335756328, 5166.48257808346, 5277.788888444573, 5402.324709411324, 5455.781768729206, 4404.515503294571, 4652.418177819558, 5540.556755459473, 5548.445155484419, 5591.552675786009, 4410.536893626123, 5294.23233631129, 5455.8363249102595, 5465.169168328654, 5505.779768809202], "value": 3937.841012245852, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7824474412929, 725.3435640745121, 760.0347558600578, 816.2694388522273, 823.2220551578758, 11912.46393912274, 15730.606734831636, 15764.211789410512, 15765.921170394146, 15797.526074217763, 14355.569332920002, 14494.202818844959, 14517.564473065799, 14548.781768273484, 14562.927185364058, 676.2763842688325, 698.4272896225098, 702.3152426384286, 840.7225615546855, 853.1766844994579, 860.9225169734723, 864.0754392799707, 867.1675191815864, 875.7810547363159, 878.5782824244367, 934.9298802589799, 936.1247233836351, 937.2736129499624, 938.1837801113041, 939.4269495607674, 717.4834119433999, 932.9253659707241, 936.9927328122228, 937.3157594955652, 939.173527295982, 547.7179252684871, 666.516775337881, 685.5543896467286, 688.5662032919229, 696.4010950582496, 14941.70116597662, 15010.399584016626, 15020.924895375454, 15022.248887555688, 15025.624359390993, 13529.797053044213, 13558.0, 13599.252041137734, 13607.523736669169, 13609.591712248648, 12378.319192444413, 12416.56542021034, 12430.937845310715, 12453.564125255663, 12791.04067194964, 4390.1219756048595, 4404.207242696313, 4450.710703804242, 4483.193025255453, 4558.931616025729, 97797.51101244519, 100507.0, 101174.0, 105195.47402263017, 105938.0, 10165.237607179492, 10502.212334074975, 10800.94599527013, 10875.564977400974, 11446.255993360404, 21472.0, 21671.241506547194, 21872.671909921362, 21943.451413714785, 21974.01116949741, 7471.3649339806725, 7503.512271702322, 7545.132309784335, 7616.0099187105325, 7766.339861111755, 3828.73198876076, 4219.240536703362, 4259.403683484315, 4260.552641972548, 4261.808218630169, 3384.1708781348725, 3485.128717820545, 3490.0925759302763, 3494.4583589543663, 3501.8599256029984, 625.956183067181, 629.8866204083309, 637.7959053103056, 644.716324817079, 646.5021933111572, 634.6192284629278, 637.7130291368902, 640.3148630964823, 647.9676016199171, 649.9715142057455, 8701.347398945105, 9486.0, 9491.810163796823, 9512.90487095134, 9579.568919398644, 611.651358725531, 613.9508839292846, 633.2021652717609, 640.7853369121303, 669.0900375489331, 4416.968194630506, 4552.1806074906835, 4579.931301030487, 4587.151376995266, 4591.058832939239, 84667.57666211629, 86268.56865715756, 87047.0, 87504.56247718721, 107673.0, 83744.58127709445, 84343.0, 84459.0, 86026.0, 86326.13673863182, 67578.32421675819, 68012.6599367, 69730.65134674395, 69886.30113698798, 76055.0, 9140.497272650067, 10935.726606834893, 11043.066767407592, 11106.222564420414, 11226.943865280948, 41364.0, 43750.78124609357, 47457.762711187606, 47696.7615161922, 51156.0, 67403.3259667397, 68154.0, 69227.30772692208, 70299.29700702928, 71474.0, 12584.18202816814, 12762.17045892014, 12928.353582320658, 13044.347782611021, 13130.671733206746, 10060.69817905464, 11515.30908145514, 12367.319797411228, 14556.854431455973, 14650.459259702335, 13305.933470332586, 13695.657608559866, 13899.722005560034, 14232.64418389507, 14279.143251404948, 13258.270795106362, 13575.456981720621, 13827.101238419471, 13912.165270083828, 14301.856981430468, 11425.37160456183, 13346.933265333611, 13572.979229695613, 13653.0, 13754.381052852446, 10849.240553161202, 11173.162012849232, 11176.10591152722, 11251.324920504796, 11530.0, 9547.618095276111, 10419.94790026045, 10759.946200269262, 10871.0, 10886.782264354826], "value": 7379.46177630537, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 113.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 109.0, 145.0, 110.0, 114.0, 135.0, 130.0, 131.0, 108.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 184.0, 185.0, 184.0, 185.0, 182.0, 185.0, 183.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 59.0, 59.0, 58.0, 71.0, 74.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 85.0, 70.0, 68.0, 69.0, 84.0, 69.0, 70.0, 84.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 84.0, 86.0, 84.0, 87.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 89.0, 130.0, 149.0, 93.0, 121.0, 103.0, 133.0, 148.0, 148.0], "value": 121.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 85.0, 86.0, 87.0, 88.0, 86.0, 86.0, 80.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 55.0, 54.0, 56.0, 54.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 69.0, 79.0, 74.0, 71.0, 70.0, 72.0, 73.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 90.0, 90.0, 85.0, 84.0, 82.0, 86.0, 87.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 143.0, 146.0, 150.0, 139.0, 140.0, 139.0, 130.0, 204.0, 169.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 230.0, 213.0, 180.0, 177.0, 169.0, 175.0, 175.0, 171.0, 176.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 68.0, 67.0, 67.0, 66.0, 65.0, 67.0], "value": 66.4, "unit": "score"}]}]} 12:00:29 INFO - Return code: 0 12:00:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:00:29 ERROR - # TBPL SUCCESS # 12:00:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:00:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:00:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:00:29 INFO - 2017-01-29 12:00:29.542 screenresolution[1664:10703] starting screenresolution argv=screenresolution get 12:00:29 INFO - 2017-01-29 12:00:29.544 screenresolution[1664:10703] Display 0: 1600x1200x32@60 12:00:29 INFO - 2017-01-29 12:00:29.551 screenresolution[1665:10705] starting screenresolution argv=screenresolution list 12:00:29 INFO - Available Modes on Display 0 12:00:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:00:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:00:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:00:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:00:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:00:29 INFO - Intel Iris: 12:00:29 INFO - Chipset Model: Intel Iris 12:00:29 INFO - Type: GPU 12:00:29 INFO - Bus: Built-In 12:00:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:00:29 INFO - Vendor: Intel (0x8086) 12:00:29 INFO - Device ID: 0x0a2e 12:00:29 INFO - Revision ID: 0x0009 12:00:29 INFO - Displays: 12:00:29 INFO - Display: 12:00:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:00:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:00:29 INFO - Display Serial Number: 1600x1200 60 12:00:29 INFO - Main Display: Yes 12:00:29 INFO - Mirror: Off 12:00:29 INFO - Online: Yes 12:00:29 INFO - Rotation: Supported 12:00:29 INFO - Return code: 0 12:00:29 INFO - Running post-action listener: _resource_record_post_action 12:00:29 INFO - [mozharness: 2017-01-29 20:00:29.792812Z] Finished run-tests step (success) 12:00:29 INFO - Running post-run listener: _resource_record_post_run 12:00:29 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 32582656; Write bytes: 555747840; Read time: 199; Write time: 1661 12:00:29 INFO - TinderboxPrint: CPU usage
53.8% 12:00:29 INFO - TinderboxPrint: I/O read bytes / time
32,582,656 / 199 12:00:29 INFO - TinderboxPrint: I/O write bytes / time
555,747,840 / 1,661 12:00:29 INFO - TinderboxPrint: CPU idle
2,221.3 (46.2%) 12:00:29 INFO - TinderboxPrint: CPU system
960.7 (20.0%) 12:00:29 INFO - TinderboxPrint: CPU user
1,623.7 (33.8%) 12:00:29 INFO - TinderboxPrint: Swap in / out
281,075,712 / 0 12:00:29 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248613376; Write bytes: 244230144; Read time: 17957; Write time: 519 12:00:29 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30510080; Write bytes: 298263040; Read time: 178; Write time: 1108 12:00:30 INFO - Running post-run listener: _upload_blobber_files 12:00:30 INFO - Blob upload gear active. 12:00:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:00:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:00:30 INFO - (blobuploader) - INFO - Open directory for files ... 12:00:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:00:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:00:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:31 INFO - (blobuploader) - INFO - Done attempting. 12:00:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:00:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:00:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:33 INFO - (blobuploader) - INFO - Done attempting. 12:00:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:00:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:34 INFO - (blobuploader) - INFO - Done attempting. 12:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:00:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:36 INFO - (blobuploader) - INFO - Done attempting. 12:00:36 INFO - (blobuploader) - INFO - Iteration through files over. 12:00:36 INFO - Return code: 0 12:00:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:00:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:00:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a949c4965b39fd83d5a651d79a571bec40cd9c2699de0298be0d8285b43cff5be0e31c4283aea8c499e05ee38b8d867ee140572c3cdbe0a7e35e6effd06d40db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecde39c6ddfd734e99a9ae33e4675ca4e708862198612757528df28de6707445f753520fbf8f309239cb95e25e58b3b1ecd1cd4330b2d69a656d2a2f5f2b2f76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222a1ac8d9f95c753aa4237e3d7795a9b3909b6134c765d7b4175dbb6339ab787bcbe1aa52524548897f1b001207c4019b268a706c03af04f5d2407e2a59cfc2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a016389af5fe5831cefcec0de2529cd9dcf57bf101a3e4fb2b4266070840bec9559d8834dfdc5f9b87554d8c7f231dcb305682470883e7ede2452a0a61d3657"} 12:00:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:00:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:00:36 INFO - Contents: 12:00:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a949c4965b39fd83d5a651d79a571bec40cd9c2699de0298be0d8285b43cff5be0e31c4283aea8c499e05ee38b8d867ee140572c3cdbe0a7e35e6effd06d40db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecde39c6ddfd734e99a9ae33e4675ca4e708862198612757528df28de6707445f753520fbf8f309239cb95e25e58b3b1ecd1cd4330b2d69a656d2a2f5f2b2f76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222a1ac8d9f95c753aa4237e3d7795a9b3909b6134c765d7b4175dbb6339ab787bcbe1aa52524548897f1b001207c4019b268a706c03af04f5d2407e2a59cfc2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a016389af5fe5831cefcec0de2529cd9dcf57bf101a3e4fb2b4266070840bec9559d8834dfdc5f9b87554d8c7f231dcb305682470883e7ede2452a0a61d3657"} 12:00:36 INFO - Running post-run listener: copy_logs_to_upload_dir 12:00:36 INFO - Copying logs to upload dir... 12:00:36 INFO - mkdir: /builds/slave/test/build/upload/logs 12:00:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.396329 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-29 12:00:36.638450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 12:00:36.642380) ========= 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.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a949c4965b39fd83d5a651d79a571bec40cd9c2699de0298be0d8285b43cff5be0e31c4283aea8c499e05ee38b8d867ee140572c3cdbe0a7e35e6effd06d40db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecde39c6ddfd734e99a9ae33e4675ca4e708862198612757528df28de6707445f753520fbf8f309239cb95e25e58b3b1ecd1cd4330b2d69a656d2a2f5f2b2f76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222a1ac8d9f95c753aa4237e3d7795a9b3909b6134c765d7b4175dbb6339ab787bcbe1aa52524548897f1b001207c4019b268a706c03af04f5d2407e2a59cfc2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a016389af5fe5831cefcec0de2529cd9dcf57bf101a3e4fb2b4266070840bec9559d8834dfdc5f9b87554d8c7f231dcb305682470883e7ede2452a0a61d3657"} build_url:https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://queue.taskcluster.net/v1/task/SZVJcwyTTIuuqfj-rRjN7w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a949c4965b39fd83d5a651d79a571bec40cd9c2699de0298be0d8285b43cff5be0e31c4283aea8c499e05ee38b8d867ee140572c3cdbe0a7e35e6effd06d40db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecde39c6ddfd734e99a9ae33e4675ca4e708862198612757528df28de6707445f753520fbf8f309239cb95e25e58b3b1ecd1cd4330b2d69a656d2a2f5f2b2f76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222a1ac8d9f95c753aa4237e3d7795a9b3909b6134c765d7b4175dbb6339ab787bcbe1aa52524548897f1b001207c4019b268a706c03af04f5d2407e2a59cfc2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a016389af5fe5831cefcec0de2529cd9dcf57bf101a3e4fb2b4266070840bec9559d8834dfdc5f9b87554d8c7f231dcb305682470883e7ede2452a0a61d3657"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 12:00:36.677395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 12:00:36.677722) ========= 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.JuI2CCDCQQ/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.uL189FwOZD/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005201 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 12:00:36.726888) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 12:00:36.727201) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 12:00:36.727510) ========= ========= Total master_lag: 0.19 =========