builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0094 starttime: 1490670464.93 results: success (0) buildid: 20170327184522 builduid: 83b0a9b6517c40cf907af40a0ab93f4a revision: db2babffcafbd6b5b47a8c5813005838eb171a90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:44.928221) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:44.928721) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:44.928998) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:44.954246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:44.954630) ========= 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.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 20:07:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.8M=0.001s 2017-03-27 20:07:45 (12.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:45.064199) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:45.064563) ========= 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.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.026165 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:45.115968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 20:07:45.116313) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev db2babffcafbd6b5b47a8c5813005838eb171a90 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev db2babffcafbd6b5b47a8c5813005838eb171a90 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 20:07:45,202 truncating revision to first 12 chars 2017-03-27 20:07:45,202 Setting DEBUG logging. 2017-03-27 20:07:45,202 attempt 1/10 2017-03-27 20:07:45,202 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/db2babffcafb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 20:07:46,380 unpacking tar archive at: mozilla-aurora-db2babffcafb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.437904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 20:07:46.577795) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:46.578100) ========= script_repo_revision: db2babffcafbd6b5b47a8c5813005838eb171a90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:46.578475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:46.578751) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 20:07:46.598381) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-27 20:07:46.598698) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:07:46 INFO - MultiFileLogger online at 20170327 20:07:46 in /builds/slave/test 20:07:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:07:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:07:46 INFO - {'append_to_log': False, 20:07:46 INFO - 'base_work_dir': '/builds/slave/test', 20:07:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:07:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:07:46 INFO - 'branch': 'Mozilla-Aurora', 20:07:46 INFO - 'buildbot_json_path': 'buildprops.json', 20:07:46 INFO - 'config_files': ('talos/mac_config.py',), 20:07:46 INFO - 'default_actions': ('clobber', 20:07:46 INFO - 'read-buildbot-config', 20:07:46 INFO - 'download-and-extract', 20:07:46 INFO - 'populate-webroot', 20:07:46 INFO - 'create-virtualenv', 20:07:46 INFO - 'install', 20:07:46 INFO - 'run-tests'), 20:07:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:07:46 INFO - 'download_minidump_stackwalk': True, 20:07:46 INFO - 'download_symbols': 'ondemand', 20:07:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:07:46 INFO - 'tooltool.py': '/tools/tooltool.py', 20:07:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:07:46 INFO - '/tools/misc-python/virtualenv.py')}, 20:07:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:07:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:07:46 INFO - 'gecko_profile': False, 20:07:46 INFO - 'gecko_profile_interval': 0, 20:07:46 INFO - 'installer_path': 'installer.exe', 20:07:46 INFO - 'log_level': 'info', 20:07:46 INFO - 'log_name': 'talos', 20:07:46 INFO - 'log_to_console': True, 20:07:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:07:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:07:46 INFO - 'opt_config_files': (), 20:07:46 INFO - 'pip_index': False, 20:07:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:46 INFO - 'cmd': ('bash', 20:07:46 INFO - '-c', 20:07:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:46 INFO - 'enabled': True, 20:07:46 INFO - 'halt_on_failure': False, 20:07:46 INFO - 'name': 'check_screen_resolution'},), 20:07:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:46 INFO - 'cmd': ('bash', 20:07:46 INFO - '-c', 20:07:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:46 INFO - 'enabled': True, 20:07:46 INFO - 'halt_on_failure': False, 20:07:46 INFO - 'name': 'check_screen_resolution'},), 20:07:46 INFO - 'run_cmd_checks_enabled': True, 20:07:46 INFO - 'suite': 'dromaeojs-e10s', 20:07:46 INFO - 'system_bits': '32', 20:07:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:07:46 INFO - 'title': 't-yosemite-r7-0094', 20:07:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:07:46 INFO - 'use_talos_json': True, 20:07:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:07:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:07:46 INFO - 'work_dir': 'build'} 20:07:46 INFO - [mozharness: 2017-03-28 03:07:46.812153Z] Running clobber step. 20:07:46 INFO - Running pre-action listener: _resource_record_pre_action 20:07:46 INFO - Running main action method: clobber 20:07:46 INFO - rmtree: /builds/slave/test/build 20:07:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:07:48 INFO - Running post-action listener: _resource_record_post_action 20:07:48 INFO - [mozharness: 2017-03-28 03:07:48.901358Z] Finished clobber step (success) 20:07:48 INFO - [mozharness: 2017-03-28 03:07:48.901631Z] Running read-buildbot-config step. 20:07:48 INFO - Running pre-action listener: _resource_record_pre_action 20:07:48 INFO - Running main action method: read_buildbot_config 20:07:48 INFO - Using buildbot properties: 20:07:48 INFO - { 20:07:48 INFO - "project": "", 20:07:48 INFO - "product": "firefox", 20:07:48 INFO - "script_repo_revision": "production", 20:07:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 20:07:48 INFO - "repository": "", 20:07:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 20:07:48 INFO - "buildid": "20170327184522", 20:07:48 INFO - "pgo_build": "False", 20:07:48 INFO - "basedir": "/builds/slave/test", 20:07:48 INFO - "buildnumber": 6, 20:07:48 INFO - "slavename": "t-yosemite-r7-0094", 20:07:48 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:07:48 INFO - "platform": "yosemite_r7", 20:07:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:07:48 INFO - "branch": "mozilla-aurora", 20:07:48 INFO - "repo_path": "releases/mozilla-aurora", 20:07:48 INFO - "revision": "db2babffcafbd6b5b47a8c5813005838eb171a90", 20:07:48 INFO - "stage_platform": "macosx64", 20:07:48 INFO - "builduid": "83b0a9b6517c40cf907af40a0ab93f4a", 20:07:48 INFO - "slavebuilddir": "test" 20:07:48 INFO - } 20:07:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 20:07:48 INFO - Running post-action listener: _resource_record_post_action 20:07:48 INFO - [mozharness: 2017-03-28 03:07:48.907663Z] Finished read-buildbot-config step (success) 20:07:48 INFO - [mozharness: 2017-03-28 03:07:48.907906Z] Running download-and-extract step. 20:07:48 INFO - Running pre-action listener: _resource_record_pre_action 20:07:48 INFO - Running main action method: download_and_extract 20:07:48 INFO - mkdir: /builds/slave/test/build/tests 20:07:48 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')]} 20:07:48 INFO - trying https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 20:07:48 INFO - Downloading https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 20:07:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 20:07:50 INFO - Downloaded 1199 bytes. 20:07:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 20:07:50 INFO - Using the following test package requirements: 20:07:50 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 20:07:50 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 20:07:50 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 20:07:50 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'jsshell-mac.zip'], 20:07:50 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 20:07:50 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 20:07:50 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 20:07:50 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 20:07:50 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 20:07:50 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:07:50 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 20:07:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:07:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 20:07:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:07:50 INFO - Fetch https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 20:07:52 INFO - Expected file size: 51787824 20:07:52 INFO - Obtained file size: 51787824 20:08:08 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:08:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 20:08:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:08:08 INFO - Fetch https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 20:08:10 INFO - Expected file size: 51787824 20:08:10 INFO - Obtained file size: 51787824 20:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 20:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 20:08:26 INFO - Expected file size: 14404438 20:08:26 INFO - Obtained file size: 14404438 20:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:26 INFO - trying https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:08:26 INFO - Downloading https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 20:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 20:08:28 INFO - Downloaded 74670029 bytes. 20:08:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:08:28 INFO - mkdir: /builds/slave/test/properties 20:08:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:08:28 INFO - Writing to file /builds/slave/test/properties/build_url 20:08:28 INFO - Contents: 20:08:28 INFO - build_url:https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:08:29 INFO - Running post-action listener: _resource_record_post_action 20:08:29 INFO - Running post-action listener: set_extra_try_arguments 20:08:29 INFO - [mozharness: 2017-03-28 03:08:29.872889Z] Finished download-and-extract step (success) 20:08:29 INFO - [mozharness: 2017-03-28 03:08:29.873044Z] Running populate-webroot step. 20:08:29 INFO - Running pre-action listener: _resource_record_pre_action 20:08:29 INFO - Running main action method: populate_webroot 20:08:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:08:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:08:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:08:29 INFO - '--tppagecycles', 20:08:29 INFO - '7'], 20:08:29 INFO - 'tests': ['tsvgm']}}, 20:08:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:08:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:08:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:08:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:08:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['damp', 'tps']}, 20:08:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'tests': ['damp', 'tps']}, 20:08:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['dromaeo_dom']}, 20:08:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:08:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['basic_compositor_video']}, 20:08:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:08:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'other-e10s': {'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['a11yr', 20:08:29 INFO - 'ts_paint', 20:08:29 INFO - 'tpaint', 20:08:29 INFO - 'sessionrestore', 20:08:29 INFO - 'sessionrestore_no_auto_restore', 20:08:29 INFO - 'tabpaint']}, 20:08:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['tsvgx', 20:08:29 INFO - 'tsvgr_opacity', 20:08:29 INFO - 'tart', 20:08:29 INFO - 'tscrollx', 20:08:29 INFO - 'cart', 20:08:29 INFO - 'tsvg_static']}, 20:08:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:08:29 INFO - 'tsvgr_opacity', 20:08:29 INFO - 'tart', 20:08:29 INFO - 'tscrollx', 20:08:29 INFO - 'cart', 20:08:29 INFO - 'tsvg_static']}, 20:08:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'talos_options': ['--disable-e10s'], 20:08:29 INFO - 'tests': ['tp5o']}, 20:08:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:08:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'talos_options': ['--disable-e10s', 20:08:29 INFO - '--xperf_path', 20:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:29 INFO - 'tests': ['tp5n']}, 20:08:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:08:29 INFO - 'talos_options': ['--xperf_path', 20:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:29 INFO - 'tests': ['tp5n']}}, 20:08:29 INFO - 'talos.zip': {'path': '', 20:08:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:08:29 INFO - Running post-action listener: _resource_record_post_action 20:08:29 INFO - [mozharness: 2017-03-28 03:08:29.891878Z] Finished populate-webroot step (success) 20:08:29 INFO - [mozharness: 2017-03-28 03:08:29.891990Z] Running create-virtualenv step. 20:08:29 INFO - Running pre-action listener: _resource_record_pre_action 20:08:29 INFO - Running main action method: create_virtualenv 20:08:29 INFO - Creating virtualenv /builds/slave/test/build/venv 20:08:29 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 20:08:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:08:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:08:30 INFO - Using real prefix '/tools/python27' 20:08:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:08:30 INFO - Installing distribute.............................................................................................................................................................................................done. 20:08:33 INFO - Installing pip.................done. 20:08:33 INFO - Return code: 0 20:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:08:33 INFO - Reading from file tmpfile_stdout 20:08:33 INFO - Output received: 20:08:33 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) 20:08:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:33 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:33 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 20:08:33 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 20:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:33 INFO - 'HOME': '/Users/cltbld', 20:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:33 INFO - 'LOGNAME': 'cltbld', 20:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:33 INFO - 'MOZ_NO_REMOTE': '1', 20:08:33 INFO - 'NO_EM_RESTART': '1', 20:08:33 INFO - 'PAGER': '/bin/cat', 20:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:33 INFO - 'PWD': '/builds/slave/test', 20:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:33 INFO - 'SHELL': '/bin/bash', 20:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:33 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:33 INFO - 'USER': 'cltbld', 20:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:33 INFO - 'XPC_FLAGS': '0x0', 20:08:33 INFO - 'XPC_SERVICE_NAME': '0', 20:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:34 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 20:08:34 INFO - Cleaning up... 20:08:34 INFO - Return code: 0 20:08:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:08:34 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')]} 20:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:34 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')]} 20:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:34 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:34 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 20:08:34 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 20:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:34 INFO - 'HOME': '/Users/cltbld', 20:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:34 INFO - 'LOGNAME': 'cltbld', 20:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:34 INFO - 'MOZ_NO_REMOTE': '1', 20:08:34 INFO - 'NO_EM_RESTART': '1', 20:08:34 INFO - 'PAGER': '/bin/cat', 20:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:34 INFO - 'PWD': '/builds/slave/test', 20:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:34 INFO - 'SHELL': '/bin/bash', 20:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:34 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:34 INFO - 'USER': 'cltbld', 20:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:34 INFO - 'XPC_FLAGS': '0x0', 20:08:34 INFO - 'XPC_SERVICE_NAME': '0', 20:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:34 INFO - Downloading/unpacking psutil>=3.1.1 20:08:34 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 20:08:34 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 20:08:34 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 20:08:34 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 20:08:34 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 20:08:34 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 20:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:38 INFO - Installing collected packages: psutil 20:08:38 INFO - Running setup.py install for psutil 20:08:38 INFO - building 'psutil._psutil_osx' extension 20:08:38 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 20:08:38 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 20:08:38 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 20:08:38 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 20:08:38 INFO - building 'psutil._psutil_posix' extension 20:08:38 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 20:08:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:38 INFO - ^ 20:08:38 INFO - 1 warning generated. 20:08:38 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 20:08:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:38 INFO - Successfully installed psutil 20:08:38 INFO - Cleaning up... 20:08:38 INFO - Return code: 0 20:08:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08:38 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')]} 20:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:38 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')]} 20:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:38 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:38 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 20:08:38 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 20:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:38 INFO - 'HOME': '/Users/cltbld', 20:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:38 INFO - 'LOGNAME': 'cltbld', 20:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:38 INFO - 'MOZ_NO_REMOTE': '1', 20:08:38 INFO - 'NO_EM_RESTART': '1', 20:08:38 INFO - 'PAGER': '/bin/cat', 20:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:38 INFO - 'PWD': '/builds/slave/test', 20:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:38 INFO - 'SHELL': '/bin/bash', 20:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:38 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:38 INFO - 'USER': 'cltbld', 20:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:38 INFO - 'XPC_FLAGS': '0x0', 20:08:38 INFO - 'XPC_SERVICE_NAME': '0', 20:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:41 INFO - Installing collected packages: mozsystemmonitor 20:08:41 INFO - Running setup.py install for mozsystemmonitor 20:08:42 INFO - Successfully installed mozsystemmonitor 20:08:42 INFO - Cleaning up... 20:08:42 INFO - Return code: 0 20:08:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:42 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:42 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 20:08:42 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 20:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:42 INFO - 'HOME': '/Users/cltbld', 20:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:42 INFO - 'LOGNAME': 'cltbld', 20:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:42 INFO - 'MOZ_NO_REMOTE': '1', 20:08:42 INFO - 'NO_EM_RESTART': '1', 20:08:42 INFO - 'PAGER': '/bin/cat', 20:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:42 INFO - 'PWD': '/builds/slave/test', 20:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:42 INFO - 'SHELL': '/bin/bash', 20:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:42 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:42 INFO - 'USER': 'cltbld', 20:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:42 INFO - 'XPC_FLAGS': '0x0', 20:08:42 INFO - 'XPC_SERVICE_NAME': '0', 20:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:43 INFO - Downloading/unpacking jsonschema==2.5.1 20:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:08:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:08:46 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 20:08:46 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 20:08:46 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 20:08:46 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 20:08:46 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 20:08:46 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 20:08:46 INFO - Downloading functools32-3.2.3-2.tar.gz 20:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:08:46 INFO - warning: no files found matching '*.txt' 20:08:46 INFO - no previously-included directories found matching 'build' 20:08:46 INFO - no previously-included directories found matching 'dist' 20:08:46 INFO - no previously-included directories found matching '.git*' 20:08:46 INFO - Installing collected packages: jsonschema, functools32 20:08:46 INFO - Running setup.py install for functools32 20:08:46 INFO - warning: no files found matching '*.txt' 20:08:46 INFO - no previously-included directories found matching 'build' 20:08:46 INFO - no previously-included directories found matching 'dist' 20:08:46 INFO - no previously-included directories found matching '.git*' 20:08:47 INFO - Successfully installed jsonschema functools32 20:08:47 INFO - Cleaning up... 20:08:47 INFO - Return code: 0 20:08:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:47 INFO - 'HOME': '/Users/cltbld', 20:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:47 INFO - 'LOGNAME': 'cltbld', 20:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:47 INFO - 'MOZ_NO_REMOTE': '1', 20:08:47 INFO - 'NO_EM_RESTART': '1', 20:08:47 INFO - 'PAGER': '/bin/cat', 20:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:47 INFO - 'PWD': '/builds/slave/test', 20:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:47 INFO - 'SHELL': '/bin/bash', 20:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:47 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:47 INFO - 'USER': 'cltbld', 20:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:47 INFO - 'XPC_FLAGS': '0x0', 20:08:47 INFO - 'XPC_SERVICE_NAME': '0', 20:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:08:47 INFO - Cleaning up... 20:08:47 INFO - Return code: 0 20:08:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:47 INFO - 'HOME': '/Users/cltbld', 20:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:47 INFO - 'LOGNAME': 'cltbld', 20:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:47 INFO - 'MOZ_NO_REMOTE': '1', 20:08:47 INFO - 'NO_EM_RESTART': '1', 20:08:47 INFO - 'PAGER': '/bin/cat', 20:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:47 INFO - 'PWD': '/builds/slave/test', 20:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:47 INFO - 'SHELL': '/bin/bash', 20:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:47 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:47 INFO - 'USER': 'cltbld', 20:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:47 INFO - 'XPC_FLAGS': '0x0', 20:08:47 INFO - 'XPC_SERVICE_NAME': '0', 20:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:47 INFO - Downloading/unpacking blobuploader==1.2.4 20:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:50 INFO - Downloading blobuploader-1.2.4.tar.gz 20:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:08:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:08:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:08:51 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 20:08:51 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 20:08:51 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 20:08:51 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 20:08:51 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 20:08:51 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 20:08:51 INFO - Downloading docopt-0.6.1.tar.gz 20:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:08:51 INFO - Installing collected packages: blobuploader, requests, docopt 20:08:51 INFO - Running setup.py install for blobuploader 20:08:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:08:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:08:51 INFO - Running setup.py install for requests 20:08:52 INFO - Running setup.py install for docopt 20:08:52 INFO - Successfully installed blobuploader requests docopt 20:08:52 INFO - Cleaning up... 20:08:52 INFO - Return code: 0 20:08:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:52 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')]} 20:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:52 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')]} 20:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:52 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:52 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 20:08:52 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 20:08:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:52 INFO - 'HOME': '/Users/cltbld', 20:08:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:52 INFO - 'LOGNAME': 'cltbld', 20:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:52 INFO - 'MOZ_NO_REMOTE': '1', 20:08:52 INFO - 'NO_EM_RESTART': '1', 20:08:52 INFO - 'PAGER': '/bin/cat', 20:08:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:52 INFO - 'PWD': '/builds/slave/test', 20:08:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:52 INFO - 'SHELL': '/bin/bash', 20:08:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:52 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:52 INFO - 'USER': 'cltbld', 20:08:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:52 INFO - 'XPC_FLAGS': '0x0', 20:08:52 INFO - 'XPC_SERVICE_NAME': '0', 20:08:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BxEg1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-vnGy3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-AHEsMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0huZUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-SeM8zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-7fTrRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8S2s8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-hCu4SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uI3qwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bGpn7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-zzihBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:53 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bWX_p4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KJ3U9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Np3Ug2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2pqdXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-GHg1_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:54 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BSoDAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:08:54 INFO - Running setup.py install for manifestparser 20:08:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:08:54 INFO - Running setup.py install for mozcrash 20:08:54 INFO - Running setup.py install for mozdebug 20:08:55 INFO - Running setup.py install for mozdevice 20:08:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Running setup.py install for mozfile 20:08:55 INFO - Running setup.py install for mozhttpd 20:08:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Running setup.py install for mozinfo 20:08:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Running setup.py install for mozInstall 20:08:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:08:55 INFO - Running setup.py install for mozleak 20:08:55 INFO - Running setup.py install for mozlog 20:08:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Running setup.py install for moznetwork 20:08:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Running setup.py install for mozprocess 20:08:56 INFO - Running setup.py install for mozprofile 20:08:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Running setup.py install for mozrunner 20:08:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:08:56 INFO - Running setup.py install for mozscreenshot 20:08:56 INFO - Running setup.py install for moztest 20:08:57 INFO - Running setup.py install for mozversion 20:08:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:08:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:08:57 INFO - Cleaning up... 20:08:57 INFO - Return code: 0 20:08:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:57 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:08:57 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 20:08:57 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 20:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:57 INFO - 'HOME': '/Users/cltbld', 20:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:57 INFO - 'LOGNAME': 'cltbld', 20:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:57 INFO - 'MOZ_NO_REMOTE': '1', 20:08:57 INFO - 'NO_EM_RESTART': '1', 20:08:57 INFO - 'PAGER': '/bin/cat', 20:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:57 INFO - 'PWD': '/builds/slave/test', 20:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:57 INFO - 'SHELL': '/bin/bash', 20:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:08:57 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:57 INFO - 'USER': 'cltbld', 20:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:57 INFO - 'XPC_FLAGS': '0x0', 20:08:57 INFO - 'XPC_SERVICE_NAME': '0', 20:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_eBzld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:57 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)) 20:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wCr85j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:57 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)) 20:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-jGiF7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:57 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)) 20:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1LzMIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Lobitd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1P4Xy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-iqRbtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-NBQ3QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KnjWsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-B_kbCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BfPzP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:58 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)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xoISWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:58 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Xbe8CF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:59 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)) 20:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:59 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BnJCRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:59 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)) 20:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:59 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xUPImj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:59 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)) 20:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:59 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9Ram7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:59 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)) 20:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:59 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-NzHRoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:59 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)) 20:08:59 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)) 20:08:59 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)) 20:08:59 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)) 20:08:59 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)) 20:08:59 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)) 20:08:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:09:02 INFO - Downloading blessings-1.6.tar.gz 20:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:09:02 INFO - Installing collected packages: blessings 20:09:02 INFO - Running setup.py install for blessings 20:09:02 INFO - Successfully installed blessings 20:09:02 INFO - Cleaning up... 20:09:02 INFO - Return code: 0 20:09:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:02 INFO - Reading from file tmpfile_stdout 20:09:02 INFO - Current package versions: 20:09:02 INFO - blessings == 1.6 20:09:02 INFO - blobuploader == 1.2.4 20:09:02 INFO - docopt == 0.6.1 20:09:02 INFO - functools32 == 3.2.3-2 20:09:02 INFO - jsonschema == 2.5.1 20:09:02 INFO - manifestparser == 1.1 20:09:02 INFO - mozInstall == 1.12 20:09:02 INFO - mozcrash == 1.0 20:09:02 INFO - mozdebug == 0.1 20:09:02 INFO - mozdevice == 0.49 20:09:02 INFO - mozfile == 1.2 20:09:02 INFO - mozhttpd == 0.7 20:09:02 INFO - mozinfo == 0.9 20:09:02 INFO - mozleak == 0.1 20:09:02 INFO - mozlog == 3.4 20:09:02 INFO - moznetwork == 0.27 20:09:02 INFO - mozprocess == 0.25 20:09:02 INFO - mozprofile == 0.28 20:09:02 INFO - mozrunner == 6.13 20:09:02 INFO - mozscreenshot == 0.1 20:09:02 INFO - mozsystemmonitor == 0.3 20:09:02 INFO - moztest == 0.8 20:09:02 INFO - mozversion == 1.4 20:09:02 INFO - psutil == 3.1.1 20:09:02 INFO - requests == 1.2.3 20:09:02 INFO - wsgiref == 0.1.2 20:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:02 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:09:02 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 20:09:02 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 20:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:02 INFO - 'HOME': '/Users/cltbld', 20:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:02 INFO - 'LOGNAME': 'cltbld', 20:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:02 INFO - 'MOZ_NO_REMOTE': '1', 20:09:02 INFO - 'NO_EM_RESTART': '1', 20:09:02 INFO - 'PAGER': '/bin/cat', 20:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:02 INFO - 'PWD': '/builds/slave/test', 20:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:02 INFO - 'SHELL': '/bin/bash', 20:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:09:02 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:02 INFO - 'USER': 'cltbld', 20:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:02 INFO - 'XPC_FLAGS': '0x0', 20:09:02 INFO - 'XPC_SERVICE_NAME': '0', 20:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 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)) 20:09:02 INFO - Cleaning up... 20:09:03 INFO - Return code: 0 20:09:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:09:03 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')]} 20:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:03 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')]} 20:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:03 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 0x102ce6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ce9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf215903a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, '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.WHLaGUTYBH/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.p0blLpvmky/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:09:03 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 20:09:03 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 20:09:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:09:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:03 INFO - 'HOME': '/Users/cltbld', 20:09:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:03 INFO - 'LOGNAME': 'cltbld', 20:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:03 INFO - 'MOZ_NO_REMOTE': '1', 20:09:03 INFO - 'NO_EM_RESTART': '1', 20:09:03 INFO - 'PAGER': '/bin/cat', 20:09:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:03 INFO - 'PWD': '/builds/slave/test', 20:09:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:03 INFO - 'SHELL': '/bin/bash', 20:09:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:09:03 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:09:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:03 INFO - 'USER': 'cltbld', 20:09:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:03 INFO - 'XPC_FLAGS': '0x0', 20:09:03 INFO - 'XPC_SERVICE_NAME': '0', 20:09:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:09:03 INFO - Cleaning up... 20:09:03 INFO - Return code: 0 20:09:03 INFO - Running post-action listener: _resource_record_post_action 20:09:03 INFO - Running post-action listener: _start_resource_monitoring 20:09:03 INFO - Starting resource monitoring. 20:09:03 INFO - [mozharness: 2017-03-28 03:09:03.319334Z] Finished create-virtualenv step (success) 20:09:03 INFO - [mozharness: 2017-03-28 03:09:03.320444Z] Running install step. 20:09:03 INFO - Running pre-action listener: _resource_record_pre_action 20:09:03 INFO - Running main action method: install 20:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:03 INFO - Reading from file tmpfile_stdout 20:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 20:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:09:03 INFO - Reading from file tmpfile_stdout 20:09:03 INFO - Output received: 20:09:03 INFO - Usage: mozinstall [options] installer 20:09:03 INFO - Options: 20:09:03 INFO - -h, --help show this help message and exit 20:09:03 INFO - -d DEST, --destination=DEST 20:09:03 INFO - Directory to install application into. [default: 20:09:03 INFO - "/builds/slave/test"] 20:09:03 INFO - --app=APP Application being installed. [default: firefox] 20:09:03 INFO - mkdir: /builds/slave/test/build/application 20:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:09:23 INFO - Reading from file tmpfile_stdout 20:09:23 INFO - Output received: 20:09:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:09:23 INFO - Running post-action listener: _resource_record_post_action 20:09:23 INFO - [mozharness: 2017-03-28 03:09:23.917137Z] Finished install step (success) 20:09:23 INFO - [mozharness: 2017-03-28 03:09:23.917278Z] Running run-tests step. 20:09:23 INFO - Running pre-action listener: _resource_record_pre_action 20:09:23 INFO - Running main action method: run_tests 20:09:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:09:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:09:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:09:23 INFO - 2017-03-27 20:09:23.932 screenresolution[3295:103549] starting screenresolution argv=screenresolution get 20:09:23 INFO - 2017-03-27 20:09:23.935 screenresolution[3295:103549] Display 0: 1600x1200x32@60 20:09:23 INFO - 2017-03-27 20:09:23.941 screenresolution[3296:103552] starting screenresolution argv=screenresolution list 20:09:23 INFO - Available Modes on Display 0 20:09:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:09:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:09:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:09:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:09:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:09:24 INFO - Intel Iris: 20:09:24 INFO - Chipset Model: Intel Iris 20:09:24 INFO - Type: GPU 20:09:24 INFO - Bus: Built-In 20:09:24 INFO - VRAM (Dynamic, Max): 1536 MB 20:09:24 INFO - Vendor: Intel (0x8086) 20:09:24 INFO - Device ID: 0x0a2e 20:09:24 INFO - Revision ID: 0x0009 20:09:24 INFO - Displays: 20:09:24 INFO - Display: 20:09:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:09:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:09:24 INFO - Display Serial Number: 1600x1200 60 20:09:24 INFO - Main Display: Yes 20:09:24 INFO - Mirror: Off 20:09:24 INFO - Online: Yes 20:09:24 INFO - Rotation: Supported 20:09:24 INFO - Return code: 0 20:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:24 INFO - Python 2.7.3 20:09:24 INFO - Return code: 0 20:09:24 INFO - grabbing minidump binary from tooltool 20:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac4d30>, '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 0x1029ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ab9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:09:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:09:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:09:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:24 INFO - Return code: 0 20:09:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:09:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:24 INFO - ENV: RUST_BACKTRACE is now 1 20:09:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:09:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WHLaGUTYBH/Render', 20:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:24 INFO - 'HOME': '/Users/cltbld', 20:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:24 INFO - 'LOGNAME': 'cltbld', 20:09:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:24 INFO - 'MOZ_NO_REMOTE': '1', 20:09:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:24 INFO - 'NO_EM_RESTART': '1', 20:09:24 INFO - 'PAGER': '/bin/cat', 20:09:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:24 INFO - 'PWD': '/builds/slave/test', 20:09:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:24 INFO - 'RUST_BACKTRACE': '1', 20:09:24 INFO - 'SHELL': '/bin/bash', 20:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p0blLpvmky/Listeners', 20:09:24 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:24 INFO - 'USER': 'cltbld', 20:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:24 INFO - 'XPC_FLAGS': '0x0', 20:09:24 INFO - 'XPC_SERVICE_NAME': '0', 20:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:09:24 INFO - mozversion application_buildid: 20170327184522 20:09:24 INFO - mozversion application_changeset: db2babffcafbd6b5b47a8c5813005838eb171a90 20:09:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:09:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:24 INFO - mozversion application_name: Firefox 20:09:24 INFO - mozversion application_remotingname: firefox-dev 20:09:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:09:24 INFO - mozversion application_vendor: Mozilla 20:09:24 INFO - mozversion application_version: 54.0a2 20:09:24 INFO - mozversion platform_buildid: 20170327184522 20:09:24 INFO - mozversion platform_changeset: db2babffcafbd6b5b47a8c5813005838eb171a90 20:09:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:09:24 INFO - mozversion platform_version: 54.0a2 20:09:24 INFO - using testdate: 1490670564 20:09:24 INFO - actual date: 1490670564 20:09:24 INFO - starting webserver on 'localhost:64368' 20:09:24 INFO - SUITE-START | Running 2 tests 20:09:24 INFO - TEST-START | dromaeo_css 20:09:24 INFO - operating with platform_type : mac_ 20:09:24 INFO - Initialising browser for dromaeo_css test... 20:09:24 INFO - TEST-INFO | started process 3306 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpWStjyo/profile http://localhost:64368/getInfo.html) 20:09:27 INFO - PROCESS | 3306 | 2017-03-27 20:09:27.331 plugin-container[3308:103722] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 20:09:27 INFO - PROCESS | 3306 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:27 INFO - PROCESS | 3306 | 2017-03-27 20:09:27.334 plugin-container[3308:103722] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 20:09:27 INFO - PROCESS | 3306 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:27 INFO - PROCESS | 3306 | 2017-03-27 20:09:27.334 plugin-container[3308:103722] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:27 INFO - PROCESS | 3306 | __metrics Screen width/height:1600/1200 20:09:27 INFO - PROCESS | 3306 | colorDepth:24 20:09:27 INFO - PROCESS | 3306 | Browser inner width/height: 1024/689 20:09:27 INFO - PROCESS | 3306 | __metrics 20:09:27 ERROR - PROCESS | 3306 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 20:09:28 INFO - TEST-INFO | 3306: exit 0 20:09:28 INFO - Browser initialized. 20:09:28 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:28 INFO - TEST-INFO | started process 3309 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpWStjyo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:30 INFO - PROCESS | 3309 | 2017-03-27 20:09:30.963 plugin-container[3310:103851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 20:09:30 INFO - PROCESS | 3309 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:30 INFO - PROCESS | 3309 | 2017-03-27 20:09:30.963 plugin-container[3310:103851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 20:09:30 INFO - PROCESS | 3309 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:30 INFO - PROCESS | 3309 | 2017-03-27 20:09:30.964 plugin-container[3310:103851] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:40 INFO - PROCESS | 3309 | RSS: Main: 220667904 20:09:40 INFO - PROCESS | 3309 | 20:12:44 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64368/tests/dromaeo/cssquery-ext.html) 20:12:46 INFO - PROCESS | 3309 | RSS: Main: 238755840 20:12:46 INFO - PROCESS | 3309 | 20:14:28 INFO - PROCESS | 3309 | ************************* 20:14:28 INFO - PROCESS | 3309 | A coding exception was thrown and uncaught in a Task. 20:14:28 INFO - PROCESS | 3309 | 20:14:28 ERROR - PROCESS | 3309 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:14:28 INFO - PROCESS | 3309 | Full stack: 20:14:28 INFO - PROCESS | 3309 | ************************* 20:15:49 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-ext.html (next: http://localhost:64368/tests/dromaeo/cssquery-jquery.html) 20:15:49 INFO - PROCESS | 3309 | RSS: Main: 249241600 20:15:49 INFO - PROCESS | 3309 | 20:18:52 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64368/tests/dromaeo/cssquery-mootools.html) 20:18:52 INFO - PROCESS | 3309 | RSS: Main: 255868928 20:18:52 INFO - PROCESS | 3309 | 20:21:54 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64368/tests/dromaeo/cssquery-prototype.html) 20:21:55 INFO - PROCESS | 3309 | RSS: Main: 252739584 20:21:55 INFO - PROCESS | 3309 | 20:24:58 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64368/tests/dromaeo/cssquery-yui.html) 20:24:58 INFO - PROCESS | 3309 | RSS: Main: 258015232 20:24:58 INFO - PROCESS | 3309 | 20:28:01 INFO - PROCESS | 3309 | Cycle 1(1): loaded http://localhost:64368/tests/dromaeo/cssquery-yui.html (next: http://localhost:64368/tests/dromaeo/cssquery-dojo.html) 20:28:02 INFO - PROCESS | 3309 | RSS: Main: 254197760 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | __start_tp_report 20:28:02 INFO - PROCESS | 3309 | _x_x_mozilla_page_load 20:28:02 INFO - PROCESS | 3309 | _x_x_mozilla_page_load_details 20:28:02 INFO - PROCESS | 3309 | |i|pagename|runs| 20:28:02 INFO - PROCESS | 3309 | |0;dojo.html;3478.9999999999986;3482.5472688550485;3484.285721427107;3516.47252912063;3535.6641119093692;6106.389361063897;6157.568970172089;6173.783917562886;6178.814635560935;6185.536084793636;6080.422359875815;6110.694465276741;6124.816255512331;6129.601575897564;6203;5448.809291674792;5463.863403414906;5509.724513774316;5514.448555144473;5607.57943154263;5568.554515638739;5576.33084029917;5590.245316882209;5592.245046918654;5615.887682246353;5365.7585408656705;5387.730613469331;5464;5567.192757050226;5570.053090974524;5515.310586176728;5518.586106042023;5529.723513824313;5534.806281780138;5568.554515638739;5418.376886658046;5501.449855014506;5512.91730624041;5543.445655434424;5596.992541342557;6209.130721698965;6209.658468784215;6242.469390101847;6254.937450625481;6274.686265686697;6251.9684252098805;6304.054391841215;6333.429991300805;6366.968165159191;6501.674916254168;6539.836504087389;6590.110335104795;6615.106960560359;6618.503612229054;6650.434713049348;6234.2830574483605;6239.376062393724;6296.118543403927;6302.18071650687;6308.842278943063;6338.30278669346;6426.807195784134;6638.336166383419;6756.594604323756;6772.051912732221;4972.552470277692;5337.839864804062;6296.716647750863;6386.9680651596445;6414.102025716404;6564.8358791030605;6578.440832529192;6586.440152586988;6592.17597800275;6604.933950660528;6366.331535188739;6461.903071453932;6497.967510162419;6506.837329066811;6511.902321465087;5624.550035997192;6076.392360763976;6249.750009999549;6307.306196318401;6527.67361631926;5187;5194.714290713971;5205.375354957429;5223.190405487156;5224.164133738584;4022.1955608877756;4173.64524015456;4610.239310513794;4628.884277893079;4667.766611669403;6189.381061893865;6247.906616342213;6284.214473190851;6286.685665716787;6301.369863013753;6280.183576135165;6457.354264573599;6463.741450341934;6507.642079685568;6526.216853977458;5984.251968503937;6008.759649614054;6056.454919057218;6253.593516421418;6265.8120256392385;5637.915431268534;5696.3449203341315;5749.108888122348;5796.3913789052385;5806.129080637938;6716.899246511306;6779.5932244064525;6833.624150671761;6834.760783372558;6857.55425897315;5374.892502150014;6310.400511951358;6373.521985850987;6481.740730370732;6605.53761236728;6784.3554862288;6807.965960170365;6813.488988325995;6832.385085342343;6840.794776156723;6476.0933469313395;6493.318201588765;6493.610383377012;6515.381038801306;6522.673866306761;6239.344868788712;6328.841778955563;6480.157579514633;6597.373249541286;6599.142111525471;6342.651154186564;6354;6402.10370548133;6408.839779005562;6418.133551970611;6427.292997770148;6429.51778616615;6429.7106630200815;6441.227052753699;6532.869342613216;6148.4773794227995;6177.104319873503;6208.161898143693;6282.560220784501;6323.146375239284;6288.402601752826;6299.96850015747;6312.305646378986;6370.713317900613;6412.198475190601;6317.842053948688;6327.430531252209;6328.588641738273;6336.049592561153;6338.302786693368 20:28:02 INFO - PROCESS | 3309 | |1;ext.html;755.6108604068904;834.7954751085985;855.9443636163649;881.088073843572;961.7066794627644;36672;37422.62577374226;37452.812735936284;38613.227735445274;39268.82193534191;37168.44247336292;37372.81313593435;37426.62573374266;37501.43747843778;37504.062398439986;7251.7461888833905;7419.740309089156;7771.533707977511;7935.246151615587;11013;11113.333200007984;11194.888051119466;11199.496022678959;11211.495482703298;11217.943910280477;11206.887931120707;11222.719432014183;11226.719332016683;11232.831507527393;11283.435828208598;9424.623015079389;11161.665150045512;11181.83227251592;11197.720056998558;11213.719657008558;9142.588583513758;10899.400532970683;10914.23600347976;10918.344899306067;10926.399048052353;31155.376892462024;37838.621613783784;38171.618283817086;38228;38234.80882595597;3886.5336159660737;3891.727579069466;3934.567197608261;3939.113699417608;3951.249262640089;23819.332646714738;24367.756322436726;26713.73286267132;28041.43917121646;28572;3004.489236829744;3046.7257946784894;3066.356065226305;3095.4273459410083;3150.8739650413954;340840;341283;342696;342908;344642.27678861446;41140.38289425661;41656;49158.508414916105;50035.24947125796;50229.74885125551;52606.47393526016;52931.412057638336;52983.470165298626;52989;53284.46715532795;14600.269986500633;14714.926425367805;14735.852641473663;14766;14789.038712483654;12689.555865544662;12725;12738.490460381667;12739.917107046005;12753.808692869614;12088;12184.268943863219;12186.756264874653;12255.93872030652;12322.952549033253;4300.913981720348;5826.242588463472;5873.119032145212;5874.50066744323;5877.823665290048;1061.4586560853866;1079.1959989807488;1096.2655021135859;1096.4024606589428;1166.2477701882237;26164.34589135249;26557.867210663822;26664.73335266661;27171.86414067917;27522.724772752015;1034.3070143004054;1153.763478486908;1172.2439026827649;1179.1215544419463;1202.6812894582943;13563;13779.931100344635;13786.724265514633;13930.164190148624;13933.303334833421;458830;462099;462455;462852;463275;52705.2094218587;52768.73615631897;52902;52974;53013.734931325096;1242.863285038645;1438.2377340009812;1469.169918995764;1471.7498025335503;1475.5130806833934;18606.81393186051;25118.995240190186;25605;25682.61476077823;26228.73771262339;46014.539854601026;46072.539274606825;46076;46137.307940381594;46301.53698463107;422004;422103;423240;425302;426675;26991.325216869736;27373.04194353188;32257.032289031366;33464;33544.83227583847;41513.754587362426;41619.7919010403;42210;42561.78719106509;42688;28883.5667464984;29258;30043;30062.398752025274;30236.84881575578;29024.419511609227;29193;29325;29737.553936691398;30092.24769380783;33925.83037084898;34457;34838.82580587081;34849.954501365;34896.47655285223;28663.856680716464;29588.556171657012;30195.849020754755;30286.242843929078;30423;23778.524429511657;23880.283591492283;24036.27891163268;24336.87831560831;24439.755602443747 20:28:02 INFO - PROCESS | 3309 | |2;jquery.html;390.60353740952957;399.6183644619388;405.91475790084064;423.34381708352055;445.38314434508214;19671.803281967175;20245;20706.378808635727;21497.24759633413;22040.889795551;21303.786962130373;21737.02183401747;21822.454438639;21973.340799775993;22008.779912200873;6604.943209086535;7011.964940175292;7030.964845175767;7034.2262351141335;7061.823454413655;6172.321044685081;6858.074159988388;6932.480063995195;6938.410235129994;6939.791806245821;6911;6918;6949.235584085746;7144.714211431537;7444.404447644202;6249.812505624793;7634.885476717854;7656.578888161149;7661.731839385595;7702.0757509098985;7360.742374016937;7414.443916706268;7452.031235939293;7453.664585093684;7459.1049074110915;24303.513929721485;25845.74154258452;26152.084677036404;26244.73755262442;26309.73690263111;3220.7745457817964;3284.425225585513;3351.8826841060445;3358.22760765023;3559.857605695795;15294.694106117815;15629.984051036647;15685.451009214737;15707.214639268104;16735.581610459834;1786.6783978883795;2042.693595960603;2073.554185850034;2087.2903212907686;2154.2675490333204;152879;154648.75878663504;159404;161692.19153904272;162332.18833905872;16539.66920661604;17020;19284.8071519283;19668;20758.065887035118;31520.211994700316;31594.210144746106;31757.523637145;31786.841065794986;31859.840700796347;9980.800383992424;10006.149477294366;10096.69709908704;10144.949275253724;10378.844317335246;8380.958095209608;8386.329093672492;8400.915990840136;8555.828883422297;8558.101399353103;9422.104900034486;9551.61793528265;9556.044395560388;9581.856272155923;9587.424754514752;3417.223044017097;3972.4835771350117;4047.129867078533;4086.366613174963;4090.959090409058;789.4000559574764;871.7689812199773;894.9639569978351;906.2387594420718;910.8087301666592;17916.55208619795;21054.578908422052;21576;21873.562528749335;21930.89034554817;808.0424696734271;842.4818736477145;909.3180114913814;924.2652091587204;945.6737425588161;8785.34109941757;9727.9027209727;9863;10703.785924281627;11447.37039462821;593483;633464;670160;684040;695315;102618.48690756499;117711;145811.2709436446;154819.45180548276;155179;1094.9288296260718;1156.9710757230969;1292.7608392447428;1331.2278878250786;1391.7703578909366;19486.12312445972;19856.404307870787;20140.79859201448;20211.8989405052;20324.491887702334;47959.56121316366;52345;54418.82324707126;55899.720501397234;63467.6826615864;90686.09313906776;91373.54313228391;91546.62680059938;93131.53434232785;93301.13397731872;10417.531211095671;11405.714857128638;11608.477618507019;11683.766324673288;12030.578929737418;12742.68143296425;12749.426275817425;12910.806337905127;12921.224726516439;12929.482820687068;10364.067233948981;10534.999175078356;10756.300840445347;10822.40476773785;11407.258528195973;10064.547095380873;10396.584136634752;10442.530086146293;10458;10584.947075264574;9682.661106861226;11272.267302625303;11537.01935335506;11597.246178998337;11660.125490588409;11519.942400287944;11534.250273732181;11606.47770850297;11658.825117623073;11895.286282823059;10783.622573210214;10968.725781855517;11257.549698011988;11296.096312295162;11367.920047595466 20:28:02 INFO - PROCESS | 3309 | |3;mootools.html;1552.8680062194712;1582.5727053695502;1599.272331089354;1602.2949902043104;1602.8076630804305;8789.384743067987;9398.295127865404;9570.330076894621;11340.886591134085;11373.658790236286;10279.331843430191;10618.522166502507;10621.52203150858;10652.573897044129;10836.729081772959;2348.095983046531;2395.8802059896934;2398.5202959408102;2403.975960240393;2410.855348679076;2363.61000434928;2393.1743548475747;2398.328468028955;2399.832011759169;2410.3612542676206;2224.165937773335;2251.324602619208;2348;2367.159658321297;2377.3105799318178;2405.194259922928;2409.3976505873534;2420.685310909588;2423.7091549014053;2429.8663573503363;2367.5620010297976;2383.1063351243283;2389.2712722619594;2389.6057150570136;2409.0484258717884;11166.553337866475;11271.154663400195;11332.6033588824;11349.602763903224;11351.545938162464;5764.971175144139;5888.911666325009;5966.403359664042;5973.313068997078;5979.1031345298115;3498.737594680409;3609.2601016791496;3613.5663720353464;3663.2673465307043;3665.596784353719;1172.267332916927;1178.4579093616946;1195.115614445313;1232.5069972011177;1241.6709571963436;15391.615209619737;15502.844971550252;15559.844401555953;15600.141992190425;15611.219439028004;4678.537284894821;4923.532264434873;4931.87670308238;4972.254161875748;5009.624278179078;12909.031822613342;12912.225266484042;12943.5469758558;12959.352032398343;12990.220586764824;5846.181534585076;5975.641461512323;5978.790742323998;5980.342162362137;5986.49114825236;4328.848490302824;4364.432623758891;4388.357663139586;5242.449542798027;5259.500347466985;4082.2702929646593;4115.670746340345;4191.790410479463;4236.9364459533135;4245.3631955206965;1125.060574420368;1138.4876805437586;1144.9656510304703;1151.6832870960552;1158.7624536969902;921.8893732752113;934.2899396458605;948.7770373962113;953.6519170502717;1002.4636819301638;6373.362663733589;6493.415592596689;6581.539292249592;6801.2178599461695;6868.278830722702;964.2141654551658;982.6904525074578;992;994.7413672445216;1030.407515678488;3608.891733248007;3703.8147792706077;4256.7445953242905;4298.720583162084;4409.470863496401;12092.851179137968;12444;13676.521321753691;13725.039247252596;13745;13546.93226533861;13690.65773355674;13701.657458563615;13713.520026799015;13716.177029378268;8428.157184281401;9244.399114057565;9793.951030244803;10063;10240.948795255976;5573.359063707563;5580.441955804387;6344.429001389897;6439.356064393523;6702.061711360511;11133.944330278297;11195.216334856483;12048.638540843789;12061.698457538632;12223.69440763988;12349.567765128177;12575.434105465092;12681.8097728536;12744.42650080767;12773.042021848585;7992.5204487730925;8176.182381761776;9025.3231007676;9065.90934090677;9314.394564353566;10113.342632728849;10463.581456741646;10475.057244848;10548.841767373342;10577.31247468912;8710.91289087101;8779.341549383695;8808.603612837309;8847.42491738035;8860.556972151495;7218.494705370574;8666.393352465268;8683.131686831266;8686.261667758312;8824.823503530022;7745.496542724705;7894.644740986554;7899.131095579367;7909.24862138096;7926.286634202949;5797.507211887037;5826.73779679907;5831.008728516247;5969.552283578836;6031.638101713911;5844.181814545882;5905.970470147622;6064.514838812973;6136.249083005993;6158.538109641884 20:28:02 INFO - PROCESS | 3309 | |4;prototype.html;370.04712864374227;373.193901173465;373.8224343436868;378.6667732395492;387.3666555182278;5286.894262114755;5342.519173274404;5403.945960540394;5404.270423492837;5419.566434685225;4357.760323182224;5154.484551544843;5247.895042099156;5299.629025968184;5306.761195746191;4367.08291258836;4450.710703804259;4615.607673347761;4625.213713668668;4643.187442197619;3884.941725874114;4088.897777555555;4429.42417485728;4595.655325850558;4596.954030459703;3624.112092537332;3913.4521167036637;4621.890746220916;4643.257078867382;4645.256758918574;4245.745255284693;4270.658347332206;4296.334068219416;4407.559244075599;4558.8632341029825;4332.101109001892;4387;4387.42963414754;4389.078293558357;4434.467863856358;4757.286407038937;5192.091384007821;5236.6857988520815;5243.79024839006;5252.789888404459;4718.593517306164;4999.775010124553;5163.836163836164;5350.250964864922;5428.321459817523;5707.316341679215;5747.827565173052;5761.300416377178;5772.816572602648;5774.131332007565;4419.734815911023;4592.33411155384;4727;4919.754012299407;5014.222795466709;5151.5878729701535;5441.455854414567;5794.710264486759;6022.879542409171;6037.732076264034;4706.717596944194;4828.444728856227;5124.385073791094;5133.845984620468;5174.508421699932;4363.759993200341;4497.77511124449;4905.092557876804;5278.894422111536;5390.649607775482;4154.459920210354;5157.813702848366;5380.461953804666;5397.325334333208;5447.291852059287;5215.608829337815;5508.3559891251625;5749.642646514909;5795.623284486495;5802.607374230239;1539.2073082362592;1611.548766345425;1794.5154808201712;1810.2939853457162;1814.945551633453;1342.3556692787517;1352.7565038293005;1397.6645605054916;1414.0808474491662;1475.3950880139091;4649.976750116228;5233.319668443078;5238.8212652153725;5242.842714718565;5257;5042.495750424928;5508.55931525477;5762.625429347079;5813.82558523245;5870.530357571384;3526.7002304803864;4379.1241751649795;4805.927911081337;5013.947071115107;5324.21638751015;2573.6156953605946;2761.64098667176;2936.6916473770375;3047.9695203047686;3060.4338197433544;5250.579953603703;5607.971960140173;5829.679367634821;6048.425399587032;6053.606515576562;4679.532046795293;5758.654480731169;5901.701625642352;6050;6067.7876274331065;5111.412187598474;5767.923547522889;6026.186464827279;6031.517478601701;6154.476869466056;5055.823046193365;5179.171332586768;5876.147958545901;5921.5558833086825;5937;5771.394003629558;5804.37477506282;5843.561732870136;5852.356240813422;5858.326292476249;5793.449622285876;5858.7070646468355;6112.694365281807;6195.907061394173;6210;4018.698597605133;4183.602557757008;4332.285172946428;4638.605718513964;4648.721076735272;5106.438291787975;5761.769529218952;5862.794802181903;5866.6480011199465;5876.44173803488;4955.1576232039915;5703.918069904538;5704.258446401941;5735.336752341774;5926.496247818984;4649.861063910639;5059.797608095782;5290.365156181283;5661.876796201424;5710.457506536873;5570.5265052470995;5595.272614560081;5631.831045068655;5687.630304030225;5691.686957217393;4773.9045219096115;4977.328060711902;5284.908328627642;5740.995325817881;5750.5112065474905;4929.482404347636;5118.667286626357;5491.588130890279;5512.972435137799;5517.372375149261 20:28:02 INFO - PROCESS | 3309 | |5;yui.html;683.87348340557;719.4496210399043;738.2285511640339;757.1860250231001;848.3933987199154;15313;15892.761608575865;16359.100249486273;16470.423535176273;16733.581660458494;14925.701485970274;15217.54347369578;15314.081155130672;15493.992890462112;15504;876.0713643537854;931.2503434735036;932.3007744191856;932.5197523275518;932.5290728182292;910.1581037540282;952.7951490429556;958.6357184270003;959.5538074795219;961.048561923694;999.9900001000007;1004.7287232447235;1005.3113617172224;1005.7485628592851;1007.1137399088792;840.9285210757093;1000.2298230362653;1000.559753708366;1001.5843424978661;1002.0730823987818;741.0107506478857;747.7308169059135;748.9063867016623;748.9251074892521;749.232037161908;13190.60428187156;16494.587635309093;16553.917230413772;16733.581660458585;17002.91498542512;12046;14205.715885682228;14405.495807646786;14498;14531.636709082357;10011.449370284632;11132.443377831076;11835.927354098858;11944.104192185534;11958.521659133625;3621.167131559756;3626.38351480249;3634.341548666397;3644.671979521829;3743.1952130291847;113013;113577.09637545317;119867;120041;120284;9430.95284523573;10614.681559553226;10744.194185436123;11095.83356249657;11397.430128493543;22578.774212257995;22752.772472275396;22844.65733014006;22919;22937.197198097987;7608.923910760822;7619.885701714478;7683.462157648911;7701.884471732928;7813.726519571787;4222.282212023963;4285.849995250213;4309;4327.956720432819;4385.605295523418;3520.612732599463;3591.102224443889;3611.566612006576;3619.2580520993133;3621.257642183346;535.7053620508705;642.3608509533044;647.3591144766647;649.3409189672486;659.2912618934569;665.7703092433103;676.6075676107846;681.3935597318391;693.2963042511855;695.6069820551372;10246.813424181684;10456.63401780934;10557.788844223227;10680.453037683417;10718.732031699114;672.9293424190389;706.6254884911006;709.3190537084457;709.6912842913349;719.1837264704598;4292;5069.163588008011;5080.54275115234;5095.184770436712;5097.770600322994;85026;108939;109677;111569;111869;63181;65143.69712605816;65371.69256614936;73528.63235683787;83188;58424;67633.66183169151;68737.06831925653;72543;75153;9833.508324583885;10949.50727217293;11089.445527723743;11341.149413793833;11403.372814495282;39248.80375598218;43036;45296.773516132205;47804;51932.74033629808;55954.160687588905;62167;62427.6878615604;62851.42871428251;63116.68441657762;12304.815427768412;12442.75114497723;12551.93724031374;12666.176698514568;12919.160254583421;11884.46519906589;12673.556425525063;12790.360481975678;12989.480420783062;13395;11799.705007374885;11929.701757456132;11944;12129.150959432754;12482.687932801753;11549.36478493691;11961.521539138337;12103.818442723543;12121.333326666765;12319.815202771786;8904.421212621159;10844.132469402271;11690.47392867306;11733.120015999006;12267.57063502773;7370.926290737239;8568.186022327869;8941.910580894108;8967.058458861986;9031.412958157698;9015.549222538977;9304.534773261445;9737.756556086155;10183.439910804977;10538.841917371092 20:28:02 INFO - PROCESS | 3309 | __end_tp_report 20:28:02 INFO - PROCESS | 3309 | __start_cc_report 20:28:02 INFO - PROCESS | 3309 | _x_x_mozilla_cycle_collect,411 20:28:02 INFO - PROCESS | 3309 | __end_cc_report 20:28:02 INFO - PROCESS | 3309 | __startTimestamp1490671682147__endTimestamp 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | ------- Summary: start ------- 20:28:02 INFO - PROCESS | 3309 | Number of tests: 6 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#0] dojo.html Cycles:165 Average:6038.55 Median:6284.21 stddev:692.25 (11.0%) stddev-sans-first:664.61 20:28:02 INFO - PROCESS | 3309 | Values: 3479.0 3482.5 3484.3 3516.5 3535.7 6106.4 6157.6 6173.8 6178.8 6185.5 6080.4 6110.7 6124.8 6129.6 6203.0 5448.8 5463.9 5509.7 5514.4 5607.6 5568.6 5576.3 5590.2 5592.2 5615.9 5365.8 5387.7 5464.0 5567.2 5570.1 5515.3 5518.6 5529.7 5534.8 5568.6 5418.4 5501.4 5512.9 5543.4 5597.0 6209.1 6209.7 6242.5 6254.9 6274.7 6252.0 6304.1 6333.4 6367.0 6501.7 6539.8 6590.1 6615.1 6618.5 6650.4 6234.3 6239.4 6296.1 6302.2 6308.8 6338.3 6426.8 6638.3 6756.6 6772.1 4972.6 5337.8 6296.7 6387.0 6414.1 6564.8 6578.4 6586.4 6592.2 6604.9 6366.3 6461.9 6498.0 6506.8 6511.9 5624.6 6076.4 6249.8 6307.3 6527.7 5187.0 5194.7 5205.4 5223.2 5224.2 4022.2 4173.6 4610.2 4628.9 4667.8 6189.4 6247.9 6284.2 6286.7 6301.4 6280.2 6457.4 6463.7 6507.6 6526.2 5984.3 6008.8 6056.5 6253.6 6265.8 5637.9 5696.3 5749.1 5796.4 5806.1 6716.9 6779.6 6833.6 6834.8 6857.6 5374.9 6310.4 6373.5 6481.7 6605.5 6784.4 6808.0 6813.5 6832.4 6840.8 6476.1 6493.3 6493.6 6515.4 6522.7 6239.3 6328.8 6480.2 6597.4 6599.1 6342.7 6354.0 6402.1 6408.8 6418.1 6427.3 6429.5 6429.7 6441.2 6532.9 6148.5 6177.1 6208.2 6282.6 6323.1 6288.4 6300.0 6312.3 6370.7 6412.2 6317.8 6327.4 6328.6 6336.0 6338.3 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#1] ext.html Cycles:180 Average:54421.36 Median:30354.62 stddev:109374.94 (360.3%) stddev-sans-first:109607.55 20:28:02 INFO - PROCESS | 3309 | Values: 755.6 834.8 855.9 881.1 961.7 36672.0 37422.6 37452.8 38613.2 39268.8 37168.4 37372.8 37426.6 37501.4 37504.1 7251.7 7419.7 7771.5 7935.2 11013.0 11113.3 11194.9 11199.5 11211.5 11217.9 11206.9 11222.7 11226.7 11232.8 11283.4 9424.6 11161.7 11181.8 11197.7 11213.7 9142.6 10899.4 10914.2 10918.3 10926.4 31155.4 37838.6 38171.6 38228.0 38234.8 3886.5 3891.7 3934.6 3939.1 3951.2 23819.3 24367.8 26713.7 28041.4 28572.0 3004.5 3046.7 3066.4 3095.4 3150.9 340840.0 341283.0 342696.0 342908.0 344642.3 41140.4 41656.0 49158.5 50035.2 50229.7 52606.5 52931.4 52983.5 52989.0 53284.5 14600.3 14714.9 14735.9 14766.0 14789.0 12689.6 12725.0 12738.5 12739.9 12753.8 12088.0 12184.3 12186.8 12255.9 12323.0 4300.9 5826.2 5873.1 5874.5 5877.8 1061.5 1079.2 1096.3 1096.4 1166.2 26164.3 26557.9 26664.7 27171.9 27522.7 1034.3 1153.8 1172.2 1179.1 1202.7 13563.0 13779.9 13786.7 13930.2 13933.3 458830.0 462099.0 462455.0 462852.0 463275.0 52705.2 52768.7 52902.0 52974.0 53013.7 1242.9 1438.2 1469.2 1471.7 1475.5 18606.8 25119.0 25605.0 25682.6 26228.7 46014.5 46072.5 46076.0 46137.3 46301.5 422004.0 422103.0 423240.0 425302.0 426675.0 26991.3 27373.0 32257.0 33464.0 33544.8 41513.8 41619.8 42210.0 42561.8 42688.0 28883.6 29258.0 30043.0 30062.4 30236.8 29024.4 29193.0 29325.0 29737.6 30092.2 33925.8 34457.0 34838.8 34850.0 34896.5 28663.9 29588.6 30195.8 30286.2 30423.0 23778.5 23880.3 24036.3 24336.9 24439.8 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#2] jquery.html Cycles:180 Average:39913.42 Median:25074.63 stddev:110142.19 (439.3%) stddev-sans-first:110411.19 20:28:02 INFO - PROCESS | 3309 | Values: 390.6 399.6 405.9 423.3 445.4 19671.8 20245.0 20706.4 21497.2 22040.9 21303.8 21737.0 21822.5 21973.3 22008.8 6604.9 7012.0 7031.0 7034.2 7061.8 6172.3 6858.1 6932.5 6938.4 6939.8 6911.0 6918.0 6949.2 7144.7 7444.4 6249.8 7634.9 7656.6 7661.7 7702.1 7360.7 7414.4 7452.0 7453.7 7459.1 24303.5 25845.7 26152.1 26244.7 26309.7 3220.8 3284.4 3351.9 3358.2 3559.9 15294.7 15630.0 15685.5 15707.2 16735.6 1786.7 2042.7 2073.6 2087.3 2154.3 152879.0 154648.8 159404.0 161692.2 162332.2 16539.7 17020.0 19284.8 19668.0 20758.1 31520.2 31594.2 31757.5 31786.8 31859.8 9980.8 10006.1 10096.7 10144.9 10378.8 8381.0 8386.3 8400.9 8555.8 8558.1 9422.1 9551.6 9556.0 9581.9 9587.4 3417.2 3972.5 4047.1 4086.4 4091.0 789.4 871.8 895.0 906.2 910.8 17916.6 21054.6 21576.0 21873.6 21930.9 808.0 842.5 909.3 924.3 945.7 8785.3 9727.9 9863.0 10703.8 11447.4 593483.0 633464.0 670160.0 684040.0 695315.0 102618.5 117711.0 145811.3 154819.5 155179.0 1094.9 1157.0 1292.8 1331.2 1391.8 19486.1 19856.4 20140.8 20211.9 20324.5 47959.6 52345.0 54418.8 55899.7 63467.7 90686.1 91373.5 91546.6 93131.5 93301.1 10417.5 11405.7 11608.5 11683.8 12030.6 12742.7 12749.4 12910.8 12921.2 12929.5 10364.1 10535.0 10756.3 10822.4 11407.3 10064.5 10396.6 10442.5 10458.0 10584.9 9682.7 11272.3 11537.0 11597.2 11660.1 11519.9 11534.3 11606.5 11658.8 11895.3 10783.6 10968.7 11257.5 11296.1 11367.9 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#3] mootools.html Cycles:180 Average:6700.23 Median:2964.30 stddev:4203.99 (141.8%) stddev-sans-first:4198.00 20:28:02 INFO - PROCESS | 3309 | Values: 1552.9 1582.6 1599.3 1602.3 1602.8 8789.4 9398.3 9570.3 11340.9 11373.7 10279.3 10618.5 10621.5 10652.6 10836.7 2348.1 2395.9 2398.5 2404.0 2410.9 2363.6 2393.2 2398.3 2399.8 2410.4 2224.2 2251.3 2348.0 2367.2 2377.3 2405.2 2409.4 2420.7 2423.7 2429.9 2367.6 2383.1 2389.3 2389.6 2409.0 11166.6 11271.2 11332.6 11349.6 11351.5 5765.0 5888.9 5966.4 5973.3 5979.1 3498.7 3609.3 3613.6 3663.3 3665.6 1172.3 1178.5 1195.1 1232.5 1241.7 15391.6 15502.8 15559.8 15600.1 15611.2 4678.5 4923.5 4931.9 4972.3 5009.6 12909.0 12912.2 12943.5 12959.4 12990.2 5846.2 5975.6 5978.8 5980.3 5986.5 4328.8 4364.4 4388.4 5242.4 5259.5 4082.3 4115.7 4191.8 4236.9 4245.4 1125.1 1138.5 1145.0 1151.7 1158.8 921.9 934.3 948.8 953.7 1002.5 6373.4 6493.4 6581.5 6801.2 6868.3 964.2 982.7 992.0 994.7 1030.4 3608.9 3703.8 4256.7 4298.7 4409.5 12092.9 12444.0 13676.5 13725.0 13745.0 13546.9 13690.7 13701.7 13713.5 13716.2 8428.2 9244.4 9794.0 10063.0 10240.9 5573.4 5580.4 6344.4 6439.4 6702.1 11133.9 11195.2 12048.6 12061.7 12223.7 12349.6 12575.4 12681.8 12744.4 12773.0 7992.5 8176.2 9025.3 9065.9 9314.4 10113.3 10463.6 10475.1 10548.8 10577.3 8710.9 8779.3 8808.6 8847.4 8860.6 7218.5 8666.4 8683.1 8686.3 8824.8 7745.5 7894.6 7899.1 7909.2 7926.3 5797.5 5826.7 5831.0 5969.6 6031.6 5844.2 5906.0 6064.5 6136.2 6158.5 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#4] prototype.html Cycles:180 Average:4805.47 Median:5203.85 stddev:1309.05 (25.2%) stddev-sans-first:1269.68 20:28:02 INFO - PROCESS | 3309 | Values: 370.0 373.2 373.8 378.7 387.4 5286.9 5342.5 5403.9 5404.3 5419.6 4357.8 5154.5 5247.9 5299.6 5306.8 4367.1 4450.7 4615.6 4625.2 4643.2 3884.9 4088.9 4429.4 4595.7 4597.0 3624.1 3913.5 4621.9 4643.3 4645.3 4245.7 4270.7 4296.3 4407.6 4558.9 4332.1 4387.0 4387.4 4389.1 4434.5 4757.3 5192.1 5236.7 5243.8 5252.8 4718.6 4999.8 5163.8 5350.3 5428.3 5707.3 5747.8 5761.3 5772.8 5774.1 4419.7 4592.3 4727.0 4919.8 5014.2 5151.6 5441.5 5794.7 6022.9 6037.7 4706.7 4828.4 5124.4 5133.8 5174.5 4363.8 4497.8 4905.1 5278.9 5390.6 4154.5 5157.8 5380.5 5397.3 5447.3 5215.6 5508.4 5749.6 5795.6 5802.6 1539.2 1611.5 1794.5 1810.3 1814.9 1342.4 1352.8 1397.7 1414.1 1475.4 4650.0 5233.3 5238.8 5242.8 5257.0 5042.5 5508.6 5762.6 5813.8 5870.5 3526.7 4379.1 4805.9 5013.9 5324.2 2573.6 2761.6 2936.7 3048.0 3060.4 5250.6 5608.0 5829.7 6048.4 6053.6 4679.5 5758.7 5901.7 6050.0 6067.8 5111.4 5767.9 6026.2 6031.5 6154.5 5055.8 5179.2 5876.1 5921.6 5937.0 5771.4 5804.4 5843.6 5852.4 5858.3 5793.4 5858.7 6112.7 6195.9 6210.0 4018.7 4183.6 4332.3 4638.6 4648.7 5106.4 5761.8 5862.8 5866.6 5876.4 4955.2 5703.9 5704.3 5735.3 5926.5 4649.9 5059.8 5290.4 5661.9 5710.5 5570.5 5595.3 5631.8 5687.6 5691.7 4773.9 4977.3 5284.9 5741.0 5750.5 4929.5 5118.7 5491.6 5513.0 5517.4 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - PROCESS | 3309 | [#5] yui.html Cycles:180 Average:19614.50 Median:3621.21 stddev:28790.89 (795.1%) stddev-sans-first:28836.57 20:28:02 INFO - PROCESS | 3309 | Values: 683.9 719.4 738.2 757.2 848.4 15313.0 15892.8 16359.1 16470.4 16733.6 14925.7 15217.5 15314.1 15494.0 15504.0 876.1 931.3 932.3 932.5 932.5 910.2 952.8 958.6 959.6 961.0 1000.0 1004.7 1005.3 1005.7 1007.1 840.9 1000.2 1000.6 1001.6 1002.1 741.0 747.7 748.9 748.9 749.2 13190.6 16494.6 16553.9 16733.6 17002.9 12046.0 14205.7 14405.5 14498.0 14531.6 10011.4 11132.4 11835.9 11944.1 11958.5 3621.2 3626.4 3634.3 3644.7 3743.2 113013.0 113577.1 119867.0 120041.0 120284.0 9431.0 10614.7 10744.2 11095.8 11397.4 22578.8 22752.8 22844.7 22919.0 22937.2 7608.9 7619.9 7683.5 7701.9 7813.7 4222.3 4285.8 4309.0 4328.0 4385.6 3520.6 3591.1 3611.6 3619.3 3621.3 535.7 642.4 647.4 649.3 659.3 665.8 676.6 681.4 693.3 695.6 10246.8 10456.6 10557.8 10680.5 10718.7 672.9 706.6 709.3 709.7 719.2 4292.0 5069.2 5080.5 5095.2 5097.8 85026.0 108939.0 109677.0 111569.0 111869.0 63181.0 65143.7 65371.7 73528.6 83188.0 58424.0 67633.7 68737.1 72543.0 75153.0 9833.5 10949.5 11089.4 11341.1 11403.4 39248.8 43036.0 45296.8 47804.0 51932.7 55954.2 62167.0 62427.7 62851.4 63116.7 12304.8 12442.8 12551.9 12666.2 12919.2 11884.5 12673.6 12790.4 12989.5 13395.0 11799.7 11929.7 11944.0 12129.2 12482.7 11549.4 11961.5 12103.8 12121.3 12319.8 8904.4 10844.1 11690.5 11733.1 12267.6 7370.9 8568.2 8941.9 8967.1 9031.4 9015.5 9304.5 9737.8 10183.4 10538.8 20:28:02 INFO - PROCESS | 3309 | -------- Summary: end -------- 20:28:02 INFO - PROCESS | 3309 | 20:28:02 INFO - TEST-INFO | 3309: exit 0 20:28:02 INFO - TEST-OK | dromaeo_css | took 1118422ms 20:28:02 INFO - TEST-START | kraken 20:28:02 INFO - operating with platform_type : mac_ 20:28:02 INFO - Initialising browser for kraken test... 20:28:02 INFO - TEST-INFO | started process 3318 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpjbaTqN/profile http://localhost:64368/getInfo.html) 20:28:05 INFO - PROCESS | 3318 | 2017-03-27 20:28:05.888 plugin-container[3319:106059] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 20:28:05 INFO - PROCESS | 3318 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:28:05 INFO - PROCESS | 3318 | 2017-03-27 20:28:05.892 plugin-container[3319:106059] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 20:28:05 INFO - PROCESS | 3318 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:28:05 INFO - PROCESS | 3318 | 2017-03-27 20:28:05.892 plugin-container[3319:106059] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:28:05 INFO - PROCESS | 3318 | __metrics Screen width/height:1600/1200 20:28:05 INFO - PROCESS | 3318 | colorDepth:24 20:28:05 INFO - PROCESS | 3318 | Browser inner width/height: 1024/689 20:28:05 INFO - PROCESS | 3318 | __metrics 20:28:06 ERROR - PROCESS | 3318 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 20:28:06 INFO - TEST-INFO | 3318: exit 0 20:28:06 INFO - Browser initialized. 20:28:06 INFO - Running cycle 1/1 for kraken test... 20:28:06 INFO - TEST-INFO | started process 3320 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpjbaTqN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:09 INFO - PROCESS | 3320 | 2017-03-27 20:28:09.302 plugin-container[3321:106177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:28:09 INFO - PROCESS | 3320 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:28:09 INFO - PROCESS | 3320 | 2017-03-27 20:28:09.303 plugin-container[3321:106177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.CFPasteboardClient' 20:28:09 INFO - PROCESS | 3320 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:28:09 INFO - PROCESS | 3320 | 2017-03-27 20:28:09.303 plugin-container[3321:106177] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:28:19 INFO - PROCESS | 3320 | RSS: Main: 220831744 20:28:19 INFO - PROCESS | 3320 | 20:28:21 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64368/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:22 INFO - PROCESS | 3320 | RSS: Main: 235208704 20:28:22 INFO - PROCESS | 3320 | 20:28:25 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64368/tests/kraken/driver.html?testName=audio-dft) 20:28:26 INFO - PROCESS | 3320 | RSS: Main: 243212288 20:28:26 INFO - PROCESS | 3320 | 20:28:29 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64368/tests/kraken/driver.html?testName=audio-fft) 20:28:30 INFO - PROCESS | 3320 | RSS: Main: 247099392 20:28:30 INFO - PROCESS | 3320 | 20:28:32 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64368/tests/kraken/driver.html?testName=audio-oscillator) 20:28:32 INFO - PROCESS | 3320 | RSS: Main: 247554048 20:28:32 INFO - PROCESS | 3320 | 20:28:34 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64368/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:35 INFO - PROCESS | 3320 | RSS: Main: 247418880 20:28:35 INFO - PROCESS | 3320 | 20:28:38 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64368/tests/kraken/driver.html?testName=imaging-darkroom) 20:28:39 INFO - PROCESS | 3320 | RSS: Main: 251330560 20:28:39 INFO - PROCESS | 3320 | 20:28:42 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64368/tests/kraken/driver.html?testName=imaging-desaturate) 20:28:42 INFO - PROCESS | 3320 | RSS: Main: 250433536 20:28:42 INFO - PROCESS | 3320 | 20:28:46 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64368/tests/kraken/driver.html?testName=json-parse-financial) 20:28:46 INFO - PROCESS | 3320 | RSS: Main: 251789312 20:28:46 INFO - PROCESS | 3320 | 20:28:48 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64368/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:28:48 INFO - PROCESS | 3320 | RSS: Main: 251449344 20:28:48 INFO - PROCESS | 3320 | 20:28:50 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:28:51 INFO - PROCESS | 3320 | RSS: Main: 251535360 20:28:51 INFO - PROCESS | 3320 | 20:28:53 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:54 INFO - PROCESS | 3320 | RSS: Main: 251559936 20:28:54 INFO - PROCESS | 3320 | 20:28:57 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:28:57 INFO - PROCESS | 3320 | RSS: Main: 251998208 20:28:57 INFO - PROCESS | 3320 | 20:29:00 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:01 INFO - PROCESS | 3320 | RSS: Main: 251731968 20:29:01 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | Cycle 1(1): loaded http://localhost:64368/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64368/tests/kraken/driver.html?testName=ai-astar) 20:29:03 INFO - PROCESS | 3320 | RSS: Main: 253693952 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | __start_tp_report 20:29:03 INFO - PROCESS | 3320 | _x_x_mozilla_page_load 20:29:03 INFO - PROCESS | 3320 | _x_x_mozilla_page_load_details 20:29:03 INFO - PROCESS | 3320 | |i|pagename|runs| 20:29:03 INFO - PROCESS | 3320 | |0;ai-astar;107;122;116;127;145;117;117;120;119;117 20:29:03 INFO - PROCESS | 3320 | |1;audio-beat-detection;149;113;122;177;109;112;111;109;174;143 20:29:03 INFO - PROCESS | 3320 | |2;audio-dft;193;187;274;276;210;199;204;198;202;199 20:29:03 INFO - PROCESS | 3320 | |3;audio-fft;84;62;61;85;79;72;61;64;79;73 20:29:03 INFO - PROCESS | 3320 | |4;audio-oscillator;90;71;86;73;72;73;104;101;102;93 20:29:03 INFO - PROCESS | 3320 | |5;imaging-gaussian-blur;99;88;89;89;100;89;89;89;91;89 20:29:03 INFO - PROCESS | 3320 | |6;imaging-darkroom;97;98;97;148;135;97;127;98;153;99 20:29:03 INFO - PROCESS | 3320 | |7;imaging-desaturate;91;95;90;93;109;110;91;91;113;109 20:29:03 INFO - PROCESS | 3320 | |8;json-parse-financial;68;66;65;65;71;68;68;68;69;68 20:29:03 INFO - PROCESS | 3320 | |9;json-stringify-tinderbox;57;65;56;60;62;63;55;66;83;85 20:29:03 INFO - PROCESS | 3320 | |10;stanford-crypto-aes;83;87;83;87;83;87;85;86;84;83 20:29:03 INFO - PROCESS | 3320 | |11;stanford-crypto-ccm;140;139;140;154;136;153;156;158;181;140 20:29:03 INFO - PROCESS | 3320 | |12;stanford-crypto-pbkdf2;171;166;168;167;168;170;167;168;169;165 20:29:03 INFO - PROCESS | 3320 | |13;stanford-crypto-sha256-iterative;66;67;65;66;67;65;65;67;68;81 20:29:03 INFO - PROCESS | 3320 | __end_tp_report 20:29:03 INFO - PROCESS | 3320 | __start_cc_report 20:29:03 INFO - PROCESS | 3320 | _x_x_mozilla_cycle_collect,1024 20:29:03 INFO - PROCESS | 3320 | __end_cc_report 20:29:03 INFO - PROCESS | 3320 | __startTimestamp1490671743417__endTimestamp 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | ------- Summary: start ------- 20:29:03 INFO - PROCESS | 3320 | Number of tests: 14 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#0] ai-astar Cycles:10 Average:120.70 Median:119.50 stddev:9.92 (8.3%) stddev-sans-first:9.20 20:29:03 INFO - PROCESS | 3320 | Values: 107.0 122.0 116.0 127.0 145.0 117.0 117.0 120.0 119.0 117.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:132.50 stddev:27.00 (20.4%) stddev-sans-first:27.92 20:29:03 INFO - PROCESS | 3320 | Values: 149.0 113.0 122.0 177.0 109.0 112.0 111.0 109.0 174.0 143.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#2] audio-dft Cycles:10 Average:214.20 Median:203.00 stddev:32.63 (16.1%) stddev-sans-first:33.69 20:29:03 INFO - PROCESS | 3320 | Values: 193.0 187.0 274.0 276.0 210.0 199.0 204.0 198.0 202.0 199.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#3] audio-fft Cycles:10 Average:72.00 Median:76.00 stddev:9.53 (12.5%) stddev-sans-first:9.07 20:29:03 INFO - PROCESS | 3320 | Values: 84.0 62.0 61.0 85.0 79.0 72.0 61.0 64.0 79.0 73.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:73.00 stddev:13.44 (18.4%) stddev-sans-first:14.20 20:29:03 INFO - PROCESS | 3320 | Values: 90.0 71.0 86.0 73.0 72.0 73.0 104.0 101.0 102.0 93.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:89.00 stddev:4.44 (5.0%) stddev-sans-first:3.71 20:29:03 INFO - PROCESS | 3320 | Values: 99.0 88.0 89.0 89.0 100.0 89.0 89.0 89.0 91.0 89.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:97.00 stddev:23.29 (24.0%) stddev-sans-first:23.79 20:29:03 INFO - PROCESS | 3320 | Values: 97.0 98.0 97.0 148.0 135.0 97.0 127.0 98.0 153.0 99.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:91.00 stddev:9.67 (10.6%) stddev-sans-first:9.79 20:29:03 INFO - PROCESS | 3320 | Values: 91.0 95.0 90.0 93.0 109.0 110.0 91.0 91.0 113.0 109.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.84 (2.7%) stddev-sans-first:1.94 20:29:03 INFO - PROCESS | 3320 | Values: 68.0 66.0 65.0 65.0 71.0 68.0 68.0 68.0 69.0 68.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:64.00 stddev:10.58 (16.5%) stddev-sans-first:10.80 20:29:03 INFO - PROCESS | 3320 | Values: 57.0 65.0 56.0 60.0 62.0 63.0 55.0 66.0 83.0 85.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.50 stddev:1.81 (2.1%) stddev-sans-first:1.80 20:29:03 INFO - PROCESS | 3320 | Values: 83.0 87.0 83.0 87.0 83.0 87.0 85.0 86.0 84.0 83.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:153.50 stddev:13.75 (9.0%) stddev-sans-first:14.13 20:29:03 INFO - PROCESS | 3320 | Values: 140.0 139.0 140.0 154.0 136.0 153.0 156.0 158.0 181.0 140.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.79 (1.1%) stddev-sans-first:1.51 20:29:03 INFO - PROCESS | 3320 | Values: 171.0 166.0 168.0 167.0 168.0 170.0 167.0 168.0 169.0 165.0 20:29:03 INFO - PROCESS | 3320 | 20:29:03 INFO - PROCESS | 3320 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.00 stddev:4.79 (7.1%) stddev-sans-first:5.04 20:29:03 INFO - PROCESS | 3320 | Values: 66.0 67.0 65.0 66.0 67.0 65.0 65.0 67.0 68.0 81.0 20:29:03 INFO - PROCESS | 3320 | -------- Summary: end -------- 20:29:03 INFO - PROCESS | 3320 | 20:29:04 INFO - TEST-INFO | 3320: exit 0 20:29:04 INFO - TEST-OK | kraken | took 61528ms 20:29:04 INFO - SUITE-END | took 1179s 20:29:04 INFO - Completed test suite (00:19:40) 20:29:04 INFO - javascript benchmark 20:29:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7727.498525419237, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3478.9999999999986, 3482.5472688550485, 3484.285721427107, 3516.47252912063, 3535.6641119093692, 6106.389361063897, 6157.568970172089, 6173.783917562886, 6178.814635560935, 6185.536084793636, 6080.422359875815, 6110.694465276741, 6124.816255512331, 6129.601575897564, 6203.0, 5448.809291674792, 5463.863403414906, 5509.724513774316, 5514.448555144473, 5607.57943154263, 5568.554515638739, 5576.33084029917, 5590.245316882209, 5592.245046918654, 5615.887682246353, 5365.7585408656705, 5387.730613469331, 5464.0, 5567.192757050226, 5570.053090974524, 5515.310586176728, 5518.586106042023, 5529.723513824313, 5534.806281780138, 5568.554515638739, 5418.376886658046, 5501.449855014506, 5512.91730624041, 5543.445655434424, 5596.992541342557, 6209.130721698965, 6209.658468784215, 6242.469390101847, 6254.937450625481, 6274.686265686697, 6251.9684252098805, 6304.054391841215, 6333.429991300805, 6366.968165159191, 6501.674916254168, 6539.836504087389, 6590.110335104795, 6615.106960560359, 6618.503612229054, 6650.434713049348, 6234.2830574483605, 6239.376062393724, 6296.118543403927, 6302.18071650687, 6308.842278943063, 6338.30278669346, 6426.807195784134, 6638.336166383419, 6756.594604323756, 6772.051912732221, 4972.552470277692, 5337.839864804062, 6296.716647750863, 6386.9680651596445, 6414.102025716404, 6564.8358791030605, 6578.440832529192, 6586.440152586988, 6592.17597800275, 6604.933950660528, 6366.331535188739, 6461.903071453932, 6497.967510162419, 6506.837329066811, 6511.902321465087, 5624.550035997192, 6076.392360763976, 6249.750009999549, 6307.306196318401, 6527.67361631926, 5187.0, 5194.714290713971, 5205.375354957429, 5223.190405487156, 5224.164133738584, 4022.1955608877756, 4173.64524015456, 4610.239310513794, 4628.884277893079, 4667.766611669403, 6189.381061893865, 6247.906616342213, 6284.214473190851, 6286.685665716787, 6301.369863013753, 6280.183576135165, 6457.354264573599, 6463.741450341934, 6507.642079685568, 6526.216853977458, 5984.251968503937, 6008.759649614054, 6056.454919057218, 6253.593516421418, 6265.8120256392385, 5637.915431268534, 5696.3449203341315, 5749.108888122348, 5796.3913789052385, 5806.129080637938, 6716.899246511306, 6779.5932244064525, 6833.624150671761, 6834.760783372558, 6857.55425897315, 5374.892502150014, 6310.400511951358, 6373.521985850987, 6481.740730370732, 6605.53761236728, 6784.3554862288, 6807.965960170365, 6813.488988325995, 6832.385085342343, 6840.794776156723, 6476.0933469313395, 6493.318201588765, 6493.610383377012, 6515.381038801306, 6522.673866306761, 6239.344868788712, 6328.841778955563, 6480.157579514633, 6597.373249541286, 6599.142111525471, 6342.651154186564, 6354.0, 6402.10370548133, 6408.839779005562, 6418.133551970611, 6427.292997770148, 6429.51778616615, 6429.7106630200815, 6441.227052753699, 6532.869342613216, 6148.4773794227995, 6177.104319873503, 6208.161898143693, 6282.560220784501, 6323.146375239284, 6288.402601752826, 6299.96850015747, 6312.305646378986, 6370.713317900613, 6412.198475190601, 6317.842053948688, 6327.430531252209, 6328.588641738273, 6336.049592561153, 6338.302786693368], "value": 5993.346088294711, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.6108604068904, 834.7954751085985, 855.9443636163649, 881.088073843572, 961.7066794627644, 36672.0, 37422.62577374226, 37452.812735936284, 38613.227735445274, 39268.82193534191, 37168.44247336292, 37372.81313593435, 37426.62573374266, 37501.43747843778, 37504.062398439986, 7251.7461888833905, 7419.740309089156, 7771.533707977511, 7935.246151615587, 11013.0, 11113.333200007984, 11194.888051119466, 11199.496022678959, 11211.495482703298, 11217.943910280477, 11206.887931120707, 11222.719432014183, 11226.719332016683, 11232.831507527393, 11283.435828208598, 9424.623015079389, 11161.665150045512, 11181.83227251592, 11197.720056998558, 11213.719657008558, 9142.588583513758, 10899.400532970683, 10914.23600347976, 10918.344899306067, 10926.399048052353, 31155.376892462024, 37838.621613783784, 38171.618283817086, 38228.0, 38234.80882595597, 3886.5336159660737, 3891.727579069466, 3934.567197608261, 3939.113699417608, 3951.249262640089, 23819.332646714738, 24367.756322436726, 26713.73286267132, 28041.43917121646, 28572.0, 3004.489236829744, 3046.7257946784894, 3066.356065226305, 3095.4273459410083, 3150.8739650413954, 340840.0, 341283.0, 342696.0, 342908.0, 344642.27678861446, 41140.38289425661, 41656.0, 49158.508414916105, 50035.24947125796, 50229.74885125551, 52606.47393526016, 52931.412057638336, 52983.470165298626, 52989.0, 53284.46715532795, 14600.269986500633, 14714.926425367805, 14735.852641473663, 14766.0, 14789.038712483654, 12689.555865544662, 12725.0, 12738.490460381667, 12739.917107046005, 12753.808692869614, 12088.0, 12184.268943863219, 12186.756264874653, 12255.93872030652, 12322.952549033253, 4300.913981720348, 5826.242588463472, 5873.119032145212, 5874.50066744323, 5877.823665290048, 1061.4586560853866, 1079.1959989807488, 1096.2655021135859, 1096.4024606589428, 1166.2477701882237, 26164.34589135249, 26557.867210663822, 26664.73335266661, 27171.86414067917, 27522.724772752015, 1034.3070143004054, 1153.763478486908, 1172.2439026827649, 1179.1215544419463, 1202.6812894582943, 13563.0, 13779.931100344635, 13786.724265514633, 13930.164190148624, 13933.303334833421, 458830.0, 462099.0, 462455.0, 462852.0, 463275.0, 52705.2094218587, 52768.73615631897, 52902.0, 52974.0, 53013.734931325096, 1242.863285038645, 1438.2377340009812, 1469.169918995764, 1471.7498025335503, 1475.5130806833934, 18606.81393186051, 25118.995240190186, 25605.0, 25682.61476077823, 26228.73771262339, 46014.539854601026, 46072.539274606825, 46076.0, 46137.307940381594, 46301.53698463107, 422004.0, 422103.0, 423240.0, 425302.0, 426675.0, 26991.325216869736, 27373.04194353188, 32257.032289031366, 33464.0, 33544.83227583847, 41513.754587362426, 41619.7919010403, 42210.0, 42561.78719106509, 42688.0, 28883.5667464984, 29258.0, 30043.0, 30062.398752025274, 30236.84881575578, 29024.419511609227, 29193.0, 29325.0, 29737.553936691398, 30092.24769380783, 33925.83037084898, 34457.0, 34838.82580587081, 34849.954501365, 34896.47655285223, 28663.856680716464, 29588.556171657012, 30195.849020754755, 30286.242843929078, 30423.0, 23778.524429511657, 23880.283591492283, 24036.27891163268, 24336.87831560831, 24439.755602443747], "value": 18469.391407175415, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.60353740952957, 399.6183644619388, 405.91475790084064, 423.34381708352055, 445.38314434508214, 19671.803281967175, 20245.0, 20706.378808635727, 21497.24759633413, 22040.889795551, 21303.786962130373, 21737.02183401747, 21822.454438639, 21973.340799775993, 22008.779912200873, 6604.943209086535, 7011.964940175292, 7030.964845175767, 7034.2262351141335, 7061.823454413655, 6172.321044685081, 6858.074159988388, 6932.480063995195, 6938.410235129994, 6939.791806245821, 6911.0, 6918.0, 6949.235584085746, 7144.714211431537, 7444.404447644202, 6249.812505624793, 7634.885476717854, 7656.578888161149, 7661.731839385595, 7702.0757509098985, 7360.742374016937, 7414.443916706268, 7452.031235939293, 7453.664585093684, 7459.1049074110915, 24303.513929721485, 25845.74154258452, 26152.084677036404, 26244.73755262442, 26309.73690263111, 3220.7745457817964, 3284.425225585513, 3351.8826841060445, 3358.22760765023, 3559.857605695795, 15294.694106117815, 15629.984051036647, 15685.451009214737, 15707.214639268104, 16735.581610459834, 1786.6783978883795, 2042.693595960603, 2073.554185850034, 2087.2903212907686, 2154.2675490333204, 152879.0, 154648.75878663504, 159404.0, 161692.19153904272, 162332.18833905872, 16539.66920661604, 17020.0, 19284.8071519283, 19668.0, 20758.065887035118, 31520.211994700316, 31594.210144746106, 31757.523637145, 31786.841065794986, 31859.840700796347, 9980.800383992424, 10006.149477294366, 10096.69709908704, 10144.949275253724, 10378.844317335246, 8380.958095209608, 8386.329093672492, 8400.915990840136, 8555.828883422297, 8558.101399353103, 9422.104900034486, 9551.61793528265, 9556.044395560388, 9581.856272155923, 9587.424754514752, 3417.223044017097, 3972.4835771350117, 4047.129867078533, 4086.366613174963, 4090.959090409058, 789.4000559574764, 871.7689812199773, 894.9639569978351, 906.2387594420718, 910.8087301666592, 17916.55208619795, 21054.578908422052, 21576.0, 21873.562528749335, 21930.89034554817, 808.0424696734271, 842.4818736477145, 909.3180114913814, 924.2652091587204, 945.6737425588161, 8785.34109941757, 9727.9027209727, 9863.0, 10703.785924281627, 11447.37039462821, 593483.0, 633464.0, 670160.0, 684040.0, 695315.0, 102618.48690756499, 117711.0, 145811.2709436446, 154819.45180548276, 155179.0, 1094.9288296260718, 1156.9710757230969, 1292.7608392447428, 1331.2278878250786, 1391.7703578909366, 19486.12312445972, 19856.404307870787, 20140.79859201448, 20211.8989405052, 20324.491887702334, 47959.56121316366, 52345.0, 54418.82324707126, 55899.720501397234, 63467.6826615864, 90686.09313906776, 91373.54313228391, 91546.62680059938, 93131.53434232785, 93301.13397731872, 10417.531211095671, 11405.714857128638, 11608.477618507019, 11683.766324673288, 12030.578929737418, 12742.68143296425, 12749.426275817425, 12910.806337905127, 12921.224726516439, 12929.482820687068, 10364.067233948981, 10534.999175078356, 10756.300840445347, 10822.40476773785, 11407.258528195973, 10064.547095380873, 10396.584136634752, 10442.530086146293, 10458.0, 10584.947075264574, 9682.661106861226, 11272.267302625303, 11537.01935335506, 11597.246178998337, 11660.125490588409, 11519.942400287944, 11534.250273732181, 11606.47770850297, 11658.825117623073, 11895.286282823059, 10783.622573210214, 10968.725781855517, 11257.549698011988, 11296.096312295162, 11367.920047595466], "value": 11433.506232001071, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1552.8680062194712, 1582.5727053695502, 1599.272331089354, 1602.2949902043104, 1602.8076630804305, 8789.384743067987, 9398.295127865404, 9570.330076894621, 11340.886591134085, 11373.658790236286, 10279.331843430191, 10618.522166502507, 10621.52203150858, 10652.573897044129, 10836.729081772959, 2348.095983046531, 2395.8802059896934, 2398.5202959408102, 2403.975960240393, 2410.855348679076, 2363.61000434928, 2393.1743548475747, 2398.328468028955, 2399.832011759169, 2410.3612542676206, 2224.165937773335, 2251.324602619208, 2348.0, 2367.159658321297, 2377.3105799318178, 2405.194259922928, 2409.3976505873534, 2420.685310909588, 2423.7091549014053, 2429.8663573503363, 2367.5620010297976, 2383.1063351243283, 2389.2712722619594, 2389.6057150570136, 2409.0484258717884, 11166.553337866475, 11271.154663400195, 11332.6033588824, 11349.602763903224, 11351.545938162464, 5764.971175144139, 5888.911666325009, 5966.403359664042, 5973.313068997078, 5979.1031345298115, 3498.737594680409, 3609.2601016791496, 3613.5663720353464, 3663.2673465307043, 3665.596784353719, 1172.267332916927, 1178.4579093616946, 1195.115614445313, 1232.5069972011177, 1241.6709571963436, 15391.615209619737, 15502.844971550252, 15559.844401555953, 15600.141992190425, 15611.219439028004, 4678.537284894821, 4923.532264434873, 4931.87670308238, 4972.254161875748, 5009.624278179078, 12909.031822613342, 12912.225266484042, 12943.5469758558, 12959.352032398343, 12990.220586764824, 5846.181534585076, 5975.641461512323, 5978.790742323998, 5980.342162362137, 5986.49114825236, 4328.848490302824, 4364.432623758891, 4388.357663139586, 5242.449542798027, 5259.500347466985, 4082.2702929646593, 4115.670746340345, 4191.790410479463, 4236.9364459533135, 4245.3631955206965, 1125.060574420368, 1138.4876805437586, 1144.9656510304703, 1151.6832870960552, 1158.7624536969902, 921.8893732752113, 934.2899396458605, 948.7770373962113, 953.6519170502717, 1002.4636819301638, 6373.362663733589, 6493.415592596689, 6581.539292249592, 6801.2178599461695, 6868.278830722702, 964.2141654551658, 982.6904525074578, 992.0, 994.7413672445216, 1030.407515678488, 3608.891733248007, 3703.8147792706077, 4256.7445953242905, 4298.720583162084, 4409.470863496401, 12092.851179137968, 12444.0, 13676.521321753691, 13725.039247252596, 13745.0, 13546.93226533861, 13690.65773355674, 13701.657458563615, 13713.520026799015, 13716.177029378268, 8428.157184281401, 9244.399114057565, 9793.951030244803, 10063.0, 10240.948795255976, 5573.359063707563, 5580.441955804387, 6344.429001389897, 6439.356064393523, 6702.061711360511, 11133.944330278297, 11195.216334856483, 12048.638540843789, 12061.698457538632, 12223.69440763988, 12349.567765128177, 12575.434105465092, 12681.8097728536, 12744.42650080767, 12773.042021848585, 7992.5204487730925, 8176.182381761776, 9025.3231007676, 9065.90934090677, 9314.394564353566, 10113.342632728849, 10463.581456741646, 10475.057244848, 10548.841767373342, 10577.31247468912, 8710.91289087101, 8779.341549383695, 8808.603612837309, 8847.42491738035, 8860.556972151495, 7218.494705370574, 8666.393352465268, 8683.131686831266, 8686.261667758312, 8824.823503530022, 7745.496542724705, 7894.644740986554, 7899.131095579367, 7909.24862138096, 7926.286634202949, 5797.507211887037, 5826.73779679907, 5831.008728516247, 5969.552283578836, 6031.638101713911, 5844.181814545882, 5905.970470147622, 6064.514838812973, 6136.249083005993, 6158.538109641884], "value": 5132.169173118105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.04712864374227, 373.193901173465, 373.8224343436868, 378.6667732395492, 387.3666555182278, 5286.894262114755, 5342.519173274404, 5403.945960540394, 5404.270423492837, 5419.566434685225, 4357.760323182224, 5154.484551544843, 5247.895042099156, 5299.629025968184, 5306.761195746191, 4367.08291258836, 4450.710703804259, 4615.607673347761, 4625.213713668668, 4643.187442197619, 3884.941725874114, 4088.897777555555, 4429.42417485728, 4595.655325850558, 4596.954030459703, 3624.112092537332, 3913.4521167036637, 4621.890746220916, 4643.257078867382, 4645.256758918574, 4245.745255284693, 4270.658347332206, 4296.334068219416, 4407.559244075599, 4558.8632341029825, 4332.101109001892, 4387.0, 4387.42963414754, 4389.078293558357, 4434.467863856358, 4757.286407038937, 5192.091384007821, 5236.6857988520815, 5243.79024839006, 5252.789888404459, 4718.593517306164, 4999.775010124553, 5163.836163836164, 5350.250964864922, 5428.321459817523, 5707.316341679215, 5747.827565173052, 5761.300416377178, 5772.816572602648, 5774.131332007565, 4419.734815911023, 4592.33411155384, 4727.0, 4919.754012299407, 5014.222795466709, 5151.5878729701535, 5441.455854414567, 5794.710264486759, 6022.879542409171, 6037.732076264034, 4706.717596944194, 4828.444728856227, 5124.385073791094, 5133.845984620468, 5174.508421699932, 4363.759993200341, 4497.77511124449, 4905.092557876804, 5278.894422111536, 5390.649607775482, 4154.459920210354, 5157.813702848366, 5380.461953804666, 5397.325334333208, 5447.291852059287, 5215.608829337815, 5508.3559891251625, 5749.642646514909, 5795.623284486495, 5802.607374230239, 1539.2073082362592, 1611.548766345425, 1794.5154808201712, 1810.2939853457162, 1814.945551633453, 1342.3556692787517, 1352.7565038293005, 1397.6645605054916, 1414.0808474491662, 1475.3950880139091, 4649.976750116228, 5233.319668443078, 5238.8212652153725, 5242.842714718565, 5257.0, 5042.495750424928, 5508.55931525477, 5762.625429347079, 5813.82558523245, 5870.530357571384, 3526.7002304803864, 4379.1241751649795, 4805.927911081337, 5013.947071115107, 5324.21638751015, 2573.6156953605946, 2761.64098667176, 2936.6916473770375, 3047.9695203047686, 3060.4338197433544, 5250.579953603703, 5607.971960140173, 5829.679367634821, 6048.425399587032, 6053.606515576562, 4679.532046795293, 5758.654480731169, 5901.701625642352, 6050.0, 6067.7876274331065, 5111.412187598474, 5767.923547522889, 6026.186464827279, 6031.517478601701, 6154.476869466056, 5055.823046193365, 5179.171332586768, 5876.147958545901, 5921.5558833086825, 5937.0, 5771.394003629558, 5804.37477506282, 5843.561732870136, 5852.356240813422, 5858.326292476249, 5793.449622285876, 5858.7070646468355, 6112.694365281807, 6195.907061394173, 6210.0, 4018.698597605133, 4183.602557757008, 4332.285172946428, 4638.605718513964, 4648.721076735272, 5106.438291787975, 5761.769529218952, 5862.794802181903, 5866.6480011199465, 5876.44173803488, 4955.1576232039915, 5703.918069904538, 5704.258446401941, 5735.336752341774, 5926.496247818984, 4649.861063910639, 5059.797608095782, 5290.365156181283, 5661.876796201424, 5710.457506536873, 5570.5265052470995, 5595.272614560081, 5631.831045068655, 5687.630304030225, 5691.686957217393, 4773.9045219096115, 4977.328060711902, 5284.908328627642, 5740.995325817881, 5750.5112065474905, 4929.482404347636, 5118.667286626357, 5491.588130890279, 5512.972435137799, 5517.372375149261], "value": 4431.312050844156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.87348340557, 719.4496210399043, 738.2285511640339, 757.1860250231001, 848.3933987199154, 15313.0, 15892.761608575865, 16359.100249486273, 16470.423535176273, 16733.581660458494, 14925.701485970274, 15217.54347369578, 15314.081155130672, 15493.992890462112, 15504.0, 876.0713643537854, 931.2503434735036, 932.3007744191856, 932.5197523275518, 932.5290728182292, 910.1581037540282, 952.7951490429556, 958.6357184270003, 959.5538074795219, 961.048561923694, 999.9900001000007, 1004.7287232447235, 1005.3113617172224, 1005.7485628592851, 1007.1137399088792, 840.9285210757093, 1000.2298230362653, 1000.559753708366, 1001.5843424978661, 1002.0730823987818, 741.0107506478857, 747.7308169059135, 748.9063867016623, 748.9251074892521, 749.232037161908, 13190.60428187156, 16494.587635309093, 16553.917230413772, 16733.581660458585, 17002.91498542512, 12046.0, 14205.715885682228, 14405.495807646786, 14498.0, 14531.636709082357, 10011.449370284632, 11132.443377831076, 11835.927354098858, 11944.104192185534, 11958.521659133625, 3621.167131559756, 3626.38351480249, 3634.341548666397, 3644.671979521829, 3743.1952130291847, 113013.0, 113577.09637545317, 119867.0, 120041.0, 120284.0, 9430.95284523573, 10614.681559553226, 10744.194185436123, 11095.83356249657, 11397.430128493543, 22578.774212257995, 22752.772472275396, 22844.65733014006, 22919.0, 22937.197198097987, 7608.923910760822, 7619.885701714478, 7683.462157648911, 7701.884471732928, 7813.726519571787, 4222.282212023963, 4285.849995250213, 4309.0, 4327.956720432819, 4385.605295523418, 3520.612732599463, 3591.102224443889, 3611.566612006576, 3619.2580520993133, 3621.257642183346, 535.7053620508705, 642.3608509533044, 647.3591144766647, 649.3409189672486, 659.2912618934569, 665.7703092433103, 676.6075676107846, 681.3935597318391, 693.2963042511855, 695.6069820551372, 10246.813424181684, 10456.63401780934, 10557.788844223227, 10680.453037683417, 10718.732031699114, 672.9293424190389, 706.6254884911006, 709.3190537084457, 709.6912842913349, 719.1837264704598, 4292.0, 5069.163588008011, 5080.54275115234, 5095.184770436712, 5097.770600322994, 85026.0, 108939.0, 109677.0, 111569.0, 111869.0, 63181.0, 65143.69712605816, 65371.69256614936, 73528.63235683787, 83188.0, 58424.0, 67633.66183169151, 68737.06831925653, 72543.0, 75153.0, 9833.508324583885, 10949.50727217293, 11089.445527723743, 11341.149413793833, 11403.372814495282, 39248.80375598218, 43036.0, 45296.773516132205, 47804.0, 51932.74033629808, 55954.160687588905, 62167.0, 62427.6878615604, 62851.42871428251, 63116.68441657762, 12304.815427768412, 12442.75114497723, 12551.93724031374, 12666.176698514568, 12919.160254583421, 11884.46519906589, 12673.556425525063, 12790.360481975678, 12989.480420783062, 13395.0, 11799.705007374885, 11929.701757456132, 11944.0, 12129.150959432754, 12482.687932801753, 11549.36478493691, 11961.521539138337, 12103.818442723543, 12121.333326666765, 12319.815202771786, 8904.421212621159, 10844.132469402271, 11690.47392867306, 11733.120015999006, 12267.57063502773, 7370.926290737239, 8568.186022327869, 8941.910580894108, 8967.058458861986, 9031.412958157698, 9015.549222538977, 9304.534773261445, 9737.756556086155, 10183.439910804977, 10538.841917371092], "value": 7397.088739245599, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 122.0, 116.0, 127.0, 145.0, 117.0, 117.0, 120.0, 119.0, 117.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 122.0, 177.0, 109.0, 112.0, 111.0, 109.0, 174.0, 143.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 187.0, 274.0, 276.0, 210.0, 199.0, 204.0, 198.0, 202.0, 199.0], "value": 214.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 61.0, 85.0, 79.0, 72.0, 61.0, 64.0, 79.0, 73.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 73.0, 72.0, 73.0, 104.0, 101.0, 102.0, 93.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 88.0, 89.0, 89.0, 100.0, 89.0, 89.0, 89.0, 91.0, 89.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 148.0, 135.0, 97.0, 127.0, 98.0, 153.0, 99.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 90.0, 93.0, 109.0, 110.0, 91.0, 91.0, 113.0, 109.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 65.0, 71.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 56.0, 60.0, 62.0, 63.0, 55.0, 66.0, 83.0, 85.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 83.0, 87.0, 83.0, 87.0, 85.0, 86.0, 84.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 140.0, 154.0, 136.0, 153.0, 156.0, 158.0, 181.0, 140.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 166.0, 168.0, 167.0, 168.0, 170.0, 167.0, 168.0, 169.0, 165.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 67.0, 65.0, 65.0, 67.0, 68.0, 81.0], "value": 67.7, "unit": "score"}]}]} 20:29:04 INFO - Return code: 0 20:29:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:29:04 ERROR - # TBPL SUCCESS # 20:29:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:29:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:29:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:29:04 INFO - 2017-03-27 20:29:04.722 screenresolution[3325:106608] starting screenresolution argv=screenresolution get 20:29:04 INFO - 2017-03-27 20:29:04.725 screenresolution[3325:106608] Display 0: 1600x1200x32@60 20:29:04 INFO - 2017-03-27 20:29:04.731 screenresolution[3326:106610] starting screenresolution argv=screenresolution list 20:29:04 INFO - Available Modes on Display 0 20:29:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:29:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:29:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:29:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:29:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:29:04 INFO - Intel Iris: 20:29:04 INFO - Chipset Model: Intel Iris 20:29:04 INFO - Type: GPU 20:29:04 INFO - Bus: Built-In 20:29:04 INFO - VRAM (Dynamic, Max): 1536 MB 20:29:04 INFO - Vendor: Intel (0x8086) 20:29:04 INFO - Device ID: 0x0a2e 20:29:04 INFO - Revision ID: 0x0009 20:29:04 INFO - Displays: 20:29:04 INFO - Display: 20:29:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:29:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:29:04 INFO - Display Serial Number: 1600x1200 60 20:29:04 INFO - Main Display: Yes 20:29:04 INFO - Mirror: Off 20:29:04 INFO - Online: Yes 20:29:04 INFO - Rotation: Supported 20:29:04 INFO - Return code: 0 20:29:04 INFO - Running post-action listener: _resource_record_post_action 20:29:04 INFO - [mozharness: 2017-03-28 03:29:04.975794Z] Finished run-tests step (success) 20:29:04 INFO - Running post-run listener: _resource_record_post_run 20:29:05 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 462848; Write bytes: 514302976; Read time: 9; Write time: 1846 20:29:05 INFO - TinderboxPrint: CPU usage
54.6% 20:29:05 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 9 20:29:05 INFO - TinderboxPrint: I/O write bytes / time
514,302,976 / 1,846 20:29:05 INFO - TinderboxPrint: CPU idle
2,181.2 (45.4%) 20:29:05 INFO - TinderboxPrint: CPU system
967.5 (20.1%) 20:29:05 INFO - TinderboxPrint: CPU user
1,657.0 (34.5%) 20:29:05 INFO - TinderboxPrint: Swap in / out
207,712,256 / 0 20:29:05 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201351680; Write bytes: 201623552; Read time: 15735; Write time: 414 20:29:05 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 462848; Write bytes: 301911040; Read time: 9; Write time: 1397 20:29:05 INFO - Running post-run listener: _upload_blobber_files 20:29:05 INFO - Blob upload gear active. 20:29:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:29:05 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 20:29:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:29:05 INFO - (blobuploader) - INFO - Open directory for files ... 20:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:06 INFO - (blobuploader) - INFO - Done attempting. 20:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:08 INFO - (blobuploader) - INFO - Done attempting. 20:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:08 INFO - (blobuploader) - INFO - Done attempting. 20:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:10 INFO - (blobuploader) - INFO - Done attempting. 20:29:10 INFO - (blobuploader) - INFO - Iteration through files over. 20:29:10 INFO - Return code: 0 20:29:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:29:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:29:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b965de03175bf44934018861d4f0fb59be1b8b8a242068b955186fa80ab485d9be0cd4eb8fd14f5e92ed28b59cc68c073eaa8fc955d7924f1fdd475498a92ead", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d76555ee8001777221c345c42e2e899559d4db1051c1468931ebfd7b966b4da285cae43e7ea73d65704624c67c95e312663bf0b2f3901318c97632d57731aa99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01c327064ed1086c6a0f00932e2892f2c3507f8da5cc6303495edad74896245086681be9132ee8e346661f89a02e64347d197cd288191f212c1dd28b79046dc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/567e943c77b624f34ac7da2c14d08c6f620255121ac6666aaad7993f502109215bb9e9a369d35dcdb4a00450951427241815565dbe1d4756085055243b9ac5d0"} 20:29:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:29:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:29:10 INFO - Contents: 20:29:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b965de03175bf44934018861d4f0fb59be1b8b8a242068b955186fa80ab485d9be0cd4eb8fd14f5e92ed28b59cc68c073eaa8fc955d7924f1fdd475498a92ead", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d76555ee8001777221c345c42e2e899559d4db1051c1468931ebfd7b966b4da285cae43e7ea73d65704624c67c95e312663bf0b2f3901318c97632d57731aa99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01c327064ed1086c6a0f00932e2892f2c3507f8da5cc6303495edad74896245086681be9132ee8e346661f89a02e64347d197cd288191f212c1dd28b79046dc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/567e943c77b624f34ac7da2c14d08c6f620255121ac6666aaad7993f502109215bb9e9a369d35dcdb4a00450951427241815565dbe1d4756085055243b9ac5d0"} 20:29:10 INFO - Running post-run listener: copy_logs_to_upload_dir 20:29:10 INFO - Copying logs to upload dir... 20:29:10 INFO - mkdir: /builds/slave/test/build/upload/logs 20:29:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.302518 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-27 20:29:10.940785) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 20:29:10.943917) ========= 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.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b965de03175bf44934018861d4f0fb59be1b8b8a242068b955186fa80ab485d9be0cd4eb8fd14f5e92ed28b59cc68c073eaa8fc955d7924f1fdd475498a92ead", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d76555ee8001777221c345c42e2e899559d4db1051c1468931ebfd7b966b4da285cae43e7ea73d65704624c67c95e312663bf0b2f3901318c97632d57731aa99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01c327064ed1086c6a0f00932e2892f2c3507f8da5cc6303495edad74896245086681be9132ee8e346661f89a02e64347d197cd288191f212c1dd28b79046dc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/567e943c77b624f34ac7da2c14d08c6f620255121ac6666aaad7993f502109215bb9e9a369d35dcdb4a00450951427241815565dbe1d4756085055243b9ac5d0"} build_url:https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/OLagjjlQTVOVUc5rVT3oWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b965de03175bf44934018861d4f0fb59be1b8b8a242068b955186fa80ab485d9be0cd4eb8fd14f5e92ed28b59cc68c073eaa8fc955d7924f1fdd475498a92ead", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d76555ee8001777221c345c42e2e899559d4db1051c1468931ebfd7b966b4da285cae43e7ea73d65704624c67c95e312663bf0b2f3901318c97632d57731aa99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01c327064ed1086c6a0f00932e2892f2c3507f8da5cc6303495edad74896245086681be9132ee8e346661f89a02e64347d197cd288191f212c1dd28b79046dc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/567e943c77b624f34ac7da2c14d08c6f620255121ac6666aaad7993f502109215bb9e9a369d35dcdb4a00450951427241815565dbe1d4756085055243b9ac5d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 20:29:10.977429) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:29:10.977727) ========= 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.WHLaGUTYBH/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.p0blLpvmky/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005460 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 20:29:11.026462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 20:29:11.026793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 20:29:11.027125) ========= ========= Total master_lag: 0.18 =========