builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1489579604.39 results: success (0) buildid: 20170315030215 builduid: e841dc20f8bf4e2099b5c83ed8923d13 revision: 8dd496fd015a2b6e99573070279d9d1593836ea9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.393768) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.394223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.394506) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.422617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.423040) ========= 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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-15 05:06:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.8M=0s 2017-03-15 05:06:44 (28.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.590833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.591162) ========= 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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.037678 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:44.650811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-15 05:06:44.651174) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8dd496fd015a2b6e99573070279d9d1593836ea9 --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 8dd496fd015a2b6e99573070279d9d1593836ea9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-15 05:06:44,749 truncating revision to first 12 chars 2017-03-15 05:06:44,749 Setting DEBUG logging. 2017-03-15 05:06:44,749 attempt 1/10 2017-03-15 05:06:44,749 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8dd496fd015a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-15 05:06:46,917 unpacking tar archive at: mozilla-central-8dd496fd015a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.445814 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-15 05:06:47.120460) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:47.120814) ========= script_repo_revision: 8dd496fd015a2b6e99573070279d9d1593836ea9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:47.121216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:47.121484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 05:06:47.141502) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-15 05:06:47.141928) ========= /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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:06:47 INFO - MultiFileLogger online at 20170315 05:06:47 in /builds/slave/test 05:06:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:06:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:47 INFO - {'append_to_log': False, 05:06:47 INFO - 'base_work_dir': '/builds/slave/test', 05:06:47 INFO - 'blob_upload_branch': 'Firefox', 05:06:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:47 INFO - 'branch': 'Firefox', 05:06:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:47 INFO - 'config_files': ('talos/mac_config.py',), 05:06:47 INFO - 'default_actions': ('clobber', 05:06:47 INFO - 'read-buildbot-config', 05:06:47 INFO - 'download-and-extract', 05:06:47 INFO - 'populate-webroot', 05:06:47 INFO - 'create-virtualenv', 05:06:47 INFO - 'install', 05:06:47 INFO - 'run-tests'), 05:06:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:47 INFO - 'download_minidump_stackwalk': True, 05:06:47 INFO - 'download_symbols': 'ondemand', 05:06:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:47 INFO - 'gecko_profile': False, 05:06:47 INFO - 'gecko_profile_interval': 0, 05:06:47 INFO - 'installer_path': 'installer.exe', 05:06:47 INFO - 'log_level': 'info', 05:06:47 INFO - 'log_name': 'talos', 05:06:47 INFO - 'log_to_console': True, 05:06:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:06:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:06:47 INFO - 'opt_config_files': (), 05:06:47 INFO - 'pip_index': False, 05:06:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:47 INFO - 'cmd': ('bash', 05:06:47 INFO - '-c', 05:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:47 INFO - 'enabled': True, 05:06:47 INFO - 'halt_on_failure': False, 05:06:47 INFO - 'name': 'check_screen_resolution'},), 05:06:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:47 INFO - 'cmd': ('bash', 05:06:47 INFO - '-c', 05:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:47 INFO - 'enabled': True, 05:06:47 INFO - 'halt_on_failure': False, 05:06:47 INFO - 'name': 'check_screen_resolution'},), 05:06:47 INFO - 'run_cmd_checks_enabled': True, 05:06:47 INFO - 'suite': 'dromaeojs', 05:06:47 INFO - 'system_bits': '32', 05:06:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:47 INFO - 'title': 't-yosemite-r7-0181', 05:06:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:47 INFO - 'use_talos_json': True, 05:06:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:47 INFO - 'work_dir': 'build'} 05:06:47 INFO - [mozharness: 2017-03-15 12:06:47.348226Z] Running clobber step. 05:06:47 INFO - Running pre-action listener: _resource_record_pre_action 05:06:47 INFO - Running main action method: clobber 05:06:47 INFO - rmtree: /builds/slave/test/build 05:06:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:49 INFO - Running post-action listener: _resource_record_post_action 05:06:49 INFO - [mozharness: 2017-03-15 12:06:49.732689Z] Finished clobber step (success) 05:06:49 INFO - [mozharness: 2017-03-15 12:06:49.732827Z] Running read-buildbot-config step. 05:06:49 INFO - Running pre-action listener: _resource_record_pre_action 05:06:49 INFO - Running main action method: read_buildbot_config 05:06:49 INFO - Using buildbot properties: 05:06:49 INFO - { 05:06:49 INFO - "project": "", 05:06:49 INFO - "product": "firefox", 05:06:49 INFO - "script_repo_revision": "production", 05:06:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:06:49 INFO - "repository": "", 05:06:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:06:49 INFO - "buildid": "20170315030215", 05:06:49 INFO - "stage_platform": "macosx64", 05:06:49 INFO - "basedir": "/builds/slave/test", 05:06:49 INFO - "nightly_build": "True", 05:06:49 INFO - "buildnumber": 43, 05:06:49 INFO - "slavename": "t-yosemite-r7-0181", 05:06:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:06:49 INFO - "platform": "yosemite_r7", 05:06:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:06:49 INFO - "branch": "mozilla-central", 05:06:49 INFO - "repo_path": "mozilla-central", 05:06:49 INFO - "revision": "8dd496fd015a2b6e99573070279d9d1593836ea9", 05:06:49 INFO - "pgo_build": "False", 05:06:49 INFO - "builduid": "e841dc20f8bf4e2099b5c83ed8923d13", 05:06:49 INFO - "slavebuilddir": "test" 05:06:49 INFO - } 05:06:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:06:49 INFO - Running post-action listener: _resource_record_post_action 05:06:49 INFO - [mozharness: 2017-03-15 12:06:49.736399Z] Finished read-buildbot-config step (success) 05:06:49 INFO - [mozharness: 2017-03-15 12:06:49.736532Z] Running download-and-extract step. 05:06:49 INFO - Running pre-action listener: _resource_record_pre_action 05:06:49 INFO - Running main action method: download_and_extract 05:06:49 INFO - mkdir: /builds/slave/test/build/tests 05:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:49 INFO - trying https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:06:49 INFO - Downloading https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:06:51 INFO - Downloaded 1199 bytes. 05:06:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:06:51 INFO - Using the following test package requirements: 05:06:51 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:06:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:06:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:06:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'jsshell-mac.zip'], 05:06:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:06:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:06:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:06:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:06:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:06:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:06:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:06:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:06:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:06:51 INFO - Fetch https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:06:52 INFO - Expected file size: 52470470 05:06:52 INFO - Obtained file size: 52470470 05:07:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:07:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:07:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:07:08 INFO - Fetch https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:07:10 INFO - Expected file size: 52470470 05:07:10 INFO - Obtained file size: 52470470 05:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:07:26 INFO - Expected file size: 14404452 05:07:26 INFO - Obtained file size: 14404452 05:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:26 INFO - trying https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:07:28 INFO - Downloaded 75159545 bytes. 05:07:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:28 INFO - mkdir: /builds/slave/test/properties 05:07:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:07:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:07:28 INFO - Contents: 05:07:28 INFO - build_url:https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:29 INFO - Running post-action listener: _resource_record_post_action 05:07:29 INFO - Running post-action listener: set_extra_try_arguments 05:07:29 INFO - [mozharness: 2017-03-15 12:07:29.483401Z] Finished download-and-extract step (success) 05:07:29 INFO - [mozharness: 2017-03-15 12:07:29.483553Z] Running populate-webroot step. 05:07:29 INFO - Running pre-action listener: _resource_record_pre_action 05:07:29 INFO - Running main action method: populate_webroot 05:07:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:07:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:07:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:07:29 INFO - '--tppagecycles', 05:07:29 INFO - '7'], 05:07:29 INFO - 'tests': ['tsvgm']}}, 05:07:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:07:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:07:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:07:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:07:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['damp', 'tps']}, 05:07:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'tests': ['damp', 'tps']}, 05:07:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['dromaeo_dom']}, 05:07:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:07:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['basic_compositor_video']}, 05:07:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:07:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['a11yr', 05:07:29 INFO - 'ts_paint', 05:07:29 INFO - 'tpaint', 05:07:29 INFO - 'sessionrestore', 05:07:29 INFO - 'sessionrestore_no_auto_restore', 05:07:29 INFO - 'tabpaint']}, 05:07:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['tsvgx', 05:07:29 INFO - 'tsvgr_opacity', 05:07:29 INFO - 'tart', 05:07:29 INFO - 'tscrollx', 05:07:29 INFO - 'cart', 05:07:29 INFO - 'tsvg_static']}, 05:07:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:07:29 INFO - 'tsvgr_opacity', 05:07:29 INFO - 'tart', 05:07:29 INFO - 'tscrollx', 05:07:29 INFO - 'cart', 05:07:29 INFO - 'tsvg_static']}, 05:07:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'talos_options': ['--disable-e10s'], 05:07:29 INFO - 'tests': ['tp5o']}, 05:07:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:07:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'talos_options': ['--disable-e10s', 05:07:29 INFO - '--xperf_path', 05:07:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:29 INFO - 'tests': ['tp5n']}, 05:07:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:07:29 INFO - 'talos_options': ['--xperf_path', 05:07:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:29 INFO - 'tests': ['tp5n']}}, 05:07:29 INFO - 'talos.zip': {'path': '', 05:07:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:07:29 INFO - Running post-action listener: _resource_record_post_action 05:07:29 INFO - [mozharness: 2017-03-15 12:07:29.502229Z] Finished populate-webroot step (success) 05:07:29 INFO - [mozharness: 2017-03-15 12:07:29.502338Z] Running create-virtualenv step. 05:07:29 INFO - Running pre-action listener: _resource_record_pre_action 05:07:29 INFO - Running main action method: create_virtualenv 05:07:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:07:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:07:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:07:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:07:29 INFO - Using real prefix '/tools/python27' 05:07:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:07:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:07:33 INFO - Installing pip.................done. 05:07:33 INFO - Return code: 0 05:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:07:33 INFO - Reading from file tmpfile_stdout 05:07:33 INFO - Output received: 05:07:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:07:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:33 INFO - 'HOME': '/Users/cltbld', 05:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:33 INFO - 'LOGNAME': 'cltbld', 05:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:33 INFO - 'MOZ_NO_REMOTE': '1', 05:07:33 INFO - 'NO_EM_RESTART': '1', 05:07:33 INFO - 'PAGER': '/bin/cat', 05:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:33 INFO - 'PWD': '/builds/slave/test', 05:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:33 INFO - 'SHELL': '/bin/bash', 05:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:33 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:33 INFO - 'USER': 'cltbld', 05:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:33 INFO - 'XPC_FLAGS': '0x0', 05:07:33 INFO - 'XPC_SERVICE_NAME': '0', 05:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:07:33 INFO - Cleaning up... 05:07:33 INFO - Return code: 0 05:07:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:33 INFO - 'HOME': '/Users/cltbld', 05:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:33 INFO - 'LOGNAME': 'cltbld', 05:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:33 INFO - 'MOZ_NO_REMOTE': '1', 05:07:33 INFO - 'NO_EM_RESTART': '1', 05:07:33 INFO - 'PAGER': '/bin/cat', 05:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:33 INFO - 'PWD': '/builds/slave/test', 05:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:33 INFO - 'SHELL': '/bin/bash', 05:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:33 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:33 INFO - 'USER': 'cltbld', 05:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:33 INFO - 'XPC_FLAGS': '0x0', 05:07:33 INFO - 'XPC_SERVICE_NAME': '0', 05:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:34 INFO - Downloading/unpacking psutil>=3.1.1 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:07:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:37 INFO - Installing collected packages: psutil 05:07:37 INFO - Running setup.py install for psutil 05:07:37 INFO - building 'psutil._psutil_osx' extension 05:07:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:07:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:07:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:07:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:07:38 INFO - building 'psutil._psutil_posix' extension 05:07:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:07:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:07:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:07:38 INFO - ^ 05:07:38 INFO - 1 warning generated. 05:07:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:07:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:38 INFO - Successfully installed psutil 05:07:38 INFO - Cleaning up... 05:07:38 INFO - Return code: 0 05:07:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:38 INFO - 'HOME': '/Users/cltbld', 05:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:38 INFO - 'LOGNAME': 'cltbld', 05:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:38 INFO - 'MOZ_NO_REMOTE': '1', 05:07:38 INFO - 'NO_EM_RESTART': '1', 05:07:38 INFO - 'PAGER': '/bin/cat', 05:07:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:38 INFO - 'PWD': '/builds/slave/test', 05:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:38 INFO - 'SHELL': '/bin/bash', 05:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:38 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:38 INFO - 'USER': 'cltbld', 05:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:38 INFO - 'XPC_FLAGS': '0x0', 05:07:38 INFO - 'XPC_SERVICE_NAME': '0', 05:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:07:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:07:41 INFO - Installing collected packages: mozsystemmonitor 05:07:41 INFO - Running setup.py install for mozsystemmonitor 05:07:41 INFO - Successfully installed mozsystemmonitor 05:07:41 INFO - Cleaning up... 05:07:41 INFO - Return code: 0 05:07:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:42 INFO - 'HOME': '/Users/cltbld', 05:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:42 INFO - 'LOGNAME': 'cltbld', 05:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:42 INFO - 'MOZ_NO_REMOTE': '1', 05:07:42 INFO - 'NO_EM_RESTART': '1', 05:07:42 INFO - 'PAGER': '/bin/cat', 05:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:42 INFO - 'PWD': '/builds/slave/test', 05:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:42 INFO - 'SHELL': '/bin/bash', 05:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:42 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:42 INFO - 'USER': 'cltbld', 05:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:42 INFO - 'XPC_FLAGS': '0x0', 05:07:42 INFO - 'XPC_SERVICE_NAME': '0', 05:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:42 INFO - Downloading/unpacking jsonschema==2.5.1 05:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:07:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:46 INFO - Downloading functools32-3.2.3-2.tar.gz 05:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:07:46 INFO - warning: no files found matching '*.txt' 05:07:46 INFO - no previously-included directories found matching 'build' 05:07:46 INFO - no previously-included directories found matching 'dist' 05:07:46 INFO - no previously-included directories found matching '.git*' 05:07:46 INFO - Installing collected packages: jsonschema, functools32 05:07:46 INFO - Running setup.py install for functools32 05:07:46 INFO - warning: no files found matching '*.txt' 05:07:46 INFO - no previously-included directories found matching 'build' 05:07:46 INFO - no previously-included directories found matching 'dist' 05:07:46 INFO - no previously-included directories found matching '.git*' 05:07:46 INFO - Successfully installed jsonschema functools32 05:07:46 INFO - Cleaning up... 05:07:46 INFO - Return code: 0 05:07:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:46 INFO - 'HOME': '/Users/cltbld', 05:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:46 INFO - 'LOGNAME': 'cltbld', 05:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:46 INFO - 'MOZ_NO_REMOTE': '1', 05:07:46 INFO - 'NO_EM_RESTART': '1', 05:07:46 INFO - 'PAGER': '/bin/cat', 05:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:46 INFO - 'PWD': '/builds/slave/test', 05:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:46 INFO - 'SHELL': '/bin/bash', 05:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:46 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:46 INFO - 'USER': 'cltbld', 05:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:46 INFO - 'XPC_FLAGS': '0x0', 05:07:46 INFO - 'XPC_SERVICE_NAME': '0', 05:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:07:46 INFO - Cleaning up... 05:07:46 INFO - Return code: 0 05:07:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:46 INFO - 'HOME': '/Users/cltbld', 05:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:46 INFO - 'LOGNAME': 'cltbld', 05:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:46 INFO - 'MOZ_NO_REMOTE': '1', 05:07:46 INFO - 'NO_EM_RESTART': '1', 05:07:46 INFO - 'PAGER': '/bin/cat', 05:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:46 INFO - 'PWD': '/builds/slave/test', 05:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:46 INFO - 'SHELL': '/bin/bash', 05:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:46 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:46 INFO - 'USER': 'cltbld', 05:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:46 INFO - 'XPC_FLAGS': '0x0', 05:07:46 INFO - 'XPC_SERVICE_NAME': '0', 05:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:47 INFO - Downloading/unpacking blobuploader==1.2.4 05:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:49 INFO - Downloading blobuploader-1.2.4.tar.gz 05:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:07:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:07:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:50 INFO - Downloading docopt-0.6.1.tar.gz 05:07:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:07:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:07:51 INFO - Running setup.py install for blobuploader 05:07:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:07:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:07:51 INFO - Running setup.py install for requests 05:07:51 INFO - Running setup.py install for docopt 05:07:51 INFO - Successfully installed blobuploader requests docopt 05:07:51 INFO - Cleaning up... 05:07:51 INFO - Return code: 0 05:07:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:51 INFO - 'HOME': '/Users/cltbld', 05:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:51 INFO - 'LOGNAME': 'cltbld', 05:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:51 INFO - 'MOZ_NO_REMOTE': '1', 05:07:51 INFO - 'NO_EM_RESTART': '1', 05:07:51 INFO - 'PAGER': '/bin/cat', 05:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:51 INFO - 'PWD': '/builds/slave/test', 05:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:51 INFO - 'SHELL': '/bin/bash', 05:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:51 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:51 INFO - 'USER': 'cltbld', 05:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:51 INFO - 'XPC_FLAGS': '0x0', 05:07:51 INFO - 'XPC_SERVICE_NAME': '0', 05:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-seb83n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-pkujWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GDTuZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JyxZNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZnjyqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-wr93M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:52 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jzmZL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-qLCAoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5e8ISg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-T8onRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-7RInBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-V4eEOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1754Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-v0SrVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_6v75q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SSGkmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:53 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-OxXHlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:07:54 INFO - Running setup.py install for manifestparser 05:07:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:07:54 INFO - Running setup.py install for mozcrash 05:07:54 INFO - Running setup.py install for mozdebug 05:07:54 INFO - Running setup.py install for mozdevice 05:07:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:07:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:07:54 INFO - Running setup.py install for mozfile 05:07:54 INFO - Running setup.py install for mozhttpd 05:07:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:07:54 INFO - Running setup.py install for mozinfo 05:07:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Running setup.py install for mozInstall 05:07:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Running setup.py install for mozleak 05:07:55 INFO - Running setup.py install for mozlog 05:07:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Running setup.py install for moznetwork 05:07:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:07:55 INFO - Running setup.py install for mozprocess 05:07:55 INFO - Running setup.py install for mozprofile 05:07:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:07:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:07:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:07:56 INFO - Running setup.py install for mozrunner 05:07:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:07:56 INFO - Running setup.py install for mozscreenshot 05:07:56 INFO - Running setup.py install for moztest 05:07:56 INFO - Running setup.py install for mozversion 05:07:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:07:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:07:56 INFO - Cleaning up... 05:07:56 INFO - Return code: 0 05:07:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:07:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:07:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:07:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:56 INFO - 'HOME': '/Users/cltbld', 05:07:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:56 INFO - 'LOGNAME': 'cltbld', 05:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:56 INFO - 'MOZ_NO_REMOTE': '1', 05:07:56 INFO - 'NO_EM_RESTART': '1', 05:07:56 INFO - 'PAGER': '/bin/cat', 05:07:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:56 INFO - 'PWD': '/builds/slave/test', 05:07:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:56 INFO - 'SHELL': '/bin/bash', 05:07:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:07:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:07:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:56 INFO - 'USER': 'cltbld', 05:07:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:56 INFO - 'XPC_FLAGS': '0x0', 05:07:56 INFO - 'XPC_SERVICE_NAME': '0', 05:07:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:56 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uYWFgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Ekp8Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-czbpE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Daf6TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Fk7zLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UcRvAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-8XHEqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-TUfZjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-pRMT9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:57 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-pEH6ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-W2n7jO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HA3Nta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mXtjFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WSnoDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JBii27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WzNZ5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:58 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SDGPXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:01 INFO - Downloading blessings-1.6.tar.gz 05:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:08:01 INFO - Installing collected packages: blessings 05:08:01 INFO - Running setup.py install for blessings 05:08:01 INFO - Successfully installed blessings 05:08:01 INFO - Cleaning up... 05:08:01 INFO - Return code: 0 05:08:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:01 INFO - Reading from file tmpfile_stdout 05:08:01 INFO - Current package versions: 05:08:01 INFO - blessings == 1.6 05:08:01 INFO - blobuploader == 1.2.4 05:08:01 INFO - docopt == 0.6.1 05:08:01 INFO - functools32 == 3.2.3-2 05:08:01 INFO - jsonschema == 2.5.1 05:08:01 INFO - manifestparser == 1.1 05:08:01 INFO - mozInstall == 1.12 05:08:01 INFO - mozcrash == 1.0 05:08:01 INFO - mozdebug == 0.1 05:08:01 INFO - mozdevice == 0.49 05:08:01 INFO - mozfile == 1.2 05:08:01 INFO - mozhttpd == 0.7 05:08:01 INFO - mozinfo == 0.9 05:08:01 INFO - mozleak == 0.1 05:08:01 INFO - mozlog == 3.4 05:08:01 INFO - moznetwork == 0.27 05:08:01 INFO - mozprocess == 0.25 05:08:01 INFO - mozprofile == 0.28 05:08:01 INFO - mozrunner == 6.13 05:08:01 INFO - mozscreenshot == 0.1 05:08:01 INFO - mozsystemmonitor == 0.3 05:08:01 INFO - moztest == 0.8 05:08:01 INFO - mozversion == 1.4 05:08:01 INFO - psutil == 3.1.1 05:08:01 INFO - requests == 1.2.3 05:08:01 INFO - wsgiref == 0.1.2 05:08:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:01 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 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:08:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:01 INFO - 'HOME': '/Users/cltbld', 05:08:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:01 INFO - 'LOGNAME': 'cltbld', 05:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:01 INFO - 'MOZ_NO_REMOTE': '1', 05:08:01 INFO - 'NO_EM_RESTART': '1', 05:08:01 INFO - 'PAGER': '/bin/cat', 05:08:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:01 INFO - 'PWD': '/builds/slave/test', 05:08:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:01 INFO - 'SHELL': '/bin/bash', 05:08:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:08:01 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:08:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:01 INFO - 'USER': 'cltbld', 05:08:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:01 INFO - 'XPC_FLAGS': '0x0', 05:08:01 INFO - 'XPC_SERVICE_NAME': '0', 05:08:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:02 INFO - Cleaning up... 05:08:02 INFO - Return code: 0 05:08:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102459b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f084a67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, '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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:08:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:08:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:02 INFO - 'HOME': '/Users/cltbld', 05:08:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:02 INFO - 'LOGNAME': 'cltbld', 05:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:02 INFO - 'MOZ_NO_REMOTE': '1', 05:08:02 INFO - 'NO_EM_RESTART': '1', 05:08:02 INFO - 'PAGER': '/bin/cat', 05:08:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:02 INFO - 'PWD': '/builds/slave/test', 05:08:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:02 INFO - 'SHELL': '/bin/bash', 05:08:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:08:02 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:08:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:02 INFO - 'USER': 'cltbld', 05:08:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:02 INFO - 'XPC_FLAGS': '0x0', 05:08:02 INFO - 'XPC_SERVICE_NAME': '0', 05:08:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:08:02 INFO - Cleaning up... 05:08:02 INFO - Return code: 0 05:08:02 INFO - Running post-action listener: _resource_record_post_action 05:08:02 INFO - Running post-action listener: _start_resource_monitoring 05:08:02 INFO - Starting resource monitoring. 05:08:02 INFO - [mozharness: 2017-03-15 12:08:02.542177Z] Finished create-virtualenv step (success) 05:08:02 INFO - [mozharness: 2017-03-15 12:08:02.543136Z] Running install step. 05:08:02 INFO - Running pre-action listener: _resource_record_pre_action 05:08:02 INFO - Running main action method: install 05:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:02 INFO - Reading from file tmpfile_stdout 05:08:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:08:02 INFO - Reading from file tmpfile_stdout 05:08:02 INFO - Output received: 05:08:02 INFO - Usage: mozinstall [options] installer 05:08:02 INFO - Options: 05:08:02 INFO - -h, --help show this help message and exit 05:08:02 INFO - -d DEST, --destination=DEST 05:08:02 INFO - Directory to install application into. [default: 05:08:02 INFO - "/builds/slave/test"] 05:08:02 INFO - --app=APP Application being installed. [default: firefox] 05:08:02 INFO - mkdir: /builds/slave/test/build/application 05:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:08:23 INFO - Reading from file tmpfile_stdout 05:08:23 INFO - Output received: 05:08:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:08:23 INFO - Running post-action listener: _resource_record_post_action 05:08:23 INFO - [mozharness: 2017-03-15 12:08:23.419929Z] Finished install step (success) 05:08:23 INFO - [mozharness: 2017-03-15 12:08:23.420064Z] Running run-tests step. 05:08:23 INFO - Running pre-action listener: _resource_record_pre_action 05:08:23 INFO - Running main action method: run_tests 05:08:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:23 INFO - 2017-03-15 05:08:23.434 screenresolution[1636:7455] starting screenresolution argv=screenresolution get 05:08:23 INFO - 2017-03-15 05:08:23.437 screenresolution[1636:7455] Display 0: 1600x1200x32@60 05:08:23 INFO - 2017-03-15 05:08:23.443 screenresolution[1637:7458] starting screenresolution argv=screenresolution list 05:08:23 INFO - Available Modes on Display 0 05:08:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:23 INFO - Intel Iris: 05:08:23 INFO - Chipset Model: Intel Iris 05:08:23 INFO - Type: GPU 05:08:23 INFO - Bus: Built-In 05:08:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:23 INFO - Vendor: Intel (0x8086) 05:08:23 INFO - Device ID: 0x0a2e 05:08:23 INFO - Revision ID: 0x0009 05:08:23 INFO - Displays: 05:08:23 INFO - Display: 05:08:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:23 INFO - Display Serial Number: 1600x1200 60 05:08:23 INFO - Main Display: Yes 05:08:23 INFO - Mirror: Off 05:08:23 INFO - Online: Yes 05:08:23 INFO - Rotation: Supported 05:08:23 INFO - Return code: 0 05:08:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:08:23 INFO - Python 2.7.3 05:08:23 INFO - Return code: 0 05:08:23 INFO - grabbing minidump binary from tooltool 05:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102237d30>, '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 0x10215edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10222cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:08:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:08:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:08:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:08:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:08:23 INFO - Return code: 0 05:08:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:08:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:08:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:08:23 INFO - ENV: RUST_BACKTRACE is now 1 05:08:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:08:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:08:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tW1S8fkiFK/Render', 05:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:23 INFO - 'HOME': '/Users/cltbld', 05:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:23 INFO - 'LOGNAME': 'cltbld', 05:08:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:08:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:23 INFO - 'MOZ_NO_REMOTE': '1', 05:08:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:08:23 INFO - 'NO_EM_RESTART': '1', 05:08:23 INFO - 'PAGER': '/bin/cat', 05:08:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:23 INFO - 'PWD': '/builds/slave/test', 05:08:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:23 INFO - 'RUST_BACKTRACE': '1', 05:08:23 INFO - 'SHELL': '/bin/bash', 05:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DedyqnJ7J/Listeners', 05:08:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 05:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:23 INFO - 'USER': 'cltbld', 05:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:23 INFO - 'XPC_FLAGS': '0x0', 05:08:23 INFO - 'XPC_SERVICE_NAME': '0', 05:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:08:24 INFO - mozversion application_buildid: 20170315030215 05:08:24 INFO - mozversion application_changeset: 8dd496fd015a2b6e99573070279d9d1593836ea9 05:08:24 INFO - mozversion application_display_name: Nightly 05:08:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:08:24 INFO - mozversion application_name: Firefox 05:08:24 INFO - mozversion application_remotingname: firefox 05:08:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:08:24 INFO - mozversion application_vendor: Mozilla 05:08:24 INFO - mozversion application_version: 55.0a1 05:08:24 INFO - mozversion platform_buildid: 20170315030215 05:08:24 INFO - mozversion platform_changeset: 8dd496fd015a2b6e99573070279d9d1593836ea9 05:08:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:08:24 INFO - mozversion platform_version: 55.0a1 05:08:24 INFO - using testdate: 1489579704 05:08:24 INFO - actual date: 1489579704 05:08:24 INFO - starting webserver on 'localhost:49240' 05:08:24 INFO - SUITE-START | Running 2 tests 05:08:24 INFO - TEST-START | dromaeo_css 05:08:24 INFO - operating with platform_type : mac_ 05:08:24 INFO - Initialising browser for dromaeo_css test... 05:08:24 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpN2PD12/profile http://localhost:49240/getInfo.html) 05:08:26 INFO - PID 1647 | __metrics Screen width/height:1600/1200 05:08:26 INFO - PID 1647 | colorDepth:24 05:08:26 INFO - PID 1647 | Browser inner width/height: 1024/689 05:08:26 INFO - PID 1647 | __metrics 05:08:27 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:08:27 INFO - PID 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:08:27 INFO - TEST-INFO | 1647: exit 0 05:08:27 INFO - Browser initialized. 05:08:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:08:27 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpN2PD12/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:40 INFO - PID 1650 | RSS: Main: 233316352 05:08:40 INFO - PID 1650 | 05:11:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 05:11:47 INFO - PID 1650 | RSS: Main: 574730240 05:11:47 INFO - PID 1650 | 05:13:29 INFO - PID 1650 | ************************* 05:13:29 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 05:13:29 INFO - PID 1650 | 05:13:29 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:13:29 INFO - PID 1650 | Full stack: 05:13:29 INFO - PID 1650 | ************************* 05:14:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 05:14:51 INFO - PID 1650 | RSS: Main: 369283072 05:14:51 INFO - PID 1650 | 05:17:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 05:17:54 INFO - PID 1650 | RSS: Main: 351154176 05:17:54 INFO - PID 1650 | 05:20:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 05:20:57 INFO - PID 1650 | RSS: Main: 338632704 05:20:57 INFO - PID 1650 | 05:24:01 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:24:01 INFO - PID 1650 | RSS: Main: 336134144 05:24:01 INFO - PID 1650 | 05:27:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:27:06 INFO - PID 1650 | RSS: Main: 382574592 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | __start_tp_report 05:27:06 INFO - PID 1650 | _x_x_mozilla_page_load 05:27:06 INFO - PID 1650 | _x_x_mozilla_page_load_details 05:27:06 INFO - PID 1650 | |i|pagename|runs| 05:27:06 INFO - PID 1650 | |0;dojo.html;2788.637477127974;3145.54389613506;3301.785383950043;3337.9499307510405;3374.5444365010717;5561.721913904299;5596.300462442195;5721.170430287611;5760.107183386572;5762.884742305157;5554.555635549156;5595.076812325961;5609.887802243953;5616.241807356006;5627.380988091317;5095.847124586259;5126.7436628168625;5146.922796158042;5183.818566350178;5199.558037566811;5191.5846732261325;5218.269442278084;5222.451642577532;5225.242339860718;5226.294450249225;5209.348831396075;5218.921716174261;5219.895602087956;5226.320578324812;5235.083860324447;5204.739763011872;5227.47725227478;5232.555232805216;5251.396089449686;5256.553192978601;5075.847724568269;5197.818076367309;5198.662086964335;5214.139666954948;5222.791088356461;5723.828285151452;5752.137179423078;5762.97118514409;5766.942330576682;5784.739686714108;6085.2393450818645;6097.349215877944;6175.938240617626;6180.752769889199;6184.87630247397;4604.562566556172;6135.3674730672865;6169;6175.62946223228;6199.070139479114;5886.8822623547285;5903.321118071391;5908.02517584598;5913.822585322448;5939.821805345803;6290.370962903765;6296.5277604178955;6306.211723534559;6318.273398559132;6322.873542529123;5981.3719559445635;6028.698565071817;6045.3652366501765;6108.816735497942;6164.753409863555;6195.2255968004;6240.157578726905;6247.468965137923;6249.812505624838;6263.592866463665;6158.261008678897;6202.41077097675;6204.286507051656;6218.31598524162;6221.502279817604;6072.271327440735;6100.450959413674;6147.44672979434;6234.719437625317;6249.156363890907;4891.217405215149;4970.080535101018;5026.748662566857;5042.546170844642;5044;4468.73187608738;4470.374147619336;4485.75328356937;4486.371907932892;4492.573205545468;5836.445537673914;5836.8540786479825;5841.503472204825;5862.824115276549;5877.118432235199;6155.384461553809;6206.627602343874;6216.564840461215;6217.533684973645;6226.346233645492;5689.431056894277;5712.285964254468;5739.827805165852;5743.2246646703;5747.568932330092;5379.381371142291;5410.107332290128;5412.566994640498;5413.648112872651;5415.729213539307;6263;6276.686165691788;6278.246610406689;6314.968425157845;6324.462420694293;6224.439800417984;6272.153259309935;6280.560360774702;6296.401841825019;6310.116583678197;6319.241690996926;6331.2085989251345;6338.90491642635;6347.619142851444;6356.904646430215;6241.251049873863;6249.468795152463;6250.156228909039;6254.749810007549;6256.874862502816;6142.9078563820685;6170.722317495633;6186.350433204449;6284.5600807943;6285.182926219562;5320.334958130234;5892.793752218652;6102.458955353496;6120.479759220516;6152.569320147547;5940.55445841555;5992.610480318765;5995.400459953969;6016.067509536029;6100.603460775035;5833.649981001154;5862.7654893805475;5865.237519122486;5870.236869207147;5884.205632239764;5000.67495612784;5830.82507524775;5906.616069955439;6003.129546215861;6057.42454466825;5833.358330583717;5846.6784326862435;5899.705014749331;5904.261967254093;5908.940910590839 05:27:06 INFO - PID 1650 | |1;ext.html;736.5433431272611;783.0173132718437;854.2525290370926;862.0345213361032;864.6627815152087;35504.64495355046;36151.45772813407;36346.818265908696;36450.99999999996;36507.63492365075;37270.627293727055;37658.81170594143;37661.43507847384;37723.43414848773;37755.811220943855;8836.779080522974;8993.190612844843;9001.684941027066;9005.909940900572;9015.819683606323;7526.661300241475;9043.773905652346;9052.230560402373;9058.184763371295;9066.909330906672;8961.462312261285;8992.370534062618;9018.639254429814;9024.503652299121;9024.639014439415;8960.686375976842;8988.865167022468;8993.865092023625;9004.00955894851;9012.053734357864;8777.078406767325;8839.292856571523;8852.8672069919;8881.511516866569;8895.243904268145;36580;36801;36844.63155368439;36863.81568092169;36925;3718.3864662330684;3750.7374483786148;3779.1496913194587;3820.6179382061573;3887.5529314128953;26372.8681356592;26500.867495662398;27510.862445687642;27758.16725498238;28023.019194328102;3027.56100365448;3041.148478426044;3108.8911888084035;3159.57345758322;3176.174194709369;308292;311853.4407327949;312297.43851280824;312487;312953;37722.24555508929;38294;38323.04192395157;46198.769006155424;46555.767221163675;47642.761786190844;48648.5135148644;48655.75672121687;48708.512914871106;48722;14037.227952462508;14168.85831141696;14174.64563385903;14198;14203.573892783234;9251.76870578241;9369.344145909858;10065.547050382751;11558.479868405942;11562;10101.292909496411;11057.004869561779;12346.506139754489;12525.874741252654;12538.247705137539;4737.620990320766;5668.206451096849;5726.685032323262;5728.112142617901;5736.799212027559;837.9287760540301;934.6775362499926;941.331654525281;944.4474982135528;955.1260596554214;23947.760522394554;26015;26603.201903942914;27066.729332706815;27693.86153069222;1017.6946915925192;1040.2094408249784;1048.219076787798;1056.1709058389129;1063.7340664833791;14049.367778449994;14073.648158795908;14298.213598252196;14427.855721442862;14490.130592164292;441490;442236;443078;443110.56889430695;443870;52420.47579524156;52447.47552524502;52472.21291680628;52571.21143182855;52742.47257527376;1352.2427440633278;1416.4334266293565;1418.4538952503153;1418.666613345863;1434.0678558936775;25773;26306.42161470318;27000.864995674896;27201.31996700098;27439.45121097607;36172.09569760777;44744.77627611841;44746.3288050686;44760.77619611881;45018.77490612526;410994;412193;412681;413278;414924;24538.877305613358;33291.83478578238;33441.83279083687;33459.33081338311;33466.49800253047;37650.87047388583;38528.80735596304;39741.20517589574;39848.60151398449;40051.599484004786;32247;33194.5020824683;33448.1637959053;34125.31749365049;34404.82797585996;33606.831965840014;33946.83026584851;34979.95060148199;35183.64816351804;35260.647393526764;33815.661843381255;34060.926836062165;34671.30657386889;34727.784527541415;34755.478667820506;28066.719332807228;29841.701582984762;30023.399532009673;30082;30576.08271751851;22601.6681448115;22611;22863.542729145658;22907.146523413707;23185.188518401774 05:27:06 INFO - PID 1650 | |2;jquery.html;383.9939358879736;398.28309043721293;413.49553544675496;418.4163092485982;431.75605782732754;13135.408906599214;15174.317155727991;21650.35048948534;21797.564048719018;21998;21888.23391181309;22162.22432214873;22509.761963092024;23076;23435.765642343573;6364.45902098321;6544.574602650836;6659.733610655568;6732.249615100085;6735.057092007123;6369.426751592356;6572.178477690289;6631.701573429207;6708.12794336738;6750.223724271723;6712.160979877515;6713.26154123046;6713.865722685568;6713.93286067138;6720.495962802785;6367.522435817309;6593.472522198261;6660.533762636617;6671.599704017726;6685.030670552767;6214.098955651405;6392;6454.41910228077;6487.318831522716;6500.4799616030605;25527;26853.865730671416;26895.86552067247;27019.86490067537;27319.590206146924;2898.4058268054996;2903.8112522685956;2909.7526710229445;2925.3615740947607;2941.352902361477;15925.124118173495;16494.278444056716;16591.41930032443;16840;17540.210690518958;1973.4967583266318;2041.9387418377473;2060.5672808710187;2063.8039386258283;2068.586282743457;123197;131875.6812431873;134002.32998835042;155536;160226.19886900607;20427.693584596243;20678.58803297469;21582.244621438174;21834.89082554577;22020.33938981833;35799.64200358015;35807.925762227176;35895;35903.64096359003;35974;9448.811023779486;10207.775066992008;10237.418288741377;10511.84232236517;10956.890431095746;7684.001079859582;8161.836763264821;8557.005048233068;8568.485890846569;8690.913090869137;10136.430825775984;10492.21308401873;10592.89407105919;10624.946875265574;10646.89353106459;3586.5875424326537;3610.5306310180035;4133;4202.280981331543;4251.149770046003;958.9280803939592;962.1100482054072;973.3235401396104;973.9318247722733;995.621663767778;21944.191462995914;22454.438639034153;22526.324210273717;22547.661785073236;22568.210112646306;871.011402058668;937.1284705224207;942.7669762765136;942.9811403771886;950.6815216902415;9871.210303175872;11870.406479675981;11950;11964.9829764471;12028.759424811455;615017;615491;616972;622497;635980;133375.66624333634;134523.32738336246;136804;137240.62759372478;139471;1032.9690109296732;1228.9631311060682;1243.9240057350507;1302.2707283920845;1302.5310888080282;19195.61608767789;20129.892855893067;20848.89575552173;21399.89300053552;21857.453563661034;44905.55094449014;58898.05509724582;60098;60314.09528857158;61182.082268765116;60226.69886650714;86418.135818641;86669.61012417011;87044.25911481862;89337;11610.303381797119;11685.415729213675;11710.180287380143;11964.341961192218;12126.636200913987;10766.89233107659;13054;13256;13555.728885422433;13647.317634118453;10332.741681458023;10406.01142891424;10777.838332425177;10816.729581760519;10860.076893463827;10103.48447732846;10959.01368876773;10995.670129896116;11129.332240065622;11207;8752.912470875464;9844.753881153028;11464.94267528657;11734.94132529332;11913.583024594098;11524.250923689933;11563.364014979257;11785.823212651645;11911.940440297743;12098.395080246128;11316.321020738782;11474.655760327203;11617.954384105471;11652.883471165518;11684.532618695157 05:27:06 INFO - PID 1650 | |3;mootools.html;1587.2936543238256;1591.8169410517794;1617.0944271208125;1619.9757003644947;1671.3064078407458;10607.15142788577;11130.998210161084;11138.108951283899;11154.051905588014;11173.162012849049;10540.47297635119;10878.292910960781;10892.727681807959;10895.128389728823;10909.07272881804;2316.721993360799;2341;2359.1271229645054;2361.197192954395;2361.456864921069;2305.101010605865;2337.5091230841545;2343;2343.929682109531;2349.0721165139844;2211.314492507328;2217.3569664797187;2225.2990308052913;2230.7657695941934;2242.1703969531213;2208.3595757230382;2221.94445138872;2232.2075663139676;2234.7038717543946;2236.4297104238394;2264.569731750962;2334.124703236286;2336.7546407627124;2339.263132113379;2339.508703172336;11091.944540277327;11182.832257516144;11189.888101118966;11210.27133236337;11212.439378031066;5707.74315155819;5926.525877929756;5942.108683697436;5942.197803296543;5964.850878728023;3301.4387554115856;3410.5907291125222;3615.0420138663276;3615.620359862203;3616;1111.8390627408683;1147.8507793986812;1171.174322102924;1213.6055781870928;1216.355331674214;13747.312634368342;14092.929535352258;14131.646708832259;14991.025583337048;15040.398384064625;3646.4894914711963;4140.296149654566;4270.935935960901;4274.401583778274;4301.956980430155;10971.23201375912;11245.775084498264;11282.94358528202;11304.886951130547;11307.717307067389;5435.1303791393975;5500.6424582402815;5506.339239291311;5515.365732940683;5521.475459831309;4951.232558953368;5154.484551544815;5166.250893620404;5233.110371236899;5255.132903071027;3629.9818500907854;3739.813009349576;3779.962200378016;3817.732758706864;3831.65515103642;881.1144799476613;1012.1700205831148;1022.0392830739081;1031.2471895516314;1034.4414016431042;887.3655336434351;917.8164367126601;919.6091661044029;938.666773295484;947.9573419196015;6309.684515774193;6377.362263773585;6460.612363258219;6471.255805582324;6538.79642759408;893.7274131389988;964.2478866484154;979.8138353712772;983.498415807943;993.5082134343546;3947.842086316515;4008.158286759755;4122.3404255319;4141.7100802943505;4189.413482112506;13274.336283185801;13786.241756703477;13787.103838250683;13797.310134493235;13803.17180969145;13461.932690336485;13508;13535.120217185851;13677.042607017413;13697.657558560917;9876.50617469138;9978.351407158512;10000.749981250381;10003.049710277512;10005.849912251322;6245.250569931637;6303.9684801575695;6403.103565500741;6472.223333200046;6517.608943463408;11804.940975295069;12103.273803571814;12105.515779368727;12125.27248365101;12289.078319125923;10290.434026128634;10361.533730982275;11345.092392608407;12426.937865310978;12461.501539938301;7726.304632583095;8020.318272946864;8858.822823543622;8961.193492585699;9045.728628141167;9870.506474676095;9893.406395616286;9952.950235248778;9989.900100999188;9995.950020249853;8666.046734859034;8792.07683193281;8919;8929.598168082583;8946.731598052069;8623.525706086226;8663.350248731243;8754;8765.561721913751;8849;7420.517666351886;7722.7297044603165;7724.691012359667;7725;7732.8453430932195;5317.83007738297;5640.492355688008;5673.461021202979;5753.309602847517;5845.620034697731;5746.16680581305;5829.125631155361;5833.6208146472045;5863.120531920246;5906.350301466749 05:27:06 INFO - PID 1650 | |4;prototype.html;337.3842737004966;349.6468666261568;392.25667360352134;398.37256321293967;398.7906349166689;5339.599530035248;5568.638038527492;5712.942870571293;5803.361630220672;6053.515718742501;4610.469795973456;5637.20038161335;5910.497607703351;5910.527157827374;5916.497097746696;4014.959850401488;4375.781210939456;4600.700954437968;4617.422822147231;4621.145088158684;3760.849566017356;4602.838900638479;4618.769061546909;4624.583787459128;4636.582707556319;4458.621017213541;4556.767184079794;4594.58648721615;4598.609118224955;4629.814807407716;4768.570828625423;4780.904381912343;4785.1625965455905;4815.855524334275;4825.638077144211;4368.912621747579;4377.737335759833;4384;4388.539203383662;4418.491873434564;4939.629527785431;5441.211024401439;5619.213310136584;5712.1446770245375;5781.392953739839;4571.428571428572;5319.7498587832215;5433.565314774809;5576.267628861869;5659.236003139606;5186.948130518685;6272.87454250917;6285.214348206474;6316;6327.810165695037;4256.87229383119;4673.719576825401;4857.846541207287;4911.607071434277;4945.109880221558;6000.709847382834;6244.781432649836;6489.415952564292;6552.6068435894;6635.86728265432;4949.82675606352;4964.826231081967;5117.667351622133;5140.665856719302;5147.382314122329;4374.278244089754;4914.582260507826;5006.499350064964;5277.788888444496;5322.973385133049;5313.162717470608;5519.2824932758485;5543.944560554423;5585.3297604287745;5586.972065139648;5190.844274671766;6238.594491358047;6280.246370435577;6297.653629050446;6327.892618791693;1705.1985566783592;1785.3840425053336;1789.5883946692334;1793.9282428702707;1811.501836994837;1113.5378817790688;1182.3378907811652;1261.9116661833766;1292.5992942187763;1312.1405479411;5295.894082118336;5707.514861236758;5852.151438041546;5939.495142912814;5974.3428222895445;5037.168867136881;6426.871462570816;6500.5449618526245;6532.216134063943;6539.215294164731;5224.92162617561;5640.830775076754;5906.734196961147;6024.590163934461;6030.3668114847305;3378.6283508814013;3417.9829100854836;3429.5198672185916;3431.725461963037;3431.811250381253;6501.349865013461;6693.732250710014;6749.190097188369;6772.72909083641;6813.3527314905;5566.66600003993;6618.205815302194;6632.668366581652;6664.633445160563;6670.866582668319;5278.340207474066;5316.76074576645;5897.587168898136;6342.778002769881;6759.087523184307;6445.871082578416;6524.4780417565535;6534.346565343428;6535.1572066151375;6559.245686746085;5630.60585758993;5987.599114650641;6083.057126145454;6438.871222575429;6441.323661015525;4989.525995030466;5009.624278179224;5729.799457018984;6662.800115996528;6779.72881084751;4608.649588709715;4754.291652511047;5045.470225626404;5102.775333919758;5103.87240318995;6324.304326523987;6436.967815160894;6486.772962946275;6512.837179070561;6555.410013098844;5286.577073834007;5582.757104600451;6247.250329960434;6278.843028924313;6311.65285909261;6095.9390406097145;6206.200201941387;6250.937340652191;6265;6289.842753931189;6169.784057557964;6179;6184.3716420522;6186.876262474815;6213.471854892385;6033;6294.590851594632;6317.178766760292;6319.494440444661;6322.747090116344;5078.187490001582;5094.566961808139;5119.795208191631;6064.151018857452;6184.350643182581 05:27:06 INFO - PID 1650 | |5;yui.html;713.9464540159489;716.1784007330181;725.463157263625;768.8769796832507;834.6995081770559;14930.402783888632;15726.764098538531;15791.842081579181;15820.683586328267;15832.762508562366;13922.860771392283;14636.048656837325;14700.558983230494;14841.925790371033;14872.628184295398;907.6596276396335;956.4739393333673;959.5873774277059;960.3229723045271;960.4525420510312;940.5673390240463;946.1342871272777;947.6162154327474;978.8923218446001;990.7572644702058;1021.7547788530736;1024.9128824049928;1025.1491262252312;1025.2874252394588;1027.239842516536;1008.6116845014723;1008.712516932678;1008.9798204035951;1010.914072303855;1013.5692330759472;759.4759615865036;760.2397602397602;760.7223363472348;761.3414396547003;762.3977058124075;16012;16049.51851444447;16455;16462.259198335985;17067.40264090751;14304.785428218585;14427.751653781856;14581.17478281658;14620.853791462056;14738.484153054591;11494.885051149466;11576.652700419001;11628.847046376666;11832.053435725122;12111.697207569792;3583.337082639694;3708.647678470541;3863.343231650598;3870.2066076454257;3873.283442563135;100254;101481.98518014872;104019.4799026;106342;107107.46446267719;11055.944720276348;11265.549378024787;11525.596604118815;11735.41322933867;11804.645860624023;24429.389265268153;24784.628230576556;24784.884680189436;24824.503509929702;24859.62710559343;7606.923930760732;7709;7738.922610773933;7764.844703105906;7992.720254791055;4144.834206631761;4149.792510374469;4272.743635381887;4284.207421627009;4432.113577284556;3252.3170134271595;3312.287858110518;3452.671996160361;3604.6395360463744;3610.2598967212184;524.3026774390052;524.4571868116481;632.2570979099539;646.3149061994301;654.731560060373;661.381608196338;671.3018460800713;677.4478799778165;687.4775170870174;695.7468392013242;8878;9630.518474076269;10022.649207277711;10106.39361638304;10398.58405663765;612.5834432585796;634.1565717595587;640.3980258556942;657.3525077798365;691.6991108867659;4324.394584758136;4762.4283986284445;4799.712017278975;4854.514548545118;4864.537868902448;88747;93463;93591.53204234071;94764.52617737005;94914.52542737243;85215;87399.12600874036;87735;89114.55442722875;89870.55064724635;72833;73258.63370683219;73757.63121184574;74819.99999999891;75203.62398188084;9406.529673516434;9419.387739796892;9557.52212389364;9619.088859357013;9708.466034368177;48944.37139728977;49011.50988490212;50096.74951625219;50398;51000;62241.68879155575;62453.687731561055;62954.740905180726;63121;63803;9835.754106147404;11417.28758592582;11635.360055197045;11750.58872939443;11971.76056478883;12629.305388203737;12679.746405072032;12821.102522823687;12845.935770321463;12861.871381286443;11486.540538378706;11540.422978850856;11540.769184616429;12075.57735479254;12215.083868709704;11643.184977051525;11800.881991179978;11815.110244174273;11938;12118.272903625812;11665.35840528779;11671.649850504498;11692.415379231175;11820.274550164446;12049.51801927913;8179.672813087648;10016.899831001596;10123.13953313947;10599.52276794573;10850;9099.818003640023;9998;10031.398116113056;10051.497425128568;10054.447005414773 05:27:06 INFO - PID 1650 | __end_tp_report 05:27:06 INFO - PID 1650 | __start_cc_report 05:27:06 INFO - PID 1650 | _x_x_mozilla_cycle_collect,4591 05:27:06 INFO - PID 1650 | __end_cc_report 05:27:06 INFO - PID 1650 | __startTimestamp1489580826026__endTimestamp 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | ------- Summary: start ------- 05:27:06 INFO - PID 1650 | Number of tests: 6 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:5714.66 Median:5892.79 stddev:653.72 (11.1%) stddev-sans-first:614.11 05:27:06 INFO - PID 1650 | Values: 2788.6 3145.5 3301.8 3337.9 3374.5 5561.7 5596.3 5721.2 5760.1 5762.9 5554.6 5595.1 5609.9 5616.2 5627.4 5095.8 5126.7 5146.9 5183.8 5199.6 5191.6 5218.3 5222.5 5225.2 5226.3 5209.3 5218.9 5219.9 5226.3 5235.1 5204.7 5227.5 5232.6 5251.4 5256.6 5075.8 5197.8 5198.7 5214.1 5222.8 5723.8 5752.1 5763.0 5766.9 5784.7 6085.2 6097.3 6175.9 6180.8 6184.9 4604.6 6135.4 6169.0 6175.6 6199.1 5886.9 5903.3 5908.0 5913.8 5939.8 6290.4 6296.5 6306.2 6318.3 6322.9 5981.4 6028.7 6045.4 6108.8 6164.8 6195.2 6240.2 6247.5 6249.8 6263.6 6158.3 6202.4 6204.3 6218.3 6221.5 6072.3 6100.5 6147.4 6234.7 6249.2 4891.2 4970.1 5026.7 5042.5 5044.0 4468.7 4470.4 4485.8 4486.4 4492.6 5836.4 5836.9 5841.5 5862.8 5877.1 6155.4 6206.6 6216.6 6217.5 6226.3 5689.4 5712.3 5739.8 5743.2 5747.6 5379.4 5410.1 5412.6 5413.6 5415.7 6263.0 6276.7 6278.2 6315.0 6324.5 6224.4 6272.2 6280.6 6296.4 6310.1 6319.2 6331.2 6338.9 6347.6 6356.9 6241.3 6249.5 6250.2 6254.7 6256.9 6142.9 6170.7 6186.4 6284.6 6285.2 5320.3 5892.8 6102.5 6120.5 6152.6 5940.6 5992.6 5995.4 6016.1 6100.6 5833.6 5862.8 5865.2 5870.2 5884.2 5000.7 5830.8 5906.6 6003.1 6057.4 5833.4 5846.7 5899.7 5904.3 5908.9 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:52250.80 Median:36822.82 stddev:104206.47 (283.0%) stddev-sans-first:104427.02 05:27:06 INFO - PID 1650 | Values: 736.5 783.0 854.3 862.0 864.7 35504.6 36151.5 36346.8 36451.0 36507.6 37270.6 37658.8 37661.4 37723.4 37755.8 8836.8 8993.2 9001.7 9005.9 9015.8 7526.7 9043.8 9052.2 9058.2 9066.9 8961.5 8992.4 9018.6 9024.5 9024.6 8960.7 8988.9 8993.9 9004.0 9012.1 8777.1 8839.3 8852.9 8881.5 8895.2 36580.0 36801.0 36844.6 36863.8 36925.0 3718.4 3750.7 3779.1 3820.6 3887.6 26372.9 26500.9 27510.9 27758.2 28023.0 3027.6 3041.1 3108.9 3159.6 3176.2 308292.0 311853.4 312297.4 312487.0 312953.0 37722.2 38294.0 38323.0 46198.8 46555.8 47642.8 48648.5 48655.8 48708.5 48722.0 14037.2 14168.9 14174.6 14198.0 14203.6 9251.8 9369.3 10065.5 11558.5 11562.0 10101.3 11057.0 12346.5 12525.9 12538.2 4737.6 5668.2 5726.7 5728.1 5736.8 837.9 934.7 941.3 944.4 955.1 23947.8 26015.0 26603.2 27066.7 27693.9 1017.7 1040.2 1048.2 1056.2 1063.7 14049.4 14073.6 14298.2 14427.9 14490.1 441490.0 442236.0 443078.0 443110.6 443870.0 52420.5 52447.5 52472.2 52571.2 52742.5 1352.2 1416.4 1418.5 1418.7 1434.1 25773.0 26306.4 27000.9 27201.3 27439.5 36172.1 44744.8 44746.3 44760.8 45018.8 410994.0 412193.0 412681.0 413278.0 414924.0 24538.9 33291.8 33441.8 33459.3 33466.5 37650.9 38528.8 39741.2 39848.6 40051.6 32247.0 33194.5 33448.2 34125.3 34404.8 33606.8 33946.8 34980.0 35183.6 35260.6 33815.7 34060.9 34671.3 34727.8 34755.5 28066.7 29841.7 30023.4 30082.0 30576.1 22601.7 22611.0 22863.5 22907.1 23185.2 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:38577.48 Median:22518.04 stddev:104010.00 (461.9%) stddev-sans-first:104262.24 05:27:06 INFO - PID 1650 | Values: 384.0 398.3 413.5 418.4 431.8 13135.4 15174.3 21650.4 21797.6 21998.0 21888.2 22162.2 22509.8 23076.0 23435.8 6364.5 6544.6 6659.7 6732.2 6735.1 6369.4 6572.2 6631.7 6708.1 6750.2 6712.2 6713.3 6713.9 6713.9 6720.5 6367.5 6593.5 6660.5 6671.6 6685.0 6214.1 6392.0 6454.4 6487.3 6500.5 25527.0 26853.9 26895.9 27019.9 27319.6 2898.4 2903.8 2909.8 2925.4 2941.4 15925.1 16494.3 16591.4 16840.0 17540.2 1973.5 2041.9 2060.6 2063.8 2068.6 123197.0 131875.7 134002.3 155536.0 160226.2 20427.7 20678.6 21582.2 21834.9 22020.3 35799.6 35807.9 35895.0 35903.6 35974.0 9448.8 10207.8 10237.4 10511.8 10956.9 7684.0 8161.8 8557.0 8568.5 8690.9 10136.4 10492.2 10592.9 10624.9 10646.9 3586.6 3610.5 4133.0 4202.3 4251.1 958.9 962.1 973.3 973.9 995.6 21944.2 22454.4 22526.3 22547.7 22568.2 871.0 937.1 942.8 943.0 950.7 9871.2 11870.4 11950.0 11965.0 12028.8 615017.0 615491.0 616972.0 622497.0 635980.0 133375.7 134523.3 136804.0 137240.6 139471.0 1033.0 1229.0 1243.9 1302.3 1302.5 19195.6 20129.9 20848.9 21399.9 21857.5 44905.6 58898.1 60098.0 60314.1 61182.1 60226.7 86418.1 86669.6 87044.3 89337.0 11610.3 11685.4 11710.2 11964.3 12126.6 10766.9 13054.0 13256.0 13555.7 13647.3 10332.7 10406.0 10777.8 10816.7 10860.1 10103.5 10959.0 10995.7 11129.3 11207.0 8752.9 9844.8 11464.9 11734.9 11913.6 11524.3 11563.4 11785.8 11911.9 12098.4 11316.3 11474.7 11618.0 11652.9 11684.5 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:6570.33 Median:3615.81 stddev:4125.49 (114.1%) stddev-sans-first:4120.07 05:27:06 INFO - PID 1650 | Values: 1587.3 1591.8 1617.1 1620.0 1671.3 10607.2 11131.0 11138.1 11154.1 11173.2 10540.5 10878.3 10892.7 10895.1 10909.1 2316.7 2341.0 2359.1 2361.2 2361.5 2305.1 2337.5 2343.0 2343.9 2349.1 2211.3 2217.4 2225.3 2230.8 2242.2 2208.4 2221.9 2232.2 2234.7 2236.4 2264.6 2334.1 2336.8 2339.3 2339.5 11091.9 11182.8 11189.9 11210.3 11212.4 5707.7 5926.5 5942.1 5942.2 5964.9 3301.4 3410.6 3615.0 3615.6 3616.0 1111.8 1147.9 1171.2 1213.6 1216.4 13747.3 14092.9 14131.6 14991.0 15040.4 3646.5 4140.3 4270.9 4274.4 4302.0 10971.2 11245.8 11282.9 11304.9 11307.7 5435.1 5500.6 5506.3 5515.4 5521.5 4951.2 5154.5 5166.3 5233.1 5255.1 3630.0 3739.8 3780.0 3817.7 3831.7 881.1 1012.2 1022.0 1031.2 1034.4 887.4 917.8 919.6 938.7 948.0 6309.7 6377.4 6460.6 6471.3 6538.8 893.7 964.2 979.8 983.5 993.5 3947.8 4008.2 4122.3 4141.7 4189.4 13274.3 13786.2 13787.1 13797.3 13803.2 13461.9 13508.0 13535.1 13677.0 13697.7 9876.5 9978.4 10000.7 10003.0 10005.8 6245.3 6304.0 6403.1 6472.2 6517.6 11804.9 12103.3 12105.5 12125.3 12289.1 10290.4 10361.5 11345.1 12426.9 12461.5 7726.3 8020.3 8858.8 8961.2 9045.7 9870.5 9893.4 9953.0 9989.9 9996.0 8666.0 8792.1 8919.0 8929.6 8946.7 8623.5 8663.4 8754.0 8765.6 8849.0 7420.5 7722.7 7724.7 7725.0 7732.8 5317.8 5640.5 5673.5 5753.3 5845.6 5746.2 5829.1 5833.6 5863.1 5906.4 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5197.70 Median:5579.51 stddev:1476.65 (26.5%) stddev-sans-first:1435.03 05:27:06 INFO - PID 1650 | Values: 337.4 349.6 392.3 398.4 398.8 5339.6 5568.6 5712.9 5803.4 6053.5 4610.5 5637.2 5910.5 5910.5 5916.5 4015.0 4375.8 4600.7 4617.4 4621.1 3760.8 4602.8 4618.8 4624.6 4636.6 4458.6 4556.8 4594.6 4598.6 4629.8 4768.6 4780.9 4785.2 4815.9 4825.6 4368.9 4377.7 4384.0 4388.5 4418.5 4939.6 5441.2 5619.2 5712.1 5781.4 4571.4 5319.7 5433.6 5576.3 5659.2 5186.9 6272.9 6285.2 6316.0 6327.8 4256.9 4673.7 4857.8 4911.6 4945.1 6000.7 6244.8 6489.4 6552.6 6635.9 4949.8 4964.8 5117.7 5140.7 5147.4 4374.3 4914.6 5006.5 5277.8 5323.0 5313.2 5519.3 5543.9 5585.3 5587.0 5190.8 6238.6 6280.2 6297.7 6327.9 1705.2 1785.4 1789.6 1793.9 1811.5 1113.5 1182.3 1261.9 1292.6 1312.1 5295.9 5707.5 5852.2 5939.5 5974.3 5037.2 6426.9 6500.5 6532.2 6539.2 5224.9 5640.8 5906.7 6024.6 6030.4 3378.6 3418.0 3429.5 3431.7 3431.8 6501.3 6693.7 6749.2 6772.7 6813.4 5566.7 6618.2 6632.7 6664.6 6670.9 5278.3 5316.8 5897.6 6342.8 6759.1 6445.9 6524.5 6534.3 6535.2 6559.2 5630.6 5987.6 6083.1 6438.9 6441.3 4989.5 5009.6 5729.8 6662.8 6779.7 4608.6 4754.3 5045.5 5102.8 5103.9 6324.3 6437.0 6486.8 6512.8 6555.4 5286.6 5582.8 6247.3 6278.8 6311.7 6095.9 6206.2 6250.9 6265.0 6289.8 6169.8 6179.0 6184.4 6186.9 6213.5 6033.0 6294.6 6317.2 6319.5 6322.7 5078.2 5094.6 5119.8 6064.2 6184.4 05:27:06 INFO - PID 1650 | 05:27:06 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:19741.29 Median:3786.00 stddev:28028.15 (740.3%) stddev-sans-first:28070.36 05:27:06 INFO - PID 1650 | Values: 713.9 716.2 725.5 768.9 834.7 14930.4 15726.8 15791.8 15820.7 15832.8 13922.9 14636.0 14700.6 14841.9 14872.6 907.7 956.5 959.6 960.3 960.5 940.6 946.1 947.6 978.9 990.8 1021.8 1024.9 1025.1 1025.3 1027.2 1008.6 1008.7 1009.0 1010.9 1013.6 759.5 760.2 760.7 761.3 762.4 16012.0 16049.5 16455.0 16462.3 17067.4 14304.8 14427.8 14581.2 14620.9 14738.5 11494.9 11576.7 11628.8 11832.1 12111.7 3583.3 3708.6 3863.3 3870.2 3873.3 100254.0 101482.0 104019.5 106342.0 107107.5 11055.9 11265.5 11525.6 11735.4 11804.6 24429.4 24784.6 24784.9 24824.5 24859.6 7606.9 7709.0 7738.9 7764.8 7992.7 4144.8 4149.8 4272.7 4284.2 4432.1 3252.3 3312.3 3452.7 3604.6 3610.3 524.3 524.5 632.3 646.3 654.7 661.4 671.3 677.4 687.5 695.7 8878.0 9630.5 10022.6 10106.4 10398.6 612.6 634.2 640.4 657.4 691.7 4324.4 4762.4 4799.7 4854.5 4864.5 88747.0 93463.0 93591.5 94764.5 94914.5 85215.0 87399.1 87735.0 89114.6 89870.6 72833.0 73258.6 73757.6 74820.0 75203.6 9406.5 9419.4 9557.5 9619.1 9708.5 48944.4 49011.5 50096.7 50398.0 51000.0 62241.7 62453.7 62954.7 63121.0 63803.0 9835.8 11417.3 11635.4 11750.6 11971.8 12629.3 12679.7 12821.1 12845.9 12861.9 11486.5 11540.4 11540.8 12075.6 12215.1 11643.2 11800.9 11815.1 11938.0 12118.3 11665.4 11671.6 11692.4 11820.3 12049.5 8179.7 10016.9 10123.1 10599.5 10850.0 9099.8 9998.0 10031.4 10051.5 10054.4 05:27:06 INFO - PID 1650 | -------- Summary: end -------- 05:27:06 INFO - PID 1650 | 05:27:06 INFO - TEST-INFO | 1650: exit 0 05:27:06 INFO - TEST-OK | dromaeo_css | took 1122671ms 05:27:06 INFO - TEST-START | kraken 05:27:06 INFO - operating with platform_type : mac_ 05:27:06 INFO - Initialising browser for kraken test... 05:27:06 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpaIlnch/profile http://localhost:49240/getInfo.html) 05:27:09 INFO - PID 1660 | __metrics Screen width/height:1600/1200 05:27:09 INFO - PID 1660 | colorDepth:24 05:27:09 INFO - PID 1660 | Browser inner width/height: 1024/689 05:27:09 INFO - PID 1660 | __metrics 05:27:09 INFO - PID 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:27:09 INFO - PID 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:27:10 INFO - TEST-INFO | 1660: exit 0 05:27:10 INFO - Browser initialized. 05:27:10 INFO - Running cycle 1/1 for kraken test... 05:27:10 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpaIlnch/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:27:22 INFO - PID 1662 | RSS: Main: 234364928 05:27:22 INFO - PID 1662 | 05:27:25 INFO - PID 1662 | 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) 05:27:26 INFO - PID 1662 | RSS: Main: 284766208 05:27:26 INFO - PID 1662 | 05:27:28 INFO - PID 1662 | 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) 05:27:29 INFO - PID 1662 | RSS: Main: 286101504 05:27:29 INFO - PID 1662 | 05:27:32 INFO - PID 1662 | 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) 05:27:33 INFO - PID 1662 | RSS: Main: 379506688 05:27:33 INFO - PID 1662 | 05:27:35 INFO - PID 1662 | 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) 05:27:35 INFO - PID 1662 | RSS: Main: 282599424 05:27:35 INFO - PID 1662 | 05:27:37 INFO - PID 1662 | 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) 05:27:38 INFO - PID 1662 | RSS: Main: 285978624 05:27:38 INFO - PID 1662 | 05:27:41 INFO - PID 1662 | 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) 05:27:42 INFO - PID 1662 | RSS: Main: 326508544 05:27:42 INFO - PID 1662 | 05:27:44 INFO - PID 1662 | 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) 05:27:45 INFO - PID 1662 | RSS: Main: 335425536 05:27:45 INFO - PID 1662 | 05:27:48 INFO - PID 1662 | 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) 05:27:49 INFO - PID 1662 | RSS: Main: 341663744 05:27:49 INFO - PID 1662 | 05:27:51 INFO - PID 1662 | 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) 05:27:51 INFO - PID 1662 | RSS: Main: 301846528 05:27:51 INFO - PID 1662 | 05:27:53 INFO - PID 1662 | 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) 05:27:54 INFO - PID 1662 | RSS: Main: 306987008 05:27:54 INFO - PID 1662 | 05:27:56 INFO - PID 1662 | 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) 05:27:56 INFO - PID 1662 | RSS: Main: 309329920 05:27:56 INFO - PID 1662 | 05:27:59 INFO - PID 1662 | 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) 05:28:00 INFO - PID 1662 | RSS: Main: 325283840 05:28:00 INFO - PID 1662 | 05:28:03 INFO - PID 1662 | 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) 05:28:04 INFO - PID 1662 | RSS: Main: 318885888 05:28:04 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | 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) 05:28:06 INFO - PID 1662 | RSS: Main: 381579264 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | __start_tp_report 05:28:06 INFO - PID 1662 | _x_x_mozilla_page_load 05:28:06 INFO - PID 1662 | _x_x_mozilla_page_load_details 05:28:06 INFO - PID 1662 | |i|pagename|runs| 05:28:06 INFO - PID 1662 | |0;ai-astar;113;115;114;114;113;114;113;112;113;113 05:28:06 INFO - PID 1662 | |1;audio-beat-detection;147;112;110;148;114;110;112;111;136;135 05:28:06 INFO - PID 1662 | |2;audio-dft;187;184;183;184;184;185;183;183;184;183 05:28:06 INFO - PID 1662 | |3;audio-fft;82;57;59;90;61;61;59;58;78;60 05:28:06 INFO - PID 1662 | |4;audio-oscillator;87;71;88;72;69;70;85;70;70;113 05:28:06 INFO - PID 1662 | |5;imaging-gaussian-blur;124;87;87;90;87;87;87;86;87;86 05:28:06 INFO - PID 1662 | |6;imaging-darkroom;94;94;95;95;96;95;94;95;96;94 05:28:06 INFO - PID 1662 | |7;imaging-desaturate;84;86;87;84;83;86;87;84;112;138 05:28:06 INFO - PID 1662 | |8;json-parse-financial;66;64;60;67;91;95;95;70;65;62 05:28:06 INFO - PID 1662 | |9;json-stringify-tinderbox;55;54;65;64;60;56;59;58;58;58 05:28:06 INFO - PID 1662 | |10;stanford-crypto-aes;84;83;83;84;83;82;81;82;73;97 05:28:06 INFO - PID 1662 | |11;stanford-crypto-ccm;140;138;139;140;135;136;134;141;184;136 05:28:06 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;188;193;187;184;183;190;231;186;188;167 05:28:06 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;57;84;92;93;68;68;67;68;68;67 05:28:06 INFO - PID 1662 | __end_tp_report 05:28:06 INFO - PID 1662 | __start_cc_report 05:28:06 INFO - PID 1662 | _x_x_mozilla_cycle_collect,2163 05:28:06 INFO - PID 1662 | __end_cc_report 05:28:06 INFO - PID 1662 | __startTimestamp1489580886381__endTimestamp 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | ------- Summary: start ------- 05:28:06 INFO - PID 1662 | Number of tests: 14 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 05:28:06 INFO - PID 1662 | Values: 113.0 115.0 114.0 114.0 113.0 114.0 113.0 112.0 113.0 113.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:124.50 stddev:16.04 (12.9%) stddev-sans-first:14.59 05:28:06 INFO - PID 1662 | Values: 147.0 112.0 110.0 148.0 114.0 110.0 112.0 111.0 136.0 135.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.25 (0.7%) stddev-sans-first:0.71 05:28:06 INFO - PID 1662 | Values: 187.0 184.0 183.0 184.0 184.0 185.0 183.0 183.0 184.0 183.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:66.50 Median:61.00 stddev:12.03 (19.7%) stddev-sans-first:11.38 05:28:06 INFO - PID 1662 | Values: 82.0 57.0 59.0 90.0 61.0 61.0 59.0 58.0 78.0 60.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:71.50 stddev:14.10 (19.7%) stddev-sans-first:14.70 05:28:06 INFO - PID 1662 | Values: 87.0 71.0 88.0 72.0 69.0 70.0 85.0 70.0 70.0 113.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:87.00 stddev:11.72 (13.5%) stddev-sans-first:1.17 05:28:06 INFO - PID 1662 | Values: 124.0 87.0 87.0 90.0 87.0 87.0 87.0 86.0 87.0 86.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 05:28:06 INFO - PID 1662 | Values: 94.0 94.0 95.0 95.0 96.0 95.0 94.0 95.0 96.0 94.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:85.00 stddev:17.95 (21.1%) stddev-sans-first:18.73 05:28:06 INFO - PID 1662 | Values: 84.0 86.0 87.0 84.0 83.0 86.0 87.0 84.0 112.0 138.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:73.50 Median:68.50 stddev:14.21 (20.8%) stddev-sans-first:14.82 05:28:06 INFO - PID 1662 | Values: 66.0 64.0 60.0 67.0 91.0 95.0 95.0 70.0 65.0 62.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:58.50 stddev:3.56 (6.1%) stddev-sans-first:3.52 05:28:06 INFO - PID 1662 | Values: 55.0 54.0 65.0 64.0 60.0 56.0 59.0 58.0 58.0 58.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:5.81 (7.0%) stddev-sans-first:6.15 05:28:06 INFO - PID 1662 | Values: 84.0 83.0 83.0 84.0 83.0 82.0 81.0 82.0 73.0 97.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:139.50 stddev:14.84 (10.6%) stddev-sans-first:15.72 05:28:06 INFO - PID 1662 | Values: 140.0 138.0 139.0 140.0 135.0 136.0 134.0 141.0 184.0 136.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.70 Median:188.00 stddev:16.11 (8.6%) stddev-sans-first:17.08 05:28:06 INFO - PID 1662 | Values: 188.0 193.0 187.0 184.0 183.0 190.0 231.0 186.0 188.0 167.0 05:28:06 INFO - PID 1662 | 05:28:06 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:68.00 stddev:12.06 (17.7%) stddev-sans-first:11.28 05:28:06 INFO - PID 1662 | Values: 57.0 84.0 92.0 93.0 68.0 68.0 67.0 68.0 68.0 67.0 05:28:06 INFO - PID 1662 | -------- Summary: end -------- 05:28:06 INFO - PID 1662 | 05:28:07 INFO - TEST-INFO | 1662: exit 0 05:28:07 INFO - TEST-OK | kraken | took 60590ms 05:28:07 INFO - SUITE-END | took 1183s 05:28:07 INFO - Completed test suite (00:19:43) 05:28:07 INFO - javascript benchmark 05:28:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7666.390072136704, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2788.637477127974, 3145.54389613506, 3301.785383950043, 3337.9499307510405, 3374.5444365010717, 5561.721913904299, 5596.300462442195, 5721.170430287611, 5760.107183386572, 5762.884742305157, 5554.555635549156, 5595.076812325961, 5609.887802243953, 5616.241807356006, 5627.380988091317, 5095.847124586259, 5126.7436628168625, 5146.922796158042, 5183.818566350178, 5199.558037566811, 5191.5846732261325, 5218.269442278084, 5222.451642577532, 5225.242339860718, 5226.294450249225, 5209.348831396075, 5218.921716174261, 5219.895602087956, 5226.320578324812, 5235.083860324447, 5204.739763011872, 5227.47725227478, 5232.555232805216, 5251.396089449686, 5256.553192978601, 5075.847724568269, 5197.818076367309, 5198.662086964335, 5214.139666954948, 5222.791088356461, 5723.828285151452, 5752.137179423078, 5762.97118514409, 5766.942330576682, 5784.739686714108, 6085.2393450818645, 6097.349215877944, 6175.938240617626, 6180.752769889199, 6184.87630247397, 4604.562566556172, 6135.3674730672865, 6169.0, 6175.62946223228, 6199.070139479114, 5886.8822623547285, 5903.321118071391, 5908.02517584598, 5913.822585322448, 5939.821805345803, 6290.370962903765, 6296.5277604178955, 6306.211723534559, 6318.273398559132, 6322.873542529123, 5981.3719559445635, 6028.698565071817, 6045.3652366501765, 6108.816735497942, 6164.753409863555, 6195.2255968004, 6240.157578726905, 6247.468965137923, 6249.812505624838, 6263.592866463665, 6158.261008678897, 6202.41077097675, 6204.286507051656, 6218.31598524162, 6221.502279817604, 6072.271327440735, 6100.450959413674, 6147.44672979434, 6234.719437625317, 6249.156363890907, 4891.217405215149, 4970.080535101018, 5026.748662566857, 5042.546170844642, 5044.0, 4468.73187608738, 4470.374147619336, 4485.75328356937, 4486.371907932892, 4492.573205545468, 5836.445537673914, 5836.8540786479825, 5841.503472204825, 5862.824115276549, 5877.118432235199, 6155.384461553809, 6206.627602343874, 6216.564840461215, 6217.533684973645, 6226.346233645492, 5689.431056894277, 5712.285964254468, 5739.827805165852, 5743.2246646703, 5747.568932330092, 5379.381371142291, 5410.107332290128, 5412.566994640498, 5413.648112872651, 5415.729213539307, 6263.0, 6276.686165691788, 6278.246610406689, 6314.968425157845, 6324.462420694293, 6224.439800417984, 6272.153259309935, 6280.560360774702, 6296.401841825019, 6310.116583678197, 6319.241690996926, 6331.2085989251345, 6338.90491642635, 6347.619142851444, 6356.904646430215, 6241.251049873863, 6249.468795152463, 6250.156228909039, 6254.749810007549, 6256.874862502816, 6142.9078563820685, 6170.722317495633, 6186.350433204449, 6284.5600807943, 6285.182926219562, 5320.334958130234, 5892.793752218652, 6102.458955353496, 6120.479759220516, 6152.569320147547, 5940.55445841555, 5992.610480318765, 5995.400459953969, 6016.067509536029, 6100.603460775035, 5833.649981001154, 5862.7654893805475, 5865.237519122486, 5870.236869207147, 5884.205632239764, 5000.67495612784, 5830.82507524775, 5906.616069955439, 6003.129546215861, 6057.42454466825, 5833.358330583717, 5846.6784326862435, 5899.705014749331, 5904.261967254093, 5908.940910590839], "value": 5671.478541528685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.5433431272611, 783.0173132718437, 854.2525290370926, 862.0345213361032, 864.6627815152087, 35504.64495355046, 36151.45772813407, 36346.818265908696, 36450.99999999996, 36507.63492365075, 37270.627293727055, 37658.81170594143, 37661.43507847384, 37723.43414848773, 37755.811220943855, 8836.779080522974, 8993.190612844843, 9001.684941027066, 9005.909940900572, 9015.819683606323, 7526.661300241475, 9043.773905652346, 9052.230560402373, 9058.184763371295, 9066.909330906672, 8961.462312261285, 8992.370534062618, 9018.639254429814, 9024.503652299121, 9024.639014439415, 8960.686375976842, 8988.865167022468, 8993.865092023625, 9004.00955894851, 9012.053734357864, 8777.078406767325, 8839.292856571523, 8852.8672069919, 8881.511516866569, 8895.243904268145, 36580.0, 36801.0, 36844.63155368439, 36863.81568092169, 36925.0, 3718.3864662330684, 3750.7374483786148, 3779.1496913194587, 3820.6179382061573, 3887.5529314128953, 26372.8681356592, 26500.867495662398, 27510.862445687642, 27758.16725498238, 28023.019194328102, 3027.56100365448, 3041.148478426044, 3108.8911888084035, 3159.57345758322, 3176.174194709369, 308292.0, 311853.4407327949, 312297.43851280824, 312487.0, 312953.0, 37722.24555508929, 38294.0, 38323.04192395157, 46198.769006155424, 46555.767221163675, 47642.761786190844, 48648.5135148644, 48655.75672121687, 48708.512914871106, 48722.0, 14037.227952462508, 14168.85831141696, 14174.64563385903, 14198.0, 14203.573892783234, 9251.76870578241, 9369.344145909858, 10065.547050382751, 11558.479868405942, 11562.0, 10101.292909496411, 11057.004869561779, 12346.506139754489, 12525.874741252654, 12538.247705137539, 4737.620990320766, 5668.206451096849, 5726.685032323262, 5728.112142617901, 5736.799212027559, 837.9287760540301, 934.6775362499926, 941.331654525281, 944.4474982135528, 955.1260596554214, 23947.760522394554, 26015.0, 26603.201903942914, 27066.729332706815, 27693.86153069222, 1017.6946915925192, 1040.2094408249784, 1048.219076787798, 1056.1709058389129, 1063.7340664833791, 14049.367778449994, 14073.648158795908, 14298.213598252196, 14427.855721442862, 14490.130592164292, 441490.0, 442236.0, 443078.0, 443110.56889430695, 443870.0, 52420.47579524156, 52447.47552524502, 52472.21291680628, 52571.21143182855, 52742.47257527376, 1352.2427440633278, 1416.4334266293565, 1418.4538952503153, 1418.666613345863, 1434.0678558936775, 25773.0, 26306.42161470318, 27000.864995674896, 27201.31996700098, 27439.45121097607, 36172.09569760777, 44744.77627611841, 44746.3288050686, 44760.77619611881, 45018.77490612526, 410994.0, 412193.0, 412681.0, 413278.0, 414924.0, 24538.877305613358, 33291.83478578238, 33441.83279083687, 33459.33081338311, 33466.49800253047, 37650.87047388583, 38528.80735596304, 39741.20517589574, 39848.60151398449, 40051.599484004786, 32247.0, 33194.5020824683, 33448.1637959053, 34125.31749365049, 34404.82797585996, 33606.831965840014, 33946.83026584851, 34979.95060148199, 35183.64816351804, 35260.647393526764, 33815.661843381255, 34060.926836062165, 34671.30657386889, 34727.784527541415, 34755.478667820506, 28066.719332807228, 29841.701582984762, 30023.399532009673, 30082.0, 30576.08271751851, 22601.6681448115, 22611.0, 22863.542729145658, 22907.146523413707, 23185.188518401774], "value": 17692.17264342242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.9939358879736, 398.28309043721293, 413.49553544675496, 418.4163092485982, 431.75605782732754, 13135.408906599214, 15174.317155727991, 21650.35048948534, 21797.564048719018, 21998.0, 21888.23391181309, 22162.22432214873, 22509.761963092024, 23076.0, 23435.765642343573, 6364.45902098321, 6544.574602650836, 6659.733610655568, 6732.249615100085, 6735.057092007123, 6369.426751592356, 6572.178477690289, 6631.701573429207, 6708.12794336738, 6750.223724271723, 6712.160979877515, 6713.26154123046, 6713.865722685568, 6713.93286067138, 6720.495962802785, 6367.522435817309, 6593.472522198261, 6660.533762636617, 6671.599704017726, 6685.030670552767, 6214.098955651405, 6392.0, 6454.41910228077, 6487.318831522716, 6500.4799616030605, 25527.0, 26853.865730671416, 26895.86552067247, 27019.86490067537, 27319.590206146924, 2898.4058268054996, 2903.8112522685956, 2909.7526710229445, 2925.3615740947607, 2941.352902361477, 15925.124118173495, 16494.278444056716, 16591.41930032443, 16840.0, 17540.210690518958, 1973.4967583266318, 2041.9387418377473, 2060.5672808710187, 2063.8039386258283, 2068.586282743457, 123197.0, 131875.6812431873, 134002.32998835042, 155536.0, 160226.19886900607, 20427.693584596243, 20678.58803297469, 21582.244621438174, 21834.89082554577, 22020.33938981833, 35799.64200358015, 35807.925762227176, 35895.0, 35903.64096359003, 35974.0, 9448.811023779486, 10207.775066992008, 10237.418288741377, 10511.84232236517, 10956.890431095746, 7684.001079859582, 8161.836763264821, 8557.005048233068, 8568.485890846569, 8690.913090869137, 10136.430825775984, 10492.21308401873, 10592.89407105919, 10624.946875265574, 10646.89353106459, 3586.5875424326537, 3610.5306310180035, 4133.0, 4202.280981331543, 4251.149770046003, 958.9280803939592, 962.1100482054072, 973.3235401396104, 973.9318247722733, 995.621663767778, 21944.191462995914, 22454.438639034153, 22526.324210273717, 22547.661785073236, 22568.210112646306, 871.011402058668, 937.1284705224207, 942.7669762765136, 942.9811403771886, 950.6815216902415, 9871.210303175872, 11870.406479675981, 11950.0, 11964.9829764471, 12028.759424811455, 615017.0, 615491.0, 616972.0, 622497.0, 635980.0, 133375.66624333634, 134523.32738336246, 136804.0, 137240.62759372478, 139471.0, 1032.9690109296732, 1228.9631311060682, 1243.9240057350507, 1302.2707283920845, 1302.5310888080282, 19195.61608767789, 20129.892855893067, 20848.89575552173, 21399.89300053552, 21857.453563661034, 44905.55094449014, 58898.05509724582, 60098.0, 60314.09528857158, 61182.082268765116, 60226.69886650714, 86418.135818641, 86669.61012417011, 87044.25911481862, 89337.0, 11610.303381797119, 11685.415729213675, 11710.180287380143, 11964.341961192218, 12126.636200913987, 10766.89233107659, 13054.0, 13256.0, 13555.728885422433, 13647.317634118453, 10332.741681458023, 10406.01142891424, 10777.838332425177, 10816.729581760519, 10860.076893463827, 10103.48447732846, 10959.01368876773, 10995.670129896116, 11129.332240065622, 11207.0, 8752.912470875464, 9844.753881153028, 11464.94267528657, 11734.94132529332, 11913.583024594098, 11524.250923689933, 11563.364014979257, 11785.823212651645, 11911.940440297743, 12098.395080246128, 11316.321020738782, 11474.655760327203, 11617.954384105471, 11652.883471165518, 11684.532618695157], "value": 11452.766098776508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1587.2936543238256, 1591.8169410517794, 1617.0944271208125, 1619.9757003644947, 1671.3064078407458, 10607.15142788577, 11130.998210161084, 11138.108951283899, 11154.051905588014, 11173.162012849049, 10540.47297635119, 10878.292910960781, 10892.727681807959, 10895.128389728823, 10909.07272881804, 2316.721993360799, 2341.0, 2359.1271229645054, 2361.197192954395, 2361.456864921069, 2305.101010605865, 2337.5091230841545, 2343.0, 2343.929682109531, 2349.0721165139844, 2211.314492507328, 2217.3569664797187, 2225.2990308052913, 2230.7657695941934, 2242.1703969531213, 2208.3595757230382, 2221.94445138872, 2232.2075663139676, 2234.7038717543946, 2236.4297104238394, 2264.569731750962, 2334.124703236286, 2336.7546407627124, 2339.263132113379, 2339.508703172336, 11091.944540277327, 11182.832257516144, 11189.888101118966, 11210.27133236337, 11212.439378031066, 5707.74315155819, 5926.525877929756, 5942.108683697436, 5942.197803296543, 5964.850878728023, 3301.4387554115856, 3410.5907291125222, 3615.0420138663276, 3615.620359862203, 3616.0, 1111.8390627408683, 1147.8507793986812, 1171.174322102924, 1213.6055781870928, 1216.355331674214, 13747.312634368342, 14092.929535352258, 14131.646708832259, 14991.025583337048, 15040.398384064625, 3646.4894914711963, 4140.296149654566, 4270.935935960901, 4274.401583778274, 4301.956980430155, 10971.23201375912, 11245.775084498264, 11282.94358528202, 11304.886951130547, 11307.717307067389, 5435.1303791393975, 5500.6424582402815, 5506.339239291311, 5515.365732940683, 5521.475459831309, 4951.232558953368, 5154.484551544815, 5166.250893620404, 5233.110371236899, 5255.132903071027, 3629.9818500907854, 3739.813009349576, 3779.962200378016, 3817.732758706864, 3831.65515103642, 881.1144799476613, 1012.1700205831148, 1022.0392830739081, 1031.2471895516314, 1034.4414016431042, 887.3655336434351, 917.8164367126601, 919.6091661044029, 938.666773295484, 947.9573419196015, 6309.684515774193, 6377.362263773585, 6460.612363258219, 6471.255805582324, 6538.79642759408, 893.7274131389988, 964.2478866484154, 979.8138353712772, 983.498415807943, 993.5082134343546, 3947.842086316515, 4008.158286759755, 4122.3404255319, 4141.7100802943505, 4189.413482112506, 13274.336283185801, 13786.241756703477, 13787.103838250683, 13797.310134493235, 13803.17180969145, 13461.932690336485, 13508.0, 13535.120217185851, 13677.042607017413, 13697.657558560917, 9876.50617469138, 9978.351407158512, 10000.749981250381, 10003.049710277512, 10005.849912251322, 6245.250569931637, 6303.9684801575695, 6403.103565500741, 6472.223333200046, 6517.608943463408, 11804.940975295069, 12103.273803571814, 12105.515779368727, 12125.27248365101, 12289.078319125923, 10290.434026128634, 10361.533730982275, 11345.092392608407, 12426.937865310978, 12461.501539938301, 7726.304632583095, 8020.318272946864, 8858.822823543622, 8961.193492585699, 9045.728628141167, 9870.506474676095, 9893.406395616286, 9952.950235248778, 9989.900100999188, 9995.950020249853, 8666.046734859034, 8792.07683193281, 8919.0, 8929.598168082583, 8946.731598052069, 8623.525706086226, 8663.350248731243, 8754.0, 8765.561721913751, 8849.0, 7420.517666351886, 7722.7297044603165, 7724.691012359667, 7725.0, 7732.8453430932195, 5317.83007738297, 5640.492355688008, 5673.461021202979, 5753.309602847517, 5845.620034697731, 5746.16680581305, 5829.125631155361, 5833.6208146472045, 5863.120531920246, 5906.350301466749], "value": 5001.812072576392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.3842737004966, 349.6468666261568, 392.25667360352134, 398.37256321293967, 398.7906349166689, 5339.599530035248, 5568.638038527492, 5712.942870571293, 5803.361630220672, 6053.515718742501, 4610.469795973456, 5637.20038161335, 5910.497607703351, 5910.527157827374, 5916.497097746696, 4014.959850401488, 4375.781210939456, 4600.700954437968, 4617.422822147231, 4621.145088158684, 3760.849566017356, 4602.838900638479, 4618.769061546909, 4624.583787459128, 4636.582707556319, 4458.621017213541, 4556.767184079794, 4594.58648721615, 4598.609118224955, 4629.814807407716, 4768.570828625423, 4780.904381912343, 4785.1625965455905, 4815.855524334275, 4825.638077144211, 4368.912621747579, 4377.737335759833, 4384.0, 4388.539203383662, 4418.491873434564, 4939.629527785431, 5441.211024401439, 5619.213310136584, 5712.1446770245375, 5781.392953739839, 4571.428571428572, 5319.7498587832215, 5433.565314774809, 5576.267628861869, 5659.236003139606, 5186.948130518685, 6272.87454250917, 6285.214348206474, 6316.0, 6327.810165695037, 4256.87229383119, 4673.719576825401, 4857.846541207287, 4911.607071434277, 4945.109880221558, 6000.709847382834, 6244.781432649836, 6489.415952564292, 6552.6068435894, 6635.86728265432, 4949.82675606352, 4964.826231081967, 5117.667351622133, 5140.665856719302, 5147.382314122329, 4374.278244089754, 4914.582260507826, 5006.499350064964, 5277.788888444496, 5322.973385133049, 5313.162717470608, 5519.2824932758485, 5543.944560554423, 5585.3297604287745, 5586.972065139648, 5190.844274671766, 6238.594491358047, 6280.246370435577, 6297.653629050446, 6327.892618791693, 1705.1985566783592, 1785.3840425053336, 1789.5883946692334, 1793.9282428702707, 1811.501836994837, 1113.5378817790688, 1182.3378907811652, 1261.9116661833766, 1292.5992942187763, 1312.1405479411, 5295.894082118336, 5707.514861236758, 5852.151438041546, 5939.495142912814, 5974.3428222895445, 5037.168867136881, 6426.871462570816, 6500.5449618526245, 6532.216134063943, 6539.215294164731, 5224.92162617561, 5640.830775076754, 5906.734196961147, 6024.590163934461, 6030.3668114847305, 3378.6283508814013, 3417.9829100854836, 3429.5198672185916, 3431.725461963037, 3431.811250381253, 6501.349865013461, 6693.732250710014, 6749.190097188369, 6772.72909083641, 6813.3527314905, 5566.66600003993, 6618.205815302194, 6632.668366581652, 6664.633445160563, 6670.866582668319, 5278.340207474066, 5316.76074576645, 5897.587168898136, 6342.778002769881, 6759.087523184307, 6445.871082578416, 6524.4780417565535, 6534.346565343428, 6535.1572066151375, 6559.245686746085, 5630.60585758993, 5987.599114650641, 6083.057126145454, 6438.871222575429, 6441.323661015525, 4989.525995030466, 5009.624278179224, 5729.799457018984, 6662.800115996528, 6779.72881084751, 4608.649588709715, 4754.291652511047, 5045.470225626404, 5102.775333919758, 5103.87240318995, 6324.304326523987, 6436.967815160894, 6486.772962946275, 6512.837179070561, 6555.410013098844, 5286.577073834007, 5582.757104600451, 6247.250329960434, 6278.843028924313, 6311.65285909261, 6095.9390406097145, 6206.200201941387, 6250.937340652191, 6265.0, 6289.842753931189, 6169.784057557964, 6179.0, 6184.3716420522, 6186.876262474815, 6213.471854892385, 6033.0, 6294.590851594632, 6317.178766760292, 6319.494440444661, 6322.747090116344, 5078.187490001582, 5094.566961808139, 5119.795208191631, 6064.151018857452, 6184.350643182581], "value": 4753.903731968263, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.9464540159489, 716.1784007330181, 725.463157263625, 768.8769796832507, 834.6995081770559, 14930.402783888632, 15726.764098538531, 15791.842081579181, 15820.683586328267, 15832.762508562366, 13922.860771392283, 14636.048656837325, 14700.558983230494, 14841.925790371033, 14872.628184295398, 907.6596276396335, 956.4739393333673, 959.5873774277059, 960.3229723045271, 960.4525420510312, 940.5673390240463, 946.1342871272777, 947.6162154327474, 978.8923218446001, 990.7572644702058, 1021.7547788530736, 1024.9128824049928, 1025.1491262252312, 1025.2874252394588, 1027.239842516536, 1008.6116845014723, 1008.712516932678, 1008.9798204035951, 1010.914072303855, 1013.5692330759472, 759.4759615865036, 760.2397602397602, 760.7223363472348, 761.3414396547003, 762.3977058124075, 16012.0, 16049.51851444447, 16455.0, 16462.259198335985, 17067.40264090751, 14304.785428218585, 14427.751653781856, 14581.17478281658, 14620.853791462056, 14738.484153054591, 11494.885051149466, 11576.652700419001, 11628.847046376666, 11832.053435725122, 12111.697207569792, 3583.337082639694, 3708.647678470541, 3863.343231650598, 3870.2066076454257, 3873.283442563135, 100254.0, 101481.98518014872, 104019.4799026, 106342.0, 107107.46446267719, 11055.944720276348, 11265.549378024787, 11525.596604118815, 11735.41322933867, 11804.645860624023, 24429.389265268153, 24784.628230576556, 24784.884680189436, 24824.503509929702, 24859.62710559343, 7606.923930760732, 7709.0, 7738.922610773933, 7764.844703105906, 7992.720254791055, 4144.834206631761, 4149.792510374469, 4272.743635381887, 4284.207421627009, 4432.113577284556, 3252.3170134271595, 3312.287858110518, 3452.671996160361, 3604.6395360463744, 3610.2598967212184, 524.3026774390052, 524.4571868116481, 632.2570979099539, 646.3149061994301, 654.731560060373, 661.381608196338, 671.3018460800713, 677.4478799778165, 687.4775170870174, 695.7468392013242, 8878.0, 9630.518474076269, 10022.649207277711, 10106.39361638304, 10398.58405663765, 612.5834432585796, 634.1565717595587, 640.3980258556942, 657.3525077798365, 691.6991108867659, 4324.394584758136, 4762.4283986284445, 4799.712017278975, 4854.514548545118, 4864.537868902448, 88747.0, 93463.0, 93591.53204234071, 94764.52617737005, 94914.52542737243, 85215.0, 87399.12600874036, 87735.0, 89114.55442722875, 89870.55064724635, 72833.0, 73258.63370683219, 73757.63121184574, 74819.99999999891, 75203.62398188084, 9406.529673516434, 9419.387739796892, 9557.52212389364, 9619.088859357013, 9708.466034368177, 48944.37139728977, 49011.50988490212, 50096.74951625219, 50398.0, 51000.0, 62241.68879155575, 62453.687731561055, 62954.740905180726, 63121.0, 63803.0, 9835.754106147404, 11417.28758592582, 11635.360055197045, 11750.58872939443, 11971.76056478883, 12629.305388203737, 12679.746405072032, 12821.102522823687, 12845.935770321463, 12861.871381286443, 11486.540538378706, 11540.422978850856, 11540.769184616429, 12075.57735479254, 12215.083868709704, 11643.184977051525, 11800.881991179978, 11815.110244174273, 11938.0, 12118.272903625812, 11665.35840528779, 11671.649850504498, 11692.415379231175, 11820.274550164446, 12049.51801927913, 8179.672813087648, 10016.899831001596, 10123.13953313947, 10599.52276794573, 10850.0, 9099.818003640023, 9998.0, 10031.398116113056, 10051.497425128568, 10054.447005414773], "value": 7429.234911456675, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 114.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 110.0, 148.0, 114.0, 110.0, 112.0, 111.0, 136.0, 135.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 183.0, 184.0, 184.0, 185.0, 183.0, 183.0, 184.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 59.0, 90.0, 61.0, 61.0, 59.0, 58.0, 78.0, 60.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 72.0, 69.0, 70.0, 85.0, 70.0, 70.0, 113.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 87.0, 87.0, 90.0, 87.0, 87.0, 87.0, 86.0, 87.0, 86.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 96.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 87.0, 84.0, 83.0, 86.0, 87.0, 84.0, 112.0, 138.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 60.0, 67.0, 91.0, 95.0, 95.0, 70.0, 65.0, 62.0], "value": 73.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 65.0, 64.0, 60.0, 56.0, 59.0, 58.0, 58.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 83.0, 82.0, 81.0, 82.0, 73.0, 97.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 139.0, 140.0, 135.0, 136.0, 134.0, 141.0, 184.0, 136.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 193.0, 187.0, 184.0, 183.0, 190.0, 231.0, 186.0, 188.0, 167.0], "value": 189.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 84.0, 92.0, 93.0, 68.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 73.2, "unit": "score"}]}]} 05:28:07 INFO - Return code: 0 05:28:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:28:07 ERROR - # TBPL SUCCESS # 05:28:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:28:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:28:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:28:07 INFO - 2017-03-15 05:28:07.690 screenresolution[1666:9915] starting screenresolution argv=screenresolution get 05:28:07 INFO - 2017-03-15 05:28:07.693 screenresolution[1666:9915] Display 0: 1600x1200x32@60 05:28:07 INFO - 2017-03-15 05:28:07.699 screenresolution[1667:9918] starting screenresolution argv=screenresolution list 05:28:07 INFO - Available Modes on Display 0 05:28:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:28:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:28:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:28:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:28:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:28:07 INFO - Intel Iris: 05:28:07 INFO - Chipset Model: Intel Iris 05:28:07 INFO - Type: GPU 05:28:07 INFO - Bus: Built-In 05:28:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:28:07 INFO - Vendor: Intel (0x8086) 05:28:07 INFO - Device ID: 0x0a2e 05:28:07 INFO - Revision ID: 0x0009 05:28:07 INFO - Displays: 05:28:07 INFO - Display: 05:28:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:28:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:28:07 INFO - Display Serial Number: 1600x1200 60 05:28:07 INFO - Main Display: Yes 05:28:07 INFO - Mirror: Off 05:28:07 INFO - Online: Yes 05:28:07 INFO - Rotation: Supported 05:28:07 INFO - Return code: 0 05:28:07 INFO - Running post-action listener: _resource_record_post_action 05:28:07 INFO - [mozharness: 2017-03-15 12:28:07.936519Z] Finished run-tests step (success) 05:28:07 INFO - Running post-run listener: _resource_record_post_run 05:28:08 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 40436736; Write bytes: 513170432; Read time: 401; Write time: 1691 05:28:08 INFO - TinderboxPrint: CPU usage
53.4% 05:28:08 INFO - TinderboxPrint: I/O read bytes / time
40,436,736 / 401 05:28:08 INFO - TinderboxPrint: I/O write bytes / time
513,170,432 / 1,691 05:28:08 INFO - TinderboxPrint: CPU idle
2,246.1 (46.6%) 05:28:08 INFO - TinderboxPrint: CPU system
958.6 (19.9%) 05:28:08 INFO - TinderboxPrint: CPU user
1,612.9 (33.5%) 05:28:08 INFO - TinderboxPrint: Swap in / out
248,733,696 / 0 05:28:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201800192; Write bytes: 204396544; Read time: 15801; Write time: 448 05:28:08 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 39404544; Write bytes: 298849280; Read time: 384; Write time: 1208 05:28:08 INFO - Running post-run listener: _upload_blobber_files 05:28:08 INFO - Blob upload gear active. 05:28:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:28:08 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 05:28:08 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'] 05:28:08 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 05:28:08 INFO - (blobuploader) - INFO - Open directory for files ... 05:28:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:28:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:28:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:09 INFO - (blobuploader) - INFO - Done attempting. 05:28:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:28:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:10 INFO - (blobuploader) - INFO - Done attempting. 05:28:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:11 INFO - (blobuploader) - INFO - Done attempting. 05:28:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:28:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:14 INFO - (blobuploader) - INFO - Done attempting. 05:28:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:28:14 INFO - Return code: 0 05:28:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:28:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:28:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4a5ef9119e723a086b65880df1bf9167d83959044d7051875f21704ceb179ac80745a0b753883826f4cc821d83d455f9ff21dddf64cb8b19c2e4a2b65b498ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98f293ede6070255c9ad7d792ec2ca5ae636005a371a312af828705e596edd233ebd5fd15f4be76f7526191e3ad9bdb9920b324c9ba41dbe3921bff132abc250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/048df77a4a66f3559f79e3dbc83fe9c6442555118ff7500431fd6c44ad60cf3df4a9e68120531c0409a59f569bc3d6cd94a53615a2b6a87274d0f54eca8909e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1be96527e2e629ada55466be4077047a4b5699fd1c5204f1ed9ce4fa199588d428a96728659b100ae4bde43e7f3cb61b2ad49308d3412ee4df33724b8b4f26d"} 05:28:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:28:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:28:14 INFO - Contents: 05:28:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4a5ef9119e723a086b65880df1bf9167d83959044d7051875f21704ceb179ac80745a0b753883826f4cc821d83d455f9ff21dddf64cb8b19c2e4a2b65b498ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98f293ede6070255c9ad7d792ec2ca5ae636005a371a312af828705e596edd233ebd5fd15f4be76f7526191e3ad9bdb9920b324c9ba41dbe3921bff132abc250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/048df77a4a66f3559f79e3dbc83fe9c6442555118ff7500431fd6c44ad60cf3df4a9e68120531c0409a59f569bc3d6cd94a53615a2b6a87274d0f54eca8909e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1be96527e2e629ada55466be4077047a4b5699fd1c5204f1ed9ce4fa199588d428a96728659b100ae4bde43e7f3cb61b2ad49308d3412ee4df33724b8b4f26d"} 05:28:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:28:14 INFO - Copying logs to upload dir... 05:28:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:28:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.007266 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-15 05:28:14.187803) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 05:28:14.191431) ========= 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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/c4a5ef9119e723a086b65880df1bf9167d83959044d7051875f21704ceb179ac80745a0b753883826f4cc821d83d455f9ff21dddf64cb8b19c2e4a2b65b498ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98f293ede6070255c9ad7d792ec2ca5ae636005a371a312af828705e596edd233ebd5fd15f4be76f7526191e3ad9bdb9920b324c9ba41dbe3921bff132abc250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/048df77a4a66f3559f79e3dbc83fe9c6442555118ff7500431fd6c44ad60cf3df4a9e68120531c0409a59f569bc3d6cd94a53615a2b6a87274d0f54eca8909e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1be96527e2e629ada55466be4077047a4b5699fd1c5204f1ed9ce4fa199588d428a96728659b100ae4bde43e7f3cb61b2ad49308d3412ee4df33724b8b4f26d"} build_url:https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.013259 build_url: 'https://queue.taskcluster.net/v1/task/SwKNLYfdS2-FJFEEWhhQwg/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/c4a5ef9119e723a086b65880df1bf9167d83959044d7051875f21704ceb179ac80745a0b753883826f4cc821d83d455f9ff21dddf64cb8b19c2e4a2b65b498ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98f293ede6070255c9ad7d792ec2ca5ae636005a371a312af828705e596edd233ebd5fd15f4be76f7526191e3ad9bdb9920b324c9ba41dbe3921bff132abc250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/048df77a4a66f3559f79e3dbc83fe9c6442555118ff7500431fd6c44ad60cf3df4a9e68120531c0409a59f569bc3d6cd94a53615a2b6a87274d0f54eca8909e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1be96527e2e629ada55466be4077047a4b5699fd1c5204f1ed9ce4fa199588d428a96728659b100ae4bde43e7f3cb61b2ad49308d3412ee4df33724b8b4f26d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 05:28:14.229106) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:28:14.229527) ========= 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.tW1S8fkiFK/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.1DedyqnJ7J/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005608 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 05:28:14.278435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 05:28:14.278842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 05:28:14.279272) ========= ========= Total master_lag: 0.18 =========