builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0130 starttime: 1490142032.75 results: success (0) buildid: 20170321160934 builduid: 55c1e09a32204164bbc0ae7466603b0d revision: 8744e9f8eb99f1290aae81985812d57364f18708 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.751417) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.751824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.752094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.801665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.801994) ========= 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.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 17:20:32-- 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% 151M=0s 2017-03-21 17:20:32 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.990847) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:32.991174) ========= 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.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.028330 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:33.041760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 17:20:33.042096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8744e9f8eb99f1290aae81985812d57364f18708 --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 8744e9f8eb99f1290aae81985812d57364f18708 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 17:20:33,154 truncating revision to first 12 chars 2017-03-21 17:20:33,154 Setting DEBUG logging. 2017-03-21 17:20:33,154 attempt 1/10 2017-03-21 17:20:33,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8744e9f8eb99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 17:20:34,154 unpacking tar archive at: mozilla-central-8744e9f8eb99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277845 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 17:20:34.343405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:34.343735) ========= script_repo_revision: 8744e9f8eb99f1290aae81985812d57364f18708 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:34.344184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:34.344447) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 17:20:34.364814) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-21 17:20:34.365187) ========= /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.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:20:34 INFO - MultiFileLogger online at 20170321 17:20:34 in /builds/slave/test 17:20:34 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 17:20:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:34 INFO - {'append_to_log': False, 17:20:34 INFO - 'base_work_dir': '/builds/slave/test', 17:20:34 INFO - 'blob_upload_branch': 'Firefox', 17:20:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:34 INFO - 'branch': 'Firefox', 17:20:34 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:34 INFO - 'config_files': ('talos/mac_config.py',), 17:20:34 INFO - 'default_actions': ('clobber', 17:20:34 INFO - 'read-buildbot-config', 17:20:34 INFO - 'download-and-extract', 17:20:34 INFO - 'populate-webroot', 17:20:34 INFO - 'create-virtualenv', 17:20:34 INFO - 'install', 17:20:34 INFO - 'run-tests'), 17:20:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:34 INFO - 'download_minidump_stackwalk': True, 17:20:34 INFO - 'download_symbols': 'ondemand', 17:20:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:34 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:34 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:34 INFO - 'gecko_profile': False, 17:20:34 INFO - 'gecko_profile_interval': 0, 17:20:34 INFO - 'installer_path': 'installer.exe', 17:20:34 INFO - 'log_level': 'info', 17:20:34 INFO - 'log_name': 'talos', 17:20:34 INFO - 'log_to_console': True, 17:20:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:20:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:20:34 INFO - 'opt_config_files': (), 17:20:34 INFO - 'pip_index': False, 17:20:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:34 INFO - 'cmd': ('bash', 17:20:34 INFO - '-c', 17:20:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:34 INFO - 'enabled': True, 17:20:34 INFO - 'halt_on_failure': False, 17:20:34 INFO - 'name': 'check_screen_resolution'},), 17:20:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:34 INFO - 'cmd': ('bash', 17:20:34 INFO - '-c', 17:20:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:34 INFO - 'enabled': True, 17:20:34 INFO - 'halt_on_failure': False, 17:20:34 INFO - 'name': 'check_screen_resolution'},), 17:20:34 INFO - 'run_cmd_checks_enabled': True, 17:20:34 INFO - 'suite': 'dromaeojs', 17:20:34 INFO - 'system_bits': '32', 17:20:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:34 INFO - 'title': 't-yosemite-r7-0130', 17:20:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:34 INFO - 'use_talos_json': True, 17:20:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:34 INFO - 'work_dir': 'build'} 17:20:34 INFO - [mozharness: 2017-03-22 00:20:34.587748Z] Running clobber step. 17:20:34 INFO - Running pre-action listener: _resource_record_pre_action 17:20:34 INFO - Running main action method: clobber 17:20:34 INFO - rmtree: /builds/slave/test/build 17:20:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:37 INFO - Running post-action listener: _resource_record_post_action 17:20:37 INFO - [mozharness: 2017-03-22 00:20:37.187150Z] Finished clobber step (success) 17:20:37 INFO - [mozharness: 2017-03-22 00:20:37.187229Z] Running read-buildbot-config step. 17:20:37 INFO - Running pre-action listener: _resource_record_pre_action 17:20:37 INFO - Running main action method: read_buildbot_config 17:20:37 INFO - Using buildbot properties: 17:20:37 INFO - { 17:20:37 INFO - "project": "", 17:20:37 INFO - "product": "firefox", 17:20:37 INFO - "script_repo_revision": "production", 17:20:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:20:37 INFO - "repository": "", 17:20:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:20:37 INFO - "buildid": "20170321160934", 17:20:37 INFO - "pgo_build": "False", 17:20:37 INFO - "basedir": "/builds/slave/test", 17:20:37 INFO - "buildnumber": 8, 17:20:37 INFO - "slavename": "t-yosemite-r7-0130", 17:20:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:20:37 INFO - "platform": "yosemite_r7", 17:20:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:20:37 INFO - "branch": "mozilla-central", 17:20:37 INFO - "repo_path": "mozilla-central", 17:20:37 INFO - "revision": "8744e9f8eb99f1290aae81985812d57364f18708", 17:20:37 INFO - "stage_platform": "macosx64", 17:20:37 INFO - "builduid": "55c1e09a32204164bbc0ae7466603b0d", 17:20:37 INFO - "slavebuilddir": "test" 17:20:37 INFO - } 17:20:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:20:37 INFO - Running post-action listener: _resource_record_post_action 17:20:37 INFO - [mozharness: 2017-03-22 00:20:37.190660Z] Finished read-buildbot-config step (success) 17:20:37 INFO - [mozharness: 2017-03-22 00:20:37.190794Z] Running download-and-extract step. 17:20:37 INFO - Running pre-action listener: _resource_record_pre_action 17:20:37 INFO - Running main action method: download_and_extract 17:20:37 INFO - mkdir: /builds/slave/test/build/tests 17:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:37 INFO - trying https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:37 INFO - Downloading https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/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 17:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/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 17:20:38 INFO - Downloaded 1325 bytes. 17:20:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:38 INFO - Using the following test package requirements: 17:20:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:20:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:20:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:20:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'jsshell-mac.zip'], 17:20:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:20:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:20:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:20:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:20:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:20:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:20:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:38 INFO - Fetch https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:41 INFO - Expected file size: 52477569 17:20:41 INFO - Obtained file size: 52477569 17:20:57 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 17:20:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:57 INFO - Fetch https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:59 INFO - Expected file size: 52477569 17:20:59 INFO - Obtained file size: 52477569 17:21:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:21:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:21:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:21:15 INFO - Expected file size: 14404171 17:21:15 INFO - Obtained file size: 14404171 17:21:15 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')]} 17:21:15 INFO - trying https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:21:15 INFO - Downloading https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/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 17:21:17 INFO - Downloaded 75279508 bytes. 17:21:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:21:17 INFO - mkdir: /builds/slave/test/properties 17:21:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:21:17 INFO - Writing to file /builds/slave/test/properties/build_url 17:21:17 INFO - Contents: 17:21:17 INFO - build_url:https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:21:18 INFO - Running post-action listener: _resource_record_post_action 17:21:18 INFO - Running post-action listener: set_extra_try_arguments 17:21:18 INFO - [mozharness: 2017-03-22 00:21:18.955498Z] Finished download-and-extract step (success) 17:21:18 INFO - [mozharness: 2017-03-22 00:21:18.955636Z] Running populate-webroot step. 17:21:18 INFO - Running pre-action listener: _resource_record_pre_action 17:21:18 INFO - Running main action method: populate_webroot 17:21:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:21:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:21:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:21:18 INFO - '--tppagecycles', 17:21:18 INFO - '7'], 17:21:18 INFO - 'tests': ['tsvgm']}}, 17:21:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:21:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:21:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:21:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:21:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:21:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:21:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['damp', 'tps']}, 17:21:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'tests': ['damp', 'tps']}, 17:21:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['dromaeo_dom']}, 17:21:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:21:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['basic_compositor_video']}, 17:21:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:21:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'other-e10s': {'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['a11yr', 17:21:18 INFO - 'ts_paint', 17:21:18 INFO - 'tpaint', 17:21:18 INFO - 'sessionrestore', 17:21:18 INFO - 'sessionrestore_no_auto_restore', 17:21:18 INFO - 'tabpaint']}, 17:21:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['tsvgx', 17:21:18 INFO - 'tsvgr_opacity', 17:21:18 INFO - 'tart', 17:21:18 INFO - 'tscrollx', 17:21:18 INFO - 'cart', 17:21:18 INFO - 'tsvg_static']}, 17:21:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:21:18 INFO - 'tsvgr_opacity', 17:21:18 INFO - 'tart', 17:21:18 INFO - 'tscrollx', 17:21:18 INFO - 'cart', 17:21:18 INFO - 'tsvg_static']}, 17:21:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'talos_options': ['--disable-e10s'], 17:21:18 INFO - 'tests': ['tp5o']}, 17:21:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:21:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'talos_options': ['--disable-e10s', 17:21:18 INFO - '--xperf_path', 17:21:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:21:18 INFO - 'tests': ['tp5n']}, 17:21:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:21:18 INFO - 'talos_options': ['--xperf_path', 17:21:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:21:18 INFO - 'tests': ['tp5n']}}, 17:21:18 INFO - 'talos.zip': {'path': '', 17:21:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:21:18 INFO - Running post-action listener: _resource_record_post_action 17:21:18 INFO - [mozharness: 2017-03-22 00:21:18.974304Z] Finished populate-webroot step (success) 17:21:18 INFO - [mozharness: 2017-03-22 00:21:18.974439Z] Running create-virtualenv step. 17:21:18 INFO - Running pre-action listener: _resource_record_pre_action 17:21:18 INFO - Running main action method: create_virtualenv 17:21:18 INFO - Creating virtualenv /builds/slave/test/build/venv 17:21:18 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 17:21:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:21:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:21:19 INFO - Using real prefix '/tools/python27' 17:21:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:21:19 INFO - Installing distribute.............................................................................................................................................................................................done. 17:21:22 INFO - Installing pip.................done. 17:21:22 INFO - Return code: 0 17:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:21:22 INFO - Reading from file tmpfile_stdout 17:21:22 INFO - Output received: 17:21:22 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) 17:21:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:23 INFO - 'HOME': '/Users/cltbld', 17:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:23 INFO - 'LOGNAME': 'cltbld', 17:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:23 INFO - 'MOZ_NO_REMOTE': '1', 17:21:23 INFO - 'NO_EM_RESTART': '1', 17:21:23 INFO - 'PAGER': '/bin/cat', 17:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:23 INFO - 'PWD': '/builds/slave/test', 17:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:23 INFO - 'SHELL': '/bin/bash', 17:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:23 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:23 INFO - 'USER': 'cltbld', 17:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:23 INFO - 'XPC_FLAGS': '0x0', 17:21:23 INFO - 'XPC_SERVICE_NAME': '0', 17:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:23 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 17:21:23 INFO - Cleaning up... 17:21:23 INFO - Return code: 0 17:21:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21: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')]} 17:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:23 INFO - 'HOME': '/Users/cltbld', 17:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:23 INFO - 'LOGNAME': 'cltbld', 17:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:23 INFO - 'MOZ_NO_REMOTE': '1', 17:21:23 INFO - 'NO_EM_RESTART': '1', 17:21:23 INFO - 'PAGER': '/bin/cat', 17:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:23 INFO - 'PWD': '/builds/slave/test', 17:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:23 INFO - 'SHELL': '/bin/bash', 17:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:23 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:23 INFO - 'USER': 'cltbld', 17:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:23 INFO - 'XPC_FLAGS': '0x0', 17:21:23 INFO - 'XPC_SERVICE_NAME': '0', 17:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:23 INFO - Downloading/unpacking psutil>=3.1.1 17:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:21:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:21:27 INFO - Installing collected packages: psutil 17:21:27 INFO - Running setup.py install for psutil 17:21:27 INFO - building 'psutil._psutil_osx' extension 17:21:27 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 17:21:27 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 17:21:28 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 17:21:28 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 17:21:28 INFO - building 'psutil._psutil_posix' extension 17:21:28 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 17:21:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:21:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:21:28 INFO - ^ 17:21:28 INFO - 1 warning generated. 17:21:28 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 17:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:21:28 INFO - Successfully installed psutil 17:21:28 INFO - Cleaning up... 17:21:28 INFO - Return code: 0 17:21:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:21:28 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')]} 17:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:28 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')]} 17:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:28 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:28 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 17:21:28 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 17:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:28 INFO - 'HOME': '/Users/cltbld', 17:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:28 INFO - 'LOGNAME': 'cltbld', 17:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:28 INFO - 'MOZ_NO_REMOTE': '1', 17:21:28 INFO - 'NO_EM_RESTART': '1', 17:21:28 INFO - 'PAGER': '/bin/cat', 17:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:28 INFO - 'PWD': '/builds/slave/test', 17:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:28 INFO - 'SHELL': '/bin/bash', 17:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:28 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:28 INFO - 'USER': 'cltbld', 17:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:28 INFO - 'XPC_FLAGS': '0x0', 17:21:28 INFO - 'XPC_SERVICE_NAME': '0', 17:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:21:31 INFO - Installing collected packages: mozsystemmonitor 17:21:31 INFO - Running setup.py install for mozsystemmonitor 17:21:31 INFO - Successfully installed mozsystemmonitor 17:21:31 INFO - Cleaning up... 17:21:31 INFO - Return code: 0 17:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:21:31 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')]} 17:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:31 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')]} 17:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:31 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:31 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 17:21:31 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 17:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:31 INFO - 'HOME': '/Users/cltbld', 17:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:31 INFO - 'LOGNAME': 'cltbld', 17:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:31 INFO - 'MOZ_NO_REMOTE': '1', 17:21:31 INFO - 'NO_EM_RESTART': '1', 17:21:31 INFO - 'PAGER': '/bin/cat', 17:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:31 INFO - 'PWD': '/builds/slave/test', 17:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:31 INFO - 'SHELL': '/bin/bash', 17:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:31 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:31 INFO - 'USER': 'cltbld', 17:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:31 INFO - 'XPC_FLAGS': '0x0', 17:21:31 INFO - 'XPC_SERVICE_NAME': '0', 17:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:32 INFO - Downloading/unpacking jsonschema==2.5.1 17:21:32 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 17:21:32 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 17:21:32 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 17:21:32 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 17:21:32 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 17:21:32 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 17:21:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:21:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:35 INFO - Downloading functools32-3.2.3-2.tar.gz 17:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:21:35 INFO - warning: no files found matching '*.txt' 17:21:35 INFO - no previously-included directories found matching 'build' 17:21:35 INFO - no previously-included directories found matching 'dist' 17:21:35 INFO - no previously-included directories found matching '.git*' 17:21:35 INFO - Installing collected packages: jsonschema, functools32 17:21:35 INFO - Running setup.py install for functools32 17:21:35 INFO - warning: no files found matching '*.txt' 17:21:35 INFO - no previously-included directories found matching 'build' 17:21:35 INFO - no previously-included directories found matching 'dist' 17:21:35 INFO - no previously-included directories found matching '.git*' 17:21:35 INFO - Successfully installed jsonschema functools32 17:21:35 INFO - Cleaning up... 17:21:35 INFO - Return code: 0 17:21:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:21:35 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')]} 17:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:35 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')]} 17:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:35 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:35 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 17:21:35 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 17:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:35 INFO - 'HOME': '/Users/cltbld', 17:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:35 INFO - 'LOGNAME': 'cltbld', 17:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:35 INFO - 'MOZ_NO_REMOTE': '1', 17:21:35 INFO - 'NO_EM_RESTART': '1', 17:21:35 INFO - 'PAGER': '/bin/cat', 17:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:35 INFO - 'PWD': '/builds/slave/test', 17:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:35 INFO - 'SHELL': '/bin/bash', 17:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:35 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:35 INFO - 'USER': 'cltbld', 17:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:35 INFO - 'XPC_FLAGS': '0x0', 17:21:35 INFO - 'XPC_SERVICE_NAME': '0', 17:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:21:35 INFO - Cleaning up... 17:21:35 INFO - Return code: 0 17:21:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:21:35 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')]} 17:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:35 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')]} 17:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:35 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:35 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 17:21:35 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 17:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:35 INFO - 'HOME': '/Users/cltbld', 17:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:35 INFO - 'LOGNAME': 'cltbld', 17:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:35 INFO - 'MOZ_NO_REMOTE': '1', 17:21:35 INFO - 'NO_EM_RESTART': '1', 17:21:35 INFO - 'PAGER': '/bin/cat', 17:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:35 INFO - 'PWD': '/builds/slave/test', 17:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:35 INFO - 'SHELL': '/bin/bash', 17:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:35 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:35 INFO - 'USER': 'cltbld', 17:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:35 INFO - 'XPC_FLAGS': '0x0', 17:21:35 INFO - 'XPC_SERVICE_NAME': '0', 17:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:36 INFO - Downloading/unpacking blobuploader==1.2.4 17:21:36 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 17:21:36 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 17:21:36 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 17:21:36 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 17:21:36 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 17:21:36 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 17:21:38 INFO - Downloading blobuploader-1.2.4.tar.gz 17:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:21:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:21:38 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 17:21:38 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 17:21:38 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 17:21:38 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 17:21:38 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 17:21:38 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 17:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:21:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:39 INFO - Downloading docopt-0.6.1.tar.gz 17:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:21:40 INFO - Installing collected packages: blobuploader, requests, docopt 17:21:40 INFO - Running setup.py install for blobuploader 17:21:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:21:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:21:40 INFO - Running setup.py install for requests 17:21:40 INFO - Running setup.py install for docopt 17:21:40 INFO - Successfully installed blobuploader requests docopt 17:21:40 INFO - Cleaning up... 17:21:40 INFO - Return code: 0 17:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:40 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:40 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 17:21:40 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 17:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:40 INFO - 'HOME': '/Users/cltbld', 17:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:40 INFO - 'LOGNAME': 'cltbld', 17:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:40 INFO - 'MOZ_NO_REMOTE': '1', 17:21:40 INFO - 'NO_EM_RESTART': '1', 17:21:40 INFO - 'PAGER': '/bin/cat', 17:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:40 INFO - 'PWD': '/builds/slave/test', 17:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:40 INFO - 'SHELL': '/bin/bash', 17:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:40 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:40 INFO - 'USER': 'cltbld', 17:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:40 INFO - 'XPC_FLAGS': '0x0', 17:21:40 INFO - 'XPC_SERVICE_NAME': '0', 17:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-glo_O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-IJsAGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-IBThsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-sT5wPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-6oDwoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-40Yas_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-xAJZyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:41 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-6c0qqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-wBNhDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-caivng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-JmVs1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-qjnghK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-uELS4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Go1AJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-cHOr3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Ckawmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:42 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-3jUdQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:43 INFO - Running setup.py install for manifestparser 17:21:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:21:43 INFO - Running setup.py install for mozcrash 17:21:43 INFO - Running setup.py install for mozdebug 17:21:43 INFO - Running setup.py install for mozdevice 17:21:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:21:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:21:43 INFO - Running setup.py install for mozfile 17:21:43 INFO - Running setup.py install for mozhttpd 17:21:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:43 INFO - Running setup.py install for mozinfo 17:21:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Running setup.py install for mozInstall 17:21:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Running setup.py install for mozleak 17:21:44 INFO - Running setup.py install for mozlog 17:21:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Running setup.py install for moznetwork 17:21:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Running setup.py install for mozprocess 17:21:44 INFO - Running setup.py install for mozprofile 17:21:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:44 INFO - Running setup.py install for mozrunner 17:21:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:45 INFO - Running setup.py install for mozscreenshot 17:21:45 INFO - Running setup.py install for moztest 17:21:45 INFO - Running setup.py install for mozversion 17:21:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:45 INFO - Cleaning up... 17:21:45 INFO - Return code: 0 17:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:45 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:45 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 17:21:45 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 17:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:45 INFO - 'HOME': '/Users/cltbld', 17:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:45 INFO - 'LOGNAME': 'cltbld', 17:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:45 INFO - 'MOZ_NO_REMOTE': '1', 17:21:45 INFO - 'NO_EM_RESTART': '1', 17:21:45 INFO - 'PAGER': '/bin/cat', 17:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:45 INFO - 'PWD': '/builds/slave/test', 17:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:45 INFO - 'SHELL': '/bin/bash', 17:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:45 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:45 INFO - 'USER': 'cltbld', 17:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:45 INFO - 'XPC_FLAGS': '0x0', 17:21:45 INFO - 'XPC_SERVICE_NAME': '0', 17:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-7Fr53H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:45 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)) 17:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-QbjYPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-clGqiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-fEFQbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-ShHViF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-pDdQfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-K1UmgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Lc8RUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-qAeMX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-mD2_w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:46 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)) 17:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-nq2BvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-6GIt6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-2iMOv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-ZJSRQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-SThQaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-I1QqW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:47 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)) 17:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:47 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-fhTA8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:47 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)) 17:21:47 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)) 17:21:47 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)) 17:21:47 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)) 17:21:47 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)) 17:21:47 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)) 17:21:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:50 INFO - Downloading blessings-1.6.tar.gz 17:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:50 INFO - Installing collected packages: blessings 17:21:50 INFO - Running setup.py install for blessings 17:21:50 INFO - Successfully installed blessings 17:21:50 INFO - Cleaning up... 17:21:50 INFO - Return code: 0 17:21:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:50 INFO - Reading from file tmpfile_stdout 17:21:50 INFO - Current package versions: 17:21:50 INFO - blessings == 1.6 17:21:50 INFO - blobuploader == 1.2.4 17:21:50 INFO - docopt == 0.6.1 17:21:50 INFO - functools32 == 3.2.3-2 17:21:50 INFO - jsonschema == 2.5.1 17:21:50 INFO - manifestparser == 1.1 17:21:50 INFO - mozInstall == 1.12 17:21:50 INFO - mozcrash == 1.0 17:21:50 INFO - mozdebug == 0.1 17:21:50 INFO - mozdevice == 0.49 17:21:50 INFO - mozfile == 1.2 17:21:50 INFO - mozhttpd == 0.7 17:21:50 INFO - mozinfo == 0.9 17:21:50 INFO - mozleak == 0.1 17:21:50 INFO - mozlog == 3.4 17:21:50 INFO - moznetwork == 0.27 17:21:50 INFO - mozprocess == 0.25 17:21:50 INFO - mozprofile == 0.28 17:21:50 INFO - mozrunner == 6.13 17:21:50 INFO - mozscreenshot == 0.1 17:21:50 INFO - mozsystemmonitor == 0.3 17:21:50 INFO - moztest == 0.8 17:21:50 INFO - mozversion == 1.4 17:21:50 INFO - psutil == 3.1.1 17:21:50 INFO - requests == 1.2.3 17:21:50 INFO - wsgiref == 0.1.2 17:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:50 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:51 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 17:21:51 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 17:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:51 INFO - 'HOME': '/Users/cltbld', 17:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:51 INFO - 'LOGNAME': 'cltbld', 17:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:51 INFO - 'MOZ_NO_REMOTE': '1', 17:21:51 INFO - 'NO_EM_RESTART': '1', 17:21:51 INFO - 'PAGER': '/bin/cat', 17:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:51 INFO - 'PWD': '/builds/slave/test', 17:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:51 INFO - 'SHELL': '/bin/bash', 17:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:51 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:51 INFO - 'USER': 'cltbld', 17:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:51 INFO - 'XPC_FLAGS': '0x0', 17:21:51 INFO - 'XPC_SERVICE_NAME': '0', 17:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 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)) 17:21:51 INFO - Cleaning up... 17:21:51 INFO - Return code: 0 17:21:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21: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')]} 17:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:51 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 0x107bedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef91cdaa70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, '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.YEKiUeD65B/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.EKpMvjX8zp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 17:21:51 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 17:21:51 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 17:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:51 INFO - 'HOME': '/Users/cltbld', 17:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:51 INFO - 'LOGNAME': 'cltbld', 17:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:51 INFO - 'MOZ_NO_REMOTE': '1', 17:21:51 INFO - 'NO_EM_RESTART': '1', 17:21:51 INFO - 'PAGER': '/bin/cat', 17:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:51 INFO - 'PWD': '/builds/slave/test', 17:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:51 INFO - 'SHELL': '/bin/bash', 17:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:21:51 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:51 INFO - 'USER': 'cltbld', 17:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:51 INFO - 'XPC_FLAGS': '0x0', 17:21:51 INFO - 'XPC_SERVICE_NAME': '0', 17:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:51 INFO - Cleaning up... 17:21:51 INFO - Return code: 0 17:21:51 INFO - Running post-action listener: _resource_record_post_action 17:21:51 INFO - Running post-action listener: _start_resource_monitoring 17:21:51 INFO - Starting resource monitoring. 17:21:51 INFO - [mozharness: 2017-03-22 00:21:51.560804Z] Finished create-virtualenv step (success) 17:21:51 INFO - [mozharness: 2017-03-22 00:21:51.561784Z] Running install step. 17:21:51 INFO - Running pre-action listener: _resource_record_pre_action 17:21:51 INFO - Running main action method: install 17:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:51 INFO - Reading from file tmpfile_stdout 17:21:51 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:51 INFO - Reading from file tmpfile_stdout 17:21:51 INFO - Output received: 17:21:51 INFO - Usage: mozinstall [options] installer 17:21:51 INFO - Options: 17:21:51 INFO - -h, --help show this help message and exit 17:21:51 INFO - -d DEST, --destination=DEST 17:21:51 INFO - Directory to install application into. [default: 17:21:51 INFO - "/builds/slave/test"] 17:21:51 INFO - --app=APP Application being installed. [default: firefox] 17:21:51 INFO - mkdir: /builds/slave/test/build/application 17:21:51 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'] 17:21:51 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 17:22:13 INFO - Reading from file tmpfile_stdout 17:22:13 INFO - Output received: 17:22:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:22:13 INFO - Running post-action listener: _resource_record_post_action 17:22:13 INFO - [mozharness: 2017-03-22 00:22:13.279602Z] Finished install step (success) 17:22:13 INFO - [mozharness: 2017-03-22 00:22:13.279771Z] Running run-tests step. 17:22:13 INFO - Running pre-action listener: _resource_record_pre_action 17:22:13 INFO - Running main action method: run_tests 17:22:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:22:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:22:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:22:13 INFO - 2017-03-21 17:22:13.294 screenresolution[1634:7606] starting screenresolution argv=screenresolution get 17:22:13 INFO - 2017-03-21 17:22:13.297 screenresolution[1634:7606] Display 0: 1600x1200x32@60 17:22:13 INFO - 2017-03-21 17:22:13.302 screenresolution[1635:7609] starting screenresolution argv=screenresolution list 17:22:13 INFO - Available Modes on Display 0 17:22:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:22:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:22:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:22:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:22:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:22:13 INFO - Intel Iris: 17:22:13 INFO - Chipset Model: Intel Iris 17:22:13 INFO - Type: GPU 17:22:13 INFO - Bus: Built-In 17:22:13 INFO - VRAM (Dynamic, Max): 1536 MB 17:22:13 INFO - Vendor: Intel (0x8086) 17:22:13 INFO - Device ID: 0x0a2e 17:22:13 INFO - Revision ID: 0x0009 17:22:13 INFO - Displays: 17:22:13 INFO - Display: 17:22:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:22:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:22:13 INFO - Display Serial Number: 1600x1200 60 17:22:13 INFO - Main Display: Yes 17:22:13 INFO - Mirror: Off 17:22:13 INFO - Online: Yes 17:22:13 INFO - Rotation: Supported 17:22:13 INFO - Return code: 0 17:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:22:13 INFO - Python 2.7.3 17:22:13 INFO - Return code: 0 17:22:13 INFO - grabbing minidump binary from tooltool 17:22:13 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')]} 17:22:13 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 0x1079cbd30>, '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 0x1078f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079c0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:22:13 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 17:22:13 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 17:22:13 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 17:22:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:22:13 INFO - Return code: 0 17:22:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:22:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:22:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:22:13 INFO - ENV: RUST_BACKTRACE is now 1 17:22:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:22:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:22:13 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/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 17:22:13 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/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --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 17:22:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YEKiUeD65B/Render', 17:22:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:22:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:22:13 INFO - 'HOME': '/Users/cltbld', 17:22:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:22:13 INFO - 'LOGNAME': 'cltbld', 17:22:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:22:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:13 INFO - 'MOZ_NO_REMOTE': '1', 17:22:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:22:13 INFO - 'NO_EM_RESTART': '1', 17:22:13 INFO - 'PAGER': '/bin/cat', 17:22:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:13 INFO - 'PWD': '/builds/slave/test', 17:22:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:22:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:22:13 INFO - 'RUST_BACKTRACE': '1', 17:22:13 INFO - 'SHELL': '/bin/bash', 17:22:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EKpMvjX8zp/Listeners', 17:22:13 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 17:22:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:22:13 INFO - 'USER': 'cltbld', 17:22:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:22:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:13 INFO - 'XPC_FLAGS': '0x0', 17:22:13 INFO - 'XPC_SERVICE_NAME': '0', 17:22:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:22:13 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/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 17:22:13 INFO - mozversion application_buildid: 20170321160934 17:22:13 INFO - mozversion application_changeset: 8744e9f8eb99f1290aae81985812d57364f18708 17:22:13 INFO - mozversion application_display_name: Nightly 17:22:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:22:13 INFO - mozversion application_name: Firefox 17:22:13 INFO - mozversion application_remotingname: firefox 17:22:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:22:13 INFO - mozversion application_vendor: Mozilla 17:22:13 INFO - mozversion application_version: 55.0a1 17:22:13 INFO - mozversion platform_buildid: 20170321160934 17:22:13 INFO - mozversion platform_changeset: 8744e9f8eb99f1290aae81985812d57364f18708 17:22:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:22:13 INFO - mozversion platform_version: 55.0a1 17:22:13 INFO - using testdate: 1490142133 17:22:13 INFO - actual date: 1490142133 17:22:13 INFO - starting webserver on 'localhost:49240' 17:22:13 INFO - SUITE-START | Running 2 tests 17:22:13 INFO - TEST-START | dromaeo_css 17:22:13 INFO - operating with platform_type : mac_ 17:22:13 INFO - Initialising browser for dromaeo_css test... 17:22:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp8ToTcv/profile http://localhost:49240/getInfo.html) 17:22:16 INFO - PID 1645 | __metrics Screen width/height:1600/1200 17:22:16 INFO - PID 1645 | colorDepth:24 17:22:16 INFO - PID 1645 | Browser inner width/height: 1024/689 17:22:16 INFO - PID 1645 | __metrics 17:22:16 INFO - PID 1645 | 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" 17:22:16 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:22:17 INFO - TEST-INFO | 1645: exit 0 17:22:17 INFO - Browser initialized. 17:22:17 INFO - Running cycle 1/1 for dromaeo_css test... 17:22:17 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp8ToTcv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:22:29 INFO - PID 1647 | RSS: Main: 232599552 17:22:29 INFO - PID 1647 | 17:25:36 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 17:25:38 INFO - PID 1647 | RSS: Main: 389226496 17:25:38 INFO - PID 1647 | 17:27:19 INFO - PID 1647 | ************************* 17:27:19 INFO - PID 1647 | A coding exception was thrown and uncaught in a Task. 17:27:19 INFO - PID 1647 | 17:27:19 ERROR - PID 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:27:19 INFO - PID 1647 | Full stack: 17:27:19 INFO - PID 1647 | ************************* 17:28:41 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 17:28:41 INFO - PID 1647 | RSS: Main: 369803264 17:28:41 INFO - PID 1647 | 17:31:44 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 17:31:44 INFO - PID 1647 | RSS: Main: 336732160 17:31:44 INFO - PID 1647 | 17:34:47 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 17:34:48 INFO - PID 1647 | RSS: Main: 329351168 17:34:48 INFO - PID 1647 | 17:37:52 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 17:37:52 INFO - PID 1647 | RSS: Main: 329506816 17:37:52 INFO - PID 1647 | 17:40:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 17:40:56 INFO - PID 1647 | RSS: Main: 616853504 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | __start_tp_report 17:40:56 INFO - PID 1647 | _x_x_mozilla_page_load 17:40:56 INFO - PID 1647 | _x_x_mozilla_page_load_details 17:40:56 INFO - PID 1647 | |i|pagename|runs| 17:40:56 INFO - PID 1647 | |0;dojo.html;2926.8682909269082;3428.714232162937;3436.364272609569;3457.5159477673124;3475.843587038583;5389.892202155955;5508.614396992212;5628.831135065944;5675.035244008518;6061.545384096188;6017.338092809788;6095.299040610331;6106.703363853594;6120.908186377208;6124.877502449948;5311.468853114697;5437.972810135944;5441.945580544203;5461.808836690717;5495.725213739317;5563.415841336662;5566.832995010136;5577.609567330289;5583.469570390826;5586.720663966786;5389.514943655051;5395.136778115482;5400.594955378362;5500.669959802424;5547.417521160281;5397.136458166675;5441.1838224266285;5444.8094316698725;5447.45525447456;5449.782008719647;4413.7793110344355;5335.359756829166;5382.273393091921;5421.268128802601;5525.944740552583;4937.901241975141;6100.70885372201;6126.899373736878;6149.477294429978;6151.630902145886;6470;6470.514711396617;6471.9029214561815;6472.385123413268;6472.708728107246;6197.287311959138;6375.362463753588;6390;6596.274409814917;6608.372204640552;5305.389880163831;5948.910766338508;6174.351693072253;6178.07328900664;6235.625862448177;6688.29772873851;6754.5947243165565;6762.830929226809;6772;6783.287754785676;6203.596766210182;6293.622382657055;6295;6300.59046161998;6419.903701444482;6567.934320656828;6575.375339342755;6579.374959378852;6580.86838263232;6583.539152259392;6474.5791523550815;6477.967610161919;6478.643674597848;6489.026646003137;6492.318306577836;6219.657918814418;6257.968710156511;6424.972004479261;6470.9029364559565;6499.577527460794;5141.331626888481;5207.192885102815;5230.293910322058;5257.0800109981365;5264.131418316011;4645.907081858344;4663.370444989951;4669.533046695371;4676.485586585541;4683.297505374221;6087.147799308101;6135.785247516315;6143.385661433821;6147.139400483936;6155.138280640714;6323.43089121972;6400.967995159995;6409.519286053564;6451.129097571861;6516.57642253252;6108.675989703;6136.856084434161;6137.530208312589;6144.4945988232275;6145.354737752561;5749.913751293734;5754.539636829044;5754.88490230193;5755.568332375088;5761.798337058267;6509.218893732783;6586.045023471665;6596.3073877244115;6744.359285867835;6775.424088952297;6278.623282603058;6351.269603995414;6531.314212007671;6591.143151390289;6594.868102637824;6767.221769496374;6767.492438067066;6767.763128290683;6780.830479238059;6788.558743681645;6293.905591416224;6359.523035772243;6450.193725784277;6452.516061295515;6466.838329041811;5671.688057156896;6283.3088360281245;6359.3640635936035;6514.9348506514325;6532.804015879531;6233.75064997395;6331.9366806331345;6374.808755737336;6384.3934826191435;6391.776287829904;6282.842928926813;6286.497080233662;6314.8737025258315;6414.839629009125;6416.871662566629;6270.216222972129;6270.811875643738;6276.340984196594;6293.685315734287;6297.559170857996;6218.253809542884;6268.404501572343;6369.426751592379;6385.361463853763;6397.0724244983285;5222.216667499905;6215.65813880241;6221;6263.467605253422;6286.402791734778 17:40:56 INFO - PID 1647 | |1;ext.html;735.7057177129147;782.6712780632134;801.9759407217783;1133.9773204535916;1147.432021149531;27276;27823.860880695593;27825.860870695644;36347.81826090866;36924;40037.79981100091;40446.79776601113;40485.19029619406;40527.98680033001;40615.796921015426;8280.585970701455;8440.071592124861;8454.027786804503;8456.915430845705;8468.501791971383;5989.490893274077;6141.600795948271;6146.907796383058;8425.494470331769;8548.914510854875;6192.81421557354;6245.656488893109;6263.780767673133;8350.665973361058;8550.743477695649;8500.44747091437;8559.358048146352;8569.700060497915;8569.785755356104;8583.14168583143;8216.178382161796;8313.833723325499;8326.417150799447;8335.749927502186;8346;25350.239492815246;25741.613875791878;35740;36148.63851361479;37527.437088443694;2980.2102442852442;2980.3741214345014;3038.453078445879;3052.5573791800066;3118.8596513156735;21326.89336553323;27269.77286022134;27403.31491712703;27445.45109097827;27487.587686184725;2755.3111722069484;2783.025940920682;2789.302674331417;2813.170114816134;2815.7184281571886;327660.0850987237;329502;329776.0533591998;329847;330154;49852.75073624681;49888;49929.0014199714;50009.749951250014;50050.499495005315;42444.78777606092;52097.74827020782;52180;52231.216531752056;52304.69238269074;13703.931480342733;14178.787318190236;14185.574432767033;14188.148711077369;14216.360263788154;11535.134864885167;11543.36511491876;11548.42257887119;11556.653300401002;11587.015103716278;12059.336736479403;12354.629361119181;12372.020476723681;12383.566575169825;12482.938950189155;4517.638588912879;4602.102589994919;4608.516105808909;5061.291419201315;5170.1986192140275;964.344245912786;969.1181025266973;973.6251543155936;983.4345251480428;998.4558415663481;24011.63982540263;24014.281511327154;24056.27831165068;24059.4143471465;24072.51854962891;927.874736910522;1087.690008347617;1107.5846557540922;1110.5280255891214;1129.5030186717818;12155.088368372231;12287.508499660093;12328.75342493145;12333.629991100102;12344.259344439362;416100;417275;417583;417653;419086;54327.45672543224;54554;54556;54705;54925.45074549283;1444.8410674825761;1467.486379767073;1507;1510.1543135843963;1517.0594231576713;19987.500312492302;23506.76493235046;23517.529649407257;23565.764342357044;23665.290041298787;38138.618613814615;38175.6182438172;38261.04347391338;38266;38283;415691.1369874686;420422;420676;421488.89255553525;422067;31456.213594660316;32145.874894378583;32262.677373226907;33038.51326690257;33127.503087453224;41563.75308740743;41891;42508.78745606252;43897.78051109852;44262.55737442584;35110.473342899364;35128;35738.28523429465;36105.277894442486;36478.8176059118;30339.938102166318;32090;35364.46953295754;36310.636893630726;36457.817710912335;29521.557176641938;34978.47532286967;35296.76461323841;36173.81913090418;36443.27113457663;27410.314742131606;29028.709712903445;31427.68572314339;32003;32242.83878580686;20265.898670507144;22229.332800039738;23769.52460950806;24347.14784982517;24358.75641243565 17:40:56 INFO - PID 1647 | |2;jquery.html;389.6356906292615;418.43302325349134;421.7385221162879;430.44257686296254;443.69385124264255;12947.223166609998;14899.882508811841;21271.468213294636;21344.57310853782;21364;20835.791642083575;21365.038573264203;21500.139994400244;21776.455588610253;21854;5876.177335173079;5926.259217597801;6033.637981721111;6113.358097399776;6129;3616.710663146955;3804.029972357045;6068.453839154476;6094.329623741385;6141.1095391168255;3792.27946690128;4420.270655341865;6072.210612620375;6085.2393450818645;6099.146119543268;5794.101914203306;6049.455549000567;6063.727132279036;6064.029755239163;6074.179985701918;4608.976955115203;5253.238280449352;5302.912902854905;5309.62832601718;5331.813386531491;21987.670184947237;26833.86583067092;26876.92492300306;26948;27565.448691026068;1994.1724184463576;2009.6181725472113;2013.8892360920142;2060.5981833542464;2081.708560801489;15386.538403847791;16018.679626407407;16057.197140142945;16459.86021817168;16684.74972875408;1641.5731909703443;1646.2674110021046;1657.7016137095318;1681.4955513345947;1690.897203631338;129584;134524.32737836346;135407;135479.32260338735;135884;9849.704508864745;11463.54145834174;11900.642980710592;11924.821127683092;12032.639020829214;15960.521184364256;15989.520314390587;16013.599660008593;16022;16038.919805400898;8353.582320883932;8643.611037503328;8679.956600217085;8830.823383532294;8896.154865287788;6722.6302553359055;6834.419074378634;8223;8290.958545207235;8392.958035209907;6238.844028899223;6822.079019332426;6976.511644184859;7438.615429979346;7604.353629941517;2075.273654221041;2075.4292569543495;2077.3975547091477;2082.7708952015264;2096;886.1802832380023;903.214203642835;952.7951490429452;954.6801821389912;979.4759803505091;14274.73764047437;20742.170313187606;20842;20889.895550522455;21083.945802709804;861.6984055580497;982.9164521015794;1011.2365164301053;1020.4081632653043;1021.2391768132641;9606.519674016272;9982.101610855058;10215.336003159771;10272.486375681186;10798;585856;628920;632854;653929;657236;110004.4499777496;123410;140370.64625629227;156411;160560;1117.452448300327;1168.2172944127624;1215.465195314059;1219.16487206264;1219.7316590350108;19530.707039394136;20299.28952486656;20722.689159662918;20921.790782091983;21033.36899893006;60680.6965965185;61025.694871527136;61384.693076534335;62113.37886621076;62393.68803155955;76801.61599192192;78172.2182778165;82338.58830705807;84802;86650;7723.459357844897;8408.621612027597;8671.08953559867;8764.386492945432;9056.320775941698;13008.739825203633;13661.18032918028;13821.723565528835;14049;14550.781738273723;11780;11963.940180299043;12118.151729378858;12186.025117990363;12227.87772122303;8811.603477843384;11527.711807204887;11626.767464650491;11852.288862668267;12201.389930503618;11702.70743231426;11953.701157471132;12258.693532661755;12321.691957701129;12350.011999040235;8652.264557512683;8810.647574096964;8834.558272086499;8940.284777217938;8963.327750418875;11592.710182245512;11662.300261984308;11971.580994665144;12028.917397433923;12123 17:40:56 INFO - PID 1647 | |3;mootools.html;1221.548027229925;1439;1445.5807815733438;1454.2655958740834;1457.701171259892;10338.534765935532;10736.141108711305;10747.08649764769;10749.140068794497;10759.569617215311;10173.59305627776;10467.84298235527;10517.368957862534;10523.105536029427;10537.683869483928;2376.9881150594224;2420.225527831095;2423.3335832646076;2423.75762423758;2425.1932310428756;2300.2977796430687;2403.230966090852;2408.662787209792;2408.903643854244;2412.5657381671294;2042;2123.4266747978036;2129.339904629562;2129.7976692214293;2133.445304220906;2084.3538503064024;2104.252990188484;2318.07277089164;2404.1104791227185;2407.0612461140167;2405.7594240575977;2407.650890620868;2412.372783076395;2412.7587241275905;2413.710354757423;10627.36235825853;10749.462526873625;10752.516136773864;10755.731106722316;10759.139268858473;5421.701806400646;5613.186088017214;5613.66318020916;5629.887402251932;6106.114613381035;3467.7745946513646;3480.9477857832153;3518.736094792901;3896.7467114637457;3942.1524372259814;999.7900440907418;1195.0618764270093;1526.7938828258266;1566.1464501846522;1626.2437966345706;14628.488002919954;14777.926110369379;14818.851811481854;14825.481108161162;14835.77746333795;5130.333056702605;5249.396319423316;5279.392869819943;5317.288183026684;5356.196570514454;11129.888701113046;11130.554777808959;11139.387333696724;11270.042046425982;11294.209405341548;5226.790928362823;6232.719527621268;6234.2830574484515;6246.562740608205;6252.09344645033;5552.5280351169795;5708.20085188074;5754.4533269339345;5805.071188609887;5837.270341207349;4033.4554835096887;4158.979205103955;4178.080822219107;4219.97890010548;4231.534531201565;1210.8910198082215;1216.6714986953473;1217.9695507612203;1245.4768997021092;1246.1899765152723;613.8496068463255;731.2322061835158;757.5492581913752;761.2653789093551;786.3944762532818;5711.428857114339;6174.166487524217;6313.494920406357;6403.359664033653;6452.1289625900845;541.4206798725326;550.4577990679178;560.8766071464271;566.9291338582677;583.7548229743518;3671.9632803672157;3692.2246328270826;3692.8892133236045;3698.3158115748674;3710.703143748494;11644.475998580085;13835.30823458823;14008.019438639198;14050.85949140516;14058.226797526235;11213.887861121284;13490.797638035438;13826.861731382758;13944.58166255014;14008.879289656803;9779.364341317934;10032.297739158188;10122.645707400354;10240.078392944522;10273.845892311621;6347.841303967253;6359.459445947146;6554.606723596599;6576.506761992774;6601.768938087144;12069.155159138776;12132.150749447806;12192.75614487723;12200.51197952072;12264.816027759769;12611.243325400505;12667.30329831868;12786.68033299175;12857.935710321388;13070;9128.680496182602;9188.310876684141;9212.986571476999;9282.489463079595;9328;10018.298719089593;10115.241356898408;10242.897571024492;10244.897551024693;10306.793864122825;9006.819863602823;9027.09729027092;9033.954830225806;9034.412763170372;9112.407693499901;8597.441166324168;8723.040465548911;8822;8896.733098007071;8904.24313933297;7574.280443357872;7594.392448604209;7620.733274335372;7697.846043079219;7712.614369281401;5604.523615492729;5704.98688708971;5777.653340799566;5806.796762113306;5984.336168401035;5803.709814509342;5876.1773351731645;5885.3820348862755;5896.204012458264;5989.221401217814 17:40:56 INFO - PID 1647 | |4;prototype.html;392.8566073383215;394.78089660238555;398.6862151520549;404.2945060868784;406.83929847710175;6006.429389208024;6105.419985101419;6114.266288045441;6120.2764769677515;6187.969060154698;5797.536197104232;5913.85215369616;5980.940190598093;5994.250718660168;5996.580239383234;3800.7909564973916;3850.942235866464;4382.671299652538;4507.504174540814;4685.882852928678;4253.829846806124;4459.576340247671;4684.156851766681;4701.882452938669;4712.599429048535;4274.252005898971;4567.918115404144;4575.885602859922;4600.7929643166135;4626.86119416418;4011.4785077939973;4594.609458196057;4601.401817763686;4604.010137820368;4614.353990441341;3733.757305775116;4070.898227544335;4108.322126849066;4115.218108559364;4137.613899343693;5112.769925353368;5114.33513643224;5119.6160287978555;5793.942060579425;5846.239988801472;4567;5183.50756678115;5196.506331898464;5197.896042079137;5219.843404697827;5460.832717024116;5602.047651899146;6340.904886426707;6363.395477429637;6375.203099612549;3570;4044.090079732066;4309.698321117523;4312.82748690052;4312.849050283225;6254.312025677172;6500.9024864627545;6546.574472659262;6631.3700198481065;6764.993904510676;3745.5579601853483;3977.9060758291703;4228.217779710763;4239.215745087169;4341.5007274163245;4312.89217769552;4421.159979603865;4423.6903416760515;4428.667849911205;4428.763474445384;4519.525449827786;4734.526547345238;4867.488913664014;5014.542873991718;5414.83755487336;5401.378841433286;6347.650879201688;6414.583052101599;6415.326390728999;6593.604383736991;1323.3846261488454;1635.9611646604253;1638.2382192280409;1657.4530404966333;1659.7178479658487;1139.2708666453477;1143.451143451148;1151.5336288803048;1152.6772503698978;1160.8839116088322;5168.302279192261;5175;5741.569382296345;5892.793752218652;5906.7637294508595;5412.512873841294;6318.304986451487;6619.371159739817;6633.535652504375;6650.301718319603;4651.651126165551;5855.677937713382;5876.559258055578;5892.882142357129;5976.910346344808;2845.4451381980316;3015.41199466102;3403.676650718178;3453.7582369234415;3467.7745946513396;5595.580331475156;6526.151600291932;6676.933230667728;6770.32296770319;6795.932040679629;5240.790368385298;5593.300837395325;6670.733170673216;6693.832654183684;6718.6226823501065;5676.176954341598;6288.308286088435;6702.028205910018;6742.292059333798;6753.189617245864;5644.379118297066;6416.839579010562;6517.576357536745;6544.4764418845425;6546.345365463416;5088.720120393414;6435.51733619971;6459.903101453576;6475.579087359307;6486.189226346707;6252.468540174136;6375.489960803032;6448.935510645021;6636.63783632636;6660.533762636569;4149.792510374409;4500.842470513517;4711.481737008858;4737.465455981033;4745.9050818984115;5469;5941.613795103304;6234.438900498977;6374.3306952771145;6528.2492513359675;4772.880677983078;5204.8178313758835;5503.449655034625;6122.5408094392205;6261.74953001893;5489.65503451641;6094.451499365078;6282.748690052346;6331.746730130743;6344.429001389897;4958.206686930074;5388.919166212589;5929.169916211647;6065.909011364744;6199.845003874939;5337.439568845215;6341.714622841891;6354.872902541831;6361.109444677656;6399.8080057598345;5900.822975310747;6222.377762223741;6237.594556353823;6247.031710084944;6272 17:40:56 INFO - PID 1647 | |5;yui.html;710.2471380336843;719.6509692798992;719.858421196939;729.7336472187651;730.4193166432685;14811.851881481181;15166;15459;15475;15481.148536830484;14735.410583576671;14796.778048329285;14855.702885942275;14861.182634955074;15058.774118388206;654.8853950558657;677.3395938959525;907.2243232036597;908.0692290402325;928.7353104365224;780.4380845791021;941.8022215334788;942.3450701762278;942.6842007927353;942.7171848445473;985.3939827006375;986.659602437158;988.1501908358806;988.6292640259902;989.3717489394251;979.5934687104843;980.4901451245313;981.7349315684761;982.5431174503818;983.9409635421825;726.0923845193508;727.2109760909419;728.2208037399984;728.876091064515;729.2816575672958;15537.533873983799;15575.688486230212;15798.68402631941;16157.919210403872;16174.838251617452;14028.158310501403;14405.351759170862;14541.678260523926;14630.637423912527;14659.638869635064;11517.424128793611;11612.76774464506;11757.941210293893;11944.64166075019;12112.6366209013;2580.9483810323686;2908.8254704717574;2938.413594636101;2968.717971792676;2985.2387641151367;94966.5251673744;97857;98370;98406.50796745971;101386.49306753493;9187.265018798615;9678.661246856327;9823.361481503822;10348.896511034793;10443;19516;19546.70679939802;19566.902165489082;19569.608607827762;19572.804271957382;7362.4066702316195;7468.738594149178;7552.244775522514;7603.125640551297;7695.299643957847;3649.6350364963823;3675.3568125578454;3719.516462859865;3768.566614839274;3836.290286297044;3877.495925529663;3888.766673999569;3941.3299739044432;3968.503937007874;3969.80150992455;747.2863415438352;749.2245525880687;756.8599809035346;762.1996903251563;772.7218201447474;535.7241020874253;545.5444703672399;569.177538456934;578.3262499188398;593.4214141212369;8555.358348123791;9238.260939124853;9859.852102218472;9893;9942.602295908082;525.3931708876254;559.4880684173935;564.2298262871151;576.7087620751578;577.3540094304454;4355.9981204323185;4438.334249862482;4663.230566956414;4724.787384567703;4824.469308376076;94361.05638943522;94581.5270923641;94731.52634236922;96240.55639165419;96545;80889.19110808817;82816;83824;84966;85104.57447712845;71786.56426871537;71875;73882.26117738754;74078.14804629928;74569.6271518639;8793.428427152214;9146.634134634805;9158.129977652112;9168.745095491271;9207.355485116246;37429.81285093666;38142.618573815016;38213;38442.615573845025;39054;62798.686006569675;62912.68543657436;64140.679296603215;67970.18342890433;68496.6575167141;13440.060682882131;14007.649808754863;14154;14377.856221437652;14431.134131952116;14732.631684207981;14779;14819.407223711361;14939.626509336918;15334.388406143114;13393.799093013418;13613.455461781417;13892.996738616035;13948.232847193502;13980.510682126076;13000.284984325954;13230.801537976746;13518.864811351761;13820.378082986088;13951.953603479982;9316.494129749619;12886.355682215664;13095.41070651842;13191.472341106248;13801.240931748851;10807.783844322912;10868.891311086787;10895.836562451412;10996.835047474135;11768.70578235551;7954.880676789969;8070.233327834081;8374.036985746718;10664.253502255078;10857.891421085687 17:40:56 INFO - PID 1647 | __end_tp_report 17:40:56 INFO - PID 1647 | __start_cc_report 17:40:56 INFO - PID 1647 | _x_x_mozilla_cycle_collect,1967 17:40:56 INFO - PID 1647 | __end_cc_report 17:40:56 INFO - PID 1647 | __startTimestamp1490143256064__endTimestamp 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | ------- Summary: start ------- 17:40:56 INFO - PID 1647 | Number of tests: 6 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:5990.40 Median:6233.75 stddev:703.57 (11.3%) stddev-sans-first:663.41 17:40:56 INFO - PID 1647 | Values: 2926.9 3428.7 3436.4 3457.5 3475.8 5389.9 5508.6 5628.8 5675.0 6061.5 6017.3 6095.3 6106.7 6120.9 6124.9 5311.5 5438.0 5441.9 5461.8 5495.7 5563.4 5566.8 5577.6 5583.5 5586.7 5389.5 5395.1 5400.6 5500.7 5547.4 5397.1 5441.2 5444.8 5447.5 5449.8 4413.8 5335.4 5382.3 5421.3 5525.9 4937.9 6100.7 6126.9 6149.5 6151.6 6470.0 6470.5 6471.9 6472.4 6472.7 6197.3 6375.4 6390.0 6596.3 6608.4 5305.4 5948.9 6174.4 6178.1 6235.6 6688.3 6754.6 6762.8 6772.0 6783.3 6203.6 6293.6 6295.0 6300.6 6419.9 6567.9 6575.4 6579.4 6580.9 6583.5 6474.6 6478.0 6478.6 6489.0 6492.3 6219.7 6258.0 6425.0 6470.9 6499.6 5141.3 5207.2 5230.3 5257.1 5264.1 4645.9 4663.4 4669.5 4676.5 4683.3 6087.1 6135.8 6143.4 6147.1 6155.1 6323.4 6401.0 6409.5 6451.1 6516.6 6108.7 6136.9 6137.5 6144.5 6145.4 5749.9 5754.5 5754.9 5755.6 5761.8 6509.2 6586.0 6596.3 6744.4 6775.4 6278.6 6351.3 6531.3 6591.1 6594.9 6767.2 6767.5 6767.8 6780.8 6788.6 6293.9 6359.5 6450.2 6452.5 6466.8 5671.7 6283.3 6359.4 6514.9 6532.8 6233.8 6331.9 6374.8 6384.4 6391.8 6282.8 6286.5 6314.9 6414.8 6416.9 6270.2 6270.8 6276.3 6293.7 6297.6 6218.3 6268.4 6369.4 6385.4 6397.1 5222.2 6215.7 6221.0 6263.5 6286.4 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:52073.09 Median:35922.64 stddev:103786.90 (288.9%) stddev-sans-first:104006.48 17:40:56 INFO - PID 1647 | Values: 735.7 782.7 802.0 1134.0 1147.4 27276.0 27823.9 27825.9 36347.8 36924.0 40037.8 40446.8 40485.2 40528.0 40615.8 8280.6 8440.1 8454.0 8456.9 8468.5 5989.5 6141.6 6146.9 8425.5 8548.9 6192.8 6245.7 6263.8 8350.7 8550.7 8500.4 8559.4 8569.7 8569.8 8583.1 8216.2 8313.8 8326.4 8335.7 8346.0 25350.2 25741.6 35740.0 36148.6 37527.4 2980.2 2980.4 3038.5 3052.6 3118.9 21326.9 27269.8 27403.3 27445.5 27487.6 2755.3 2783.0 2789.3 2813.2 2815.7 327660.1 329502.0 329776.1 329847.0 330154.0 49852.8 49888.0 49929.0 50009.7 50050.5 42444.8 52097.7 52180.0 52231.2 52304.7 13703.9 14178.8 14185.6 14188.1 14216.4 11535.1 11543.4 11548.4 11556.7 11587.0 12059.3 12354.6 12372.0 12383.6 12482.9 4517.6 4602.1 4608.5 5061.3 5170.2 964.3 969.1 973.6 983.4 998.5 24011.6 24014.3 24056.3 24059.4 24072.5 927.9 1087.7 1107.6 1110.5 1129.5 12155.1 12287.5 12328.8 12333.6 12344.3 416100.0 417275.0 417583.0 417653.0 419086.0 54327.5 54554.0 54556.0 54705.0 54925.5 1444.8 1467.5 1507.0 1510.2 1517.1 19987.5 23506.8 23517.5 23565.8 23665.3 38138.6 38175.6 38261.0 38266.0 38283.0 415691.1 420422.0 420676.0 421488.9 422067.0 31456.2 32145.9 32262.7 33038.5 33127.5 41563.8 41891.0 42508.8 43897.8 44262.6 35110.5 35128.0 35738.3 36105.3 36478.8 30339.9 32090.0 35364.5 36310.6 36457.8 29521.6 34978.5 35296.8 36173.8 36443.3 27410.3 29028.7 31427.7 32003.0 32242.8 20265.9 22229.3 23769.5 24347.1 24358.8 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:37382.96 Median:21432.59 stddev:105930.59 (494.3%) stddev-sans-first:106191.33 17:40:56 INFO - PID 1647 | Values: 389.6 418.4 421.7 430.4 443.7 12947.2 14899.9 21271.5 21344.6 21364.0 20835.8 21365.0 21500.1 21776.5 21854.0 5876.2 5926.3 6033.6 6113.4 6129.0 3616.7 3804.0 6068.5 6094.3 6141.1 3792.3 4420.3 6072.2 6085.2 6099.1 5794.1 6049.5 6063.7 6064.0 6074.2 4609.0 5253.2 5302.9 5309.6 5331.8 21987.7 26833.9 26876.9 26948.0 27565.4 1994.2 2009.6 2013.9 2060.6 2081.7 15386.5 16018.7 16057.2 16459.9 16684.7 1641.6 1646.3 1657.7 1681.5 1690.9 129584.0 134524.3 135407.0 135479.3 135884.0 9849.7 11463.5 11900.6 11924.8 12032.6 15960.5 15989.5 16013.6 16022.0 16038.9 8353.6 8643.6 8680.0 8830.8 8896.2 6722.6 6834.4 8223.0 8291.0 8393.0 6238.8 6822.1 6976.5 7438.6 7604.4 2075.3 2075.4 2077.4 2082.8 2096.0 886.2 903.2 952.8 954.7 979.5 14274.7 20742.2 20842.0 20889.9 21083.9 861.7 982.9 1011.2 1020.4 1021.2 9606.5 9982.1 10215.3 10272.5 10798.0 585856.0 628920.0 632854.0 653929.0 657236.0 110004.4 123410.0 140370.6 156411.0 160560.0 1117.5 1168.2 1215.5 1219.2 1219.7 19530.7 20299.3 20722.7 20921.8 21033.4 60680.7 61025.7 61384.7 62113.4 62393.7 76801.6 78172.2 82338.6 84802.0 86650.0 7723.5 8408.6 8671.1 8764.4 9056.3 13008.7 13661.2 13821.7 14049.0 14550.8 11780.0 11963.9 12118.2 12186.0 12227.9 8811.6 11527.7 11626.8 11852.3 12201.4 11702.7 11953.7 12258.7 12321.7 12350.0 8652.3 8810.6 8834.6 8940.3 8963.3 11592.7 11662.3 11971.6 12028.9 12123.0 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:6668.30 Median:2423.55 stddev:4156.92 (171.5%) stddev-sans-first:4148.43 17:40:56 INFO - PID 1647 | Values: 1221.5 1439.0 1445.6 1454.3 1457.7 10338.5 10736.1 10747.1 10749.1 10759.6 10173.6 10467.8 10517.4 10523.1 10537.7 2377.0 2420.2 2423.3 2423.8 2425.2 2300.3 2403.2 2408.7 2408.9 2412.6 2042.0 2123.4 2129.3 2129.8 2133.4 2084.4 2104.3 2318.1 2404.1 2407.1 2405.8 2407.7 2412.4 2412.8 2413.7 10627.4 10749.5 10752.5 10755.7 10759.1 5421.7 5613.2 5613.7 5629.9 6106.1 3467.8 3480.9 3518.7 3896.7 3942.2 999.8 1195.1 1526.8 1566.1 1626.2 14628.5 14777.9 14818.9 14825.5 14835.8 5130.3 5249.4 5279.4 5317.3 5356.2 11129.9 11130.6 11139.4 11270.0 11294.2 5226.8 6232.7 6234.3 6246.6 6252.1 5552.5 5708.2 5754.5 5805.1 5837.3 4033.5 4159.0 4178.1 4220.0 4231.5 1210.9 1216.7 1218.0 1245.5 1246.2 613.8 731.2 757.5 761.3 786.4 5711.4 6174.2 6313.5 6403.4 6452.1 541.4 550.5 560.9 566.9 583.8 3672.0 3692.2 3692.9 3698.3 3710.7 11644.5 13835.3 14008.0 14050.9 14058.2 11213.9 13490.8 13826.9 13944.6 14008.9 9779.4 10032.3 10122.6 10240.1 10273.8 6347.8 6359.5 6554.6 6576.5 6601.8 12069.2 12132.2 12192.8 12200.5 12264.8 12611.2 12667.3 12786.7 12857.9 13070.0 9128.7 9188.3 9213.0 9282.5 9328.0 10018.3 10115.2 10242.9 10244.9 10306.8 9006.8 9027.1 9034.0 9034.4 9112.4 8597.4 8723.0 8822.0 8896.7 8904.2 7574.3 7594.4 7620.7 7697.8 7712.6 5604.5 5705.0 5777.7 5806.8 5984.3 5803.7 5876.2 5885.4 5896.2 5989.2 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5086.54 Median:5464.92 stddev:1545.63 (28.3%) stddev-sans-first:1509.28 17:40:56 INFO - PID 1647 | Values: 392.9 394.8 398.7 404.3 406.8 6006.4 6105.4 6114.3 6120.3 6188.0 5797.5 5913.9 5980.9 5994.3 5996.6 3800.8 3850.9 4382.7 4507.5 4685.9 4253.8 4459.6 4684.2 4701.9 4712.6 4274.3 4567.9 4575.9 4600.8 4626.9 4011.5 4594.6 4601.4 4604.0 4614.4 3733.8 4070.9 4108.3 4115.2 4137.6 5112.8 5114.3 5119.6 5793.9 5846.2 4567.0 5183.5 5196.5 5197.9 5219.8 5460.8 5602.0 6340.9 6363.4 6375.2 3570.0 4044.1 4309.7 4312.8 4312.8 6254.3 6500.9 6546.6 6631.4 6765.0 3745.6 3977.9 4228.2 4239.2 4341.5 4312.9 4421.2 4423.7 4428.7 4428.8 4519.5 4734.5 4867.5 5014.5 5414.8 5401.4 6347.7 6414.6 6415.3 6593.6 1323.4 1636.0 1638.2 1657.5 1659.7 1139.3 1143.5 1151.5 1152.7 1160.9 5168.3 5175.0 5741.6 5892.8 5906.8 5412.5 6318.3 6619.4 6633.5 6650.3 4651.7 5855.7 5876.6 5892.9 5976.9 2845.4 3015.4 3403.7 3453.8 3467.8 5595.6 6526.2 6676.9 6770.3 6795.9 5240.8 5593.3 6670.7 6693.8 6718.6 5676.2 6288.3 6702.0 6742.3 6753.2 5644.4 6416.8 6517.6 6544.5 6546.3 5088.7 6435.5 6459.9 6475.6 6486.2 6252.5 6375.5 6448.9 6636.6 6660.5 4149.8 4500.8 4711.5 4737.5 4745.9 5469.0 5941.6 6234.4 6374.3 6528.2 4772.9 5204.8 5503.4 6122.5 6261.7 5489.7 6094.5 6282.7 6331.7 6344.4 4958.2 5388.9 5929.2 6065.9 6199.8 5337.4 6341.7 6354.9 6361.1 6399.8 5900.8 6222.4 6237.6 6247.0 6272.0 17:40:56 INFO - PID 1647 | 17:40:56 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:19242.39 Median:540.63 stddev:27186.21 (5028.6%) stddev-sans-first:27226.86 17:40:56 INFO - PID 1647 | Values: 710.2 719.7 719.9 729.7 730.4 14811.9 15166.0 15459.0 15475.0 15481.1 14735.4 14796.8 14855.7 14861.2 15058.8 654.9 677.3 907.2 908.1 928.7 780.4 941.8 942.3 942.7 942.7 985.4 986.7 988.2 988.6 989.4 979.6 980.5 981.7 982.5 983.9 726.1 727.2 728.2 728.9 729.3 15537.5 15575.7 15798.7 16157.9 16174.8 14028.2 14405.4 14541.7 14630.6 14659.6 11517.4 11612.8 11757.9 11944.6 12112.6 2580.9 2908.8 2938.4 2968.7 2985.2 94966.5 97857.0 98370.0 98406.5 101386.5 9187.3 9678.7 9823.4 10348.9 10443.0 19516.0 19546.7 19566.9 19569.6 19572.8 7362.4 7468.7 7552.2 7603.1 7695.3 3649.6 3675.4 3719.5 3768.6 3836.3 3877.5 3888.8 3941.3 3968.5 3969.8 747.3 749.2 756.9 762.2 772.7 535.7 545.5 569.2 578.3 593.4 8555.4 9238.3 9859.9 9893.0 9942.6 525.4 559.5 564.2 576.7 577.4 4356.0 4438.3 4663.2 4724.8 4824.5 94361.1 94581.5 94731.5 96240.6 96545.0 80889.2 82816.0 83824.0 84966.0 85104.6 71786.6 71875.0 73882.3 74078.1 74569.6 8793.4 9146.6 9158.1 9168.7 9207.4 37429.8 38142.6 38213.0 38442.6 39054.0 62798.7 62912.7 64140.7 67970.2 68496.7 13440.1 14007.6 14154.0 14377.9 14431.1 14732.6 14779.0 14819.4 14939.6 15334.4 13393.8 13613.5 13893.0 13948.2 13980.5 13000.3 13230.8 13518.9 13820.4 13952.0 9316.5 12886.4 13095.4 13191.5 13801.2 10807.8 10868.9 10895.8 10996.8 11768.7 7954.9 8070.2 8374.0 10664.3 10857.9 17:40:56 INFO - PID 1647 | -------- Summary: end -------- 17:40:56 INFO - PID 1647 | 17:40:56 INFO - TEST-INFO | 1647: exit 0 17:40:56 INFO - TEST-OK | dromaeo_css | took 1122866ms 17:40:56 INFO - TEST-START | kraken 17:40:56 INFO - operating with platform_type : mac_ 17:40:56 INFO - Initialising browser for kraken test... 17:40:56 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmplLGDhg/profile http://localhost:49240/getInfo.html) 17:40:59 INFO - PID 1654 | __metrics Screen width/height:1600/1200 17:40:59 INFO - PID 1654 | colorDepth:24 17:40:59 INFO - PID 1654 | Browser inner width/height: 1024/689 17:40:59 INFO - PID 1654 | __metrics 17:40:59 INFO - PID 1654 | 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" 17:40:59 INFO - PID 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:41:00 INFO - TEST-INFO | 1654: exit 0 17:41:00 INFO - Browser initialized. 17:41:00 INFO - Running cycle 1/1 for kraken test... 17:41:00 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmplLGDhg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:41:12 INFO - PID 1655 | RSS: Main: 233734144 17:41:12 INFO - PID 1655 | 17:41:15 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 17:41:16 INFO - PID 1655 | RSS: Main: 285310976 17:41:16 INFO - PID 1655 | 17:41:18 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 17:41:19 INFO - PID 1655 | RSS: Main: 287719424 17:41:19 INFO - PID 1655 | 17:41:22 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 17:41:23 INFO - PID 1655 | RSS: Main: 377409536 17:41:23 INFO - PID 1655 | 17:41:25 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 17:41:25 INFO - PID 1655 | RSS: Main: 280317952 17:41:25 INFO - PID 1655 | 17:41:27 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:41:28 INFO - PID 1655 | RSS: Main: 284262400 17:41:28 INFO - PID 1655 | 17:41:31 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 17:41:32 INFO - PID 1655 | RSS: Main: 325038080 17:41:32 INFO - PID 1655 | 17:41:35 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 17:41:35 INFO - PID 1655 | RSS: Main: 333475840 17:41:35 INFO - PID 1655 | 17:41:38 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 17:41:39 INFO - PID 1655 | RSS: Main: 339050496 17:41:39 INFO - PID 1655 | 17:41:41 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:41:41 INFO - PID 1655 | RSS: Main: 302563328 17:41:41 INFO - PID 1655 | 17:41:43 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:41:44 INFO - PID 1655 | RSS: Main: 304930816 17:41:44 INFO - PID 1655 | 17:41:46 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:41:46 INFO - PID 1655 | RSS: Main: 307298304 17:41:46 INFO - PID 1655 | 17:41:49 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:41:50 INFO - PID 1655 | RSS: Main: 324964352 17:41:50 INFO - PID 1655 | 17:41:53 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:41:54 INFO - PID 1655 | RSS: Main: 318984192 17:41:54 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 17:41:56 INFO - PID 1655 | RSS: Main: 380846080 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | __start_tp_report 17:41:56 INFO - PID 1655 | _x_x_mozilla_page_load 17:41:56 INFO - PID 1655 | _x_x_mozilla_page_load_details 17:41:56 INFO - PID 1655 | |i|pagename|runs| 17:41:56 INFO - PID 1655 | |0;ai-astar;112;113;123;164;144;113;112;112;112;111 17:41:56 INFO - PID 1655 | |1;audio-beat-detection;165;146;148;154;114;111;113;113;133;113 17:41:56 INFO - PID 1655 | |2;audio-dft;182;185;183;188;186;185;185;184;185;184 17:41:56 INFO - PID 1655 | |3;audio-fft;78;63;60;89;60;60;61;59;75;60 17:41:56 INFO - PID 1655 | |4;audio-oscillator;89;71;87;70;69;69;82;68;69;85 17:41:56 INFO - PID 1655 | |5;imaging-gaussian-blur;91;91;92;91;92;91;92;92;91;92 17:41:56 INFO - PID 1655 | |6;imaging-darkroom;97;98;97;98;97;97;111;129;148;97 17:41:56 INFO - PID 1655 | |7;imaging-desaturate;83;86;85;86;84;85;85;87;86;88 17:41:56 INFO - PID 1655 | |8;json-parse-financial;63;62;62;60;64;62;62;65;63;61 17:41:56 INFO - PID 1655 | |9;json-stringify-tinderbox;53;62;64;62;59;56;57;58;58;57 17:41:56 INFO - PID 1655 | |10;stanford-crypto-aes;83;82;83;83;82;83;83;83;82;81 17:41:56 INFO - PID 1655 | |11;stanford-crypto-ccm;140;145;140;145;146;147;142;136;186;136 17:41:56 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;188;183;186;188;186;188;187;186;186;212 17:41:56 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;68;67;67;68;67;67;67;68;67;67 17:41:56 INFO - PID 1655 | __end_tp_report 17:41:56 INFO - PID 1655 | __start_cc_report 17:41:56 INFO - PID 1655 | _x_x_mozilla_cycle_collect,2146 17:41:56 INFO - PID 1655 | __end_cc_report 17:41:56 INFO - PID 1655 | __startTimestamp1490143316413__endTimestamp 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | ------- Summary: start ------- 17:41:56 INFO - PID 1655 | Number of tests: 14 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:121.60 Median:113.00 stddev:18.04 (16.0%) stddev-sans-first:18.80 17:41:56 INFO - PID 1655 | Values: 112.0 113.0 123.0 164.0 144.0 113.0 112.0 112.0 112.0 111.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:139.50 stddev:20.72 (14.9%) stddev-sans-first:17.96 17:41:56 INFO - PID 1655 | Values: 165.0 146.0 148.0 154.0 114.0 111.0 113.0 113.0 133.0 113.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.64 (0.9%) stddev-sans-first:1.41 17:41:56 INFO - PID 1655 | Values: 182.0 185.0 183.0 188.0 186.0 185.0 185.0 184.0 185.0 184.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:66.50 Median:62.00 stddev:10.43 (16.8%) stddev-sans-first:10.20 17:41:56 INFO - PID 1655 | Values: 78.0 63.0 60.0 89.0 60.0 60.0 61.0 59.0 75.0 60.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:76.50 stddev:8.69 (11.4%) stddev-sans-first:7.81 17:41:56 INFO - PID 1655 | Values: 89.0 71.0 87.0 70.0 69.0 69.0 82.0 68.0 69.0 85.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:92.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 17:41:56 INFO - PID 1655 | Values: 91.0 91.0 92.0 91.0 92.0 91.0 92.0 92.0 91.0 92.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:97.00 stddev:17.77 (18.3%) stddev-sans-first:18.49 17:41:56 INFO - PID 1655 | Values: 97.0 98.0 97.0 98.0 97.0 97.0 111.0 129.0 148.0 97.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.20 17:41:56 INFO - PID 1655 | Values: 83.0 86.0 85.0 86.0 84.0 85.0 85.0 87.0 86.0 88.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.50 stddev:1.43 (2.3%) stddev-sans-first:1.50 17:41:56 INFO - PID 1655 | Values: 63.0 62.0 62.0 60.0 64.0 62.0 62.0 65.0 63.0 61.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:58.50 stddev:3.27 (5.6%) stddev-sans-first:2.77 17:41:56 INFO - PID 1655 | Values: 53.0 62.0 64.0 62.0 59.0 56.0 57.0 58.0 58.0 57.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:0.71 (0.9%) stddev-sans-first:0.73 17:41:56 INFO - PID 1655 | Values: 83.0 82.0 83.0 83.0 82.0 83.0 83.0 83.0 82.0 81.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:145.00 stddev:14.49 (10.0%) stddev-sans-first:15.19 17:41:56 INFO - PID 1655 | Values: 140.0 145.0 140.0 145.0 146.0 147.0 142.0 136.0 186.0 136.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:187.50 stddev:8.22 (4.4%) stddev-sans-first:8.71 17:41:56 INFO - PID 1655 | Values: 188.0 183.0 186.0 188.0 186.0 188.0 187.0 186.0 186.0 212.0 17:41:56 INFO - PID 1655 | 17:41:56 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 17:41:56 INFO - PID 1655 | Values: 68.0 67.0 67.0 68.0 67.0 67.0 67.0 68.0 67.0 67.0 17:41:56 INFO - PID 1655 | -------- Summary: end -------- 17:41:56 INFO - PID 1655 | 17:41:57 INFO - TEST-INFO | 1655: exit 0 17:41:57 INFO - TEST-OK | kraken | took 60578ms 17:41:57 INFO - SUITE-END | took 1183s 17:41:57 INFO - Completed test suite (00:19:43) 17:41:57 INFO - javascript benchmark 17:41:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7465.048777692505, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2926.8682909269082, 3428.714232162937, 3436.364272609569, 3457.5159477673124, 3475.843587038583, 5389.892202155955, 5508.614396992212, 5628.831135065944, 5675.035244008518, 6061.545384096188, 6017.338092809788, 6095.299040610331, 6106.703363853594, 6120.908186377208, 6124.877502449948, 5311.468853114697, 5437.972810135944, 5441.945580544203, 5461.808836690717, 5495.725213739317, 5563.415841336662, 5566.832995010136, 5577.609567330289, 5583.469570390826, 5586.720663966786, 5389.514943655051, 5395.136778115482, 5400.594955378362, 5500.669959802424, 5547.417521160281, 5397.136458166675, 5441.1838224266285, 5444.8094316698725, 5447.45525447456, 5449.782008719647, 4413.7793110344355, 5335.359756829166, 5382.273393091921, 5421.268128802601, 5525.944740552583, 4937.901241975141, 6100.70885372201, 6126.899373736878, 6149.477294429978, 6151.630902145886, 6470.0, 6470.514711396617, 6471.9029214561815, 6472.385123413268, 6472.708728107246, 6197.287311959138, 6375.362463753588, 6390.0, 6596.274409814917, 6608.372204640552, 5305.389880163831, 5948.910766338508, 6174.351693072253, 6178.07328900664, 6235.625862448177, 6688.29772873851, 6754.5947243165565, 6762.830929226809, 6772.0, 6783.287754785676, 6203.596766210182, 6293.622382657055, 6295.0, 6300.59046161998, 6419.903701444482, 6567.934320656828, 6575.375339342755, 6579.374959378852, 6580.86838263232, 6583.539152259392, 6474.5791523550815, 6477.967610161919, 6478.643674597848, 6489.026646003137, 6492.318306577836, 6219.657918814418, 6257.968710156511, 6424.972004479261, 6470.9029364559565, 6499.577527460794, 5141.331626888481, 5207.192885102815, 5230.293910322058, 5257.0800109981365, 5264.131418316011, 4645.907081858344, 4663.370444989951, 4669.533046695371, 4676.485586585541, 4683.297505374221, 6087.147799308101, 6135.785247516315, 6143.385661433821, 6147.139400483936, 6155.138280640714, 6323.43089121972, 6400.967995159995, 6409.519286053564, 6451.129097571861, 6516.57642253252, 6108.675989703, 6136.856084434161, 6137.530208312589, 6144.4945988232275, 6145.354737752561, 5749.913751293734, 5754.539636829044, 5754.88490230193, 5755.568332375088, 5761.798337058267, 6509.218893732783, 6586.045023471665, 6596.3073877244115, 6744.359285867835, 6775.424088952297, 6278.623282603058, 6351.269603995414, 6531.314212007671, 6591.143151390289, 6594.868102637824, 6767.221769496374, 6767.492438067066, 6767.763128290683, 6780.830479238059, 6788.558743681645, 6293.905591416224, 6359.523035772243, 6450.193725784277, 6452.516061295515, 6466.838329041811, 5671.688057156896, 6283.3088360281245, 6359.3640635936035, 6514.9348506514325, 6532.804015879531, 6233.75064997395, 6331.9366806331345, 6374.808755737336, 6384.3934826191435, 6391.776287829904, 6282.842928926813, 6286.497080233662, 6314.8737025258315, 6414.839629009125, 6416.871662566629, 6270.216222972129, 6270.811875643738, 6276.340984196594, 6293.685315734287, 6297.559170857996, 6218.253809542884, 6268.404501572343, 6369.426751592379, 6385.361463853763, 6397.0724244983285, 5222.216667499905, 6215.65813880241, 6221.0, 6263.467605253422, 6286.402791734778], "value": 5943.4276922422, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.7057177129147, 782.6712780632134, 801.9759407217783, 1133.9773204535916, 1147.432021149531, 27276.0, 27823.860880695593, 27825.860870695644, 36347.81826090866, 36924.0, 40037.79981100091, 40446.79776601113, 40485.19029619406, 40527.98680033001, 40615.796921015426, 8280.585970701455, 8440.071592124861, 8454.027786804503, 8456.915430845705, 8468.501791971383, 5989.490893274077, 6141.600795948271, 6146.907796383058, 8425.494470331769, 8548.914510854875, 6192.81421557354, 6245.656488893109, 6263.780767673133, 8350.665973361058, 8550.743477695649, 8500.44747091437, 8559.358048146352, 8569.700060497915, 8569.785755356104, 8583.14168583143, 8216.178382161796, 8313.833723325499, 8326.417150799447, 8335.749927502186, 8346.0, 25350.239492815246, 25741.613875791878, 35740.0, 36148.63851361479, 37527.437088443694, 2980.2102442852442, 2980.3741214345014, 3038.453078445879, 3052.5573791800066, 3118.8596513156735, 21326.89336553323, 27269.77286022134, 27403.31491712703, 27445.45109097827, 27487.587686184725, 2755.3111722069484, 2783.025940920682, 2789.302674331417, 2813.170114816134, 2815.7184281571886, 327660.0850987237, 329502.0, 329776.0533591998, 329847.0, 330154.0, 49852.75073624681, 49888.0, 49929.0014199714, 50009.749951250014, 50050.499495005315, 42444.78777606092, 52097.74827020782, 52180.0, 52231.216531752056, 52304.69238269074, 13703.931480342733, 14178.787318190236, 14185.574432767033, 14188.148711077369, 14216.360263788154, 11535.134864885167, 11543.36511491876, 11548.42257887119, 11556.653300401002, 11587.015103716278, 12059.336736479403, 12354.629361119181, 12372.020476723681, 12383.566575169825, 12482.938950189155, 4517.638588912879, 4602.102589994919, 4608.516105808909, 5061.291419201315, 5170.1986192140275, 964.344245912786, 969.1181025266973, 973.6251543155936, 983.4345251480428, 998.4558415663481, 24011.63982540263, 24014.281511327154, 24056.27831165068, 24059.4143471465, 24072.51854962891, 927.874736910522, 1087.690008347617, 1107.5846557540922, 1110.5280255891214, 1129.5030186717818, 12155.088368372231, 12287.508499660093, 12328.75342493145, 12333.629991100102, 12344.259344439362, 416100.0, 417275.0, 417583.0, 417653.0, 419086.0, 54327.45672543224, 54554.0, 54556.0, 54705.0, 54925.45074549283, 1444.8410674825761, 1467.486379767073, 1507.0, 1510.1543135843963, 1517.0594231576713, 19987.500312492302, 23506.76493235046, 23517.529649407257, 23565.764342357044, 23665.290041298787, 38138.618613814615, 38175.6182438172, 38261.04347391338, 38266.0, 38283.0, 415691.1369874686, 420422.0, 420676.0, 421488.89255553525, 422067.0, 31456.213594660316, 32145.874894378583, 32262.677373226907, 33038.51326690257, 33127.503087453224, 41563.75308740743, 41891.0, 42508.78745606252, 43897.78051109852, 44262.55737442584, 35110.473342899364, 35128.0, 35738.28523429465, 36105.277894442486, 36478.8176059118, 30339.938102166318, 32090.0, 35364.46953295754, 36310.636893630726, 36457.817710912335, 29521.557176641938, 34978.47532286967, 35296.76461323841, 36173.81913090418, 36443.27113457663, 27410.314742131606, 29028.709712903445, 31427.68572314339, 32003.0, 32242.83878580686, 20265.898670507144, 22229.332800039738, 23769.52460950806, 24347.14784982517, 24358.75641243565], "value": 17217.737248561618, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.6356906292615, 418.43302325349134, 421.7385221162879, 430.44257686296254, 443.69385124264255, 12947.223166609998, 14899.882508811841, 21271.468213294636, 21344.57310853782, 21364.0, 20835.791642083575, 21365.038573264203, 21500.139994400244, 21776.455588610253, 21854.0, 5876.177335173079, 5926.259217597801, 6033.637981721111, 6113.358097399776, 6129.0, 3616.710663146955, 3804.029972357045, 6068.453839154476, 6094.329623741385, 6141.1095391168255, 3792.27946690128, 4420.270655341865, 6072.210612620375, 6085.2393450818645, 6099.146119543268, 5794.101914203306, 6049.455549000567, 6063.727132279036, 6064.029755239163, 6074.179985701918, 4608.976955115203, 5253.238280449352, 5302.912902854905, 5309.62832601718, 5331.813386531491, 21987.670184947237, 26833.86583067092, 26876.92492300306, 26948.0, 27565.448691026068, 1994.1724184463576, 2009.6181725472113, 2013.8892360920142, 2060.5981833542464, 2081.708560801489, 15386.538403847791, 16018.679626407407, 16057.197140142945, 16459.86021817168, 16684.74972875408, 1641.5731909703443, 1646.2674110021046, 1657.7016137095318, 1681.4955513345947, 1690.897203631338, 129584.0, 134524.32737836346, 135407.0, 135479.32260338735, 135884.0, 9849.704508864745, 11463.54145834174, 11900.642980710592, 11924.821127683092, 12032.639020829214, 15960.521184364256, 15989.520314390587, 16013.599660008593, 16022.0, 16038.919805400898, 8353.582320883932, 8643.611037503328, 8679.956600217085, 8830.823383532294, 8896.154865287788, 6722.6302553359055, 6834.419074378634, 8223.0, 8290.958545207235, 8392.958035209907, 6238.844028899223, 6822.079019332426, 6976.511644184859, 7438.615429979346, 7604.353629941517, 2075.273654221041, 2075.4292569543495, 2077.3975547091477, 2082.7708952015264, 2096.0, 886.1802832380023, 903.214203642835, 952.7951490429452, 954.6801821389912, 979.4759803505091, 14274.73764047437, 20742.170313187606, 20842.0, 20889.895550522455, 21083.945802709804, 861.6984055580497, 982.9164521015794, 1011.2365164301053, 1020.4081632653043, 1021.2391768132641, 9606.519674016272, 9982.101610855058, 10215.336003159771, 10272.486375681186, 10798.0, 585856.0, 628920.0, 632854.0, 653929.0, 657236.0, 110004.4499777496, 123410.0, 140370.64625629227, 156411.0, 160560.0, 1117.452448300327, 1168.2172944127624, 1215.465195314059, 1219.16487206264, 1219.7316590350108, 19530.707039394136, 20299.28952486656, 20722.689159662918, 20921.790782091983, 21033.36899893006, 60680.6965965185, 61025.694871527136, 61384.693076534335, 62113.37886621076, 62393.68803155955, 76801.61599192192, 78172.2182778165, 82338.58830705807, 84802.0, 86650.0, 7723.459357844897, 8408.621612027597, 8671.08953559867, 8764.386492945432, 9056.320775941698, 13008.739825203633, 13661.18032918028, 13821.723565528835, 14049.0, 14550.781738273723, 11780.0, 11963.940180299043, 12118.151729378858, 12186.025117990363, 12227.87772122303, 8811.603477843384, 11527.711807204887, 11626.767464650491, 11852.288862668267, 12201.389930503618, 11702.70743231426, 11953.701157471132, 12258.693532661755, 12321.691957701129, 12350.011999040235, 8652.264557512683, 8810.647574096964, 8834.558272086499, 8940.284777217938, 8963.327750418875, 11592.710182245512, 11662.300261984308, 11971.580994665144, 12028.917397433923, 12123.0], "value": 10035.792127413275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1221.548027229925, 1439.0, 1445.5807815733438, 1454.2655958740834, 1457.701171259892, 10338.534765935532, 10736.141108711305, 10747.08649764769, 10749.140068794497, 10759.569617215311, 10173.59305627776, 10467.84298235527, 10517.368957862534, 10523.105536029427, 10537.683869483928, 2376.9881150594224, 2420.225527831095, 2423.3335832646076, 2423.75762423758, 2425.1932310428756, 2300.2977796430687, 2403.230966090852, 2408.662787209792, 2408.903643854244, 2412.5657381671294, 2042.0, 2123.4266747978036, 2129.339904629562, 2129.7976692214293, 2133.445304220906, 2084.3538503064024, 2104.252990188484, 2318.07277089164, 2404.1104791227185, 2407.0612461140167, 2405.7594240575977, 2407.650890620868, 2412.372783076395, 2412.7587241275905, 2413.710354757423, 10627.36235825853, 10749.462526873625, 10752.516136773864, 10755.731106722316, 10759.139268858473, 5421.701806400646, 5613.186088017214, 5613.66318020916, 5629.887402251932, 6106.114613381035, 3467.7745946513646, 3480.9477857832153, 3518.736094792901, 3896.7467114637457, 3942.1524372259814, 999.7900440907418, 1195.0618764270093, 1526.7938828258266, 1566.1464501846522, 1626.2437966345706, 14628.488002919954, 14777.926110369379, 14818.851811481854, 14825.481108161162, 14835.77746333795, 5130.333056702605, 5249.396319423316, 5279.392869819943, 5317.288183026684, 5356.196570514454, 11129.888701113046, 11130.554777808959, 11139.387333696724, 11270.042046425982, 11294.209405341548, 5226.790928362823, 6232.719527621268, 6234.2830574484515, 6246.562740608205, 6252.09344645033, 5552.5280351169795, 5708.20085188074, 5754.4533269339345, 5805.071188609887, 5837.270341207349, 4033.4554835096887, 4158.979205103955, 4178.080822219107, 4219.97890010548, 4231.534531201565, 1210.8910198082215, 1216.6714986953473, 1217.9695507612203, 1245.4768997021092, 1246.1899765152723, 613.8496068463255, 731.2322061835158, 757.5492581913752, 761.2653789093551, 786.3944762532818, 5711.428857114339, 6174.166487524217, 6313.494920406357, 6403.359664033653, 6452.1289625900845, 541.4206798725326, 550.4577990679178, 560.8766071464271, 566.9291338582677, 583.7548229743518, 3671.9632803672157, 3692.2246328270826, 3692.8892133236045, 3698.3158115748674, 3710.703143748494, 11644.475998580085, 13835.30823458823, 14008.019438639198, 14050.85949140516, 14058.226797526235, 11213.887861121284, 13490.797638035438, 13826.861731382758, 13944.58166255014, 14008.879289656803, 9779.364341317934, 10032.297739158188, 10122.645707400354, 10240.078392944522, 10273.845892311621, 6347.841303967253, 6359.459445947146, 6554.606723596599, 6576.506761992774, 6601.768938087144, 12069.155159138776, 12132.150749447806, 12192.75614487723, 12200.51197952072, 12264.816027759769, 12611.243325400505, 12667.30329831868, 12786.68033299175, 12857.935710321388, 13070.0, 9128.680496182602, 9188.310876684141, 9212.986571476999, 9282.489463079595, 9328.0, 10018.298719089593, 10115.241356898408, 10242.897571024492, 10244.897551024693, 10306.793864122825, 9006.819863602823, 9027.09729027092, 9033.954830225806, 9034.412763170372, 9112.407693499901, 8597.441166324168, 8723.040465548911, 8822.0, 8896.733098007071, 8904.24313933297, 7574.280443357872, 7594.392448604209, 7620.733274335372, 7697.846043079219, 7712.614369281401, 5604.523615492729, 5704.98688708971, 5777.653340799566, 5806.796762113306, 5984.336168401035, 5803.709814509342, 5876.1773351731645, 5885.3820348862755, 5896.204012458264, 5989.221401217814], "value": 5017.883136892173, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.8566073383215, 394.78089660238555, 398.6862151520549, 404.2945060868784, 406.83929847710175, 6006.429389208024, 6105.419985101419, 6114.266288045441, 6120.2764769677515, 6187.969060154698, 5797.536197104232, 5913.85215369616, 5980.940190598093, 5994.250718660168, 5996.580239383234, 3800.7909564973916, 3850.942235866464, 4382.671299652538, 4507.504174540814, 4685.882852928678, 4253.829846806124, 4459.576340247671, 4684.156851766681, 4701.882452938669, 4712.599429048535, 4274.252005898971, 4567.918115404144, 4575.885602859922, 4600.7929643166135, 4626.86119416418, 4011.4785077939973, 4594.609458196057, 4601.401817763686, 4604.010137820368, 4614.353990441341, 3733.757305775116, 4070.898227544335, 4108.322126849066, 4115.218108559364, 4137.613899343693, 5112.769925353368, 5114.33513643224, 5119.6160287978555, 5793.942060579425, 5846.239988801472, 4567.0, 5183.50756678115, 5196.506331898464, 5197.896042079137, 5219.843404697827, 5460.832717024116, 5602.047651899146, 6340.904886426707, 6363.395477429637, 6375.203099612549, 3570.0, 4044.090079732066, 4309.698321117523, 4312.82748690052, 4312.849050283225, 6254.312025677172, 6500.9024864627545, 6546.574472659262, 6631.3700198481065, 6764.993904510676, 3745.5579601853483, 3977.9060758291703, 4228.217779710763, 4239.215745087169, 4341.5007274163245, 4312.89217769552, 4421.159979603865, 4423.6903416760515, 4428.667849911205, 4428.763474445384, 4519.525449827786, 4734.526547345238, 4867.488913664014, 5014.542873991718, 5414.83755487336, 5401.378841433286, 6347.650879201688, 6414.583052101599, 6415.326390728999, 6593.604383736991, 1323.3846261488454, 1635.9611646604253, 1638.2382192280409, 1657.4530404966333, 1659.7178479658487, 1139.2708666453477, 1143.451143451148, 1151.5336288803048, 1152.6772503698978, 1160.8839116088322, 5168.302279192261, 5175.0, 5741.569382296345, 5892.793752218652, 5906.7637294508595, 5412.512873841294, 6318.304986451487, 6619.371159739817, 6633.535652504375, 6650.301718319603, 4651.651126165551, 5855.677937713382, 5876.559258055578, 5892.882142357129, 5976.910346344808, 2845.4451381980316, 3015.41199466102, 3403.676650718178, 3453.7582369234415, 3467.7745946513396, 5595.580331475156, 6526.151600291932, 6676.933230667728, 6770.32296770319, 6795.932040679629, 5240.790368385298, 5593.300837395325, 6670.733170673216, 6693.832654183684, 6718.6226823501065, 5676.176954341598, 6288.308286088435, 6702.028205910018, 6742.292059333798, 6753.189617245864, 5644.379118297066, 6416.839579010562, 6517.576357536745, 6544.4764418845425, 6546.345365463416, 5088.720120393414, 6435.51733619971, 6459.903101453576, 6475.579087359307, 6486.189226346707, 6252.468540174136, 6375.489960803032, 6448.935510645021, 6636.63783632636, 6660.533762636569, 4149.792510374409, 4500.842470513517, 4711.481737008858, 4737.465455981033, 4745.9050818984115, 5469.0, 5941.613795103304, 6234.438900498977, 6374.3306952771145, 6528.2492513359675, 4772.880677983078, 5204.8178313758835, 5503.449655034625, 6122.5408094392205, 6261.74953001893, 5489.65503451641, 6094.451499365078, 6282.748690052346, 6331.746730130743, 6344.429001389897, 4958.206686930074, 5388.919166212589, 5929.169916211647, 6065.909011364744, 6199.845003874939, 5337.439568845215, 6341.714622841891, 6354.872902541831, 6361.109444677656, 6399.8080057598345, 5900.822975310747, 6222.377762223741, 6237.594556353823, 6247.031710084944, 6272.0], "value": 4627.4692721369665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.2471380336843, 719.6509692798992, 719.858421196939, 729.7336472187651, 730.4193166432685, 14811.851881481181, 15166.0, 15459.0, 15475.0, 15481.148536830484, 14735.410583576671, 14796.778048329285, 14855.702885942275, 14861.182634955074, 15058.774118388206, 654.8853950558657, 677.3395938959525, 907.2243232036597, 908.0692290402325, 928.7353104365224, 780.4380845791021, 941.8022215334788, 942.3450701762278, 942.6842007927353, 942.7171848445473, 985.3939827006375, 986.659602437158, 988.1501908358806, 988.6292640259902, 989.3717489394251, 979.5934687104843, 980.4901451245313, 981.7349315684761, 982.5431174503818, 983.9409635421825, 726.0923845193508, 727.2109760909419, 728.2208037399984, 728.876091064515, 729.2816575672958, 15537.533873983799, 15575.688486230212, 15798.68402631941, 16157.919210403872, 16174.838251617452, 14028.158310501403, 14405.351759170862, 14541.678260523926, 14630.637423912527, 14659.638869635064, 11517.424128793611, 11612.76774464506, 11757.941210293893, 11944.64166075019, 12112.6366209013, 2580.9483810323686, 2908.8254704717574, 2938.413594636101, 2968.717971792676, 2985.2387641151367, 94966.5251673744, 97857.0, 98370.0, 98406.50796745971, 101386.49306753493, 9187.265018798615, 9678.661246856327, 9823.361481503822, 10348.896511034793, 10443.0, 19516.0, 19546.70679939802, 19566.902165489082, 19569.608607827762, 19572.804271957382, 7362.4066702316195, 7468.738594149178, 7552.244775522514, 7603.125640551297, 7695.299643957847, 3649.6350364963823, 3675.3568125578454, 3719.516462859865, 3768.566614839274, 3836.290286297044, 3877.495925529663, 3888.766673999569, 3941.3299739044432, 3968.503937007874, 3969.80150992455, 747.2863415438352, 749.2245525880687, 756.8599809035346, 762.1996903251563, 772.7218201447474, 535.7241020874253, 545.5444703672399, 569.177538456934, 578.3262499188398, 593.4214141212369, 8555.358348123791, 9238.260939124853, 9859.852102218472, 9893.0, 9942.602295908082, 525.3931708876254, 559.4880684173935, 564.2298262871151, 576.7087620751578, 577.3540094304454, 4355.9981204323185, 4438.334249862482, 4663.230566956414, 4724.787384567703, 4824.469308376076, 94361.05638943522, 94581.5270923641, 94731.52634236922, 96240.55639165419, 96545.0, 80889.19110808817, 82816.0, 83824.0, 84966.0, 85104.57447712845, 71786.56426871537, 71875.0, 73882.26117738754, 74078.14804629928, 74569.6271518639, 8793.428427152214, 9146.634134634805, 9158.129977652112, 9168.745095491271, 9207.355485116246, 37429.81285093666, 38142.618573815016, 38213.0, 38442.615573845025, 39054.0, 62798.686006569675, 62912.68543657436, 64140.679296603215, 67970.18342890433, 68496.6575167141, 13440.060682882131, 14007.649808754863, 14154.0, 14377.856221437652, 14431.134131952116, 14732.631684207981, 14779.0, 14819.407223711361, 14939.626509336918, 15334.388406143114, 13393.799093013418, 13613.455461781417, 13892.996738616035, 13948.232847193502, 13980.510682126076, 13000.284984325954, 13230.801537976746, 13518.864811351761, 13820.378082986088, 13951.953603479982, 9316.494129749619, 12886.355682215664, 13095.41070651842, 13191.472341106248, 13801.240931748851, 10807.783844322912, 10868.891311086787, 10895.836562451412, 10996.835047474135, 11768.70578235551, 7954.880676789969, 8070.233327834081, 8374.036985746718, 10664.253502255078, 10857.891421085687], "value": 7256.61944945645, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 123.0, 164.0, 144.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 146.0, 148.0, 154.0, 114.0, 111.0, 113.0, 113.0, 133.0, 113.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 183.0, 188.0, 186.0, 185.0, 185.0, 184.0, 185.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 63.0, 60.0, 89.0, 60.0, 60.0, 61.0, 59.0, 75.0, 60.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 70.0, 69.0, 69.0, 82.0, 68.0, 69.0, 85.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 91.0, 92.0, 91.0, 92.0, 92.0, 91.0, 92.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 98.0, 97.0, 97.0, 111.0, 129.0, 148.0, 97.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 86.0, 84.0, 85.0, 85.0, 87.0, 86.0, 88.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 60.0, 64.0, 62.0, 62.0, 65.0, 63.0, 61.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 62.0, 64.0, 62.0, 59.0, 56.0, 57.0, 58.0, 58.0, 57.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 82.0, 83.0, 83.0, 83.0, 82.0, 81.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 140.0, 145.0, 146.0, 147.0, 142.0, 136.0, 186.0, 136.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 183.0, 186.0, 188.0, 186.0, 188.0, 187.0, 186.0, 186.0, 212.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 67.3, "unit": "score"}]}]} 17:41:57 INFO - Return code: 0 17:41:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:41:57 ERROR - # TBPL SUCCESS # 17:41:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:41:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:41:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:41:57 INFO - 2017-03-21 17:41:57.779 screenresolution[1659:9861] starting screenresolution argv=screenresolution get 17:41:57 INFO - 2017-03-21 17:41:57.782 screenresolution[1659:9861] Display 0: 1600x1200x32@60 17:41:57 INFO - 2017-03-21 17:41:57.787 screenresolution[1660:9864] starting screenresolution argv=screenresolution list 17:41:57 INFO - Available Modes on Display 0 17:41:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:41:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:41:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:41:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:41:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:41:58 INFO - Intel Iris: 17:41:58 INFO - Chipset Model: Intel Iris 17:41:58 INFO - Type: GPU 17:41:58 INFO - Bus: Built-In 17:41:58 INFO - VRAM (Dynamic, Max): 1536 MB 17:41:58 INFO - Vendor: Intel (0x8086) 17:41:58 INFO - Device ID: 0x0a2e 17:41:58 INFO - Revision ID: 0x0009 17:41:58 INFO - Displays: 17:41:58 INFO - Display: 17:41:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:41:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:41:58 INFO - Display Serial Number: 1600x1200 60 17:41:58 INFO - Main Display: Yes 17:41:58 INFO - Mirror: Off 17:41:58 INFO - Online: Yes 17:41:58 INFO - Rotation: Supported 17:41:58 INFO - Return code: 0 17:41:58 INFO - Running post-action listener: _resource_record_post_action 17:41:58 INFO - [mozharness: 2017-03-22 00:41:58.022184Z] Finished run-tests step (success) 17:41:58 INFO - Running post-run listener: _resource_record_post_run 17:41:58 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 36668416; Write bytes: 513571840; Read time: 450; Write time: 1797 17:41:58 INFO - TinderboxPrint: CPU usage
53.3% 17:41:58 INFO - TinderboxPrint: I/O read bytes / time
36,668,416 / 450 17:41:58 INFO - TinderboxPrint: I/O write bytes / time
513,571,840 / 1,797 17:41:58 INFO - TinderboxPrint: CPU idle
2,254.2 (46.7%) 17:41:58 INFO - TinderboxPrint: CPU system
963.0 (20.0%) 17:41:58 INFO - TinderboxPrint: CPU user
1,608.3 (33.3%) 17:41:58 INFO - TinderboxPrint: Swap in / out
246,714,368 / 0 17:41:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 209357312; Write bytes: 205256704; Read time: 17085; Write time: 474 17:41:58 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 30407680; Write bytes: 298374144; Read time: 366; Write time: 1288 17:41:58 INFO - Running post-run listener: _upload_blobber_files 17:41:58 INFO - Blob upload gear active. 17:41:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:41:58 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 17:41:58 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'] 17:41:58 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 17:41:58 INFO - (blobuploader) - INFO - Open directory for files ... 17:41:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:41:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:59 INFO - (blobuploader) - INFO - Done attempting. 17:41:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:41:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:42:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:42:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:42:00 INFO - (blobuploader) - INFO - Done attempting. 17:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:42:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:42:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:42:02 INFO - (blobuploader) - INFO - Done attempting. 17:42:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:42:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:42:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:42:04 INFO - (blobuploader) - INFO - Done attempting. 17:42:04 INFO - (blobuploader) - INFO - Iteration through files over. 17:42:04 INFO - Return code: 0 17:42:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:42:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:42:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e41dea2ab1f1b026226be54422924927c4c0cb91c75f47d3eadabfdbf43616a6a735d058c6e2f60f819722284e8c7acae719029300c984e589c7c9d1bafd454", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc613feab857550fe95c7272644850bfce04439aa65e92d9bc693dcba64c4be4d7f68911bd764dfb5868f5ca9c8135b2ad36678defdd943945dc8b95519578e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea177594bcef5f74066fe9812d1d139df7d4d00c461b4ee8d73dbd83c244f6236104f2506a0b063c87403217e63b6af4bf8ca78d2f7caf195e680bacccb64113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5d7f83d95a8d7a073065de101488b7ea897e6fda862834c54903570dfffd222804a86f0379d7d420bc319692acf6c144834e1c2ce2e573a14314b9fccd6cc8"} 17:42:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:42:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:42:04 INFO - Contents: 17:42:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e41dea2ab1f1b026226be54422924927c4c0cb91c75f47d3eadabfdbf43616a6a735d058c6e2f60f819722284e8c7acae719029300c984e589c7c9d1bafd454", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc613feab857550fe95c7272644850bfce04439aa65e92d9bc693dcba64c4be4d7f68911bd764dfb5868f5ca9c8135b2ad36678defdd943945dc8b95519578e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea177594bcef5f74066fe9812d1d139df7d4d00c461b4ee8d73dbd83c244f6236104f2506a0b063c87403217e63b6af4bf8ca78d2f7caf195e680bacccb64113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5d7f83d95a8d7a073065de101488b7ea897e6fda862834c54903570dfffd222804a86f0379d7d420bc319692acf6c144834e1c2ce2e573a14314b9fccd6cc8"} 17:42:04 INFO - Running post-run listener: copy_logs_to_upload_dir 17:42:04 INFO - Copying logs to upload dir... 17:42:04 INFO - mkdir: /builds/slave/test/build/upload/logs 17:42:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.135025 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-21 17:42:04.538616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 17:42:04.542536) ========= 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.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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/4e41dea2ab1f1b026226be54422924927c4c0cb91c75f47d3eadabfdbf43616a6a735d058c6e2f60f819722284e8c7acae719029300c984e589c7c9d1bafd454", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc613feab857550fe95c7272644850bfce04439aa65e92d9bc693dcba64c4be4d7f68911bd764dfb5868f5ca9c8135b2ad36678defdd943945dc8b95519578e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea177594bcef5f74066fe9812d1d139df7d4d00c461b4ee8d73dbd83c244f6236104f2506a0b063c87403217e63b6af4bf8ca78d2f7caf195e680bacccb64113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5d7f83d95a8d7a073065de101488b7ea897e6fda862834c54903570dfffd222804a86f0379d7d420bc319692acf6c144834e1c2ce2e573a14314b9fccd6cc8"} build_url:https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010773 build_url: 'https://queue.taskcluster.net/v1/task/bzwaR3AHTROGsrH592UvYA/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/4e41dea2ab1f1b026226be54422924927c4c0cb91c75f47d3eadabfdbf43616a6a735d058c6e2f60f819722284e8c7acae719029300c984e589c7c9d1bafd454", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc613feab857550fe95c7272644850bfce04439aa65e92d9bc693dcba64c4be4d7f68911bd764dfb5868f5ca9c8135b2ad36678defdd943945dc8b95519578e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea177594bcef5f74066fe9812d1d139df7d4d00c461b4ee8d73dbd83c244f6236104f2506a0b063c87403217e63b6af4bf8ca78d2f7caf195e680bacccb64113", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5d7f83d95a8d7a073065de101488b7ea897e6fda862834c54903570dfffd222804a86f0379d7d420bc319692acf6c144834e1c2ce2e573a14314b9fccd6cc8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 17:42:04.576548) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:42:04.576848) ========= 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.YEKiUeD65B/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.EKpMvjX8zp/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.004965 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 17:42:04.624627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 17:42:04.624915) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 17:42:04.625496) ========= ========= Total master_lag: 0.17 =========