builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1490877763.5 results: success (0) buildid: 20170330044822 builduid: 04cc058cea644d1dbc50df001f67719f revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.497502) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.498381) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.498665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.618694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.618975) ========= 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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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-30 05:42:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 45.7M=0s 2017-03-30 05:42:44 (45.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.475221 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.120775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.121226) ========= 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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.029390 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.174719) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 05:42:44.175087) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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-30 05:42:44,190 truncating revision to first 12 chars 2017-03-30 05:42:44,190 Setting DEBUG logging. 2017-03-30 05:42:44,190 attempt 1/10 2017-03-30 05:42:44,190 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03d602fd723a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 05:42:45,491 unpacking tar archive at: mozilla-central-03d602fd723a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.574959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 05:42:45.773478) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:45.773794) ========= script_repo_revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:45.774197) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:45.774696) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:45.794795) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2017-03-30 05:42:45.795240) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:42:45 INFO - MultiFileLogger online at 20170330 05:42:45 in /builds/slave/test 05:42:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:42:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:42:45 INFO - {'append_to_log': False, 05:42:45 INFO - 'base_work_dir': '/builds/slave/test', 05:42:45 INFO - 'blob_upload_branch': 'Firefox', 05:42:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:42:45 INFO - 'branch': 'Firefox', 05:42:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:45 INFO - 'config_files': ('talos/mac_config.py',), 05:42:45 INFO - 'default_actions': ('clobber', 05:42:45 INFO - 'read-buildbot-config', 05:42:45 INFO - 'download-and-extract', 05:42:45 INFO - 'populate-webroot', 05:42:45 INFO - 'create-virtualenv', 05:42:45 INFO - 'install', 05:42:45 INFO - 'run-tests'), 05:42:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:45 INFO - 'download_minidump_stackwalk': True, 05:42:45 INFO - 'download_symbols': 'ondemand', 05:42:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:45 INFO - 'gecko_profile': False, 05:42:45 INFO - 'gecko_profile_interval': 0, 05:42:45 INFO - 'installer_path': 'installer.exe', 05:42:45 INFO - 'log_level': 'info', 05:42:45 INFO - 'log_name': 'talos', 05:42:45 INFO - 'log_to_console': True, 05:42:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:42:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:42:45 INFO - 'opt_config_files': (), 05:42:45 INFO - 'pip_index': False, 05:42:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:45 INFO - 'cmd': ('bash', 05:42:45 INFO - '-c', 05:42:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:45 INFO - 'enabled': True, 05:42:45 INFO - 'halt_on_failure': False, 05:42:45 INFO - 'name': 'check_screen_resolution'},), 05:42:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:45 INFO - 'cmd': ('bash', 05:42:45 INFO - '-c', 05:42:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:45 INFO - 'enabled': True, 05:42:45 INFO - 'halt_on_failure': False, 05:42:45 INFO - 'name': 'check_screen_resolution'},), 05:42:45 INFO - 'run_cmd_checks_enabled': True, 05:42:45 INFO - 'suite': 'dromaeojs', 05:42:45 INFO - 'system_bits': '32', 05:42:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:45 INFO - 'title': 't-yosemite-r7-0018', 05:42:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:45 INFO - 'use_talos_json': True, 05:42:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:42:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:45 INFO - 'work_dir': 'build'} 05:42:45 INFO - [mozharness: 2017-03-30 12:42:45.916941Z] Running clobber step. 05:42:45 INFO - Running pre-action listener: _resource_record_pre_action 05:42:45 INFO - Running main action method: clobber 05:42:45 INFO - rmtree: /builds/slave/test/build 05:42:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:42:48 INFO - Running post-action listener: _resource_record_post_action 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.477586Z] Finished clobber step (success) 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.477869Z] Running read-buildbot-config step. 05:42:48 INFO - Running pre-action listener: _resource_record_pre_action 05:42:48 INFO - Running main action method: read_buildbot_config 05:42:48 INFO - Using buildbot properties: 05:42:48 INFO - { 05:42:48 INFO - "project": "", 05:42:48 INFO - "product": "firefox", 05:42:48 INFO - "script_repo_revision": "production", 05:42:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:42:48 INFO - "repository": "", 05:42:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:42:48 INFO - "buildid": "20170330044822", 05:42:48 INFO - "pgo_build": "False", 05:42:48 INFO - "basedir": "/builds/slave/test", 05:42:48 INFO - "buildnumber": 64, 05:42:48 INFO - "slavename": "t-yosemite-r7-0018", 05:42:48 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:42:48 INFO - "platform": "yosemite_r7", 05:42:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:42:48 INFO - "branch": "mozilla-central", 05:42:48 INFO - "repo_path": "mozilla-central", 05:42:48 INFO - "revision": "03d602fd723ad6ff4588c04855884ffa1dee9410", 05:42:48 INFO - "stage_platform": "macosx64", 05:42:48 INFO - "builduid": "04cc058cea644d1dbc50df001f67719f", 05:42:48 INFO - "slavebuilddir": "test" 05:42:48 INFO - } 05:42:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:42:48 INFO - Running post-action listener: _resource_record_post_action 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.483640Z] Finished read-buildbot-config step (success) 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.483867Z] Running download-and-extract step. 05:42:48 INFO - Running pre-action listener: _resource_record_pre_action 05:42:48 INFO - Running main action method: download_and_extract 05:42:48 INFO - mkdir: /builds/slave/test/build/tests 05:42: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')]} 05:42:48 INFO - trying https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:48 INFO - Downloading https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:42:49 INFO - Downloaded 1325 bytes. 05:42:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:49 INFO - Using the following test package requirements: 05:42:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:42:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:42:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:42:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:42:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'jsshell-mac.zip'], 05:42:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:42:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:42:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:42:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:42:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:42:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:42:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:42:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:42:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:42:49 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:42:51 INFO - Expected file size: 52511532 05:42:51 INFO - Obtained file size: 52511532 05:43:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:43:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:43:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:43:08 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:43:10 INFO - Expected file size: 52511532 05:43:10 INFO - Obtained file size: 52511532 05:43:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:43:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:43:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:43:26 INFO - Expected file size: 14404152 05:43:26 INFO - Obtained file size: 14404152 05:43:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:27 INFO - trying https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:27 INFO - Downloading https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:43:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:43:29 INFO - Downloaded 75535078 bytes. 05:43:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:29 INFO - mkdir: /builds/slave/test/properties 05:43:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:43:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:43:29 INFO - Contents: 05:43:29 INFO - build_url:https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:29 INFO - Running post-action listener: _resource_record_post_action 05:43:29 INFO - Running post-action listener: set_extra_try_arguments 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.802610Z] Finished download-and-extract step (success) 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.802754Z] Running populate-webroot step. 05:43:29 INFO - Running pre-action listener: _resource_record_pre_action 05:43:29 INFO - Running main action method: populate_webroot 05:43:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:43:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:43:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:43:29 INFO - '--tppagecycles', 05:43:29 INFO - '7'], 05:43:29 INFO - 'tests': ['tsvgm']}}, 05:43:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:43:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:43:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:43:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:43:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['damp', 'tps']}, 05:43:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'tests': ['damp', 'tps']}, 05:43:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['dromaeo_dom']}, 05:43:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:43:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['basic_compositor_video']}, 05:43:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:43:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tsvgx', 05:43:29 INFO - 'tsvgr_opacity', 05:43:29 INFO - 'tart', 05:43:29 INFO - 'tscrollx', 05:43:29 INFO - 'cart', 05:43:29 INFO - 'tsvg_static']}, 05:43:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:43:29 INFO - 'tsvgr_opacity', 05:43:29 INFO - 'tart', 05:43:29 INFO - 'tscrollx', 05:43:29 INFO - 'cart', 05:43:29 INFO - 'tsvg_static']}, 05:43:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tp5o']}, 05:43:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:43:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s', 05:43:29 INFO - '--xperf_path', 05:43:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:29 INFO - 'tests': ['tp5n']}, 05:43:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--xperf_path', 05:43:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:29 INFO - 'tests': ['tp5n']}}, 05:43:29 INFO - 'talos.zip': {'path': '', 05:43:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:43:29 INFO - Running post-action listener: _resource_record_post_action 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.821164Z] Finished populate-webroot step (success) 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.821295Z] Running create-virtualenv step. 05:43:29 INFO - Running pre-action listener: _resource_record_pre_action 05:43:29 INFO - Running main action method: create_virtualenv 05:43:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:43: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 05:43:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:43:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:43:29 INFO - Using real prefix '/tools/python27' 05:43:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:43:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:43:33 INFO - Installing pip.................done. 05:43:33 INFO - Return code: 0 05:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:43:33 INFO - Reading from file tmpfile_stdout 05:43:33 INFO - Output received: 05:43: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) 05:43:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43: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 05:43: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 05:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:33 INFO - 'HOME': '/Users/cltbld', 05:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:33 INFO - 'LOGNAME': 'cltbld', 05:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:33 INFO - 'MOZ_NO_REMOTE': '1', 05:43:33 INFO - 'NO_EM_RESTART': '1', 05:43:33 INFO - 'PAGER': '/bin/cat', 05:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:33 INFO - 'PWD': '/builds/slave/test', 05:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:33 INFO - 'SHELL': '/bin/bash', 05:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:33 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:33 INFO - 'USER': 'cltbld', 05:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:33 INFO - 'XPC_FLAGS': '0x0', 05:43:33 INFO - 'XPC_SERVICE_NAME': '0', 05:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43: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 05:43:34 INFO - Cleaning up... 05:43:34 INFO - Return code: 0 05:43:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43: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 05:43: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 05:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:34 INFO - 'HOME': '/Users/cltbld', 05:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:34 INFO - 'LOGNAME': 'cltbld', 05:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:34 INFO - 'MOZ_NO_REMOTE': '1', 05:43:34 INFO - 'NO_EM_RESTART': '1', 05:43:34 INFO - 'PAGER': '/bin/cat', 05:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:34 INFO - 'PWD': '/builds/slave/test', 05:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:34 INFO - 'SHELL': '/bin/bash', 05:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:34 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:34 INFO - 'USER': 'cltbld', 05:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:34 INFO - 'XPC_FLAGS': '0x0', 05:43:34 INFO - 'XPC_SERVICE_NAME': '0', 05:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:34 INFO - Downloading/unpacking psutil>=3.1.1 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:43:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:38 INFO - Installing collected packages: psutil 05:43:38 INFO - Running setup.py install for psutil 05:43:38 INFO - building 'psutil._psutil_osx' extension 05:43: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 05:43:39 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 05:43:39 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 05:43:39 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 05:43:39 INFO - building 'psutil._psutil_posix' extension 05:43:39 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 05:43:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:43:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:43:39 INFO - ^ 05:43:39 INFO - 1 warning generated. 05:43:39 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 05:43:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:39 INFO - Successfully installed psutil 05:43:39 INFO - Cleaning up... 05:43:39 INFO - Return code: 0 05:43:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:43:39 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')]} 05:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:39 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')]} 05:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:39 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43:39 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 05:43:39 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 05:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:39 INFO - 'HOME': '/Users/cltbld', 05:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:39 INFO - 'LOGNAME': 'cltbld', 05:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:39 INFO - 'MOZ_NO_REMOTE': '1', 05:43:39 INFO - 'NO_EM_RESTART': '1', 05:43:39 INFO - 'PAGER': '/bin/cat', 05:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:39 INFO - 'PWD': '/builds/slave/test', 05:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:39 INFO - 'SHELL': '/bin/bash', 05:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:39 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:39 INFO - 'USER': 'cltbld', 05:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:39 INFO - 'XPC_FLAGS': '0x0', 05:43:39 INFO - 'XPC_SERVICE_NAME': '0', 05:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:43:42 INFO - Installing collected packages: mozsystemmonitor 05:43:42 INFO - Running setup.py install for mozsystemmonitor 05:43:42 INFO - Successfully installed mozsystemmonitor 05:43:42 INFO - Cleaning up... 05:43:42 INFO - Return code: 0 05:43:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43: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 05:43: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 05:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:42 INFO - 'HOME': '/Users/cltbld', 05:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:42 INFO - 'LOGNAME': 'cltbld', 05:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:42 INFO - 'MOZ_NO_REMOTE': '1', 05:43:42 INFO - 'NO_EM_RESTART': '1', 05:43:42 INFO - 'PAGER': '/bin/cat', 05:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:42 INFO - 'PWD': '/builds/slave/test', 05:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:42 INFO - 'SHELL': '/bin/bash', 05:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:42 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:42 INFO - 'USER': 'cltbld', 05:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:42 INFO - 'XPC_FLAGS': '0x0', 05:43:42 INFO - 'XPC_SERVICE_NAME': '0', 05:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:43 INFO - Downloading/unpacking jsonschema==2.5.1 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:43:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:46 INFO - Downloading functools32-3.2.3-2.tar.gz 05:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:43:46 INFO - warning: no files found matching '*.txt' 05:43:46 INFO - no previously-included directories found matching 'build' 05:43:46 INFO - no previously-included directories found matching 'dist' 05:43:46 INFO - no previously-included directories found matching '.git*' 05:43:46 INFO - Installing collected packages: jsonschema, functools32 05:43:46 INFO - Running setup.py install for functools32 05:43:46 INFO - warning: no files found matching '*.txt' 05:43:46 INFO - no previously-included directories found matching 'build' 05:43:46 INFO - no previously-included directories found matching 'dist' 05:43:46 INFO - no previously-included directories found matching '.git*' 05:43:46 INFO - Successfully installed jsonschema functools32 05:43:46 INFO - Cleaning up... 05:43:46 INFO - Return code: 0 05:43:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:43:46 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')]} 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 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')]} 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43:46 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 05:43:46 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 05:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:46 INFO - 'HOME': '/Users/cltbld', 05:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:46 INFO - 'LOGNAME': 'cltbld', 05:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:46 INFO - 'MOZ_NO_REMOTE': '1', 05:43:46 INFO - 'NO_EM_RESTART': '1', 05:43:46 INFO - 'PAGER': '/bin/cat', 05:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:46 INFO - 'PWD': '/builds/slave/test', 05:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:46 INFO - 'SHELL': '/bin/bash', 05:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:46 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:46 INFO - 'USER': 'cltbld', 05:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:46 INFO - 'XPC_FLAGS': '0x0', 05:43:46 INFO - 'XPC_SERVICE_NAME': '0', 05:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:43:46 INFO - Cleaning up... 05:43:46 INFO - Return code: 0 05:43:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:43:46 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')]} 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 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')]} 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43:46 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 05:43:46 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 05:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:46 INFO - 'HOME': '/Users/cltbld', 05:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:46 INFO - 'LOGNAME': 'cltbld', 05:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:46 INFO - 'MOZ_NO_REMOTE': '1', 05:43:46 INFO - 'NO_EM_RESTART': '1', 05:43:46 INFO - 'PAGER': '/bin/cat', 05:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:46 INFO - 'PWD': '/builds/slave/test', 05:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:46 INFO - 'SHELL': '/bin/bash', 05:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:46 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:46 INFO - 'USER': 'cltbld', 05:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:46 INFO - 'XPC_FLAGS': '0x0', 05:43:46 INFO - 'XPC_SERVICE_NAME': '0', 05:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:47 INFO - Downloading/unpacking blobuploader==1.2.4 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:49 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:43:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:50 INFO - Downloading docopt-0.6.1.tar.gz 05:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:51 INFO - Running setup.py install for blobuploader 05:43:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:43:51 INFO - Running setup.py install for requests 05:43:51 INFO - Running setup.py install for docopt 05:43:51 INFO - Successfully installed blobuploader requests docopt 05:43:51 INFO - Cleaning up... 05:43:51 INFO - Return code: 0 05:43:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43:51 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 05:43:51 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 05:43:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:51 INFO - 'HOME': '/Users/cltbld', 05:43:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:51 INFO - 'LOGNAME': 'cltbld', 05:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:51 INFO - 'MOZ_NO_REMOTE': '1', 05:43:51 INFO - 'NO_EM_RESTART': '1', 05:43:51 INFO - 'PAGER': '/bin/cat', 05:43:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:51 INFO - 'PWD': '/builds/slave/test', 05:43:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:51 INFO - 'SHELL': '/bin/bash', 05:43:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:51 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:51 INFO - 'USER': 'cltbld', 05:43:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:51 INFO - 'XPC_FLAGS': '0x0', 05:43:51 INFO - 'XPC_SERVICE_NAME': '0', 05:43:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-EEJ5WK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-DdWBs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-mnEnjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-0kH2Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-XbLuRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-MDLaLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-BnillP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-0FZ42i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-H6d150-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-lodBO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-cCCPLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-xIEije-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Fmgb3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-wx8u9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-xJc1tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-B_rBII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:53 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-hsSCS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:54 INFO - Running setup.py install for manifestparser 05:43:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozcrash 05:43:54 INFO - Running setup.py install for mozdebug 05:43:54 INFO - Running setup.py install for mozdevice 05:43:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozfile 05:43:54 INFO - Running setup.py install for mozhttpd 05:43:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozinfo 05:43:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozInstall 05:43:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozleak 05:43:55 INFO - Running setup.py install for mozlog 05:43:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for moznetwork 05:43:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozprocess 05:43:55 INFO - Running setup.py install for mozprofile 05:43:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozrunner 05:43:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:43:56 INFO - Running setup.py install for mozscreenshot 05:43:56 INFO - Running setup.py install for moztest 05:43:56 INFO - Running setup.py install for mozversion 05:43:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:43:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:56 INFO - Cleaning up... 05:43:56 INFO - Return code: 0 05:43:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:43:56 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 05:43:56 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 05:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:56 INFO - 'HOME': '/Users/cltbld', 05:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:56 INFO - 'LOGNAME': 'cltbld', 05:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:56 INFO - 'MOZ_NO_REMOTE': '1', 05:43:56 INFO - 'NO_EM_RESTART': '1', 05:43:56 INFO - 'PAGER': '/bin/cat', 05:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:56 INFO - 'PWD': '/builds/slave/test', 05:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:56 INFO - 'SHELL': '/bin/bash', 05:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:43:56 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:56 INFO - 'USER': 'cltbld', 05:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:56 INFO - 'XPC_FLAGS': '0x0', 05:43:56 INFO - 'XPC_SERVICE_NAME': '0', 05:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:56 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Zjn_LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43: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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-W28oQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43: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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-q1yz0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43: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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-wGy4WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-6Ti73t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-49HGRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-GddJEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-WVXmkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-p5x9Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-pVBFeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:57 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Id57iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43: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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-CAsjkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43: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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-oiMuE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:58 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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-b3FrC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:58 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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-_74Tit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:58 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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-mUz3nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:58 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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:58 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-spOs6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:01 INFO - Downloading blessings-1.6.tar.gz 05:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:44:01 INFO - Installing collected packages: blessings 05:44:01 INFO - Running setup.py install for blessings 05:44:01 INFO - Successfully installed blessings 05:44:01 INFO - Cleaning up... 05:44:01 INFO - Return code: 0 05:44:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:44:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:02 INFO - Reading from file tmpfile_stdout 05:44:02 INFO - Current package versions: 05:44:02 INFO - blessings == 1.6 05:44:02 INFO - blobuploader == 1.2.4 05:44:02 INFO - docopt == 0.6.1 05:44:02 INFO - functools32 == 3.2.3-2 05:44:02 INFO - jsonschema == 2.5.1 05:44:02 INFO - manifestparser == 1.1 05:44:02 INFO - mozInstall == 1.12 05:44:02 INFO - mozcrash == 1.0 05:44:02 INFO - mozdebug == 0.1 05:44:02 INFO - mozdevice == 0.49 05:44:02 INFO - mozfile == 1.2 05:44:02 INFO - mozhttpd == 0.7 05:44:02 INFO - mozinfo == 0.9 05:44:02 INFO - mozleak == 0.1 05:44:02 INFO - mozlog == 3.4 05:44:02 INFO - moznetwork == 0.27 05:44:02 INFO - mozprocess == 0.25 05:44:02 INFO - mozprofile == 0.28 05:44:02 INFO - mozrunner == 6.13 05:44:02 INFO - mozscreenshot == 0.1 05:44:02 INFO - mozsystemmonitor == 0.3 05:44:02 INFO - moztest == 0.8 05:44:02 INFO - mozversion == 1.4 05:44:02 INFO - psutil == 3.1.1 05:44:02 INFO - requests == 1.2.3 05:44:02 INFO - wsgiref == 0.1.2 05:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44: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')]} 05:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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')]} 05:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:44: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 05:44: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 05:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:02 INFO - 'HOME': '/Users/cltbld', 05:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:02 INFO - 'LOGNAME': 'cltbld', 05:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:02 INFO - 'MOZ_NO_REMOTE': '1', 05:44:02 INFO - 'NO_EM_RESTART': '1', 05:44:02 INFO - 'PAGER': '/bin/cat', 05:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:02 INFO - 'PWD': '/builds/slave/test', 05:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:02 INFO - 'SHELL': '/bin/bash', 05:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:44:02 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:02 INFO - 'USER': 'cltbld', 05:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:02 INFO - 'XPC_FLAGS': '0x0', 05:44:02 INFO - 'XPC_SERVICE_NAME': '0', 05:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44: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)) 05:44:02 INFO - Cleaning up... 05:44:02 INFO - Return code: 0 05:44:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:44: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')]} 05:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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')]} 05:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:02 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 0x10aaf1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b2856ba90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, '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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 05:44:02 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 05:44:02 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 05:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:02 INFO - 'HOME': '/Users/cltbld', 05:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:02 INFO - 'LOGNAME': 'cltbld', 05:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:02 INFO - 'MOZ_NO_REMOTE': '1', 05:44:02 INFO - 'NO_EM_RESTART': '1', 05:44:02 INFO - 'PAGER': '/bin/cat', 05:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:02 INFO - 'PWD': '/builds/slave/test', 05:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:02 INFO - 'SHELL': '/bin/bash', 05:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:44:02 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:02 INFO - 'USER': 'cltbld', 05:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:02 INFO - 'XPC_FLAGS': '0x0', 05:44:02 INFO - 'XPC_SERVICE_NAME': '0', 05:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:44:02 INFO - Cleaning up... 05:44:02 INFO - Return code: 0 05:44:02 INFO - Running post-action listener: _resource_record_post_action 05:44:02 INFO - Running post-action listener: _start_resource_monitoring 05:44:02 INFO - Starting resource monitoring. 05:44:02 INFO - [mozharness: 2017-03-30 12:44:02.608827Z] Finished create-virtualenv step (success) 05:44:02 INFO - [mozharness: 2017-03-30 12:44:02.609759Z] Running install step. 05:44:02 INFO - Running pre-action listener: _resource_record_pre_action 05:44:02 INFO - Running main action method: install 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:02 INFO - Reading from file tmpfile_stdout 05:44:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:44:02 INFO - Reading from file tmpfile_stdout 05:44:02 INFO - Output received: 05:44:02 INFO - Usage: mozinstall [options] installer 05:44:02 INFO - Options: 05:44:02 INFO - -h, --help show this help message and exit 05:44:02 INFO - -d DEST, --destination=DEST 05:44:02 INFO - Directory to install application into. [default: 05:44:02 INFO - "/builds/slave/test"] 05:44:02 INFO - --app=APP Application being installed. [default: firefox] 05:44:02 INFO - mkdir: /builds/slave/test/build/application 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:44:23 INFO - Reading from file tmpfile_stdout 05:44:23 INFO - Output received: 05:44:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:44:23 INFO - Running post-action listener: _resource_record_post_action 05:44:23 INFO - [mozharness: 2017-03-30 12:44:23.241964Z] Finished install step (success) 05:44:23 INFO - [mozharness: 2017-03-30 12:44:23.242101Z] Running run-tests step. 05:44:23 INFO - Running pre-action listener: _resource_record_pre_action 05:44:23 INFO - Running main action method: run_tests 05:44:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:23 INFO - 2017-03-30 05:44:23.256 screenresolution[1635:7986] starting screenresolution argv=screenresolution get 05:44:23 INFO - 2017-03-30 05:44:23.259 screenresolution[1635:7986] Display 0: 1600x1200x32@60 05:44:23 INFO - 2017-03-30 05:44:23.265 screenresolution[1636:7989] starting screenresolution argv=screenresolution list 05:44:23 INFO - Available Modes on Display 0 05:44:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:23 INFO - Intel Iris: 05:44:23 INFO - Chipset Model: Intel Iris 05:44:23 INFO - Type: GPU 05:44:23 INFO - Bus: Built-In 05:44:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:23 INFO - Vendor: Intel (0x8086) 05:44:23 INFO - Device ID: 0x0a2e 05:44:23 INFO - Revision ID: 0x0009 05:44:23 INFO - Displays: 05:44:23 INFO - Display: 05:44:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:23 INFO - Display Serial Number: 1600x1200 60 05:44:23 INFO - Main Display: Yes 05:44:23 INFO - Mirror: Off 05:44:23 INFO - Online: Yes 05:44:23 INFO - Rotation: Supported 05:44:23 INFO - Return code: 0 05:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:44:23 INFO - Python 2.7.3 05:44:23 INFO - Return code: 0 05:44:23 INFO - grabbing minidump binary from tooltool 05:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:23 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 0x10a8cfd30>, '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 0x10a7f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:44:23 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 05:44:23 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 05:44:23 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 05:44:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:44:23 INFO - Return code: 0 05:44:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:44:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - ENV: RUST_BACKTRACE is now 1 05:44:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:44:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:44:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jlVOMhfR5J/Render', 05:44:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:23 INFO - 'HOME': '/Users/cltbld', 05:44:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:23 INFO - 'LOGNAME': 'cltbld', 05:44:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:44:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:44:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:23 INFO - 'MOZ_NO_REMOTE': '1', 05:44:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:44:23 INFO - 'NO_EM_RESTART': '1', 05:44:23 INFO - 'PAGER': '/bin/cat', 05:44:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:23 INFO - 'PWD': '/builds/slave/test', 05:44:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:44:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:23 INFO - 'RUST_BACKTRACE': '1', 05:44:23 INFO - 'SHELL': '/bin/bash', 05:44:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OgxiLJlmUZ/Listeners', 05:44:23 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 05:44:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:23 INFO - 'USER': 'cltbld', 05:44:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:23 INFO - 'XPC_FLAGS': '0x0', 05:44:23 INFO - 'XPC_SERVICE_NAME': '0', 05:44:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:44:23 INFO - mozversion application_buildid: 20170330044822 05:44:23 INFO - mozversion application_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 05:44:23 INFO - mozversion application_display_name: Nightly 05:44:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:44:23 INFO - mozversion application_name: Firefox 05:44:23 INFO - mozversion application_remotingname: firefox 05:44:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:44:23 INFO - mozversion application_vendor: Mozilla 05:44:23 INFO - mozversion application_version: 55.0a1 05:44:23 INFO - mozversion platform_buildid: 20170330044822 05:44:23 INFO - mozversion platform_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 05:44:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:44:23 INFO - mozversion platform_version: 55.0a1 05:44:23 INFO - using testdate: 1490877863 05:44:23 INFO - actual date: 1490877863 05:44:23 INFO - starting webserver on 'localhost:49236' 05:44:23 INFO - SUITE-START | Running 2 tests 05:44:23 INFO - TEST-START | dromaeo_css 05:44:23 INFO - operating with platform_type : mac_ 05:44:23 INFO - Initialising browser for dromaeo_css test... 05:44:23 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpMc74Qv/profile http://localhost:49236/getInfo.html) 05:44:26 INFO - PID 1646 | __metrics Screen width/height:1600/1200 05:44:26 INFO - PID 1646 | colorDepth:24 05:44:26 INFO - PID 1646 | Browser inner width/height: 1024/689 05:44:26 INFO - PID 1646 | __metrics 05:44:26 ERROR - PID 1646 | 1490877866506 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:44:26 INFO - PID 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:44:26 INFO - PID 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:44:27 INFO - TEST-INFO | 1646: exit 0 05:44:27 INFO - Browser initialized. 05:44:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:44:27 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpMc74Qv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:39 INFO - PID 1649 | RSS: Main: 234287104 05:44:39 INFO - PID 1649 | 05:47:43 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 05:47:46 INFO - PID 1649 | RSS: Main: 371257344 05:47:46 INFO - PID 1649 | 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 05:49:29 INFO - PID 1649 | 05:49:29 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:29 INFO - PID 1649 | Full stack: 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 05:49:29 INFO - PID 1649 | 05:49:29 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:29 INFO - PID 1649 | Full stack: 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 05:49:29 INFO - PID 1649 | 05:49:29 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:29 INFO - PID 1649 | Full stack: 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | ************************* 05:49:29 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 05:49:29 INFO - PID 1649 | 05:49:29 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:29 INFO - PID 1649 | Full stack: 05:49:29 INFO - PID 1649 | ************************* 05:50:49 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 05:50:50 INFO - PID 1649 | RSS: Main: 369156096 05:50:50 INFO - PID 1649 | 05:53:52 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 05:53:53 INFO - PID 1649 | RSS: Main: 335822848 05:53:53 INFO - PID 1649 | 05:56:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 05:56:56 INFO - PID 1649 | RSS: Main: 360488960 05:56:56 INFO - PID 1649 | 05:59:59 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 06:00:00 INFO - PID 1649 | RSS: Main: 333561856 06:00:00 INFO - PID 1649 | 06:03:03 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 06:03:04 INFO - PID 1649 | RSS: Main: 517791744 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | __start_tp_report 06:03:04 INFO - PID 1649 | _x_x_mozilla_page_load 06:03:04 INFO - PID 1649 | _x_x_mozilla_page_load_details 06:03:04 INFO - PID 1649 | |i|pagename|runs| 06:03:04 INFO - PID 1649 | |0;dojo.html;3425.349183655104;3480.495328177413;3491.9301613967705;3512.5784905811297;3521.102118959666;6020.277566691992;6109.7690694183475;6229.7508099676015;6238.937610623892;6248.37516248375;6146.600470969383;6221.813345599628;6253.405926436982;6254.43710066094;6277.905831412521;5326.387465441466;5444.373897001856;5518.034343989796;5586.162075688639;5650.434956504358;5576.525995290405;5585.497305242528;5606.915896261559;5617.466340697627;5627.986962346776;5428.158635411498;5499.7250137493365;5526.944730552683;5543.307086614173;5554.555635549146;5481.040817856899;5526;5549.722513874331;5596.412376700469;5609.439056094398;5478.671279723229;5494.230807686927;5509.531689806371;5550.888982220374;5560.443955604448;6082.2093127893095;6138.051223665364;6145.78489752861;6221.626702397871;6223.657698826562;6399.232092148972;6404.231492220917;6410.134631824691;6418.903716444257;6420.711068001951;6540.607563546202;6578.506612004071;6578.967105164444;6634.601923884582;6662.100616416818;6243.282022567418;6310.1796766420075;6310.9684451577905;6329.4303512683155;6330.208723909512;6802.523823332418;6815.079964204868;6827.419669328163;6828.214755303204;6840.726370945106;6302.527310451735;6368.904466433008;6378.617282963037;6419.903701444482;6420.229572451336;6645.169353830771;6660.733570657119;6663.633500157538;6674.2658307586125;6677.565958212701;6548.836279092966;6564.015397690289;6568.817612829736;6570.113034740344;6577.934220657828;6331.588446751039;6367.331430199855;6579.374959378852;6612.7354905804195;6640.103586015923;5201.141811601119;5216.869578260498;5220.504052115043;5221.242919776611;5241.895162096736;4676.625869930397;4713.552212539803;4720.103180395714;4731.053789242096;4731.929021064687;6258.655773932477;6268.216472940882;6329.019002054689;6338.366163383625;6365.840853978595;6649.800505984828;6663.600183988976;6667.366600172976;6668.499862510331;6673.132492776005;6110.163888792202;6203.658798766111;6250.093736408196;6289.088082228142;6328.208973878265;5436.592255580847;5468.671879687232;5845.795397161079;5862.8534286642325;5881.264841894763;6831.4876384272375;6873;6888.758893438706;6891.931080689129;6900.930990690029;6625.966870165618;6643.667816609247;6653.1018312527185;6665.7333706653135;6668.033135195273;6570.441512471493;6682.766103186365;6704.229013663491;6710.127683401127;6855.862882742217;6369.235691717024;6372.394622510854;6375.075614035846;6379.393957574023;6385.45723613498;5293.761780719799;6411.134496842867;6424.10062591247;6437.742490300336;6629.237637671729;6421.839454013687;6423.614583125028;6444.194475690539;6449.3873082057125;6450.41946224842;5330.173823057432;6435.195600549931;6440;6443.581167224115;6449.677516124081;6166.568340216142;6194.5044396449075;6278.089676996901;6286.654234017173;6297.244330680348;6401.167848179707;6415.262244841903;6418.19772528434;6418.326075762165;6422.42198202164;6364.299927007933;6365;6367.90448143269;6385.93614063872;6389.4888408926245 06:03:04 INFO - PID 1649 | |1;ext.html;791.1613689489141;1108.423619717747;1137.1130518195805;1139.5498777982698;1262.3372729317114;37266;37764.86705398835;37838.24323513528;37875.81062094685;37894.242115157715;37307.62692373082;37738.622613773856;37858.43212351817;37866.62133378666;37873;6106.3893610639025;7833.334166595832;7964.5619490927975;7970.60146992651;7975.441719079667;6188.133661287401;8334.333253339719;8502.829943401128;8518.27594654455;8521.95739021304;8551.786205344853;8595.570221488932;8607.053224145338;8608.52653104079;8633.611487483047;6027.457528822383;8509.191626795444;8574.657013719412;8582.785430364229;8609.225169734755;6142.938570614281;6200.782972595937;6207.658578778165;6213.37866213375;8219.095899451055;37356.62643373559;37684.623153768385;37778.62221377779;37780.24439511222;37815;2993.5958645582787;3001.9699803002127;3006.684298148685;3017.306019615479;3031.0906727981737;26814;26888.59667104995;26997.055103071496;27325.316867078283;27944.720552794413;2768.47887631745;2784.303924018995;2799.692033876272;2820.6192164057793;2842.2041828287906;306740.46629766707;306846.465767672;307035;310173;310395.8960410367;38278.80860595735;38311.61688383081;48465.75767121212;48739.495798319695;48762.756186218845;40597.39103913444;40603.59396406057;40609.79695101506;40692.779216622956;40721.79639101845;13636.727265454636;14050.578482645536;14062.859371406359;14070.2964851757;14074.577762667137;11537.307761534335;11539.19225654196;11542.07663386927;11559.248648837798;11574.958253757202;12064.517419303129;12082;12104.152709310263;12158.392080395945;12213.816792748115;5308.336457942757;5330.254918716864;5346.4386239445075;5347.759350829222;5349.1976203569775;881.4182639457927;962.8459446488669;984.2962281968427;1000.4197565412044;1003.7741508160606;22366.888165559067;23624;23726;23804;23848.403789905045;1073.9409332486632;1075.6181555547823;1094.261373572835;1100.763335882789;1114.5486078138367;11402.486888090056;12023.579174728842;12043.434353533985;12092.214006089576;12101.636950891487;408013;412245;413342;413411.8658813373;414286;52301.7384913073;52324.476755232725;52331.93518874979;52448.47551524436;52649.04966155763;1256.1520973342956;1467.6404280951022;1492.1196494068552;1494.6114010357385;1519.3011214841076;17436.128193590524;27748.86125569359;28394.290142746595;28911.566326504697;28927.7107228925;48612.75693621509;48687;48874;49002;49056.75471622619;363851;366019;368357;370076;370788;24782.876085619457;29361.11916642504;30839.691603083684;31052;31090.22274443157;39565.60434395619;39857.2028559433;41060.58939410568;41141.79429102955;41228.58771412248;24728.505429891662;24998.50002999966;27045.86477067602;27576.86211568929;27797.305067373476;27803;28153.718462815108;28403.715962840106;28764.84940602436;28766.712332876403;32443.837780810944;32921.83539082385;33632.99101026973;33742;33880.15299617529;22890.54218915646;28421.857890710413;28721.569176462788;28821.70302336442;29347.85326073356;21832;22742.431439214153;22881.313560593208;23132.421689457897;23276.65085023692 06:03:04 INFO - PID 1649 | |2;jquery.html;420.54160964548635;428.6185295087371;429.06463908679075;430.41248695530595;444.42668957045413;20900;21208.25771098012;21672.458188545294;22293.108275668954;22584.322470325875;17749.20128594213;22482.213122540714;23039.654405183894;23182.65226021611;23537.882310588466;5913.14259432382;5995.6102853314505;6211.378862113797;6216.160818289541;6217.347178546255;5991.790287339944;6149.784757533487;6151.969240153793;6164.07538869171;6167.229096362955;5820.243368362107;6039.094135879631;6119.204503414549;6124.081387791823;6167.845803854894;5978.312494063173;6040.66776327304;6041.939580604182;6046.939530604682;6053.364396738367;5261.3686357637325;5294.152935530296;5346.599005074596;5351.973240133774;5364.356277246717;26338.60492092601;26388.944442222288;26449.73550264492;26496.73503264962;26780.46439071227;2021.878687278768;2024.271262345569;2034.043999320317;2041.275347251719;2074.201432448503;15500.612484687861;16018;16257.51227463178;16493.422730204504;16792;1637.5742307000146;1663.8918470299514;1673.355758033166;1687.0721103393084;1705.7441383792407;131375;131491;132193;134112;134338;9742.61029558824;11286.122038880198;11925.463354149084;12004.579839705568;12186.634400967985;14373.281335933161;14900.807935365145;16075.919620401823;16082.035077895363;16129.032258064319;8544.359173062045;8853.69012084574;8879.600417981206;8987.325950553604;8998.255532062652;8445.915540844635;8457.40798144124;8497.915020849836;8642.740717778477;8682.392232543662;7485.625718714043;7578.14558670718;7669.769906902802;7715.30562249389;7720.343770779435;2101.317071951622;2103.789621037884;2104.105755054096;2107.862988905716;2134.260577629227;816.3958670583726;904.3082042237694;942.1614762861059;946.682861241478;975.8682577851899;17178.6927843293;19516.51208719765;19939.800601994084;20370.28703995353;20382.980850957392;960.9093678674755;962.2927148545813;998.0668075349571;1001.9899801002042;1015.2487159502062;10206.234532410099;10542.051215390651;10591;10688.679339619825;10696;603948;611291;624810;626756;627108;153012;154860;156433.2178339101;157500;159542;1158.034166390723;1175.8765329640435;1209.800382936823;1211.8061110222322;1220.536196245421;16926.57683557921;17376.652466950844;18040.909795450938;18178.36375726843;18394.540136496693;60625;61729;63156.68421657862;63896.68051659712;64487.355126448136;86981;87892;88763;93944;94023;6839.863202735818;8674.739757807276;8703;8998.875140607424;9217.76955576116;14060.015798894388;14099.083559568595;14374.856251437352;14448.494302699355;14569.781453278421;11682.299062056303;11823.290602563873;12181.39093045362;12204.633860984184;12208.75582488363;11073.778524429628;11081.113510918945;11398.518192634905;11408.429578521207;11498.94250528742;11746.765064698488;12082.912537871634;12260.509579617072;12275.754484910072;12295.344141694562;8628.78428039304;8629.784255393666;8703.347248956226;9101.863472048057;9136.132067453582;11612.851622449874;11772.411379431165;12120.878791211975;12121.45453454579;12156.75686486283 06:03:04 INFO - PID 1649 | |3;mootools.html;1162.128403697227;1319.2546211390563;1378.8690074442932;1395.3441882315308;1402.8036074949505;9133.634654613816;9598.808023839527;9600.183984361329;9600.56797444115;9604.231661467084;9388.295877809174;9504.477253751058;10254.128399086068;10558.630447934323;10572.154227661787;2398.436367453647;2441.633754936756;2441.865697386652;2443;2445.364205306624;2152.461884528868;2304.746477887431;2361.657559653849;2406.0255596483453;2429.9392515187087;2042.8978551072462;2139.4116617930117;2344.23812261015;2400.4478969836946;2445.7798798108165;2401.3636386357543;2418.8669623170717;2431.100492817669;2434.5982912819454;2435.6468312094826;2383.9987205373786;2392.9521409571885;2406.121765555577;2409.445827459694;2415.613501839697;10616.787664246673;10854.403007834566;10867.891321086847;10881.401522916236;10921;6042.788502402394;6261.217347831521;6263.561550691454;6268;6288.9999999999545;3639.4540818877117;3653.1962968146963;3776.339140650375;4011.959880401188;4012.25773231953;1131.3607811586428;1131.4851742457245;1181.4270079011671;1182.4265231362783;1217.8355921950513;13502.447218569794;15051.774223386657;15163.545093647208;15203.467878624195;15235.390584376612;5232.215167724872;5344.225087362311;5641.52047075995;5664.150377443334;5675.517581005578;12674.049446291565;12675.619731408073;12686.302253375969;12688.936555317165;12727.745445091046;6300.527460440485;6315.621062736251;6320.936790632127;6325.430711236011;6325.493960483151;4044.656204222615;5759.337676167294;5761.769529218868;5804.564657650693;5817.214676018683;4186.895327616773;4325.4593175853015;4341.34879768031;4402.779861006937;4421.7789110544345;1126.7859106769688;1230.8276841242234;1315.5724389573427;1331.074902942465;1350.257358452847;732.648328802178;737.6754228139602;755.7392699518657;760.0385512326911;790.6165509727778;5839.182514447981;6324.873502530016;6509.739610415625;6520.478361731145;6661.500387470959;563.2986931270594;564.9745711533553;576.2882839692976;584.4885724990634;588.520355909932;3574.4995700601417;3617.8733744318824;3669.4679271506016;3699.2416554606243;3728.0493474163914;13207.801882971764;13646.522371716943;13786.862131378759;13874.861251387358;13910.374033168531;13654.590362289147;13655.180689158682;13764.518241861688;13839.654008649663;13929.651758705912;9998.650047248311;10028.298019138736;10168.898311016943;10187.439690817077;10195.439250841127;6253.155823963707;6388.712507937061;6447.967760161169;6617.702203400955;6629.867402652017;11930.472081597976;12269.202501837352;12310.569130080763;12364.319962402154;12492.875071249171;12097.153199275966;12164.695882603006;12512.186707863959;12668.429920653776;12700.555480558136;7961.800954976172;8262.752117436487;8280.296174824967;8790.780230494032;9082.273418126402;10015.549300281651;10108.241881859038;10299.639512617023;10365;10380.377177369382;8492;8536.402451828313;8574.485530868167;8597.8710319344;8623.267022303175;7526.473146879666;8352.457090289112;8404.159584041547;8434.283085937765;8459.111793261865;7593.658285377282;7768.961155194188;7774.650140743567;7788.415868809749;7788.844223115392;5624.550035997028;5656.208130861765;5800.4489573490455;5877.735501902339;5917.497012753965;5722.284714410699;5755.1942728017275;5951.702414879325;5968.194293770285;6117.755289788359 06:03:04 INFO - PID 1649 | |4;prototype.html;376.54465490877766;385.8919502539289;401.1735824202143;403.27813214346287;403.53593367627207;5661.1508273758955;5726.742296596648;6079.996800527913;6135.631862088272;6165.568410211287;5546.86132846678;5822.4177582241755;5932.3474417814;6010.188624535686;6084.756609735606;3564.500969864221;3710.591834898159;4529.6376289896725;4550.65870059746;4560.543945605446;3927.3912543555653;4567.474740404846;4581;4586.518415566367;4596.172688915994;3676.650718181782;4276.572342765729;4482.327650852366;4521.299198624219;4532.116237333721;3941.8568615101585;4220.775974967256;4473.4408198975125;4489.371487991683;4551.385562948993;3874.2638898609175;3884.4367566702954;3895.162540053902;3899.961000389988;3904.355781296111;5638.71806409682;5851.356350801409;5851.502622277111;5929.288485381739;5965.403459654043;4529.958109634769;4789.778606455367;5227.242049902781;5254.21186821976;5279.762410691528;5210.557102646279;6200.503959683215;6438.742450302029;6459.67701614922;6474.870502589922;4159.272127377697;4253.723507972003;4277.144571085764;4305.720128191658;4307.289297265947;5465.480779325958;6453.9677301613665;6655.7419638485535;6747.190337159573;6862.588244705334;3639.7998110103667;3683.723720720957;4228.978855105766;4334.45819272591;4342.699548343282;4202.814536441034;4457.665675074382;4468.977655111768;4474.129877655804;4482.46210454741;3956.742811717229;4863.440704318978;4936.173615762119;5017.297578339036;5316.946830531722;5605.971970140124;6247.437730604267;6439.323870993572;6482.384173503509;6507.316731743099;1352.1076089780697;1500.1524138861507;1517.770605809298;1532.6781375911185;1534.3018926388465;995.5221493683073;1009.7122320138725;1015.9288849780445;1017.3946501831399;1018.165104614224;5658.255745963204;6107.495926670044;6122.724477398528;6159.384061593805;6175.660338681416;6317.210348706411;6639.302873198341;6660.500462465237;6672.599644021374;6696.86606267872;5093.312402825645;5094.974525127351;5237.486006716797;5800;6017.849553761103;2982.791969252456;3265;3345.7323414126813;3486.320167567351;3503.7197024238;6600.042993765973;6893.656156906676;6901.963920643422;6904.3095690430555;6952.860942781116;6906.8618627628175;6923.676635215125;6936.4797640177185;6944.4097251734165;6961.721531138799;6787.830304242433;6986.755463558751;7004.719811207495;7015.9298407016295;7031.964840175767;5511.889762204813;6663.233728121134;6718.899216511854;6730.629815360202;6787.355201255873;6334.081558173947;6607.405333519814;6626.53614246998;6643.966780166068;6653.835578773792;5541.861453463695;6240.157578726814;6847.794566163023;6900.411226534298;6935.861282774417;3688.981555092207;4336.219480493542;4390.495093064338;4405.339199120158;4422.646188304992;5036.217673430226;5573.244428005165;6748.6963086660235;6748.69630866622;6778.627175505395;6440.420362167428;6498.180509457359;6504.349565043647;6510.414062734377;6517.837054073686;6190.876182476415;6253.749850005949;6498.512611554247;6537;6580.539362244597;4594.287885377638;5568.248286481436;5777.508911742549;5958.927393069289;6326.457183976803;5922.822315330547;6462.709178086903;6499.740010399721;6502.577332473374;6513.902291465537;4934.358533390637;5238.083335416363;5838.182654428469;6000.909986350121;6319 06:03:04 INFO - PID 1649 | |5;yui.html;729.9124105107388;732.0702707561394;737.7122922060397;747.1034758290053;774.0440555913445;14859.777103343446;15802.525924222278;15888;17580.560485987855;18520.073996300198;16738.66522669546;16922.746158807597;16968.236429360677;16981.660366792657;17297;931.1247427418222;955.1117460761488;956.273232343417;959.0744931141447;959.4435227567993;925.5279807298293;953;982.6953644370268;985.3152059318741;987.2299606307092;1032.2361452525151;1033.8759348878107;1035.2132379391678;1035.6219979707428;1039.9012093851072;1031.1647565471917;1031.5925209542227;1031.9845202321972;1032.1123833503182;1033.9172866170688;766.781467281822;767.3745897093851;767.5701607100042;769.2807225244414;770.1566784371105;15777.763333550007;16076.356945722157;16252.431164909289;16516.256768445448;16810.411635592696;14309.856901431061;14481.275936203148;14523.854761452356;14555.345009474599;14664.853351466456;11674.35791031493;11696.532138714441;11909.628403641173;11915.344656043913;12257.448414821354;2969.213158512974;3119.0486901495033;3131.5302704594264;3139.121046107071;3141.7643676724338;103177;107478.46260768647;108262;108524.45737771339;111663.44168279269;10798.460076996118;11246.831297530543;11437.885621143849;11738.530458781725;11756.647300580997;24489.63265551018;24822.875885620455;24944;25017.249482515555;25055.99776008939;7742.61286935651;7780.299773020455;7936.761897143095;8014.959925200336;8121.390895682729;4012.3981402789814;4135.56576559463;4147.336426171798;4148.688848336386;4154.255212810627;4090.9386359204636;4134.193832202692;4178.561251068594;4191.517975432804;4196.181744559843;850.9191626795445;852.957352132391;853.4537895746727;858.2190206911805;861.6338056326036;510.4396832096174;523.7407483295793;539.7085573790188;549.8900219956025;584.964902105875;9591.280653950842;9966.501674916226;10059.39643621385;10119;10123.24075694311;441.88731873372086;522.5297579209896;536.4394208052595;558.7150553217888;573.6787399056542;3822.3502004659276;4430.977845110754;4680.578747912704;4702.318163866289;4794.520547945247;62037.37962620406;81854;90613;92931;94850.61792584934;63683.681581591794;80403;84016;85091.57454212813;85135;67338.66330668413;69716.65141674259;71543.64228178826;71584.56830863353;72072;7804.492707973964;9388.530573471437;9459.574319155518;9478.383905046427;9525.190358819578;36189.0952726184;37058.6294137066;37273;37593;37794.24411511809;47803.5219647813;48196.7590162047;48422.5157748418;48699.05203791809;49130.50869491259;11458.828117578076;11570.826437603611;11724.824127637921;11888.049224887298;12033.004813202037;11070.446477676245;11895.632002319498;12512.749745004867;12585.181963172365;12670.049746268882;10224.437655928697;11752;11984.460699268728;12361.93819030899;12510.812337814757;11132.666020019413;11531.711707207387;12193.207441516273;12199.69500762488;12203.389830508262;11291.424241064424;11942.4028798558;12213.206141600767;12259.080568957543;12285.631431057083;8500.659973601234;8793.164649358301;8825.602847871989;8975.596098175729;9239.630414783333;7416.962915185605;9745.12293893553;9914.652987145415;9922.355046921926;9924.751881203028 06:03:04 INFO - PID 1649 | __end_tp_report 06:03:04 INFO - PID 1649 | __start_cc_report 06:03:04 INFO - PID 1649 | _x_x_mozilla_cycle_collect,2521 06:03:04 INFO - PID 1649 | __end_cc_report 06:03:04 INFO - PID 1649 | __startTimestamp1490878984079__endTimestamp 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | ------- Summary: start ------- 06:03:04 INFO - PID 1649 | Number of tests: 6 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6102.35 Median:6338.37 stddev:689.73 (10.9%) stddev-sans-first:659.10 06:03:04 INFO - PID 1649 | Values: 3425.3 3480.5 3491.9 3512.6 3521.1 6020.3 6109.8 6229.8 6238.9 6248.4 6146.6 6221.8 6253.4 6254.4 6277.9 5326.4 5444.4 5518.0 5586.2 5650.4 5576.5 5585.5 5606.9 5617.5 5628.0 5428.2 5499.7 5526.9 5543.3 5554.6 5481.0 5526.0 5549.7 5596.4 5609.4 5478.7 5494.2 5509.5 5550.9 5560.4 6082.2 6138.1 6145.8 6221.6 6223.7 6399.2 6404.2 6410.1 6418.9 6420.7 6540.6 6578.5 6579.0 6634.6 6662.1 6243.3 6310.2 6311.0 6329.4 6330.2 6802.5 6815.1 6827.4 6828.2 6840.7 6302.5 6368.9 6378.6 6419.9 6420.2 6645.2 6660.7 6663.6 6674.3 6677.6 6548.8 6564.0 6568.8 6570.1 6577.9 6331.6 6367.3 6579.4 6612.7 6640.1 5201.1 5216.9 5220.5 5221.2 5241.9 4676.6 4713.6 4720.1 4731.1 4731.9 6258.7 6268.2 6329.0 6338.4 6365.8 6649.8 6663.6 6667.4 6668.5 6673.1 6110.2 6203.7 6250.1 6289.1 6328.2 5436.6 5468.7 5845.8 5862.9 5881.3 6831.5 6873.0 6888.8 6891.9 6900.9 6626.0 6643.7 6653.1 6665.7 6668.0 6570.4 6682.8 6704.2 6710.1 6855.9 6369.2 6372.4 6375.1 6379.4 6385.5 5293.8 6411.1 6424.1 6437.7 6629.2 6421.8 6423.6 6444.2 6449.4 6450.4 5330.2 6435.2 6440.0 6443.6 6449.7 6166.6 6194.5 6278.1 6286.7 6297.2 6401.2 6415.3 6418.2 6418.3 6422.4 6364.3 6365.0 6367.9 6385.9 6389.5 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:49375.88 Median:31767.03 stddev:96729.51 (304.5%) stddev-sans-first:96932.08 06:03:04 INFO - PID 1649 | Values: 791.2 1108.4 1137.1 1139.5 1262.3 37266.0 37764.9 37838.2 37875.8 37894.2 37307.6 37738.6 37858.4 37866.6 37873.0 6106.4 7833.3 7964.6 7970.6 7975.4 6188.1 8334.3 8502.8 8518.3 8522.0 8551.8 8595.6 8607.1 8608.5 8633.6 6027.5 8509.2 8574.7 8582.8 8609.2 6142.9 6200.8 6207.7 6213.4 8219.1 37356.6 37684.6 37778.6 37780.2 37815.0 2993.6 3002.0 3006.7 3017.3 3031.1 26814.0 26888.6 26997.1 27325.3 27944.7 2768.5 2784.3 2799.7 2820.6 2842.2 306740.5 306846.5 307035.0 310173.0 310395.9 38278.8 38311.6 48465.8 48739.5 48762.8 40597.4 40603.6 40609.8 40692.8 40721.8 13636.7 14050.6 14062.9 14070.3 14074.6 11537.3 11539.2 11542.1 11559.2 11575.0 12064.5 12082.0 12104.2 12158.4 12213.8 5308.3 5330.3 5346.4 5347.8 5349.2 881.4 962.8 984.3 1000.4 1003.8 22366.9 23624.0 23726.0 23804.0 23848.4 1073.9 1075.6 1094.3 1100.8 1114.5 11402.5 12023.6 12043.4 12092.2 12101.6 408013.0 412245.0 413342.0 413411.9 414286.0 52301.7 52324.5 52331.9 52448.5 52649.0 1256.2 1467.6 1492.1 1494.6 1519.3 17436.1 27748.9 28394.3 28911.6 28927.7 48612.8 48687.0 48874.0 49002.0 49056.8 363851.0 366019.0 368357.0 370076.0 370788.0 24782.9 29361.1 30839.7 31052.0 31090.2 39565.6 39857.2 41060.6 41141.8 41228.6 24728.5 24998.5 27045.9 27576.9 27797.3 27803.0 28153.7 28403.7 28764.8 28766.7 32443.8 32921.8 33633.0 33742.0 33880.2 22890.5 28421.9 28721.6 28821.7 29347.9 21832.0 22742.4 22881.3 23132.4 23276.7 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:37919.54 Median:22387.66 stddev:104345.50 (466.1%) stddev-sans-first:104600.23 06:03:04 INFO - PID 1649 | Values: 420.5 428.6 429.1 430.4 444.4 20900.0 21208.3 21672.5 22293.1 22584.3 17749.2 22482.2 23039.7 23182.7 23537.9 5913.1 5995.6 6211.4 6216.2 6217.3 5991.8 6149.8 6152.0 6164.1 6167.2 5820.2 6039.1 6119.2 6124.1 6167.8 5978.3 6040.7 6041.9 6046.9 6053.4 5261.4 5294.2 5346.6 5352.0 5364.4 26338.6 26388.9 26449.7 26496.7 26780.5 2021.9 2024.3 2034.0 2041.3 2074.2 15500.6 16018.0 16257.5 16493.4 16792.0 1637.6 1663.9 1673.4 1687.1 1705.7 131375.0 131491.0 132193.0 134112.0 134338.0 9742.6 11286.1 11925.5 12004.6 12186.6 14373.3 14900.8 16075.9 16082.0 16129.0 8544.4 8853.7 8879.6 8987.3 8998.3 8445.9 8457.4 8497.9 8642.7 8682.4 7485.6 7578.1 7669.8 7715.3 7720.3 2101.3 2103.8 2104.1 2107.9 2134.3 816.4 904.3 942.2 946.7 975.9 17178.7 19516.5 19939.8 20370.3 20383.0 960.9 962.3 998.1 1002.0 1015.2 10206.2 10542.1 10591.0 10688.7 10696.0 603948.0 611291.0 624810.0 626756.0 627108.0 153012.0 154860.0 156433.2 157500.0 159542.0 1158.0 1175.9 1209.8 1211.8 1220.5 16926.6 17376.7 18040.9 18178.4 18394.5 60625.0 61729.0 63156.7 63896.7 64487.4 86981.0 87892.0 88763.0 93944.0 94023.0 6839.9 8674.7 8703.0 8998.9 9217.8 14060.0 14099.1 14374.9 14448.5 14569.8 11682.3 11823.3 12181.4 12204.6 12208.8 11073.8 11081.1 11398.5 11408.4 11498.9 11746.8 12082.9 12260.5 12275.8 12295.3 8628.8 8629.8 8703.3 9101.9 9136.1 11612.9 11772.4 12120.9 12121.5 12156.8 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:6672.94 Median:3661.33 stddev:4163.51 (113.7%) stddev-sans-first:4154.59 06:03:04 INFO - PID 1649 | Values: 1162.1 1319.3 1378.9 1395.3 1402.8 9133.6 9598.8 9600.2 9600.6 9604.2 9388.3 9504.5 10254.1 10558.6 10572.2 2398.4 2441.6 2441.9 2443.0 2445.4 2152.5 2304.7 2361.7 2406.0 2429.9 2042.9 2139.4 2344.2 2400.4 2445.8 2401.4 2418.9 2431.1 2434.6 2435.6 2384.0 2393.0 2406.1 2409.4 2415.6 10616.8 10854.4 10867.9 10881.4 10921.0 6042.8 6261.2 6263.6 6268.0 6289.0 3639.5 3653.2 3776.3 4012.0 4012.3 1131.4 1131.5 1181.4 1182.4 1217.8 13502.4 15051.8 15163.5 15203.5 15235.4 5232.2 5344.2 5641.5 5664.2 5675.5 12674.0 12675.6 12686.3 12688.9 12727.7 6300.5 6315.6 6320.9 6325.4 6325.5 4044.7 5759.3 5761.8 5804.6 5817.2 4186.9 4325.5 4341.3 4402.8 4421.8 1126.8 1230.8 1315.6 1331.1 1350.3 732.6 737.7 755.7 760.0 790.6 5839.2 6324.9 6509.7 6520.5 6661.5 563.3 565.0 576.3 584.5 588.5 3574.5 3617.9 3669.5 3699.2 3728.0 13207.8 13646.5 13786.9 13874.9 13910.4 13654.6 13655.2 13764.5 13839.7 13929.7 9998.7 10028.3 10168.9 10187.4 10195.4 6253.2 6388.7 6448.0 6617.7 6629.9 11930.5 12269.2 12310.6 12364.3 12492.9 12097.2 12164.7 12512.2 12668.4 12700.6 7961.8 8262.8 8280.3 8790.8 9082.3 10015.5 10108.2 10299.6 10365.0 10380.4 8492.0 8536.4 8574.5 8597.9 8623.3 7526.5 8352.5 8404.2 8434.3 8459.1 7593.7 7769.0 7774.7 7788.4 7788.8 5624.6 5656.2 5800.4 5877.7 5917.5 5722.3 5755.2 5951.7 5968.2 6117.8 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5163.11 Median:5659.70 stddev:1654.88 (29.2%) stddev-sans-first:1620.05 06:03:04 INFO - PID 1649 | Values: 376.5 385.9 401.2 403.3 403.5 5661.2 5726.7 6080.0 6135.6 6165.6 5546.9 5822.4 5932.3 6010.2 6084.8 3564.5 3710.6 4529.6 4550.7 4560.5 3927.4 4567.5 4581.0 4586.5 4596.2 3676.7 4276.6 4482.3 4521.3 4532.1 3941.9 4220.8 4473.4 4489.4 4551.4 3874.3 3884.4 3895.2 3900.0 3904.4 5638.7 5851.4 5851.5 5929.3 5965.4 4530.0 4789.8 5227.2 5254.2 5279.8 5210.6 6200.5 6438.7 6459.7 6474.9 4159.3 4253.7 4277.1 4305.7 4307.3 5465.5 6454.0 6655.7 6747.2 6862.6 3639.8 3683.7 4229.0 4334.5 4342.7 4202.8 4457.7 4469.0 4474.1 4482.5 3956.7 4863.4 4936.2 5017.3 5316.9 5606.0 6247.4 6439.3 6482.4 6507.3 1352.1 1500.2 1517.8 1532.7 1534.3 995.5 1009.7 1015.9 1017.4 1018.2 5658.3 6107.5 6122.7 6159.4 6175.7 6317.2 6639.3 6660.5 6672.6 6696.9 5093.3 5095.0 5237.5 5800.0 6017.8 2982.8 3265.0 3345.7 3486.3 3503.7 6600.0 6893.7 6902.0 6904.3 6952.9 6906.9 6923.7 6936.5 6944.4 6961.7 6787.8 6986.8 7004.7 7015.9 7032.0 5511.9 6663.2 6718.9 6730.6 6787.4 6334.1 6607.4 6626.5 6644.0 6653.8 5541.9 6240.2 6847.8 6900.4 6935.9 3689.0 4336.2 4390.5 4405.3 4422.6 5036.2 5573.2 6748.7 6748.7 6778.6 6440.4 6498.2 6504.3 6510.4 6517.8 6190.9 6253.7 6498.5 6537.0 6580.5 4594.3 5568.2 5777.5 5958.9 6326.5 5922.8 6462.7 6499.7 6502.6 6513.9 4934.4 5238.1 5838.2 6000.9 6319.0 06:03:04 INFO - PID 1649 | 06:03:04 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:18570.70 Median:4112.57 stddev:26282.30 (639.1%) stddev-sans-first:26321.89 06:03:04 INFO - PID 1649 | Values: 729.9 732.1 737.7 747.1 774.0 14859.8 15802.5 15888.0 17580.6 18520.1 16738.7 16922.7 16968.2 16981.7 17297.0 931.1 955.1 956.3 959.1 959.4 925.5 953.0 982.7 985.3 987.2 1032.2 1033.9 1035.2 1035.6 1039.9 1031.2 1031.6 1032.0 1032.1 1033.9 766.8 767.4 767.6 769.3 770.2 15777.8 16076.4 16252.4 16516.3 16810.4 14309.9 14481.3 14523.9 14555.3 14664.9 11674.4 11696.5 11909.6 11915.3 12257.4 2969.2 3119.0 3131.5 3139.1 3141.8 103177.0 107478.5 108262.0 108524.5 111663.4 10798.5 11246.8 11437.9 11738.5 11756.6 24489.6 24822.9 24944.0 25017.2 25056.0 7742.6 7780.3 7936.8 8015.0 8121.4 4012.4 4135.6 4147.3 4148.7 4154.3 4090.9 4134.2 4178.6 4191.5 4196.2 850.9 853.0 853.5 858.2 861.6 510.4 523.7 539.7 549.9 585.0 9591.3 9966.5 10059.4 10119.0 10123.2 441.9 522.5 536.4 558.7 573.7 3822.4 4431.0 4680.6 4702.3 4794.5 62037.4 81854.0 90613.0 92931.0 94850.6 63683.7 80403.0 84016.0 85091.6 85135.0 67338.7 69716.7 71543.6 71584.6 72072.0 7804.5 9388.5 9459.6 9478.4 9525.2 36189.1 37058.6 37273.0 37593.0 37794.2 47803.5 48196.8 48422.5 48699.1 49130.5 11458.8 11570.8 11724.8 11888.0 12033.0 11070.4 11895.6 12512.7 12585.2 12670.0 10224.4 11752.0 11984.5 12361.9 12510.8 11132.7 11531.7 12193.2 12199.7 12203.4 11291.4 11942.4 12213.2 12259.1 12285.6 8500.7 8793.2 8825.6 8975.6 9239.6 7417.0 9745.1 9914.7 9922.4 9924.8 06:03:04 INFO - PID 1649 | -------- Summary: end -------- 06:03:04 INFO - PID 1649 | 06:03:04 INFO - TEST-INFO | 1649: exit 0 06:03:04 INFO - TEST-OK | dromaeo_css | took 1120967ms 06:03:04 INFO - TEST-START | kraken 06:03:04 INFO - operating with platform_type : mac_ 06:03:04 INFO - Initialising browser for kraken test... 06:03:04 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmprzk6li/profile http://localhost:49236/getInfo.html) 06:03:07 INFO - PID 1655 | __metrics Screen width/height:1600/1200 06:03:07 INFO - PID 1655 | colorDepth:24 06:03:07 INFO - PID 1655 | Browser inner width/height: 1024/689 06:03:07 INFO - PID 1655 | __metrics 06:03:07 ERROR - PID 1655 | 1490878987194 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:03:07 INFO - PID 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:03:07 INFO - PID 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:03:08 INFO - TEST-INFO | 1655: exit 0 06:03:08 INFO - Browser initialized. 06:03:08 INFO - Running cycle 1/1 for kraken test... 06:03:08 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmprzk6li/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:03:20 INFO - PID 1656 | RSS: Main: 234078208 06:03:20 INFO - PID 1656 | 06:03:23 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 06:03:24 INFO - PID 1656 | RSS: Main: 286789632 06:03:24 INFO - PID 1656 | 06:03:27 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 06:03:27 INFO - PID 1656 | RSS: Main: 287350784 06:03:27 INFO - PID 1656 | 06:03:31 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 06:03:32 INFO - PID 1656 | RSS: Main: 382083072 06:03:32 INFO - PID 1656 | 06:03:34 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 06:03:35 INFO - PID 1656 | RSS: Main: 285003776 06:03:35 INFO - PID 1656 | 06:03:37 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:03:37 INFO - PID 1656 | RSS: Main: 287080448 06:03:37 INFO - PID 1656 | 06:03:40 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 06:03:41 INFO - PID 1656 | RSS: Main: 329121792 06:03:41 INFO - PID 1656 | 06:03:44 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 06:03:45 INFO - PID 1656 | RSS: Main: 344072192 06:03:45 INFO - PID 1656 | 06:03:48 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 06:03:49 INFO - PID 1656 | RSS: Main: 356925440 06:03:49 INFO - PID 1656 | 06:03:50 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:03:51 INFO - PID 1656 | RSS: Main: 304402432 06:03:51 INFO - PID 1656 | 06:03:53 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:03:54 INFO - PID 1656 | RSS: Main: 308150272 06:03:54 INFO - PID 1656 | 06:03:56 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:03:57 INFO - PID 1656 | RSS: Main: 309886976 06:03:57 INFO - PID 1656 | 06:04:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:04:00 INFO - PID 1656 | RSS: Main: 328376320 06:04:00 INFO - PID 1656 | 06:04:04 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:04:05 INFO - PID 1656 | RSS: Main: 311844864 06:04:05 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 06:04:07 INFO - PID 1656 | RSS: Main: 378568704 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | __start_tp_report 06:04:07 INFO - PID 1656 | _x_x_mozilla_page_load 06:04:07 INFO - PID 1656 | _x_x_mozilla_page_load_details 06:04:07 INFO - PID 1656 | |i|pagename|runs| 06:04:07 INFO - PID 1656 | |0;ai-astar;113;125;123;122;123;122;123;125;125;122 06:04:07 INFO - PID 1656 | |1;audio-beat-detection;166;147;162;182;165;161;143;121;146;117 06:04:07 INFO - PID 1656 | |2;audio-dft;215;213;212;214;217;220;218;220;209;215 06:04:07 INFO - PID 1656 | |3;audio-fft;81;68;70;131;96;65;64;65;84;64 06:04:07 INFO - PID 1656 | |4;audio-oscillator;91;75;96;78;74;76;93;75;75;92 06:04:07 INFO - PID 1656 | |5;imaging-gaussian-blur;92;92;93;92;91;90;93;93;91;91 06:04:07 INFO - PID 1656 | |6;imaging-darkroom;99;99;125;100;101;100;101;101;100;101 06:04:07 INFO - PID 1656 | |7;imaging-desaturate;100;102;103;101;101;103;102;103;103;134 06:04:07 INFO - PID 1656 | |8;json-parse-financial;64;64;63;63;65;64;65;65;65;64 06:04:07 INFO - PID 1656 | |9;json-stringify-tinderbox;58;68;75;71;69;64;64;64;65;65 06:04:07 INFO - PID 1656 | |10;stanford-crypto-aes;90;91;91;90;92;91;87;93;90;91 06:04:07 INFO - PID 1656 | |11;stanford-crypto-ccm;154;164;151;155;156;152;156;152;192;171 06:04:07 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;236;228;260;198;261;213;204;203;208;203 06:04:07 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;72;74;77;93;105;108;75;74;72;74 06:04:07 INFO - PID 1656 | __end_tp_report 06:04:07 INFO - PID 1656 | __start_cc_report 06:04:07 INFO - PID 1656 | _x_x_mozilla_cycle_collect,2243 06:04:07 INFO - PID 1656 | __end_cc_report 06:04:07 INFO - PID 1656 | __startTimestamp1490879047337__endTimestamp 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | ------- Summary: start ------- 06:04:07 INFO - PID 1656 | Number of tests: 14 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:122.30 Median:123.00 stddev:3.50 (2.8%) stddev-sans-first:1.32 06:04:07 INFO - PID 1656 | Values: 113.0 125.0 123.0 122.0 123.0 122.0 123.0 125.0 125.0 122.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:161.50 stddev:20.45 (12.7%) stddev-sans-first:20.96 06:04:07 INFO - PID 1656 | Values: 166.0 147.0 162.0 182.0 165.0 161.0 143.0 121.0 146.0 117.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:215.30 Median:216.00 stddev:3.53 (1.6%) stddev-sans-first:3.74 06:04:07 INFO - PID 1656 | Values: 215.0 213.0 212.0 214.0 217.0 220.0 218.0 220.0 209.0 215.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:78.80 Median:69.00 stddev:21.25 (30.8%) stddev-sans-first:22.53 06:04:07 INFO - PID 1656 | Values: 81.0 68.0 70.0 131.0 96.0 65.0 64.0 65.0 84.0 64.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:84.50 stddev:9.18 (10.9%) stddev-sans-first:9.21 06:04:07 INFO - PID 1656 | Values: 91.0 75.0 96.0 78.0 74.0 76.0 93.0 75.0 75.0 92.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:92.00 stddev:1.03 (1.1%) stddev-sans-first:1.09 06:04:07 INFO - PID 1656 | Values: 92.0 92.0 93.0 92.0 91.0 90.0 93.0 93.0 91.0 91.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:101.00 stddev:7.87 (7.8%) stddev-sans-first:8.24 06:04:07 INFO - PID 1656 | Values: 99.0 99.0 125.0 100.0 101.0 100.0 101.0 101.0 100.0 101.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:103.00 stddev:10.17 (9.9%) stddev-sans-first:10.62 06:04:07 INFO - PID 1656 | Values: 100.0 102.0 103.0 101.0 101.0 103.0 102.0 103.0 103.0 134.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.50 stddev:0.79 (1.2%) stddev-sans-first:0.83 06:04:07 INFO - PID 1656 | Values: 64.0 64.0 63.0 63.0 65.0 64.0 65.0 65.0 65.0 64.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.50 stddev:4.67 (7.0%) stddev-sans-first:3.87 06:04:07 INFO - PID 1656 | Values: 58.0 68.0 75.0 71.0 69.0 64.0 64.0 64.0 65.0 65.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:91.00 stddev:1.58 (1.7%) stddev-sans-first:1.66 06:04:07 INFO - PID 1656 | Values: 90.0 91.0 91.0 90.0 92.0 91.0 87.0 93.0 90.0 91.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:156.00 stddev:12.75 (8.2%) stddev-sans-first:13.31 06:04:07 INFO - PID 1656 | Values: 154.0 164.0 151.0 155.0 156.0 152.0 156.0 152.0 192.0 171.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:221.40 Median:220.50 stddev:23.79 (10.8%) stddev-sans-first:24.64 06:04:07 INFO - PID 1656 | Values: 236.0 228.0 260.0 198.0 261.0 213.0 204.0 203.0 208.0 203.0 06:04:07 INFO - PID 1656 | 06:04:07 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.40 Median:74.00 stddev:14.10 (19.1%) stddev-sans-first:14.45 06:04:07 INFO - PID 1656 | Values: 72.0 74.0 77.0 93.0 105.0 108.0 75.0 74.0 72.0 74.0 06:04:07 INFO - PID 1656 | -------- Summary: end -------- 06:04:07 INFO - PID 1656 | 06:04:08 INFO - TEST-INFO | 1656: exit 0 06:04:08 INFO - TEST-OK | kraken | took 63429ms 06:04:08 INFO - SUITE-END | took 1184s 06:04:08 INFO - Completed test suite (00:19:44) 06:04:08 INFO - javascript benchmark 06:04:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7510.739282175157, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.349183655104, 3480.495328177413, 3491.9301613967705, 3512.5784905811297, 3521.102118959666, 6020.277566691992, 6109.7690694183475, 6229.7508099676015, 6238.937610623892, 6248.37516248375, 6146.600470969383, 6221.813345599628, 6253.405926436982, 6254.43710066094, 6277.905831412521, 5326.387465441466, 5444.373897001856, 5518.034343989796, 5586.162075688639, 5650.434956504358, 5576.525995290405, 5585.497305242528, 5606.915896261559, 5617.466340697627, 5627.986962346776, 5428.158635411498, 5499.7250137493365, 5526.944730552683, 5543.307086614173, 5554.555635549146, 5481.040817856899, 5526.0, 5549.722513874331, 5596.412376700469, 5609.439056094398, 5478.671279723229, 5494.230807686927, 5509.531689806371, 5550.888982220374, 5560.443955604448, 6082.2093127893095, 6138.051223665364, 6145.78489752861, 6221.626702397871, 6223.657698826562, 6399.232092148972, 6404.231492220917, 6410.134631824691, 6418.903716444257, 6420.711068001951, 6540.607563546202, 6578.506612004071, 6578.967105164444, 6634.601923884582, 6662.100616416818, 6243.282022567418, 6310.1796766420075, 6310.9684451577905, 6329.4303512683155, 6330.208723909512, 6802.523823332418, 6815.079964204868, 6827.419669328163, 6828.214755303204, 6840.726370945106, 6302.527310451735, 6368.904466433008, 6378.617282963037, 6419.903701444482, 6420.229572451336, 6645.169353830771, 6660.733570657119, 6663.633500157538, 6674.2658307586125, 6677.565958212701, 6548.836279092966, 6564.015397690289, 6568.817612829736, 6570.113034740344, 6577.934220657828, 6331.588446751039, 6367.331430199855, 6579.374959378852, 6612.7354905804195, 6640.103586015923, 5201.141811601119, 5216.869578260498, 5220.504052115043, 5221.242919776611, 5241.895162096736, 4676.625869930397, 4713.552212539803, 4720.103180395714, 4731.053789242096, 4731.929021064687, 6258.655773932477, 6268.216472940882, 6329.019002054689, 6338.366163383625, 6365.840853978595, 6649.800505984828, 6663.600183988976, 6667.366600172976, 6668.499862510331, 6673.132492776005, 6110.163888792202, 6203.658798766111, 6250.093736408196, 6289.088082228142, 6328.208973878265, 5436.592255580847, 5468.671879687232, 5845.795397161079, 5862.8534286642325, 5881.264841894763, 6831.4876384272375, 6873.0, 6888.758893438706, 6891.931080689129, 6900.930990690029, 6625.966870165618, 6643.667816609247, 6653.1018312527185, 6665.7333706653135, 6668.033135195273, 6570.441512471493, 6682.766103186365, 6704.229013663491, 6710.127683401127, 6855.862882742217, 6369.235691717024, 6372.394622510854, 6375.075614035846, 6379.393957574023, 6385.45723613498, 5293.761780719799, 6411.134496842867, 6424.10062591247, 6437.742490300336, 6629.237637671729, 6421.839454013687, 6423.614583125028, 6444.194475690539, 6449.3873082057125, 6450.41946224842, 5330.173823057432, 6435.195600549931, 6440.0, 6443.581167224115, 6449.677516124081, 6166.568340216142, 6194.5044396449075, 6278.089676996901, 6286.654234017173, 6297.244330680348, 6401.167848179707, 6415.262244841903, 6418.19772528434, 6418.326075762165, 6422.42198202164, 6364.299927007933, 6365.0, 6367.90448143269, 6385.93614063872, 6389.4888408926245], "value": 6057.02837746924, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.1613689489141, 1108.423619717747, 1137.1130518195805, 1139.5498777982698, 1262.3372729317114, 37266.0, 37764.86705398835, 37838.24323513528, 37875.81062094685, 37894.242115157715, 37307.62692373082, 37738.622613773856, 37858.43212351817, 37866.62133378666, 37873.0, 6106.3893610639025, 7833.334166595832, 7964.5619490927975, 7970.60146992651, 7975.441719079667, 6188.133661287401, 8334.333253339719, 8502.829943401128, 8518.27594654455, 8521.95739021304, 8551.786205344853, 8595.570221488932, 8607.053224145338, 8608.52653104079, 8633.611487483047, 6027.457528822383, 8509.191626795444, 8574.657013719412, 8582.785430364229, 8609.225169734755, 6142.938570614281, 6200.782972595937, 6207.658578778165, 6213.37866213375, 8219.095899451055, 37356.62643373559, 37684.623153768385, 37778.62221377779, 37780.24439511222, 37815.0, 2993.5958645582787, 3001.9699803002127, 3006.684298148685, 3017.306019615479, 3031.0906727981737, 26814.0, 26888.59667104995, 26997.055103071496, 27325.316867078283, 27944.720552794413, 2768.47887631745, 2784.303924018995, 2799.692033876272, 2820.6192164057793, 2842.2041828287906, 306740.46629766707, 306846.465767672, 307035.0, 310173.0, 310395.8960410367, 38278.80860595735, 38311.61688383081, 48465.75767121212, 48739.495798319695, 48762.756186218845, 40597.39103913444, 40603.59396406057, 40609.79695101506, 40692.779216622956, 40721.79639101845, 13636.727265454636, 14050.578482645536, 14062.859371406359, 14070.2964851757, 14074.577762667137, 11537.307761534335, 11539.19225654196, 11542.07663386927, 11559.248648837798, 11574.958253757202, 12064.517419303129, 12082.0, 12104.152709310263, 12158.392080395945, 12213.816792748115, 5308.336457942757, 5330.254918716864, 5346.4386239445075, 5347.759350829222, 5349.1976203569775, 881.4182639457927, 962.8459446488669, 984.2962281968427, 1000.4197565412044, 1003.7741508160606, 22366.888165559067, 23624.0, 23726.0, 23804.0, 23848.403789905045, 1073.9409332486632, 1075.6181555547823, 1094.261373572835, 1100.763335882789, 1114.5486078138367, 11402.486888090056, 12023.579174728842, 12043.434353533985, 12092.214006089576, 12101.636950891487, 408013.0, 412245.0, 413342.0, 413411.8658813373, 414286.0, 52301.7384913073, 52324.476755232725, 52331.93518874979, 52448.47551524436, 52649.04966155763, 1256.1520973342956, 1467.6404280951022, 1492.1196494068552, 1494.6114010357385, 1519.3011214841076, 17436.128193590524, 27748.86125569359, 28394.290142746595, 28911.566326504697, 28927.7107228925, 48612.75693621509, 48687.0, 48874.0, 49002.0, 49056.75471622619, 363851.0, 366019.0, 368357.0, 370076.0, 370788.0, 24782.876085619457, 29361.11916642504, 30839.691603083684, 31052.0, 31090.22274443157, 39565.60434395619, 39857.2028559433, 41060.58939410568, 41141.79429102955, 41228.58771412248, 24728.505429891662, 24998.50002999966, 27045.86477067602, 27576.86211568929, 27797.305067373476, 27803.0, 28153.718462815108, 28403.715962840106, 28764.84940602436, 28766.712332876403, 32443.837780810944, 32921.83539082385, 33632.99101026973, 33742.0, 33880.15299617529, 22890.54218915646, 28421.857890710413, 28721.569176462788, 28821.70302336442, 29347.85326073356, 21832.0, 22742.431439214153, 22881.313560593208, 23132.421689457897, 23276.65085023692], "value": 16950.006749838503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.54160964548635, 428.6185295087371, 429.06463908679075, 430.41248695530595, 444.42668957045413, 20900.0, 21208.25771098012, 21672.458188545294, 22293.108275668954, 22584.322470325875, 17749.20128594213, 22482.213122540714, 23039.654405183894, 23182.65226021611, 23537.882310588466, 5913.14259432382, 5995.6102853314505, 6211.378862113797, 6216.160818289541, 6217.347178546255, 5991.790287339944, 6149.784757533487, 6151.969240153793, 6164.07538869171, 6167.229096362955, 5820.243368362107, 6039.094135879631, 6119.204503414549, 6124.081387791823, 6167.845803854894, 5978.312494063173, 6040.66776327304, 6041.939580604182, 6046.939530604682, 6053.364396738367, 5261.3686357637325, 5294.152935530296, 5346.599005074596, 5351.973240133774, 5364.356277246717, 26338.60492092601, 26388.944442222288, 26449.73550264492, 26496.73503264962, 26780.46439071227, 2021.878687278768, 2024.271262345569, 2034.043999320317, 2041.275347251719, 2074.201432448503, 15500.612484687861, 16018.0, 16257.51227463178, 16493.422730204504, 16792.0, 1637.5742307000146, 1663.8918470299514, 1673.355758033166, 1687.0721103393084, 1705.7441383792407, 131375.0, 131491.0, 132193.0, 134112.0, 134338.0, 9742.61029558824, 11286.122038880198, 11925.463354149084, 12004.579839705568, 12186.634400967985, 14373.281335933161, 14900.807935365145, 16075.919620401823, 16082.035077895363, 16129.032258064319, 8544.359173062045, 8853.69012084574, 8879.600417981206, 8987.325950553604, 8998.255532062652, 8445.915540844635, 8457.40798144124, 8497.915020849836, 8642.740717778477, 8682.392232543662, 7485.625718714043, 7578.14558670718, 7669.769906902802, 7715.30562249389, 7720.343770779435, 2101.317071951622, 2103.789621037884, 2104.105755054096, 2107.862988905716, 2134.260577629227, 816.3958670583726, 904.3082042237694, 942.1614762861059, 946.682861241478, 975.8682577851899, 17178.6927843293, 19516.51208719765, 19939.800601994084, 20370.28703995353, 20382.980850957392, 960.9093678674755, 962.2927148545813, 998.0668075349571, 1001.9899801002042, 1015.2487159502062, 10206.234532410099, 10542.051215390651, 10591.0, 10688.679339619825, 10696.0, 603948.0, 611291.0, 624810.0, 626756.0, 627108.0, 153012.0, 154860.0, 156433.2178339101, 157500.0, 159542.0, 1158.034166390723, 1175.8765329640435, 1209.800382936823, 1211.8061110222322, 1220.536196245421, 16926.57683557921, 17376.652466950844, 18040.909795450938, 18178.36375726843, 18394.540136496693, 60625.0, 61729.0, 63156.68421657862, 63896.68051659712, 64487.355126448136, 86981.0, 87892.0, 88763.0, 93944.0, 94023.0, 6839.863202735818, 8674.739757807276, 8703.0, 8998.875140607424, 9217.76955576116, 14060.015798894388, 14099.083559568595, 14374.856251437352, 14448.494302699355, 14569.781453278421, 11682.299062056303, 11823.290602563873, 12181.39093045362, 12204.633860984184, 12208.75582488363, 11073.778524429628, 11081.113510918945, 11398.518192634905, 11408.429578521207, 11498.94250528742, 11746.765064698488, 12082.912537871634, 12260.509579617072, 12275.754484910072, 12295.344141694562, 8628.78428039304, 8629.784255393666, 8703.347248956226, 9101.863472048057, 9136.132067453582, 11612.851622449874, 11772.411379431165, 12120.878791211975, 12121.45453454579, 12156.75686486283], "value": 10300.332302422697, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1162.128403697227, 1319.2546211390563, 1378.8690074442932, 1395.3441882315308, 1402.8036074949505, 9133.634654613816, 9598.808023839527, 9600.183984361329, 9600.56797444115, 9604.231661467084, 9388.295877809174, 9504.477253751058, 10254.128399086068, 10558.630447934323, 10572.154227661787, 2398.436367453647, 2441.633754936756, 2441.865697386652, 2443.0, 2445.364205306624, 2152.461884528868, 2304.746477887431, 2361.657559653849, 2406.0255596483453, 2429.9392515187087, 2042.8978551072462, 2139.4116617930117, 2344.23812261015, 2400.4478969836946, 2445.7798798108165, 2401.3636386357543, 2418.8669623170717, 2431.100492817669, 2434.5982912819454, 2435.6468312094826, 2383.9987205373786, 2392.9521409571885, 2406.121765555577, 2409.445827459694, 2415.613501839697, 10616.787664246673, 10854.403007834566, 10867.891321086847, 10881.401522916236, 10921.0, 6042.788502402394, 6261.217347831521, 6263.561550691454, 6268.0, 6288.9999999999545, 3639.4540818877117, 3653.1962968146963, 3776.339140650375, 4011.959880401188, 4012.25773231953, 1131.3607811586428, 1131.4851742457245, 1181.4270079011671, 1182.4265231362783, 1217.8355921950513, 13502.447218569794, 15051.774223386657, 15163.545093647208, 15203.467878624195, 15235.390584376612, 5232.215167724872, 5344.225087362311, 5641.52047075995, 5664.150377443334, 5675.517581005578, 12674.049446291565, 12675.619731408073, 12686.302253375969, 12688.936555317165, 12727.745445091046, 6300.527460440485, 6315.621062736251, 6320.936790632127, 6325.430711236011, 6325.493960483151, 4044.656204222615, 5759.337676167294, 5761.769529218868, 5804.564657650693, 5817.214676018683, 4186.895327616773, 4325.4593175853015, 4341.34879768031, 4402.779861006937, 4421.7789110544345, 1126.7859106769688, 1230.8276841242234, 1315.5724389573427, 1331.074902942465, 1350.257358452847, 732.648328802178, 737.6754228139602, 755.7392699518657, 760.0385512326911, 790.6165509727778, 5839.182514447981, 6324.873502530016, 6509.739610415625, 6520.478361731145, 6661.500387470959, 563.2986931270594, 564.9745711533553, 576.2882839692976, 584.4885724990634, 588.520355909932, 3574.4995700601417, 3617.8733744318824, 3669.4679271506016, 3699.2416554606243, 3728.0493474163914, 13207.801882971764, 13646.522371716943, 13786.862131378759, 13874.861251387358, 13910.374033168531, 13654.590362289147, 13655.180689158682, 13764.518241861688, 13839.654008649663, 13929.651758705912, 9998.650047248311, 10028.298019138736, 10168.898311016943, 10187.439690817077, 10195.439250841127, 6253.155823963707, 6388.712507937061, 6447.967760161169, 6617.702203400955, 6629.867402652017, 11930.472081597976, 12269.202501837352, 12310.569130080763, 12364.319962402154, 12492.875071249171, 12097.153199275966, 12164.695882603006, 12512.186707863959, 12668.429920653776, 12700.555480558136, 7961.800954976172, 8262.752117436487, 8280.296174824967, 8790.780230494032, 9082.273418126402, 10015.549300281651, 10108.241881859038, 10299.639512617023, 10365.0, 10380.377177369382, 8492.0, 8536.402451828313, 8574.485530868167, 8597.8710319344, 8623.267022303175, 7526.473146879666, 8352.457090289112, 8404.159584041547, 8434.283085937765, 8459.111793261865, 7593.658285377282, 7768.961155194188, 7774.650140743567, 7788.415868809749, 7788.844223115392, 5624.550035997028, 5656.208130861765, 5800.4489573490455, 5877.735501902339, 5917.497012753965, 5722.284714410699, 5755.1942728017275, 5951.702414879325, 5968.194293770285, 6117.755289788359], "value": 5025.333746419428, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.54465490877766, 385.8919502539289, 401.1735824202143, 403.27813214346287, 403.53593367627207, 5661.1508273758955, 5726.742296596648, 6079.996800527913, 6135.631862088272, 6165.568410211287, 5546.86132846678, 5822.4177582241755, 5932.3474417814, 6010.188624535686, 6084.756609735606, 3564.500969864221, 3710.591834898159, 4529.6376289896725, 4550.65870059746, 4560.543945605446, 3927.3912543555653, 4567.474740404846, 4581.0, 4586.518415566367, 4596.172688915994, 3676.650718181782, 4276.572342765729, 4482.327650852366, 4521.299198624219, 4532.116237333721, 3941.8568615101585, 4220.775974967256, 4473.4408198975125, 4489.371487991683, 4551.385562948993, 3874.2638898609175, 3884.4367566702954, 3895.162540053902, 3899.961000389988, 3904.355781296111, 5638.71806409682, 5851.356350801409, 5851.502622277111, 5929.288485381739, 5965.403459654043, 4529.958109634769, 4789.778606455367, 5227.242049902781, 5254.21186821976, 5279.762410691528, 5210.557102646279, 6200.503959683215, 6438.742450302029, 6459.67701614922, 6474.870502589922, 4159.272127377697, 4253.723507972003, 4277.144571085764, 4305.720128191658, 4307.289297265947, 5465.480779325958, 6453.9677301613665, 6655.7419638485535, 6747.190337159573, 6862.588244705334, 3639.7998110103667, 3683.723720720957, 4228.978855105766, 4334.45819272591, 4342.699548343282, 4202.814536441034, 4457.665675074382, 4468.977655111768, 4474.129877655804, 4482.46210454741, 3956.742811717229, 4863.440704318978, 4936.173615762119, 5017.297578339036, 5316.946830531722, 5605.971970140124, 6247.437730604267, 6439.323870993572, 6482.384173503509, 6507.316731743099, 1352.1076089780697, 1500.1524138861507, 1517.770605809298, 1532.6781375911185, 1534.3018926388465, 995.5221493683073, 1009.7122320138725, 1015.9288849780445, 1017.3946501831399, 1018.165104614224, 5658.255745963204, 6107.495926670044, 6122.724477398528, 6159.384061593805, 6175.660338681416, 6317.210348706411, 6639.302873198341, 6660.500462465237, 6672.599644021374, 6696.86606267872, 5093.312402825645, 5094.974525127351, 5237.486006716797, 5800.0, 6017.849553761103, 2982.791969252456, 3265.0, 3345.7323414126813, 3486.320167567351, 3503.7197024238, 6600.042993765973, 6893.656156906676, 6901.963920643422, 6904.3095690430555, 6952.860942781116, 6906.8618627628175, 6923.676635215125, 6936.4797640177185, 6944.4097251734165, 6961.721531138799, 6787.830304242433, 6986.755463558751, 7004.719811207495, 7015.9298407016295, 7031.964840175767, 5511.889762204813, 6663.233728121134, 6718.899216511854, 6730.629815360202, 6787.355201255873, 6334.081558173947, 6607.405333519814, 6626.53614246998, 6643.966780166068, 6653.835578773792, 5541.861453463695, 6240.157578726814, 6847.794566163023, 6900.411226534298, 6935.861282774417, 3688.981555092207, 4336.219480493542, 4390.495093064338, 4405.339199120158, 4422.646188304992, 5036.217673430226, 5573.244428005165, 6748.6963086660235, 6748.69630866622, 6778.627175505395, 6440.420362167428, 6498.180509457359, 6504.349565043647, 6510.414062734377, 6517.837054073686, 6190.876182476415, 6253.749850005949, 6498.512611554247, 6537.0, 6580.539362244597, 4594.287885377638, 5568.248286481436, 5777.508911742549, 5958.927393069289, 6326.457183976803, 5922.822315330547, 6462.709178086903, 6499.740010399721, 6502.577332473374, 6513.902291465537, 4934.358533390637, 5238.083335416363, 5838.182654428469, 6000.909986350121, 6319.0], "value": 4654.264319124635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9124105107388, 732.0702707561394, 737.7122922060397, 747.1034758290053, 774.0440555913445, 14859.777103343446, 15802.525924222278, 15888.0, 17580.560485987855, 18520.073996300198, 16738.66522669546, 16922.746158807597, 16968.236429360677, 16981.660366792657, 17297.0, 931.1247427418222, 955.1117460761488, 956.273232343417, 959.0744931141447, 959.4435227567993, 925.5279807298293, 953.0, 982.6953644370268, 985.3152059318741, 987.2299606307092, 1032.2361452525151, 1033.8759348878107, 1035.2132379391678, 1035.6219979707428, 1039.9012093851072, 1031.1647565471917, 1031.5925209542227, 1031.9845202321972, 1032.1123833503182, 1033.9172866170688, 766.781467281822, 767.3745897093851, 767.5701607100042, 769.2807225244414, 770.1566784371105, 15777.763333550007, 16076.356945722157, 16252.431164909289, 16516.256768445448, 16810.411635592696, 14309.856901431061, 14481.275936203148, 14523.854761452356, 14555.345009474599, 14664.853351466456, 11674.35791031493, 11696.532138714441, 11909.628403641173, 11915.344656043913, 12257.448414821354, 2969.213158512974, 3119.0486901495033, 3131.5302704594264, 3139.121046107071, 3141.7643676724338, 103177.0, 107478.46260768647, 108262.0, 108524.45737771339, 111663.44168279269, 10798.460076996118, 11246.831297530543, 11437.885621143849, 11738.530458781725, 11756.647300580997, 24489.63265551018, 24822.875885620455, 24944.0, 25017.249482515555, 25055.99776008939, 7742.61286935651, 7780.299773020455, 7936.761897143095, 8014.959925200336, 8121.390895682729, 4012.3981402789814, 4135.56576559463, 4147.336426171798, 4148.688848336386, 4154.255212810627, 4090.9386359204636, 4134.193832202692, 4178.561251068594, 4191.517975432804, 4196.181744559843, 850.9191626795445, 852.957352132391, 853.4537895746727, 858.2190206911805, 861.6338056326036, 510.4396832096174, 523.7407483295793, 539.7085573790188, 549.8900219956025, 584.964902105875, 9591.280653950842, 9966.501674916226, 10059.39643621385, 10119.0, 10123.24075694311, 441.88731873372086, 522.5297579209896, 536.4394208052595, 558.7150553217888, 573.6787399056542, 3822.3502004659276, 4430.977845110754, 4680.578747912704, 4702.318163866289, 4794.520547945247, 62037.37962620406, 81854.0, 90613.0, 92931.0, 94850.61792584934, 63683.681581591794, 80403.0, 84016.0, 85091.57454212813, 85135.0, 67338.66330668413, 69716.65141674259, 71543.64228178826, 71584.56830863353, 72072.0, 7804.492707973964, 9388.530573471437, 9459.574319155518, 9478.383905046427, 9525.190358819578, 36189.0952726184, 37058.6294137066, 37273.0, 37593.0, 37794.24411511809, 47803.5219647813, 48196.7590162047, 48422.5157748418, 48699.05203791809, 49130.50869491259, 11458.828117578076, 11570.826437603611, 11724.824127637921, 11888.049224887298, 12033.004813202037, 11070.446477676245, 11895.632002319498, 12512.749745004867, 12585.181963172365, 12670.049746268882, 10224.437655928697, 11752.0, 11984.460699268728, 12361.93819030899, 12510.812337814757, 11132.666020019413, 11531.711707207387, 12193.207441516273, 12199.69500762488, 12203.389830508262, 11291.424241064424, 11942.4028798558, 12213.206141600767, 12259.080568957543, 12285.631431057083, 8500.659973601234, 8793.164649358301, 8825.602847871989, 8975.596098175729, 9239.630414783333, 7416.962915185605, 9745.12293893553, 9914.652987145415, 9922.355046921926, 9924.751881203028], "value": 7257.170603680707, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 125.0, 123.0, 122.0, 123.0, 122.0, 123.0, 125.0, 125.0, 122.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 147.0, 162.0, 182.0, 165.0, 161.0, 143.0, 121.0, 146.0, 117.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 213.0, 212.0, 214.0, 217.0, 220.0, 218.0, 220.0, 209.0, 215.0], "value": 215.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 68.0, 70.0, 131.0, 96.0, 65.0, 64.0, 65.0, 84.0, 64.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 75.0, 96.0, 78.0, 74.0, 76.0, 93.0, 75.0, 75.0, 92.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 92.0, 91.0, 90.0, 93.0, 93.0, 91.0, 91.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 125.0, 100.0, 101.0, 100.0, 101.0, 101.0, 100.0, 101.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 103.0, 101.0, 101.0, 103.0, 102.0, 103.0, 103.0, 134.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 65.0, 64.0, 65.0, 65.0, 65.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 68.0, 75.0, 71.0, 69.0, 64.0, 64.0, 64.0, 65.0, 65.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 90.0, 92.0, 91.0, 87.0, 93.0, 90.0, 91.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 164.0, 151.0, 155.0, 156.0, 152.0, 156.0, 152.0, 192.0, 171.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 228.0, 260.0, 198.0, 261.0, 213.0, 204.0, 203.0, 208.0, 203.0], "value": 221.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 77.0, 93.0, 105.0, 108.0, 75.0, 74.0, 72.0, 74.0], "value": 82.4, "unit": "score"}]}]} 06:04:08 INFO - Return code: 0 06:04:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:04:08 ERROR - # TBPL SUCCESS # 06:04:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:04:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:04:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:04:08 INFO - 2017-03-30 06:04:08.652 screenresolution[1660:10148] starting screenresolution argv=screenresolution get 06:04:08 INFO - 2017-03-30 06:04:08.655 screenresolution[1660:10148] Display 0: 1600x1200x32@60 06:04:08 INFO - 2017-03-30 06:04:08.661 screenresolution[1661:10151] starting screenresolution argv=screenresolution list 06:04:08 INFO - Available Modes on Display 0 06:04:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:04:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:04:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:04:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:04:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:04:08 INFO - Intel Iris: 06:04:08 INFO - Chipset Model: Intel Iris 06:04:08 INFO - Type: GPU 06:04:08 INFO - Bus: Built-In 06:04:08 INFO - VRAM (Dynamic, Max): 1536 MB 06:04:08 INFO - Vendor: Intel (0x8086) 06:04:08 INFO - Device ID: 0x0a2e 06:04:08 INFO - Revision ID: 0x0009 06:04:08 INFO - Displays: 06:04:08 INFO - Display: 06:04:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:04:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:04:08 INFO - Display Serial Number: 1600x1200 60 06:04:08 INFO - Main Display: Yes 06:04:08 INFO - Mirror: Off 06:04:08 INFO - Online: Yes 06:04:08 INFO - Rotation: Supported 06:04:08 INFO - Return code: 0 06:04:08 INFO - Running post-action listener: _resource_record_post_action 06:04:08 INFO - [mozharness: 2017-03-30 13:04:08.899645Z] Finished run-tests step (success) 06:04:08 INFO - Running post-run listener: _resource_record_post_run 06:04:08 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32734208; Write bytes: 513558528; Read time: 316; Write time: 1554 06:04:08 INFO - TinderboxPrint: CPU usage
53.4% 06:04:08 INFO - TinderboxPrint: I/O read bytes / time
32,734,208 / 316 06:04:08 INFO - TinderboxPrint: I/O write bytes / time
513,558,528 / 1,554 06:04:08 INFO - TinderboxPrint: CPU idle
2,248.4 (46.6%) 06:04:08 INFO - TinderboxPrint: CPU system
961.3 (19.9%) 06:04:08 INFO - TinderboxPrint: CPU user
1,612.0 (33.4%) 06:04:08 INFO - TinderboxPrint: Swap in / out
240,619,520 / 0 06:04:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205052416; Write bytes: 202616832; Read time: 15600; Write time: 369 06:04:08 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 30944256; Write bytes: 300111872; Read time: 295; Write time: 1151 06:04:09 INFO - Running post-run listener: _upload_blobber_files 06:04:09 INFO - Blob upload gear active. 06:04:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:04:09 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 06:04:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:04:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:04:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:04:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:04:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:10 INFO - (blobuploader) - INFO - Done attempting. 06:04:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:04:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:04:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:11 INFO - (blobuploader) - INFO - Done attempting. 06:04:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:04:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:04:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:13 INFO - (blobuploader) - INFO - Done attempting. 06:04:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:04:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:04:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:18 INFO - (blobuploader) - INFO - Done attempting. 06:04:18 INFO - (blobuploader) - INFO - Iteration through files over. 06:04:18 INFO - Return code: 0 06:04:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:04:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:04:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924c065279ba4cfbee9caa7b4ba766a545136eb7fa2920af7e91efbefe8a5d147a4a060aeaf4f0eba8a0f5b8390aaadd4e2c236c7a96ddb463f259715a6f7117", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58a9161b02fbfeb0ed8f17e797244479f0a79644ab2d36e429a9a3334f199cb7a1a606a4c608849b5dc66db6f310b546ca8720ea42dd8cc0f85dcf6dcc54d93e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eade4057781a2daab9c301b5ea2bdd2a16f965d00a00b340a27e906b14b3a616e7c8616caaae9e6a1132a6eda2d5639d8caafe0eebb2995c267ca30777a7276", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27aa535ec0363af0245ef6ddcfd00253dce1e13a236956327950a90ea194d5c9783b8d70c1cf55088d914eb0354d8711ba4ebfa7210a714545f33b9e91494bd3"} 06:04:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:04:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:04:18 INFO - Contents: 06:04:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924c065279ba4cfbee9caa7b4ba766a545136eb7fa2920af7e91efbefe8a5d147a4a060aeaf4f0eba8a0f5b8390aaadd4e2c236c7a96ddb463f259715a6f7117", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58a9161b02fbfeb0ed8f17e797244479f0a79644ab2d36e429a9a3334f199cb7a1a606a4c608849b5dc66db6f310b546ca8720ea42dd8cc0f85dcf6dcc54d93e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eade4057781a2daab9c301b5ea2bdd2a16f965d00a00b340a27e906b14b3a616e7c8616caaae9e6a1132a6eda2d5639d8caafe0eebb2995c267ca30777a7276", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27aa535ec0363af0245ef6ddcfd00253dce1e13a236956327950a90ea194d5c9783b8d70c1cf55088d914eb0354d8711ba4ebfa7210a714545f33b9e91494bd3"} 06:04:18 INFO - Running post-run listener: copy_logs_to_upload_dir 06:04:18 INFO - Copying logs to upload dir... 06:04:18 INFO - mkdir: /builds/slave/test/build/upload/logs 06:04:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.841010 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2017-03-30 06:04:18.719478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:18.726465) ========= 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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924c065279ba4cfbee9caa7b4ba766a545136eb7fa2920af7e91efbefe8a5d147a4a060aeaf4f0eba8a0f5b8390aaadd4e2c236c7a96ddb463f259715a6f7117", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58a9161b02fbfeb0ed8f17e797244479f0a79644ab2d36e429a9a3334f199cb7a1a606a4c608849b5dc66db6f310b546ca8720ea42dd8cc0f85dcf6dcc54d93e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eade4057781a2daab9c301b5ea2bdd2a16f965d00a00b340a27e906b14b3a616e7c8616caaae9e6a1132a6eda2d5639d8caafe0eebb2995c267ca30777a7276", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27aa535ec0363af0245ef6ddcfd00253dce1e13a236956327950a90ea194d5c9783b8d70c1cf55088d914eb0354d8711ba4ebfa7210a714545f33b9e91494bd3"} build_url:https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011645 build_url: 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924c065279ba4cfbee9caa7b4ba766a545136eb7fa2920af7e91efbefe8a5d147a4a060aeaf4f0eba8a0f5b8390aaadd4e2c236c7a96ddb463f259715a6f7117", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58a9161b02fbfeb0ed8f17e797244479f0a79644ab2d36e429a9a3334f199cb7a1a606a4c608849b5dc66db6f310b546ca8720ea42dd8cc0f85dcf6dcc54d93e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eade4057781a2daab9c301b5ea2bdd2a16f965d00a00b340a27e906b14b3a616e7c8616caaae9e6a1132a6eda2d5639d8caafe0eebb2995c267ca30777a7276", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27aa535ec0363af0245ef6ddcfd00253dce1e13a236956327950a90ea194d5c9783b8d70c1cf55088d914eb0354d8711ba4ebfa7210a714545f33b9e91494bd3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:18.766059) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:18.766394) ========= 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.jlVOMhfR5J/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.OgxiLJlmUZ/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.005243 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:18.819063) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 06:04:18.819648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 06:04:18.820335) ========= ========= Total master_lag: 0.23 =========