builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0226 starttime: 1484224121.74 results: success (0) buildid: 20170112030301 builduid: a87348b09fb347a4a60989c270650435 revision: 97d6f73643940256c0eb61e384c49bf6f6c49847 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:41.738980) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:41.739479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:41.739783) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.232346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.232916) ========= 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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-12 04:28:42-- 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% 39.4M=0s 2017-01-12 04:28:42 (39.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.190081 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.448951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.449312) ========= 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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.025427 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.498546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 04:28:42.499031) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97d6f73643940256c0eb61e384c49bf6f6c49847 --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 97d6f73643940256c0eb61e384c49bf6f6c49847 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-12 04:28:42,607 truncating revision to first 12 chars 2017-01-12 04:28:42,607 Setting DEBUG logging. 2017-01-12 04:28:42,607 attempt 1/10 2017-01-12 04:28:42,607 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97d6f7364394?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 04:28:43,392 unpacking tar archive at: mozilla-central-97d6f7364394/testing/mozharness/ program finished with exit code 0 elapsedTime=1.064549 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 04:28:43.595304) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.595601) ========= script_repo_revision: 97d6f73643940256c0eb61e384c49bf6f6c49847 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.595990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.596252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.615924) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-12 04:28:43.616211) ========= /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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:28:43 INFO - MultiFileLogger online at 20170112 04:28:43 in /builds/slave/test 04:28:43 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 04:28:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:43 INFO - {'append_to_log': False, 04:28:43 INFO - 'base_work_dir': '/builds/slave/test', 04:28:43 INFO - 'blob_upload_branch': 'Firefox', 04:28:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:43 INFO - 'branch': 'Firefox', 04:28:43 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:43 INFO - 'config_files': ('talos/mac_config.py',), 04:28:43 INFO - 'default_actions': ('clobber', 04:28:43 INFO - 'read-buildbot-config', 04:28:43 INFO - 'download-and-extract', 04:28:43 INFO - 'populate-webroot', 04:28:43 INFO - 'create-virtualenv', 04:28:43 INFO - 'install', 04:28:43 INFO - 'run-tests'), 04:28:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:43 INFO - 'download_minidump_stackwalk': True, 04:28:43 INFO - 'download_symbols': 'ondemand', 04:28:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:43 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:43 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:43 INFO - 'installer_path': 'installer.exe', 04:28:43 INFO - 'log_level': 'info', 04:28:43 INFO - 'log_name': 'talos', 04:28:43 INFO - 'log_to_console': True, 04:28:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:28:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:28:43 INFO - 'opt_config_files': (), 04:28:43 INFO - 'pip_index': False, 04:28:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:43 INFO - 'cmd': ('bash', 04:28:43 INFO - '-c', 04:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:43 INFO - 'enabled': True, 04:28:43 INFO - 'halt_on_failure': False, 04:28:43 INFO - 'name': 'check_screen_resolution'},), 04:28:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:43 INFO - 'cmd': ('bash', 04:28:43 INFO - '-c', 04:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:43 INFO - 'enabled': True, 04:28:43 INFO - 'halt_on_failure': False, 04:28:43 INFO - 'name': 'check_screen_resolution'},), 04:28:43 INFO - 'run_cmd_checks_enabled': True, 04:28:43 INFO - 'sps_profile': False, 04:28:43 INFO - 'sps_profile_interval': 0, 04:28:43 INFO - 'suite': 'dromaeojs', 04:28:43 INFO - 'system_bits': '32', 04:28:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:43 INFO - 'title': 't-yosemite-r7-0226', 04:28:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:43 INFO - 'use_talos_json': True, 04:28:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:43 INFO - 'work_dir': 'build'} 04:28:43 INFO - [mozharness: 2017-01-12 12:28:43.824269Z] Running clobber step. 04:28:43 INFO - Running pre-action listener: _resource_record_pre_action 04:28:43 INFO - Running main action method: clobber 04:28:43 INFO - rmtree: /builds/slave/test/build 04:28:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:46 INFO - Running post-action listener: _resource_record_post_action 04:28:46 INFO - [mozharness: 2017-01-12 12:28:46.390912Z] Finished clobber step (success) 04:28:46 INFO - [mozharness: 2017-01-12 12:28:46.391083Z] Running read-buildbot-config step. 04:28:46 INFO - Running pre-action listener: _resource_record_pre_action 04:28:46 INFO - Running main action method: read_buildbot_config 04:28:46 INFO - Using buildbot properties: 04:28:46 INFO - { 04:28:46 INFO - "project": "", 04:28:46 INFO - "product": "firefox", 04:28:46 INFO - "script_repo_revision": "production", 04:28:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:28:46 INFO - "repository": "", 04:28:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:28:46 INFO - "buildid": "20170112030301", 04:28:46 INFO - "stage_platform": "macosx64", 04:28:46 INFO - "basedir": "/builds/slave/test", 04:28:46 INFO - "nightly_build": "True", 04:28:46 INFO - "buildnumber": 4, 04:28:46 INFO - "slavename": "t-yosemite-r7-0226", 04:28:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:28:46 INFO - "platform": "yosemite_r7", 04:28:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:28:46 INFO - "branch": "mozilla-central", 04:28:46 INFO - "repo_path": "mozilla-central", 04:28:46 INFO - "revision": "97d6f73643940256c0eb61e384c49bf6f6c49847", 04:28:46 INFO - "pgo_build": "False", 04:28:46 INFO - "builduid": "a87348b09fb347a4a60989c270650435", 04:28:46 INFO - "slavebuilddir": "test" 04:28:46 INFO - } 04:28:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:28:46 INFO - Running post-action listener: _resource_record_post_action 04:28:46 INFO - [mozharness: 2017-01-12 12:28:46.394500Z] Finished read-buildbot-config step (success) 04:28:46 INFO - [mozharness: 2017-01-12 12:28:46.394617Z] Running download-and-extract step. 04:28:46 INFO - Running pre-action listener: _resource_record_pre_action 04:28:46 INFO - Running main action method: download_and_extract 04:28:46 INFO - mkdir: /builds/slave/test/build/tests 04:28: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')]} 04:28:46 INFO - trying https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:46 INFO - Downloading https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:28:47 INFO - Downloaded 1199 bytes. 04:28:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:47 INFO - Using the following test package requirements: 04:28:47 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:28:47 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:28:47 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:28:47 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'jsshell-mac.zip'], 04:28:47 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:28:47 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:28:47 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:28:47 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:28:47 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:28:47 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:47 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:28:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:28:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:28:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:47 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:28:48 INFO - Expected file size: 16314361 04:28:48 INFO - Obtained file size: 16314361 04:28:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:28:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:28:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:55 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:28:56 INFO - Expected file size: 16314361 04:28:56 INFO - Obtained file size: 16314361 04:29:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:29:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:29:03 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:29:04 INFO - Expected file size: 14403532 04:29:04 INFO - Obtained file size: 14403532 04:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:05 INFO - trying https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:05 INFO - Downloading https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:29:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:29:07 INFO - Downloaded 70209990 bytes. 04:29:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:07 INFO - mkdir: /builds/slave/test/properties 04:29:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:07 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:07 INFO - Contents: 04:29:07 INFO - build_url:https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:08 INFO - Running post-action listener: _resource_record_post_action 04:29:08 INFO - Running post-action listener: set_extra_try_arguments 04:29:08 INFO - [mozharness: 2017-01-12 12:29:08.390532Z] Finished download-and-extract step (success) 04:29:08 INFO - [mozharness: 2017-01-12 12:29:08.390690Z] Running populate-webroot step. 04:29:08 INFO - Running pre-action listener: _resource_record_pre_action 04:29:08 INFO - Running main action method: populate_webroot 04:29:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:08 INFO - '--tppagecycles', 04:29:08 INFO - '7'], 04:29:08 INFO - 'tests': ['tsvgm']}}, 04:29:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['damp', 'tps']}, 04:29:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'tests': ['damp', 'tps']}, 04:29:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['dromaeo_dom']}, 04:29:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['basic_compositor_video']}, 04:29:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:29:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tsvgx', 04:29:08 INFO - 'tsvgr_opacity', 04:29:08 INFO - 'tart', 04:29:08 INFO - 'tscrollx', 04:29:08 INFO - 'cart', 04:29:08 INFO - 'tsvg_static']}, 04:29:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:08 INFO - 'tsvgr_opacity', 04:29:08 INFO - 'tart', 04:29:08 INFO - 'tscrollx', 04:29:08 INFO - 'cart', 04:29:08 INFO - 'tsvg_static']}, 04:29:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tp5o']}, 04:29:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:29:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s', 04:29:08 INFO - '--xperf_path', 04:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:08 INFO - 'tests': ['tp5n']}, 04:29:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:29:08 INFO - 'talos_options': ['--xperf_path', 04:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:08 INFO - 'tests': ['tp5n']}}, 04:29:08 INFO - 'talos.zip': {'path': '', 04:29:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:08 INFO - Running post-action listener: _resource_record_post_action 04:29:08 INFO - [mozharness: 2017-01-12 12:29:08.410004Z] Finished populate-webroot step (success) 04:29:08 INFO - [mozharness: 2017-01-12 12:29:08.410137Z] Running create-virtualenv step. 04:29:08 INFO - Running pre-action listener: _resource_record_pre_action 04:29:08 INFO - Running main action method: create_virtualenv 04:29:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:08 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 04:29:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:08 INFO - Using real prefix '/tools/python27' 04:29:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:12 INFO - Installing pip.................done. 04:29:12 INFO - Return code: 0 04:29:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:29:12 INFO - Reading from file tmpfile_stdout 04:29:12 INFO - Output received: 04:29:12 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) 04:29:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:12 INFO - 'HOME': '/Users/cltbld', 04:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:12 INFO - 'LOGNAME': 'cltbld', 04:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:12 INFO - 'MOZ_NO_REMOTE': '1', 04:29:12 INFO - 'NO_EM_RESTART': '1', 04:29:12 INFO - 'PAGER': '/bin/cat', 04:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:12 INFO - 'PWD': '/builds/slave/test', 04:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:12 INFO - 'SHELL': '/bin/bash', 04:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:12 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:12 INFO - 'USER': 'cltbld', 04:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:12 INFO - 'XPC_FLAGS': '0x0', 04:29:12 INFO - 'XPC_SERVICE_NAME': '0', 04:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:12 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 04:29:12 INFO - Cleaning up... 04:29:12 INFO - Return code: 0 04:29:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:12 INFO - 'HOME': '/Users/cltbld', 04:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:12 INFO - 'LOGNAME': 'cltbld', 04:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:12 INFO - 'MOZ_NO_REMOTE': '1', 04:29:12 INFO - 'NO_EM_RESTART': '1', 04:29:12 INFO - 'PAGER': '/bin/cat', 04:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:12 INFO - 'PWD': '/builds/slave/test', 04:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:12 INFO - 'SHELL': '/bin/bash', 04:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:12 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:12 INFO - 'USER': 'cltbld', 04:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:12 INFO - 'XPC_FLAGS': '0x0', 04:29:12 INFO - 'XPC_SERVICE_NAME': '0', 04:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:13 INFO - Downloading/unpacking psutil>=3.1.1 04:29:13 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 04:29:13 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 04:29:13 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 04:29:13 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 04:29:13 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 04:29:13 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 04:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:17 INFO - Installing collected packages: psutil 04:29:17 INFO - Running setup.py install for psutil 04:29:18 INFO - building 'psutil._psutil_osx' extension 04:29:18 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 04:29:18 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 04:29:18 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 04:29:18 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 04:29:18 INFO - building 'psutil._psutil_posix' extension 04:29:18 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 04:29:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:18 INFO - ^ 04:29:18 INFO - 1 warning generated. 04:29:18 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 04:29:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:19 INFO - Successfully installed psutil 04:29:19 INFO - Cleaning up... 04:29:19 INFO - Return code: 0 04:29:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:29:19 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')]} 04:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:19 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')]} 04:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:19 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:19 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 04:29:19 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 04:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:19 INFO - 'HOME': '/Users/cltbld', 04:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:19 INFO - 'LOGNAME': 'cltbld', 04:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:19 INFO - 'MOZ_NO_REMOTE': '1', 04:29:19 INFO - 'NO_EM_RESTART': '1', 04:29:19 INFO - 'PAGER': '/bin/cat', 04:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:19 INFO - 'PWD': '/builds/slave/test', 04:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:19 INFO - 'SHELL': '/bin/bash', 04:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:19 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:19 INFO - 'USER': 'cltbld', 04:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:19 INFO - 'XPC_FLAGS': '0x0', 04:29:19 INFO - 'XPC_SERVICE_NAME': '0', 04:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:29:19 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 04:29:19 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 04:29:19 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 04:29:19 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 04:29:19 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 04:29:19 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 04:29:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:29:22 INFO - Installing collected packages: mozsystemmonitor 04:29:22 INFO - Running setup.py install for mozsystemmonitor 04:29:22 INFO - Successfully installed mozsystemmonitor 04:29:22 INFO - Cleaning up... 04:29:22 INFO - Return code: 0 04:29:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:22 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:22 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 04:29:22 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 04:29:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:22 INFO - 'HOME': '/Users/cltbld', 04:29:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:22 INFO - 'LOGNAME': 'cltbld', 04:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:22 INFO - 'MOZ_NO_REMOTE': '1', 04:29:22 INFO - 'NO_EM_RESTART': '1', 04:29:22 INFO - 'PAGER': '/bin/cat', 04:29:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:22 INFO - 'PWD': '/builds/slave/test', 04:29:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:22 INFO - 'SHELL': '/bin/bash', 04:29:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:22 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:22 INFO - 'USER': 'cltbld', 04:29:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:22 INFO - 'XPC_FLAGS': '0x0', 04:29:22 INFO - 'XPC_SERVICE_NAME': '0', 04:29:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:22 INFO - Downloading/unpacking jsonschema==2.5.1 04:29:22 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 04:29:22 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 04:29:22 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 04:29:22 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 04:29:22 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 04:29:22 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 04:29:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:29:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:29:25 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 04:29:25 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 04:29:25 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 04:29:25 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 04:29:25 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 04:29:25 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 04:29:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:29:26 INFO - warning: no files found matching '*.txt' 04:29:26 INFO - no previously-included directories found matching 'build' 04:29:26 INFO - no previously-included directories found matching 'dist' 04:29:26 INFO - no previously-included directories found matching '.git*' 04:29:26 INFO - Installing collected packages: jsonschema, functools32 04:29:26 INFO - Running setup.py install for functools32 04:29:26 INFO - warning: no files found matching '*.txt' 04:29:26 INFO - no previously-included directories found matching 'build' 04:29:26 INFO - no previously-included directories found matching 'dist' 04:29:26 INFO - no previously-included directories found matching '.git*' 04:29:26 INFO - Successfully installed jsonschema functools32 04:29:26 INFO - Cleaning up... 04:29:26 INFO - Return code: 0 04:29:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:26 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 04:29:26 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 04:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:26 INFO - 'HOME': '/Users/cltbld', 04:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:26 INFO - 'LOGNAME': 'cltbld', 04:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:26 INFO - 'MOZ_NO_REMOTE': '1', 04:29:26 INFO - 'NO_EM_RESTART': '1', 04:29:26 INFO - 'PAGER': '/bin/cat', 04:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:26 INFO - 'PWD': '/builds/slave/test', 04:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:26 INFO - 'SHELL': '/bin/bash', 04:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:26 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:26 INFO - 'USER': 'cltbld', 04:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:26 INFO - 'XPC_FLAGS': '0x0', 04:29:26 INFO - 'XPC_SERVICE_NAME': '0', 04:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:29:26 INFO - Cleaning up... 04:29:26 INFO - Return code: 0 04:29:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:26 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:26 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 04:29:26 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 04:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:26 INFO - 'HOME': '/Users/cltbld', 04:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:26 INFO - 'LOGNAME': 'cltbld', 04:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:26 INFO - 'MOZ_NO_REMOTE': '1', 04:29:26 INFO - 'NO_EM_RESTART': '1', 04:29:26 INFO - 'PAGER': '/bin/cat', 04:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:26 INFO - 'PWD': '/builds/slave/test', 04:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:26 INFO - 'SHELL': '/bin/bash', 04:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:26 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:26 INFO - 'USER': 'cltbld', 04:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:26 INFO - 'XPC_FLAGS': '0x0', 04:29:26 INFO - 'XPC_SERVICE_NAME': '0', 04:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:27 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:27 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 04:29:27 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 04:29:27 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 04:29:27 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 04:29:27 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 04:29:27 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 04:29:31 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:31 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 04:29:31 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 04:29:31 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 04:29:31 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 04:29:31 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 04:29:31 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 04:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:32 INFO - Downloading docopt-0.6.1.tar.gz 04:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:32 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:32 INFO - Running setup.py install for blobuploader 04:29:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:32 INFO - Running setup.py install for requests 04:29:33 INFO - Running setup.py install for docopt 04:29:33 INFO - Successfully installed blobuploader requests docopt 04:29:33 INFO - Cleaning up... 04:29:33 INFO - Return code: 0 04:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:33 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:33 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 04:29:33 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 04:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:33 INFO - 'HOME': '/Users/cltbld', 04:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:33 INFO - 'LOGNAME': 'cltbld', 04:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:33 INFO - 'MOZ_NO_REMOTE': '1', 04:29:33 INFO - 'NO_EM_RESTART': '1', 04:29:33 INFO - 'PAGER': '/bin/cat', 04:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:33 INFO - 'PWD': '/builds/slave/test', 04:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:33 INFO - 'SHELL': '/bin/bash', 04:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:33 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:33 INFO - 'USER': 'cltbld', 04:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:33 INFO - 'XPC_FLAGS': '0x0', 04:29:33 INFO - 'XPC_SERVICE_NAME': '0', 04:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:33 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-BKu0aV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:33 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ZBedge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:33 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RqhpHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-gkQ5Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Qs6JPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cRpkn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-I1ngNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ZndH9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-AvIivG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-sR_cbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-I3l4sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-G_LnXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-oCpe3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2XYGmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-EKi6C1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KC_eBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-thUoLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:35 INFO - Running setup.py install for manifestparser 04:29:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Running setup.py install for mozcrash 04:29:35 INFO - Running setup.py install for mozdebug 04:29:35 INFO - Running setup.py install for mozdevice 04:29:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Running setup.py install for mozfile 04:29:36 INFO - Running setup.py install for mozhttpd 04:29:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Running setup.py install for mozinfo 04:29:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Running setup.py install for mozInstall 04:29:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:36 INFO - Running setup.py install for mozleak 04:29:36 INFO - Running setup.py install for mozlog 04:29:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Running setup.py install for moznetwork 04:29:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Running setup.py install for mozprocess 04:29:37 INFO - Running setup.py install for mozprofile 04:29:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Running setup.py install for mozrunner 04:29:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:37 INFO - Running setup.py install for mozscreenshot 04:29:37 INFO - Running setup.py install for moztest 04:29:37 INFO - Running setup.py install for mozversion 04:29:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:38 INFO - Cleaning up... 04:29:38 INFO - Return code: 0 04:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:38 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:38 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 04:29:38 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 04:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:38 INFO - 'HOME': '/Users/cltbld', 04:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:38 INFO - 'LOGNAME': 'cltbld', 04:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:38 INFO - 'MOZ_NO_REMOTE': '1', 04:29:38 INFO - 'NO_EM_RESTART': '1', 04:29:38 INFO - 'PAGER': '/bin/cat', 04:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:38 INFO - 'PWD': '/builds/slave/test', 04:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:38 INFO - 'SHELL': '/bin/bash', 04:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:38 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:38 INFO - 'USER': 'cltbld', 04:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:38 INFO - 'XPC_FLAGS': '0x0', 04:29:38 INFO - 'XPC_SERVICE_NAME': '0', 04:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PZzSYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:38 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)) 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-biRoak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:38 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)) 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MvjpsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:38 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)) 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4SUhEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:38 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)) 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-mhHZp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:38 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)) 04:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:38 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-YAJwSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-s2hXTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zZ4Zcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Mz3kZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-lLRwUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KvAeaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PnE2Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vUxWJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2KC4Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XEMk_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:39 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)) 04:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:39 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-JUxBwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:40 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)) 04:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:40 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xH908I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:40 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)) 04:29:40 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)) 04:29:40 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)) 04:29:40 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)) 04:29:40 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)) 04:29:40 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)) 04:29:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:42 INFO - Downloading blessings-1.6.tar.gz 04:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:42 INFO - Installing collected packages: blessings 04:29:42 INFO - Running setup.py install for blessings 04:29:43 INFO - Successfully installed blessings 04:29:43 INFO - Cleaning up... 04:29:43 INFO - Return code: 0 04:29:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:43 INFO - Reading from file tmpfile_stdout 04:29:43 INFO - Current package versions: 04:29:43 INFO - blessings == 1.6 04:29:43 INFO - blobuploader == 1.2.4 04:29:43 INFO - docopt == 0.6.1 04:29:43 INFO - functools32 == 3.2.3-2 04:29:43 INFO - jsonschema == 2.5.1 04:29:43 INFO - manifestparser == 1.1 04:29:43 INFO - mozInstall == 1.12 04:29:43 INFO - mozcrash == 1.0 04:29:43 INFO - mozdebug == 0.1 04:29:43 INFO - mozdevice == 0.49 04:29:43 INFO - mozfile == 1.2 04:29:43 INFO - mozhttpd == 0.7 04:29:43 INFO - mozinfo == 0.9 04:29:43 INFO - mozleak == 0.1 04:29:43 INFO - mozlog == 3.4 04:29:43 INFO - moznetwork == 0.27 04:29:43 INFO - mozprocess == 0.24 04:29:43 INFO - mozprofile == 0.28 04:29:43 INFO - mozrunner == 6.13 04:29:43 INFO - mozscreenshot == 0.1 04:29:43 INFO - mozsystemmonitor == 0.3 04:29:43 INFO - moztest == 0.8 04:29:43 INFO - mozversion == 1.4 04:29:43 INFO - psutil == 3.1.1 04:29:43 INFO - requests == 1.2.3 04:29:43 INFO - wsgiref == 0.1.2 04:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:43 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')]} 04:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:43 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')]} 04:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:43 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:43 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 04:29:43 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 04:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:43 INFO - 'HOME': '/Users/cltbld', 04:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:43 INFO - 'LOGNAME': 'cltbld', 04:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:43 INFO - 'MOZ_NO_REMOTE': '1', 04:29:43 INFO - 'NO_EM_RESTART': '1', 04:29:43 INFO - 'PAGER': '/bin/cat', 04:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:43 INFO - 'PWD': '/builds/slave/test', 04:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:43 INFO - 'SHELL': '/bin/bash', 04:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:43 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:43 INFO - 'USER': 'cltbld', 04:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:43 INFO - 'XPC_FLAGS': '0x0', 04:29:43 INFO - 'XPC_SERVICE_NAME': '0', 04:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 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)) 04:29:43 INFO - Cleaning up... 04:29:43 INFO - Return code: 0 04:29:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29:43 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')]} 04:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:43 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')]} 04:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:43 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 0x10f7a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a570dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, '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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 04:29:43 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 04:29:43 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 04:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:43 INFO - 'HOME': '/Users/cltbld', 04:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:43 INFO - 'LOGNAME': 'cltbld', 04:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:43 INFO - 'MOZ_NO_REMOTE': '1', 04:29:43 INFO - 'NO_EM_RESTART': '1', 04:29:43 INFO - 'PAGER': '/bin/cat', 04:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:43 INFO - 'PWD': '/builds/slave/test', 04:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:43 INFO - 'SHELL': '/bin/bash', 04:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:29:43 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:43 INFO - 'USER': 'cltbld', 04:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:43 INFO - 'XPC_FLAGS': '0x0', 04:29:43 INFO - 'XPC_SERVICE_NAME': '0', 04:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:29:44 INFO - Cleaning up... 04:29:44 INFO - Return code: 0 04:29:44 INFO - Running post-action listener: _resource_record_post_action 04:29:44 INFO - Running post-action listener: _start_resource_monitoring 04:29:44 INFO - Starting resource monitoring. 04:29:44 INFO - [mozharness: 2017-01-12 12:29:44.051103Z] Finished create-virtualenv step (success) 04:29:44 INFO - [mozharness: 2017-01-12 12:29:44.052065Z] Running install step. 04:29:44 INFO - Running pre-action listener: _resource_record_pre_action 04:29:44 INFO - Running main action method: install 04:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:44 INFO - Reading from file tmpfile_stdout 04:29:44 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:44 INFO - Reading from file tmpfile_stdout 04:29:44 INFO - Output received: 04:29:44 INFO - Usage: mozinstall [options] installer 04:29:44 INFO - Options: 04:29:44 INFO - -h, --help show this help message and exit 04:29:44 INFO - -d DEST, --destination=DEST 04:29:44 INFO - Directory to install application into. [default: 04:29:44 INFO - "/builds/slave/test"] 04:29:44 INFO - --app=APP Application being installed. [default: firefox] 04:29:44 INFO - mkdir: /builds/slave/test/build/application 04:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:30:06 INFO - Reading from file tmpfile_stdout 04:30:06 INFO - Output received: 04:30:06 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:30:06 INFO - Running post-action listener: _resource_record_post_action 04:30:06 INFO - [mozharness: 2017-01-12 12:30:06.549166Z] Finished install step (success) 04:30:06 INFO - [mozharness: 2017-01-12 12:30:06.549300Z] Running run-tests step. 04:30:06 INFO - Running pre-action listener: _resource_record_pre_action 04:30:06 INFO - Running main action method: run_tests 04:30:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:06 INFO - 2017-01-12 04:30:06.563 screenresolution[1629:7734] starting screenresolution argv=screenresolution get 04:30:06 INFO - 2017-01-12 04:30:06.566 screenresolution[1629:7734] Display 0: 1600x1200x32@60 04:30:06 INFO - 2017-01-12 04:30:06.572 screenresolution[1630:7736] starting screenresolution argv=screenresolution list 04:30:06 INFO - Available Modes on Display 0 04:30:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:06 INFO - Intel Iris: 04:30:06 INFO - Chipset Model: Intel Iris 04:30:06 INFO - Type: GPU 04:30:06 INFO - Bus: Built-In 04:30:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:06 INFO - Vendor: Intel (0x8086) 04:30:06 INFO - Device ID: 0x0a2e 04:30:06 INFO - Revision ID: 0x0009 04:30:06 INFO - Displays: 04:30:06 INFO - Display: 04:30:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:06 INFO - Display Serial Number: 1600x1200 60 04:30:06 INFO - Main Display: Yes 04:30:06 INFO - Mirror: Off 04:30:06 INFO - Online: Yes 04:30:06 INFO - Rotation: Supported 04:30:06 INFO - Return code: 0 04:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:06 INFO - Python 2.7.3 04:30:06 INFO - Return code: 0 04:30:06 INFO - grabbing minidump binary from tooltool 04:30:06 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')]} 04:30:06 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 0x10f57ed30>, '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 0x10f4a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f573be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:06 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 04:30:06 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 04:30:06 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 04:30:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:06 INFO - Return code: 0 04:30:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:06 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/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 04:30:06 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/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 04:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnOfXMIgK7/Render', 04:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:06 INFO - 'HOME': '/Users/cltbld', 04:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:06 INFO - 'LOGNAME': 'cltbld', 04:30:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:06 INFO - 'MOZ_NO_REMOTE': '1', 04:30:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:06 INFO - 'NO_EM_RESTART': '1', 04:30:06 INFO - 'PAGER': '/bin/cat', 04:30:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:06 INFO - 'PWD': '/builds/slave/test', 04:30:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:06 INFO - 'SHELL': '/bin/bash', 04:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ggOFgOEIFo/Listeners', 04:30:06 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 04:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:06 INFO - 'USER': 'cltbld', 04:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:06 INFO - 'XPC_FLAGS': '0x0', 04:30:06 INFO - 'XPC_SERVICE_NAME': '0', 04:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:06 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/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 04:30:07 INFO - mozversion application_buildid: 20170112030301 04:30:07 INFO - mozversion application_changeset: 97d6f73643940256c0eb61e384c49bf6f6c49847 04:30:07 INFO - mozversion application_display_name: Nightly 04:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:07 INFO - mozversion application_name: Firefox 04:30:07 INFO - mozversion application_remotingname: firefox 04:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:30:07 INFO - mozversion application_vendor: Mozilla 04:30:07 INFO - mozversion application_version: 53.0a1 04:30:07 INFO - mozversion platform_buildid: 20170112030301 04:30:07 INFO - mozversion platform_changeset: 97d6f73643940256c0eb61e384c49bf6f6c49847 04:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:30:07 INFO - mozversion platform_version: 53.0a1 04:30:07 INFO - using testdate: 1484224207 04:30:07 INFO - actual date: 1484224207 04:30:07 INFO - starting webserver on 'localhost:49241' 04:30:07 INFO - SUITE-START | Running 2 tests 04:30:07 INFO - TEST-START | dromaeo_css 04:30:07 INFO - operating with platform_type : mac_ 04:30:07 INFO - Initialising browser for dromaeo_css test... 04:30:07 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpdCmoGF/profile http://localhost:49241/getInfo.html) 04:30:09 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 04:30:09 INFO - PROCESS | 1640 | colorDepth:24 04:30:09 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 04:30:09 INFO - PROCESS | 1640 | __metrics 04:30:09 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:30:09 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:30:10 INFO - TEST-INFO | 1640: exit 0 04:30:10 INFO - Browser initialized. 04:30:10 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:10 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpdCmoGF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:22 INFO - PROCESS | 1643 | RSS: Main: 235790336 04:30:22 INFO - PROCESS | 1643 | 04:33:26 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:33:28 INFO - PROCESS | 1643 | RSS: Main: 333844480 04:33:28 INFO - PROCESS | 1643 | 04:35:11 INFO - PROCESS | 1643 | ************************* 04:35:11 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 04:35:11 INFO - PROCESS | 1643 | 04:35:11 INFO - PROCESS | 1643 | Full message: ReferenceError: fetch is not defined 04:35:11 INFO - PROCESS | 1643 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:35:11 INFO - PROCESS | 1643 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:35:11 INFO - PROCESS | 1643 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:35:11 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:11 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:11 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:11 INFO - PROCESS | 1643 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:35:11 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:11 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:11 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:11 INFO - PROCESS | 1643 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:35:11 INFO - PROCESS | 1643 | 04:35:11 INFO - PROCESS | 1643 | ************************* 04:36:31 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:36:31 INFO - PROCESS | 1643 | RSS: Main: 365338624 04:36:31 INFO - PROCESS | 1643 | 04:39:33 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:39:34 INFO - PROCESS | 1643 | RSS: Main: 361861120 04:39:34 INFO - PROCESS | 1643 | 04:42:36 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:42:37 INFO - PROCESS | 1643 | RSS: Main: 341172224 04:42:37 INFO - PROCESS | 1643 | 04:45:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:45:41 INFO - PROCESS | 1643 | RSS: Main: 324169728 04:45:41 INFO - PROCESS | 1643 | 04:48:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 04:48:45 INFO - PROCESS | 1643 | RSS: Main: 578732032 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | __start_tp_report 04:48:45 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 04:48:45 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 04:48:45 INFO - PROCESS | 1643 | |i|pagename|runs| 04:48:45 INFO - PROCESS | 1643 | |0;dojo.html;2953.6603290621556;3306.38831816114;3318.518814771858;3324.5678061851963;3384.7630665853385;5518.475744804237;5559.360673522546;5607.803726869561;5670.319561652603;5672.46111619396;5592.944070559293;5637.520810731093;5654.038813401721;5681.318241810988;5722.79970201043;5110.795568177268;5142.537171654551;5206.479352064801;5216.634835561512;5239.266502689627;5125;5235.816746413876;5248.448912864133;5252.238425428311;5252.921206181911;5217.34783152106;5230.058589453897;5234.084035293828;5238.05714971305;5289.20661900716;5117.33474648297;5131.4098878629065;5146.485351464861;5160.587153027786;5171.896562068775;4232.640225580829;5167.974160129213;5200.323957885488;5229.398619158807;5234.842954711365;5654.293213348332;5659.179418984224;5667.206591077253;5673.574481913873;5683.630564013326;6080.452759251689;6250.281217659936;6254.46837018854;6258.249010118815;6259.155014073087;6137.693115344259;6158.538109641795;6183.6598987055695;6186.597871138407;6217.782377616807;5683.9715801420725;5739.426057394227;5746.109353050284;5753.884922301572;5795.3045634523705;6248.843778905563;6320.747170113236;6323.462505687068;6335.176427064452;6335.651539165298;5932.555058370639;5969.343372229051;5987.43119403656;6067.180930574405;6085.847853803691;6129.63387754696;6150.876982460415;6197.25632924052;6205.627662340274;6217.844553886099;6044.879102418015;6104.3590423005835;6110.285381942539;6123.295820980644;6156.661383623944;6109.131392048963;6187.319394866561;6214.937850621526;6224.253089629183;6231.906521402183;4945.4312754033745;4947.48051454592;4963.925541116886;4970.329005584272;5001.0998020355955;4385.824567017347;4388.605025547652;4390.978045109754;4399.802008909607;4461.196984542814;5615.241942337732;5735.483806457355;5738.397468265772;5739.512141467939;5740.995325817965;6201.982405159052;6236.095766113888;6244.687765611792;6253.749850005949;6267.091271765568;5465.453454654503;5691;5692.7722891083895;5702.714864256771;5731.197632331477;5360.946390536123;5361.222622719683;5363.731813409314;5373.462653734673;5386.13821788512;6165.198524191826;6246.750129994749;6271.3728627136925;6273.749050037947;6279.529035322461;6235.158253635701;6242.063690446469;6256.343083976117;6266.498680105672;6286.717097730704;5116.539511443988;6154.476869465966;6203.503719702323;6297.433231009231;6313.210848643919;6131.233595800525;6137.662428566472;6138.848241798255;6202.5658203925295;6213.59611625261;4673.532646735435;5281.498257665516;6243.750249989949;6245.750169993149;6283.088952102011;5921.6743079131065;5978.790742323998;6115.694215289307;6134.6625935573975;6144.969275153596;6027.849303767265;6028.758849645965;6035.034394496859;6050.5764596479585;6105.247473116839;5932.110183472513;5940.821775346746;5942.6731529766275;5957.225560677084;5957.3744756799915;5944.762209511571;6011.398860113954;6013.128096426081;6048;6057.303410107894;5923.555733319932;5949.375315591974;5954.136650185786;5965.28416590012;5972.104184372205 04:48:45 INFO - PROCESS | 1643 | |1;ext.html;443.63400194839267;460.26128064456566;499.90001999600076;521.4550794419831;524.9028929648013;30328.09015729526;38211.617883821156;39613.801930990376;39656.603433965654;39708.2058358833;40651.9837004075;41106.561270355545;41131;41177;41193.382099268536;8325.333973282153;9055.094490550942;9280;9280;9293.535323233811;8302.750917472486;10880.401577913211;11085.27945683528;11132.443377831076;11286.379249141293;11159.776804463905;11196.552137914474;11223.270487418333;11291;11310.943445282803;9904.554295056667;10435.739106522398;11099.057664908649;11193.664190074229;11199.384033878092;11051.226414151013;11091.667249982433;11094.722631934186;11124;11133.443327833576;39513.629182249155;39660.80169599162;39977.400338994936;40064.39903401451;40069;4008.118213992917;4035.3543433050863;4106.897327566805;4124.505059392862;4203.789810509493;28649.85675071632;30443.2389190272;30871;30922;30959.38081238385;3099.6900309969046;3233.8544765485376;3254.7396208303517;3303.009097270809;3305.0415379539904;464354;465366.9999999966;465381;465640;468525;59258.40741592615;59653.701731491936;59822.70088649529;59910.801783964074;59929.400705993256;61720.69139654273;61739.38260617426;61755.764884702956;62027.37972620216;62083.6895815518;15500.922495387676;16029.839701603069;16055;16064.429691249345;16087.184742669986;11152.832707509393;11420.486078126338;12400;12487.06347023977;12502.124851260507;14088;15592.986455880331;15792.763108553381;15884.682306353809;16238.23949928515;6069.393060693896;6165;6179.3202747697715;6180.783672571529;6217.533684973645;703.8627467643761;731.1445608637906;855.8716192571164;855.8930133733284;871.0375035585713;26493.337666558105;29747.553786693217;29821.85089074541;30021.849890750407;30450.086497405115;1013.8124446977333;1047.3087762076993;1055.8521806947033;1098.1050443888205;1101.944902754859;14936.925315373353;15416.07503549768;15442.382304707911;15443.459478918408;15769;585936;589629;590967;762247;773489;48858.5114148854;58373.832523349294;58568;59574.702126489094;63583.046254306224;1429.9356528956223;1433.2117335465452;1444.4799872046055;1446.5245449641518;1448.3699590678088;28074.2981425466;28608.546188591958;29093.563596545646;30136.397272054874;31207.84396078005;50114.24828627647;50580;50677.74661126671;50681.746591266805;50733;551134;557185.2814718495;558050;558291;560177;23775.881120594287;24000.759992399853;24200.515989679756;24359.006403687556;24377.878110609334;39644.008899777735;41290;41956;42595.36106958336;42672.933176670835;34770.65229347674;35009.82495087508;35108;35367.46948795718;35673.46489802707;34620;34982.65017349896;35244.29511409809;35592.82203588966;35819.925402237976;34355.312893742484;34411.65588344084;34541;34570.65429345674;35613.109672258404;30457.847710761303;31278.218044548157;31378.686213137575;31546.36907261888;31558.368832623677;21243.893780530998;23629.763702362758;23634.20961864354;23930.162444314366;24171.637425438985 04:48:45 INFO - PROCESS | 1643 | |2;jquery.html;372.73908264215055;388.55316386155937;388.72594820651443;395.42860566481437;421.93881887126366;15076.32156552955;18471.26114955402;20575.679107387467;21168.4707882303;21386;20364.490887727814;21130.788692113074;21437.356879293606;21840.344789656298;21907.890460547675;5874.1776151338845;5950.077737950603;6208.820324138399;6210.223722034746;6220.253569571658;5939.67331796751;6004.279486461609;6082.756689732405;6124.479419249369;6127.35662755411;5802.53579713624;6085.330613632497;6087.3303936566945;6096.329403765582;6103.176071230394;6127.111568822518;6159.692015399212;6175.320714721377;6176.351483094255;6177.5057995360485;5084.186530155194;5881.441263080001;5931.072401469496;6078.088286756978;6089.3910608939195;13564.593062208149;13743.931280343635;13796.310184490736;26263.73736262651;26380.0766973155;2298.6666933294746;2591.7926565874686;2612.7648511634043;2636.7231440698833;2660.3881107345223;8074.74841399584;9532.616845788552;10061.496925153786;10123.848142277871;10346.948265258625;1782.8217178282198;1817.2549254805467;1838.4484654603566;1867.1131212674006;1882;89830;103198;109154.45422772915;110703.44648276787;122878;11130.332180069221;11461.598844040418;11842.171048026556;11855.17013809025;12007.939960300317;15646.139462329682;15657.765133523006;15664.451744188898;15670;15676.529704108667;7120.92879071213;8452.323814094858;8719.389642725075;8859.379843411029;8902.376833621714;8454.277286135693;8461.069282378934;8487.618057187441;8682.218600326001;8707.259882909897;4682.578567928903;4768.6661933665;4770.976145119322;4786.705245196217;4833.226683730586;1694.2206584971052;1971.339601233574;2009.14611290204;2017.8284845787978;2020.1616329223502;996.8853581838036;1001.1190152665607;1012.3015119567475;1023.8720159980003;1033.1115240892825;18135.63728725418;20074.498137546678;21744.238951636933;22009.889950550143;22088.33734987953;1022.1771473963382;1037.4138611684375;1039.8908114648004;1065.6217042949797;1076.7039064257392;10863.239573229956;11326.716832079099;11332.830007549894;11341.489632966537;11353;695494;696417;696720;702021;714539;115657;116810;137176.79524866326;142310;160278.1986090062;987.5407935310116;996.8305388084043;1024.3904876598413;1026.1790567545934;1028.9691309260734;19150;19367.612647747246;19395.51511212231;19438.51403714862;19488.1230344632;54892.72553637206;58912.70543647254;59745.402545975725;59829.50426239379;60945.47636309128;86729;88601;89387.55306223428;89750;90326.64510032487;7707.229277072248;8440.409171357946;8551.187637174458;8644.178803013703;8663.306935445024;11126.759867364615;12836.486540538273;12968.286744229157;13235.073544852154;13831.30843457803;10640.84038739435;11485.655430337103;11526.366049867338;11775.411229438665;11861.347625880315;11337.26307789991;11426.828597570877;11536.59621913229;11737.530498779954;11831.290122592673;11677.474513646737;11958.58144964922;11969.880301196876;12014.939925300318;12094.637160885188;8235;8277.213664701843;8626.611802468778;8661.047284798793;8730.738077857675;8487.872681909652;11390.860913908542;11446.82829757571;11451.770964580828;11861.881381186078 04:48:45 INFO - PROCESS | 1643 | |3;mootools.html;1350.4800651749074;1400.4118270326462;1448.1745405119086;1479.1347061968747;1483.2509582660757;7328.853422931538;7702.383809295257;7844.490108142973;8125.1062383137805;8137.18628137186;8005.479643823148;8096.878546821802;8107.473014254069;8111.391645626572;8120.2691757741795;2031.4616626593966;2260.660900864875;2301.689271948282;2306.446452851312;2308.0190918859503;2111.4826867417505;2238.473958619723;2305.538892221554;2314.6412306092584;2319.246244970383;2273.079402841852;2292.7363353214428;2298.9195378161767;2300.5168914527967;2302.735185453669;2263.649134384165;2282.4179834142187;2284.1434462076722;2286.336962280945;2288.8397812153157;2269.909203631853;2294.9541009179725;2300.2409204962323;2304.320225533472;2304.43541332373;8165.836683266301;8196.180381961756;8259.463134896212;8280.668773249063;8286.917130828735;4915.877103072452;5077.492250774893;5078.162103252959;5081.949180508184;5085.618578606583;3079.707427794356;3372.578427696538;3412.419888618916;3477.94783078254;3482.721382289411;1128.7291050147905;1182.6156499137815;1188.2157775867972;1225.436299302322;1227.1961864978364;10477.790444191074;10576.206784491194;10608.893911060944;10611;10612.893871061267;4295.37717031035;4331.870043898688;4592.2193227151465;4604.148232576984;4635.188841952645;9061.818763624691;9241.306901982243;9315.860262096074;9335.113164249386;9359.532023398802;5166.250893620404;5171.043356978971;5181.974090129525;5186.507281808223;5188.63679542436;4618.976905115453;4639.072185562901;4671.369365135663;4720.150372932905;4762.3808904842845;3777.338965680995;3829.157585331223;3838.5201849768778;3883.475730776309;3918.9608103919168;1261.4197469164287;1263.6082814327588;1264.4878824076263;1275.152023904112;1276.183242724657;672.6838385958591;768.0322793280395;771.3212373111738;804.718348577993;815.9673613055411;5257.421683614799;5454.59090568209;5463.180522921514;5560.805371811967;5577.9959607271085;541.1936215039563;560.621580433206;581.1079992211935;584.9707514624251;588.3116753398534;3615.4215325547784;3637.2179981303616;3702.7963462009848;3737.5328083989502;3793;9432.717018489457;9502.90497095034;9527.190188833889;9788.314817962675;9800.264980126376;9743.561539730728;9749.561269742879;9758.121769040821;9772.31593788428;9797.412155270564;7142;7457.776266712007;7577;7580.696772129053;7694.807629809187;5496.642718223303;5553.6390134641115;5572.944270557243;5589.776408943597;5784.363719990713;8842.778930526789;8883.33374996865;9075.228605568336;9162.58768355439;9164.221041211305;8771.298296136167;9023.187913087853;9028.864567031631;9105.180533751995;9258.120478554527;6897.241303456515;6966.233714291323;7057.012018317542;7068.787936361711;7081.575105493687;7675.923240767745;7690.154083050748;7696.807579810549;7787.688492460238;7792.961035194788;6932.445404367538;7029.0862187915245;7073.115860517435;7075.469339799639;7107.182673992557;6867.622280774405;6903.965480172567;6949.270326615632;7012.368886800213;7026.859462810818;6311.873762524816;6403.583767055126;6413.262474815269;6420.06908998202;6420.133282007057;4836.782344794422;4877.878053048702;4931.506849315183;4973.129702302011;5107.9489205107475;4960.379952505937;4988.501149884983;4989.80040798364;5047.545720885138;5122.974385128051 04:48:45 INFO - PROCESS | 1643 | |4;prototype.html;320.75141765132;353.9681827472917;358.63598447575714;365.93596120678893;380.63839352615;4613.401546793498;4722.881927951803;5086.8982620347615;5224.973875130619;5308.522232999029;4134.297169481188;4507.842225522108;5072.543471087602;5148.124818780807;5244.659097158682;3383.156179881964;3389.8474568644347;4048.271311163989;4055.2765074843223;4073.388991651246;3917.256820714783;3982.761034337949;4052.675785937133;4070.4911886014247;4134.56587058359;3542.3269578780078;3947.881563553098;4089.3457046872504;4137.586241375869;4168.706721689861;3543.7519373643727;3854.961450385488;4095.610916962884;4103.589641035903;4120.958790412087;2917.620709307798;2996.2209825445543;3405.1827561385335;3637.654422829827;3666;4798.256270278112;4856.3201151838775;5090.490950904917;5140.691558506502;5168.405633352175;3882.9223415531533;4571.9314210286875;4693.976530117362;4722.291656251556;4752.263399173134;4628.907421851578;5391.56867450603;5498.395176530578;5579.442055794429;5596.2165296858475;3653.794247898187;3999.240144372589;4006.9198616027516;4012.217617564576;4031.5565287818317;5307.310049693515;5752.827415177551;5772.278465191851;5775.797847075374;5783.971080144573;3281.360134773744;3307.2889328794427;3612.7832330060282;4085.182963407271;4093.0585965228165;3623.2934577757082;3648.3432982062955;3980.228798184838;4070.3690927906223;4140.192662430857;3987.9830643186383;4102.251491500701;4385.342198670161;4523.864284071483;4663;5116.309298244763;5292.682439053669;5544.889102217933;5544.889102218014;5597.6641401516035;1168.64940517846;1191.618682021762;1329.9069065165345;1347.730453909222;1363.88161707399;894.4052205283414;939.4269495607674;961.5048250151178;963.494165563085;970.9126178643955;4217.219814334358;4559.886002849889;4601.976990115095;4852.4662287148385;4921.9015619688125;4618.030213655103;4674.195482710346;4733.455652599995;5357.8392648220615;5391.838244852661;4250.787460626956;4428.689991700615;4733.639116227517;4790.592799612003;4857.659963802571;2326.5230627721276;2461.3231361375765;2730.986345068262;2835.6597208335133;2889.2632378743288;4740.597049250783;5584.811391456407;5650.943490565124;5708.143778433268;5709.314882214161;5002.424721157114;5446.972765136149;5538.778448862081;5620.330343087001;5942.524598032147;5822.679752613647;5846.970765146147;5877.970610146922;5885.499732522783;5903.173555702291;4961.479044700254;5233.816816411408;5293.629445938747;5504.184058636665;5670.319561652628;4774.976125119352;4843.951560484491;5466.91799623014;5472.534834538949;5653.265075540154;5834.358220595816;5839.182514448065;5856.603170161381;5863.384344643921;5872.708004239061;3396.1133036918422;3690.4279836625365;3837.9616203837604;3839.0210496323257;3861.768293902375;4751.738654374042;5464.754086066057;5641.827896259887;5687.402822703557;5717.771289148387;4958.652894297365;5099.5410413063;5459.099248624091;5508.283923089973;5565.749541270571;5445.50990410865;5518.3377994639295;5524.309461317335;5542.642052697115;5560.833175004756;4389.23188442015;5020.924686129638;5134.563562097114;5408.323959505062;5423.538999185113;5530.474604912527;5532.3637781654015;5536.307961504812;5545.833624991256;5547.8890422192135;5402.838389746224;5405.675659460445;5427.991643092081;5457.890842183214;5490.505854473116 04:48:45 INFO - PROCESS | 1643 | |5;yui.html;724.4421795217686;756.7086671631421;778.6334982106403;835.7367429259783;840.4831028917216;15083.396664133435;15641.983271087385;15713.214339283018;15725.52823415298;15757.684846303067;14139.434422623108;14589.708205835877;14594.197319147443;14602.561923142297;14632.780508292357;675.3347952267013;696.4498046543225;846.5132548784443;873.2577309287087;878.8462019146656;868.4962721621445;873.5108339329964;874.7813046738315;877.4735158904638;878.8462019146656;934.145257089762;940.7977284883749;941.4680705401427;942.5098948546752;946.2902822882838;720.7657511308793;937.779621788879;939.2720641502822;939.671115109713;942.0250041207318;548.1257394455837;672.5426709837309;685.5167107189419;693.1128155960379;697.1390332938817;15032.774508382383;15052;15053.172075535842;15060.849391506054;15065.472708455261;12791.424385902657;13704.314784260847;13713.314334283246;13729.588112356645;13763.587092387244;12279.508819647204;12355.75288494225;12456.501739930392;12520.373981300989;12685.302308373035;4420.182266280748;4423.911521769579;4592.2193227151465;4616.976915115437;4619.769011549409;101169.42398744058;102736.48631756793;102873;104109.47945260302;105846;9392.342536022414;10935.83596246057;11014.39420831862;11014.834777478345;11305;21719.022643981058;22032.669509957363;22107.77892221057;22141.335759927228;22234.55530889373;7012.439004879598;7680.846383072419;7690.692372305045;7727.806804829925;7764;4032.5362583303295;4098.4876890388705;4101.487314085739;4116.1767646470225;4130.56629053951;3386.661333866643;3454.6199918008997;3473.531073305122;3482.268723568079;3483.2511010132944;537.5968023982014;625.6089943785134;627.3475585391142;642.8971364581645;643.4177069751788;599.3526990849872;637.37537213531;673.743977288637;680.126252175254;682.0860047536225;9843.163331116935;10112.164805506693;10372.429516376671;10501.477285793517;10594;470.3274317725617;598.7156100852061;606.3693758491208;634.552640388525;674.9865002699919;4988.9750551247735;5080.517350851663;5143.099957507496;5145.099607568661;5155.123628983082;103760.96239037513;105176.47411762892;107434.92565074406;108165;110291;76921.46157076827;93961.06038939524;94486.58270125954;96317;96603.99999999859;79405;81293;82489;82840.75738863922;84535.73196402058;9654.42014973463;9694.660686875926;9713.902860971582;9723;9746.805063898824;40870.59129408668;51171.74414127906;51751.74124129355;52524;53103;71117.28882711107;72477.6376118116;73957;75124.4775940336;82962.58518707368;13807.723845523235;14074.014818962574;14123.64690882695;14361.066530675473;14464.421423142956;14316.427342906167;14497.202653853717;14824.166811640769;14953.850461495245;15012.849871501145;10653.30753501051;12284;12701.36493175356;13482.797758033441;14223.288835557974;13272.867271327163;13691.726165476435;13905.235212063433;13942.930285348508;14399.640008999859;11281.379524126252;13337.866621333662;13633.795493067413;13756;14080.78878816798;11119.27724697892;11254.718632034264;11515.30908145514;11552.768944621228;11688.766224675288;8980.371374003757;9024.774380640274;10799.082078023142;11373.658790236306;11600.6519804406 04:48:45 INFO - PROCESS | 1643 | __end_tp_report 04:48:45 INFO - PROCESS | 1643 | __start_cc_report 04:48:45 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1047 04:48:45 INFO - PROCESS | 1643 | __end_cc_report 04:48:45 INFO - PROCESS | 1643 | __startTimestamp1484225325246__endTimestamp 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | ------- Summary: start ------- 04:48:45 INFO - PROCESS | 1643 | Number of tests: 6 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5689.24 Median:5940.82 stddev:652.40 (11.0%) stddev-sans-first:618.10 04:48:45 INFO - PROCESS | 1643 | Values: 2953.7 3306.4 3318.5 3324.6 3384.8 5518.5 5559.4 5607.8 5670.3 5672.5 5592.9 5637.5 5654.0 5681.3 5722.8 5110.8 5142.5 5206.5 5216.6 5239.3 5125.0 5235.8 5248.4 5252.2 5252.9 5217.3 5230.1 5234.1 5238.1 5289.2 5117.3 5131.4 5146.5 5160.6 5171.9 4232.6 5168.0 5200.3 5229.4 5234.8 5654.3 5659.2 5667.2 5673.6 5683.6 6080.5 6250.3 6254.5 6258.2 6259.2 6137.7 6158.5 6183.7 6186.6 6217.8 5684.0 5739.4 5746.1 5753.9 5795.3 6248.8 6320.7 6323.5 6335.2 6335.7 5932.6 5969.3 5987.4 6067.2 6085.8 6129.6 6150.9 6197.3 6205.6 6217.8 6044.9 6104.4 6110.3 6123.3 6156.7 6109.1 6187.3 6214.9 6224.3 6231.9 4945.4 4947.5 4963.9 4970.3 5001.1 4385.8 4388.6 4391.0 4399.8 4461.2 5615.2 5735.5 5738.4 5739.5 5741.0 6202.0 6236.1 6244.7 6253.7 6267.1 5465.5 5691.0 5692.8 5702.7 5731.2 5360.9 5361.2 5363.7 5373.5 5386.1 6165.2 6246.8 6271.4 6273.7 6279.5 6235.2 6242.1 6256.3 6266.5 6286.7 5116.5 6154.5 6203.5 6297.4 6313.2 6131.2 6137.7 6138.8 6202.6 6213.6 4673.5 5281.5 6243.8 6245.8 6283.1 5921.7 5978.8 6115.7 6134.7 6145.0 6027.8 6028.8 6035.0 6050.6 6105.2 5932.1 5940.8 5942.7 5957.2 5957.4 5944.8 6011.4 6013.1 6048.0 6057.3 5923.6 5949.4 5954.1 5965.3 5972.1 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:68750.97 Median:34996.24 stddev:152326.15 (435.3%) stddev-sans-first:152667.13 04:48:45 INFO - PROCESS | 1643 | Values: 443.6 460.3 499.9 521.5 524.9 30328.1 38211.6 39613.8 39656.6 39708.2 40652.0 41106.6 41131.0 41177.0 41193.4 8325.3 9055.1 9280.0 9280.0 9293.5 8302.8 10880.4 11085.3 11132.4 11286.4 11159.8 11196.6 11223.3 11291.0 11310.9 9904.6 10435.7 11099.1 11193.7 11199.4 11051.2 11091.7 11094.7 11124.0 11133.4 39513.6 39660.8 39977.4 40064.4 40069.0 4008.1 4035.4 4106.9 4124.5 4203.8 28649.9 30443.2 30871.0 30922.0 30959.4 3099.7 3233.9 3254.7 3303.0 3305.0 464354.0 465367.0 465381.0 465640.0 468525.0 59258.4 59653.7 59822.7 59910.8 59929.4 61720.7 61739.4 61755.8 62027.4 62083.7 15500.9 16029.8 16055.0 16064.4 16087.2 11152.8 11420.5 12400.0 12487.1 12502.1 14088.0 15593.0 15792.8 15884.7 16238.2 6069.4 6165.0 6179.3 6180.8 6217.5 703.9 731.1 855.9 855.9 871.0 26493.3 29747.6 29821.9 30021.8 30450.1 1013.8 1047.3 1055.9 1098.1 1101.9 14936.9 15416.1 15442.4 15443.5 15769.0 585936.0 589629.0 590967.0 762247.0 773489.0 48858.5 58373.8 58568.0 59574.7 63583.0 1429.9 1433.2 1444.5 1446.5 1448.4 28074.3 28608.5 29093.6 30136.4 31207.8 50114.2 50580.0 50677.7 50681.7 50733.0 551134.0 557185.3 558050.0 558291.0 560177.0 23775.9 24000.8 24200.5 24359.0 24377.9 39644.0 41290.0 41956.0 42595.4 42672.9 34770.7 35009.8 35108.0 35367.5 35673.5 34620.0 34982.7 35244.3 35592.8 35819.9 34355.3 34411.7 34541.0 34570.7 35613.1 30457.8 31278.2 31378.7 31546.4 31558.4 21243.9 23629.8 23634.2 23930.2 24171.6 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:38210.28 Median:21590.80 stddev:116288.32 (538.6%) stddev-sans-first:116579.83 04:48:45 INFO - PROCESS | 1643 | Values: 372.7 388.6 388.7 395.4 421.9 15076.3 18471.3 20575.7 21168.5 21386.0 20364.5 21130.8 21437.4 21840.3 21907.9 5874.2 5950.1 6208.8 6210.2 6220.3 5939.7 6004.3 6082.8 6124.5 6127.4 5802.5 6085.3 6087.3 6096.3 6103.2 6127.1 6159.7 6175.3 6176.4 6177.5 5084.2 5881.4 5931.1 6078.1 6089.4 13564.6 13743.9 13796.3 26263.7 26380.1 2298.7 2591.8 2612.8 2636.7 2660.4 8074.7 9532.6 10061.5 10123.8 10346.9 1782.8 1817.3 1838.4 1867.1 1882.0 89830.0 103198.0 109154.5 110703.4 122878.0 11130.3 11461.6 11842.2 11855.2 12007.9 15646.1 15657.8 15664.5 15670.0 15676.5 7120.9 8452.3 8719.4 8859.4 8902.4 8454.3 8461.1 8487.6 8682.2 8707.3 4682.6 4768.7 4771.0 4786.7 4833.2 1694.2 1971.3 2009.1 2017.8 2020.2 996.9 1001.1 1012.3 1023.9 1033.1 18135.6 20074.5 21744.2 22009.9 22088.3 1022.2 1037.4 1039.9 1065.6 1076.7 10863.2 11326.7 11332.8 11341.5 11353.0 695494.0 696417.0 696720.0 702021.0 714539.0 115657.0 116810.0 137176.8 142310.0 160278.2 987.5 996.8 1024.4 1026.2 1029.0 19150.0 19367.6 19395.5 19438.5 19488.1 54892.7 58912.7 59745.4 59829.5 60945.5 86729.0 88601.0 89387.6 89750.0 90326.6 7707.2 8440.4 8551.2 8644.2 8663.3 11126.8 12836.5 12968.3 13235.1 13831.3 10640.8 11485.7 11526.4 11775.4 11861.3 11337.3 11426.8 11536.6 11737.5 11831.3 11677.5 11958.6 11969.9 12014.9 12094.6 8235.0 8277.2 8626.6 8661.0 8730.7 8487.9 11390.9 11446.8 11451.8 11861.9 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5333.16 Median:2899.31 stddev:2896.09 (99.9%) stddev-sans-first:2888.75 04:48:45 INFO - PROCESS | 1643 | Values: 1350.5 1400.4 1448.2 1479.1 1483.3 7328.9 7702.4 7844.5 8125.1 8137.2 8005.5 8096.9 8107.5 8111.4 8120.3 2031.5 2260.7 2301.7 2306.4 2308.0 2111.5 2238.5 2305.5 2314.6 2319.2 2273.1 2292.7 2298.9 2300.5 2302.7 2263.6 2282.4 2284.1 2286.3 2288.8 2269.9 2295.0 2300.2 2304.3 2304.4 8165.8 8196.2 8259.5 8280.7 8286.9 4915.9 5077.5 5078.2 5081.9 5085.6 3079.7 3372.6 3412.4 3477.9 3482.7 1128.7 1182.6 1188.2 1225.4 1227.2 10477.8 10576.2 10608.9 10611.0 10612.9 4295.4 4331.9 4592.2 4604.1 4635.2 9061.8 9241.3 9315.9 9335.1 9359.5 5166.3 5171.0 5182.0 5186.5 5188.6 4619.0 4639.1 4671.4 4720.2 4762.4 3777.3 3829.2 3838.5 3883.5 3919.0 1261.4 1263.6 1264.5 1275.2 1276.2 672.7 768.0 771.3 804.7 816.0 5257.4 5454.6 5463.2 5560.8 5578.0 541.2 560.6 581.1 585.0 588.3 3615.4 3637.2 3702.8 3737.5 3793.0 9432.7 9502.9 9527.2 9788.3 9800.3 9743.6 9749.6 9758.1 9772.3 9797.4 7142.0 7457.8 7577.0 7580.7 7694.8 5496.6 5553.6 5572.9 5589.8 5784.4 8842.8 8883.3 9075.2 9162.6 9164.2 8771.3 9023.2 9028.9 9105.2 9258.1 6897.2 6966.2 7057.0 7068.8 7081.6 7675.9 7690.2 7696.8 7787.7 7793.0 6932.4 7029.1 7073.1 7075.5 7107.2 6867.6 6904.0 6949.3 7012.4 7026.9 6311.9 6403.6 6413.3 6420.1 6420.1 4836.8 4877.9 4931.5 4973.1 5107.9 4960.4 4988.5 4989.8 5047.5 5123.0 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4429.79 Median:4848.21 stddev:1357.20 (28.0%) stddev-sans-first:1325.50 04:48:45 INFO - PROCESS | 1643 | Values: 320.8 354.0 358.6 365.9 380.6 4613.4 4722.9 5086.9 5225.0 5308.5 4134.3 4507.8 5072.5 5148.1 5244.7 3383.2 3389.8 4048.3 4055.3 4073.4 3917.3 3982.8 4052.7 4070.5 4134.6 3542.3 3947.9 4089.3 4137.6 4168.7 3543.8 3855.0 4095.6 4103.6 4121.0 2917.6 2996.2 3405.2 3637.7 3666.0 4798.3 4856.3 5090.5 5140.7 5168.4 3882.9 4571.9 4694.0 4722.3 4752.3 4628.9 5391.6 5498.4 5579.4 5596.2 3653.8 3999.2 4006.9 4012.2 4031.6 5307.3 5752.8 5772.3 5775.8 5784.0 3281.4 3307.3 3612.8 4085.2 4093.1 3623.3 3648.3 3980.2 4070.4 4140.2 3988.0 4102.3 4385.3 4523.9 4663.0 5116.3 5292.7 5544.9 5544.9 5597.7 1168.6 1191.6 1329.9 1347.7 1363.9 894.4 939.4 961.5 963.5 970.9 4217.2 4559.9 4602.0 4852.5 4921.9 4618.0 4674.2 4733.5 5357.8 5391.8 4250.8 4428.7 4733.6 4790.6 4857.7 2326.5 2461.3 2731.0 2835.7 2889.3 4740.6 5584.8 5650.9 5708.1 5709.3 5002.4 5447.0 5538.8 5620.3 5942.5 5822.7 5847.0 5878.0 5885.5 5903.2 4961.5 5233.8 5293.6 5504.2 5670.3 4775.0 4844.0 5466.9 5472.5 5653.3 5834.4 5839.2 5856.6 5863.4 5872.7 3396.1 3690.4 3838.0 3839.0 3861.8 4751.7 5464.8 5641.8 5687.4 5717.8 4958.7 5099.5 5459.1 5508.3 5565.7 5445.5 5518.3 5524.3 5542.6 5560.8 4389.2 5020.9 5134.6 5408.3 5423.5 5530.5 5532.4 5536.3 5545.8 5547.9 5402.8 5405.7 5428.0 5457.9 5490.5 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20972.13 Median:4123.37 stddev:30274.99 (734.2%) stddev-sans-first:30321.75 04:48:45 INFO - PROCESS | 1643 | Values: 724.4 756.7 778.6 835.7 840.5 15083.4 15642.0 15713.2 15725.5 15757.7 14139.4 14589.7 14594.2 14602.6 14632.8 675.3 696.4 846.5 873.3 878.8 868.5 873.5 874.8 877.5 878.8 934.1 940.8 941.5 942.5 946.3 720.8 937.8 939.3 939.7 942.0 548.1 672.5 685.5 693.1 697.1 15032.8 15052.0 15053.2 15060.8 15065.5 12791.4 13704.3 13713.3 13729.6 13763.6 12279.5 12355.8 12456.5 12520.4 12685.3 4420.2 4423.9 4592.2 4617.0 4619.8 101169.4 102736.5 102873.0 104109.5 105846.0 9392.3 10935.8 11014.4 11014.8 11305.0 21719.0 22032.7 22107.8 22141.3 22234.6 7012.4 7680.8 7690.7 7727.8 7764.0 4032.5 4098.5 4101.5 4116.2 4130.6 3386.7 3454.6 3473.5 3482.3 3483.3 537.6 625.6 627.3 642.9 643.4 599.4 637.4 673.7 680.1 682.1 9843.2 10112.2 10372.4 10501.5 10594.0 470.3 598.7 606.4 634.6 675.0 4989.0 5080.5 5143.1 5145.1 5155.1 103761.0 105176.5 107434.9 108165.0 110291.0 76921.5 93961.1 94486.6 96317.0 96604.0 79405.0 81293.0 82489.0 82840.8 84535.7 9654.4 9694.7 9713.9 9723.0 9746.8 40870.6 51171.7 51751.7 52524.0 53103.0 71117.3 72477.6 73957.0 75124.5 82962.6 13807.7 14074.0 14123.6 14361.1 14464.4 14316.4 14497.2 14824.2 14953.9 15012.8 10653.3 12284.0 12701.4 13482.8 14223.3 13272.9 13691.7 13905.2 13942.9 14399.6 11281.4 13337.9 13633.8 13756.0 14080.8 11119.3 11254.7 11515.3 11552.8 11688.8 8980.4 9024.8 10799.1 11373.7 11600.7 04:48:45 INFO - PROCESS | 1643 | -------- Summary: end -------- 04:48:45 INFO - PROCESS | 1643 | 04:48:45 INFO - TEST-INFO | 1643: exit 0 04:48:45 INFO - TEST-OK | dromaeo_css | took 1118749ms 04:48:45 INFO - TEST-START | kraken 04:48:45 INFO - operating with platform_type : mac_ 04:48:45 INFO - Initialising browser for kraken test... 04:48:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpQa6Jye/profile http://localhost:49241/getInfo.html) 04:48:48 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:48:48 INFO - PROCESS | 1653 | colorDepth:24 04:48:48 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:48:48 INFO - PROCESS | 1653 | __metrics 04:48:48 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:48 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:48 INFO - TEST-INFO | 1653: exit 0 04:48:48 INFO - Browser initialized. 04:48:48 INFO - Running cycle 1/1 for kraken test... 04:48:48 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpQa6Jye/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:00 INFO - PROCESS | 1655 | RSS: Main: 235802624 04:49:00 INFO - PROCESS | 1655 | 04:49:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:04 INFO - PROCESS | 1655 | RSS: Main: 287531008 04:49:04 INFO - PROCESS | 1655 | 04:49:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 04:49:07 INFO - PROCESS | 1655 | RSS: Main: 288202752 04:49:07 INFO - PROCESS | 1655 | 04:49:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 04:49:11 INFO - PROCESS | 1655 | RSS: Main: 379432960 04:49:11 INFO - PROCESS | 1655 | 04:49:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 04:49:14 INFO - PROCESS | 1655 | RSS: Main: 283021312 04:49:14 INFO - PROCESS | 1655 | 04:49:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:17 INFO - PROCESS | 1655 | RSS: Main: 286420992 04:49:17 INFO - PROCESS | 1655 | 04:49:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:20 INFO - PROCESS | 1655 | RSS: Main: 326500352 04:49:20 INFO - PROCESS | 1655 | 04:49:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 04:49:24 INFO - PROCESS | 1655 | RSS: Main: 329015296 04:49:24 INFO - PROCESS | 1655 | 04:49:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 04:49:27 INFO - PROCESS | 1655 | RSS: Main: 327479296 04:49:27 INFO - PROCESS | 1655 | 04:49:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:49:29 INFO - PROCESS | 1655 | RSS: Main: 303853568 04:49:29 INFO - PROCESS | 1655 | 04:49:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:49:32 INFO - PROCESS | 1655 | RSS: Main: 306524160 04:49:32 INFO - PROCESS | 1655 | 04:49:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:49:35 INFO - PROCESS | 1655 | RSS: Main: 308158464 04:49:35 INFO - PROCESS | 1655 | 04:49:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:49:38 INFO - PROCESS | 1655 | RSS: Main: 326041600 04:49:38 INFO - PROCESS | 1655 | 04:49:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:42 INFO - PROCESS | 1655 | RSS: Main: 320106496 04:49:42 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 04:49:44 INFO - PROCESS | 1655 | RSS: Main: 363933696 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | __start_tp_report 04:49:44 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:49:44 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:49:44 INFO - PROCESS | 1655 | |i|pagename|runs| 04:49:44 INFO - PROCESS | 1655 | |0;ai-astar;114;114;114;113;114;106;112;113;113;114 04:49:44 INFO - PROCESS | 1655 | |1;audio-beat-detection;148;111;108;150;110;112;111;112;130;111 04:49:44 INFO - PROCESS | 1655 | |2;audio-dft;184;176;156;247;265;192;180;184;183;186 04:49:44 INFO - PROCESS | 1655 | |3;audio-fft;79;58;60;85;61;59;58;59;74;59 04:49:44 INFO - PROCESS | 1655 | |4;audio-oscillator;91;69;84;74;91;97;117;94;68;83 04:49:44 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;89;88;89;88;86;91;88;89;91 04:49:44 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;93;94;94;93;93;93;93;93;93 04:49:44 INFO - PROCESS | 1655 | |7;imaging-desaturate;105;130;76;130;130;126;87;84;88;86 04:49:44 INFO - PROCESS | 1655 | |8;json-parse-financial;54;56;54;54;56;55;54;56;56;56 04:49:44 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;70;70;79;73;72;71;71;71;70 04:49:44 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;88;90;87;86;86;88;86;90;86;87 04:49:44 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;142;145;150;135;145;151;162;166;198;189 04:49:44 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;179;181;171;177;176;232;175;178;177;178 04:49:44 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;69;69;68;67;69;69;68;68;68;78 04:49:44 INFO - PROCESS | 1655 | __end_tp_report 04:49:44 INFO - PROCESS | 1655 | __start_cc_report 04:49:44 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1952 04:49:44 INFO - PROCESS | 1655 | __end_cc_report 04:49:44 INFO - PROCESS | 1655 | __startTimestamp1484225384604__endTimestamp 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:49:44 INFO - PROCESS | 1655 | Number of tests: 14 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:2.45 (2.2%) stddev-sans-first:2.55 04:49:44 INFO - PROCESS | 1655 | Values: 114.0 114.0 114.0 113.0 114.0 106.0 112.0 113.0 113.0 114.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:112.00 stddev:16.32 (14.6%) stddev-sans-first:13.90 04:49:44 INFO - PROCESS | 1655 | Values: 148.0 111.0 108.0 150.0 110.0 112.0 111.0 112.0 130.0 111.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:195.30 Median:185.00 stddev:33.64 (18.2%) stddev-sans-first:35.43 04:49:44 INFO - PROCESS | 1655 | Values: 184.0 176.0 156.0 247.0 265.0 192.0 180.0 184.0 183.0 186.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:65.20 Median:60.50 stddev:10.13 (16.7%) stddev-sans-first:9.43 04:49:44 INFO - PROCESS | 1655 | Values: 79.0 58.0 60.0 85.0 61.0 59.0 58.0 59.0 74.0 59.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:87.50 stddev:14.76 (16.9%) stddev-sans-first:15.57 04:49:44 INFO - PROCESS | 1655 | Values: 91.0 69.0 84.0 74.0 91.0 97.0 117.0 94.0 68.0 83.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:1.52 (1.7%) stddev-sans-first:1.56 04:49:44 INFO - PROCESS | 1655 | Values: 90.0 89.0 88.0 89.0 88.0 86.0 91.0 88.0 89.0 91.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 04:49:44 INFO - PROCESS | 1655 | Values: 94.0 93.0 94.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:80.00 stddev:22.52 (28.2%) stddev-sans-first:23.89 04:49:44 INFO - PROCESS | 1655 | Values: 105.0 130.0 76.0 130.0 130.0 126.0 87.0 84.0 88.0 86.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 04:49:44 INFO - PROCESS | 1655 | Values: 54.0 56.0 54.0 54.0 56.0 55.0 54.0 56.0 56.0 56.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:3.27 (4.6%) stddev-sans-first:2.85 04:49:44 INFO - PROCESS | 1655 | Values: 66.0 70.0 70.0 79.0 73.0 72.0 71.0 71.0 71.0 70.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.50 stddev:1.58 (1.8%) stddev-sans-first:1.66 04:49:44 INFO - PROCESS | 1655 | Values: 88.0 90.0 87.0 86.0 86.0 88.0 86.0 90.0 86.0 87.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:156.50 stddev:20.75 (13.3%) stddev-sans-first:21.16 04:49:44 INFO - PROCESS | 1655 | Values: 142.0 145.0 150.0 135.0 145.0 151.0 162.0 166.0 198.0 189.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:178.00 stddev:17.63 (9.9%) stddev-sans-first:18.65 04:49:44 INFO - PROCESS | 1655 | Values: 179.0 181.0 171.0 177.0 176.0 232.0 175.0 178.0 177.0 178.0 04:49:44 INFO - PROCESS | 1655 | 04:49:44 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:3.13 (4.5%) stddev-sans-first:3.32 04:49:44 INFO - PROCESS | 1655 | Values: 69.0 69.0 68.0 67.0 69.0 69.0 68.0 68.0 68.0 78.0 04:49:44 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:49:44 INFO - PROCESS | 1655 | 04:49:45 INFO - TEST-INFO | 1655: exit 0 04:49:45 INFO - TEST-OK | kraken | took 59842ms 04:49:45 INFO - SUITE-END | took 1178s 04:49:46 INFO - Completed test suite (00:19:39) 04:49:46 INFO - javascript benchmark 04:49:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2953.6603290621556, 3306.38831816114, 3318.518814771858, 3324.5678061851963, 3384.7630665853385, 5518.475744804237, 5559.360673522546, 5607.803726869561, 5670.319561652603, 5672.46111619396, 5592.944070559293, 5637.520810731093, 5654.038813401721, 5681.318241810988, 5722.79970201043, 5110.795568177268, 5142.537171654551, 5206.479352064801, 5216.634835561512, 5239.266502689627, 5125.0, 5235.816746413876, 5248.448912864133, 5252.238425428311, 5252.921206181911, 5217.34783152106, 5230.058589453897, 5234.084035293828, 5238.05714971305, 5289.20661900716, 5117.33474648297, 5131.4098878629065, 5146.485351464861, 5160.587153027786, 5171.896562068775, 4232.640225580829, 5167.974160129213, 5200.323957885488, 5229.398619158807, 5234.842954711365, 5654.293213348332, 5659.179418984224, 5667.206591077253, 5673.574481913873, 5683.630564013326, 6080.452759251689, 6250.281217659936, 6254.46837018854, 6258.249010118815, 6259.155014073087, 6137.693115344259, 6158.538109641795, 6183.6598987055695, 6186.597871138407, 6217.782377616807, 5683.9715801420725, 5739.426057394227, 5746.109353050284, 5753.884922301572, 5795.3045634523705, 6248.843778905563, 6320.747170113236, 6323.462505687068, 6335.176427064452, 6335.651539165298, 5932.555058370639, 5969.343372229051, 5987.43119403656, 6067.180930574405, 6085.847853803691, 6129.63387754696, 6150.876982460415, 6197.25632924052, 6205.627662340274, 6217.844553886099, 6044.879102418015, 6104.3590423005835, 6110.285381942539, 6123.295820980644, 6156.661383623944, 6109.131392048963, 6187.319394866561, 6214.937850621526, 6224.253089629183, 6231.906521402183, 4945.4312754033745, 4947.48051454592, 4963.925541116886, 4970.329005584272, 5001.0998020355955, 4385.824567017347, 4388.605025547652, 4390.978045109754, 4399.802008909607, 4461.196984542814, 5615.241942337732, 5735.483806457355, 5738.397468265772, 5739.512141467939, 5740.995325817965, 6201.982405159052, 6236.095766113888, 6244.687765611792, 6253.749850005949, 6267.091271765568, 5465.453454654503, 5691.0, 5692.7722891083895, 5702.714864256771, 5731.197632331477, 5360.946390536123, 5361.222622719683, 5363.731813409314, 5373.462653734673, 5386.13821788512, 6165.198524191826, 6246.750129994749, 6271.3728627136925, 6273.749050037947, 6279.529035322461, 6235.158253635701, 6242.063690446469, 6256.343083976117, 6266.498680105672, 6286.717097730704, 5116.539511443988, 6154.476869465966, 6203.503719702323, 6297.433231009231, 6313.210848643919, 6131.233595800525, 6137.662428566472, 6138.848241798255, 6202.5658203925295, 6213.59611625261, 4673.532646735435, 5281.498257665516, 6243.750249989949, 6245.750169993149, 6283.088952102011, 5921.6743079131065, 5978.790742323998, 6115.694215289307, 6134.6625935573975, 6144.969275153596, 6027.849303767265, 6028.758849645965, 6035.034394496859, 6050.5764596479585, 6105.247473116839, 5932.110183472513, 5940.821775346746, 5942.6731529766275, 5957.225560677084, 5957.3744756799915, 5944.762209511571, 6011.398860113954, 6013.128096426081, 6048.0, 6057.303410107894, 5923.555733319932, 5949.375315591974, 5954.136650185786, 5965.28416590012, 5972.104184372205], "value": 5647.129571377898, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.63400194839267, 460.26128064456566, 499.90001999600076, 521.4550794419831, 524.9028929648013, 30328.09015729526, 38211.617883821156, 39613.801930990376, 39656.603433965654, 39708.2058358833, 40651.9837004075, 41106.561270355545, 41131.0, 41177.0, 41193.382099268536, 8325.333973282153, 9055.094490550942, 9280.0, 9280.0, 9293.535323233811, 8302.750917472486, 10880.401577913211, 11085.27945683528, 11132.443377831076, 11286.379249141293, 11159.776804463905, 11196.552137914474, 11223.270487418333, 11291.0, 11310.943445282803, 9904.554295056667, 10435.739106522398, 11099.057664908649, 11193.664190074229, 11199.384033878092, 11051.226414151013, 11091.667249982433, 11094.722631934186, 11124.0, 11133.443327833576, 39513.629182249155, 39660.80169599162, 39977.400338994936, 40064.39903401451, 40069.0, 4008.118213992917, 4035.3543433050863, 4106.897327566805, 4124.505059392862, 4203.789810509493, 28649.85675071632, 30443.2389190272, 30871.0, 30922.0, 30959.38081238385, 3099.6900309969046, 3233.8544765485376, 3254.7396208303517, 3303.009097270809, 3305.0415379539904, 464354.0, 465366.9999999966, 465381.0, 465640.0, 468525.0, 59258.40741592615, 59653.701731491936, 59822.70088649529, 59910.801783964074, 59929.400705993256, 61720.69139654273, 61739.38260617426, 61755.764884702956, 62027.37972620216, 62083.6895815518, 15500.922495387676, 16029.839701603069, 16055.0, 16064.429691249345, 16087.184742669986, 11152.832707509393, 11420.486078126338, 12400.0, 12487.06347023977, 12502.124851260507, 14088.0, 15592.986455880331, 15792.763108553381, 15884.682306353809, 16238.23949928515, 6069.393060693896, 6165.0, 6179.3202747697715, 6180.783672571529, 6217.533684973645, 703.8627467643761, 731.1445608637906, 855.8716192571164, 855.8930133733284, 871.0375035585713, 26493.337666558105, 29747.553786693217, 29821.85089074541, 30021.849890750407, 30450.086497405115, 1013.8124446977333, 1047.3087762076993, 1055.8521806947033, 1098.1050443888205, 1101.944902754859, 14936.925315373353, 15416.07503549768, 15442.382304707911, 15443.459478918408, 15769.0, 585936.0, 589629.0, 590967.0, 762247.0, 773489.0, 48858.5114148854, 58373.832523349294, 58568.0, 59574.702126489094, 63583.046254306224, 1429.9356528956223, 1433.2117335465452, 1444.4799872046055, 1446.5245449641518, 1448.3699590678088, 28074.2981425466, 28608.546188591958, 29093.563596545646, 30136.397272054874, 31207.84396078005, 50114.24828627647, 50580.0, 50677.74661126671, 50681.746591266805, 50733.0, 551134.0, 557185.2814718495, 558050.0, 558291.0, 560177.0, 23775.881120594287, 24000.759992399853, 24200.515989679756, 24359.006403687556, 24377.878110609334, 39644.008899777735, 41290.0, 41956.0, 42595.36106958336, 42672.933176670835, 34770.65229347674, 35009.82495087508, 35108.0, 35367.46948795718, 35673.46489802707, 34620.0, 34982.65017349896, 35244.29511409809, 35592.82203588966, 35819.925402237976, 34355.312893742484, 34411.65588344084, 34541.0, 34570.65429345674, 35613.109672258404, 30457.847710761303, 31278.218044548157, 31378.686213137575, 31546.36907261888, 31558.368832623677, 21243.893780530998, 23629.763702362758, 23634.20961864354, 23930.162444314366, 24171.637425438985], "value": 19491.13471125712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.73908264215055, 388.55316386155937, 388.72594820651443, 395.42860566481437, 421.93881887126366, 15076.32156552955, 18471.26114955402, 20575.679107387467, 21168.4707882303, 21386.0, 20364.490887727814, 21130.788692113074, 21437.356879293606, 21840.344789656298, 21907.890460547675, 5874.1776151338845, 5950.077737950603, 6208.820324138399, 6210.223722034746, 6220.253569571658, 5939.67331796751, 6004.279486461609, 6082.756689732405, 6124.479419249369, 6127.35662755411, 5802.53579713624, 6085.330613632497, 6087.3303936566945, 6096.329403765582, 6103.176071230394, 6127.111568822518, 6159.692015399212, 6175.320714721377, 6176.351483094255, 6177.5057995360485, 5084.186530155194, 5881.441263080001, 5931.072401469496, 6078.088286756978, 6089.3910608939195, 13564.593062208149, 13743.931280343635, 13796.310184490736, 26263.73736262651, 26380.0766973155, 2298.6666933294746, 2591.7926565874686, 2612.7648511634043, 2636.7231440698833, 2660.3881107345223, 8074.74841399584, 9532.616845788552, 10061.496925153786, 10123.848142277871, 10346.948265258625, 1782.8217178282198, 1817.2549254805467, 1838.4484654603566, 1867.1131212674006, 1882.0, 89830.0, 103198.0, 109154.45422772915, 110703.44648276787, 122878.0, 11130.332180069221, 11461.598844040418, 11842.171048026556, 11855.17013809025, 12007.939960300317, 15646.139462329682, 15657.765133523006, 15664.451744188898, 15670.0, 15676.529704108667, 7120.92879071213, 8452.323814094858, 8719.389642725075, 8859.379843411029, 8902.376833621714, 8454.277286135693, 8461.069282378934, 8487.618057187441, 8682.218600326001, 8707.259882909897, 4682.578567928903, 4768.6661933665, 4770.976145119322, 4786.705245196217, 4833.226683730586, 1694.2206584971052, 1971.339601233574, 2009.14611290204, 2017.8284845787978, 2020.1616329223502, 996.8853581838036, 1001.1190152665607, 1012.3015119567475, 1023.8720159980003, 1033.1115240892825, 18135.63728725418, 20074.498137546678, 21744.238951636933, 22009.889950550143, 22088.33734987953, 1022.1771473963382, 1037.4138611684375, 1039.8908114648004, 1065.6217042949797, 1076.7039064257392, 10863.239573229956, 11326.716832079099, 11332.830007549894, 11341.489632966537, 11353.0, 695494.0, 696417.0, 696720.0, 702021.0, 714539.0, 115657.0, 116810.0, 137176.79524866326, 142310.0, 160278.1986090062, 987.5407935310116, 996.8305388084043, 1024.3904876598413, 1026.1790567545934, 1028.9691309260734, 19150.0, 19367.612647747246, 19395.51511212231, 19438.51403714862, 19488.1230344632, 54892.72553637206, 58912.70543647254, 59745.402545975725, 59829.50426239379, 60945.47636309128, 86729.0, 88601.0, 89387.55306223428, 89750.0, 90326.64510032487, 7707.229277072248, 8440.409171357946, 8551.187637174458, 8644.178803013703, 8663.306935445024, 11126.759867364615, 12836.486540538273, 12968.286744229157, 13235.073544852154, 13831.30843457803, 10640.84038739435, 11485.655430337103, 11526.366049867338, 11775.411229438665, 11861.347625880315, 11337.26307789991, 11426.828597570877, 11536.59621913229, 11737.530498779954, 11831.290122592673, 11677.474513646737, 11958.58144964922, 11969.880301196876, 12014.939925300318, 12094.637160885188, 8235.0, 8277.213664701843, 8626.611802468778, 8661.047284798793, 8730.738077857675, 8487.872681909652, 11390.860913908542, 11446.82829757571, 11451.770964580828, 11861.881381186078], "value": 9835.245598503732, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.4800651749074, 1400.4118270326462, 1448.1745405119086, 1479.1347061968747, 1483.2509582660757, 7328.853422931538, 7702.383809295257, 7844.490108142973, 8125.1062383137805, 8137.18628137186, 8005.479643823148, 8096.878546821802, 8107.473014254069, 8111.391645626572, 8120.2691757741795, 2031.4616626593966, 2260.660900864875, 2301.689271948282, 2306.446452851312, 2308.0190918859503, 2111.4826867417505, 2238.473958619723, 2305.538892221554, 2314.6412306092584, 2319.246244970383, 2273.079402841852, 2292.7363353214428, 2298.9195378161767, 2300.5168914527967, 2302.735185453669, 2263.649134384165, 2282.4179834142187, 2284.1434462076722, 2286.336962280945, 2288.8397812153157, 2269.909203631853, 2294.9541009179725, 2300.2409204962323, 2304.320225533472, 2304.43541332373, 8165.836683266301, 8196.180381961756, 8259.463134896212, 8280.668773249063, 8286.917130828735, 4915.877103072452, 5077.492250774893, 5078.162103252959, 5081.949180508184, 5085.618578606583, 3079.707427794356, 3372.578427696538, 3412.419888618916, 3477.94783078254, 3482.721382289411, 1128.7291050147905, 1182.6156499137815, 1188.2157775867972, 1225.436299302322, 1227.1961864978364, 10477.790444191074, 10576.206784491194, 10608.893911060944, 10611.0, 10612.893871061267, 4295.37717031035, 4331.870043898688, 4592.2193227151465, 4604.148232576984, 4635.188841952645, 9061.818763624691, 9241.306901982243, 9315.860262096074, 9335.113164249386, 9359.532023398802, 5166.250893620404, 5171.043356978971, 5181.974090129525, 5186.507281808223, 5188.63679542436, 4618.976905115453, 4639.072185562901, 4671.369365135663, 4720.150372932905, 4762.3808904842845, 3777.338965680995, 3829.157585331223, 3838.5201849768778, 3883.475730776309, 3918.9608103919168, 1261.4197469164287, 1263.6082814327588, 1264.4878824076263, 1275.152023904112, 1276.183242724657, 672.6838385958591, 768.0322793280395, 771.3212373111738, 804.718348577993, 815.9673613055411, 5257.421683614799, 5454.59090568209, 5463.180522921514, 5560.805371811967, 5577.9959607271085, 541.1936215039563, 560.621580433206, 581.1079992211935, 584.9707514624251, 588.3116753398534, 3615.4215325547784, 3637.2179981303616, 3702.7963462009848, 3737.5328083989502, 3793.0, 9432.717018489457, 9502.90497095034, 9527.190188833889, 9788.314817962675, 9800.264980126376, 9743.561539730728, 9749.561269742879, 9758.121769040821, 9772.31593788428, 9797.412155270564, 7142.0, 7457.776266712007, 7577.0, 7580.696772129053, 7694.807629809187, 5496.642718223303, 5553.6390134641115, 5572.944270557243, 5589.776408943597, 5784.363719990713, 8842.778930526789, 8883.33374996865, 9075.228605568336, 9162.58768355439, 9164.221041211305, 8771.298296136167, 9023.187913087853, 9028.864567031631, 9105.180533751995, 9258.120478554527, 6897.241303456515, 6966.233714291323, 7057.012018317542, 7068.787936361711, 7081.575105493687, 7675.923240767745, 7690.154083050748, 7696.807579810549, 7787.688492460238, 7792.961035194788, 6932.445404367538, 7029.0862187915245, 7073.115860517435, 7075.469339799639, 7107.182673992557, 6867.622280774405, 6903.965480172567, 6949.270326615632, 7012.368886800213, 7026.859462810818, 6311.873762524816, 6403.583767055126, 6413.262474815269, 6420.06908998202, 6420.133282007057, 4836.782344794422, 4877.878053048702, 4931.506849315183, 4973.129702302011, 5107.9489205107475, 4960.379952505937, 4988.501149884983, 4989.80040798364, 5047.545720885138, 5122.974385128051], "value": 4287.435145903448, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.75141765132, 353.9681827472917, 358.63598447575714, 365.93596120678893, 380.63839352615, 4613.401546793498, 4722.881927951803, 5086.8982620347615, 5224.973875130619, 5308.522232999029, 4134.297169481188, 4507.842225522108, 5072.543471087602, 5148.124818780807, 5244.659097158682, 3383.156179881964, 3389.8474568644347, 4048.271311163989, 4055.2765074843223, 4073.388991651246, 3917.256820714783, 3982.761034337949, 4052.675785937133, 4070.4911886014247, 4134.56587058359, 3542.3269578780078, 3947.881563553098, 4089.3457046872504, 4137.586241375869, 4168.706721689861, 3543.7519373643727, 3854.961450385488, 4095.610916962884, 4103.589641035903, 4120.958790412087, 2917.620709307798, 2996.2209825445543, 3405.1827561385335, 3637.654422829827, 3666.0, 4798.256270278112, 4856.3201151838775, 5090.490950904917, 5140.691558506502, 5168.405633352175, 3882.9223415531533, 4571.9314210286875, 4693.976530117362, 4722.291656251556, 4752.263399173134, 4628.907421851578, 5391.56867450603, 5498.395176530578, 5579.442055794429, 5596.2165296858475, 3653.794247898187, 3999.240144372589, 4006.9198616027516, 4012.217617564576, 4031.5565287818317, 5307.310049693515, 5752.827415177551, 5772.278465191851, 5775.797847075374, 5783.971080144573, 3281.360134773744, 3307.2889328794427, 3612.7832330060282, 4085.182963407271, 4093.0585965228165, 3623.2934577757082, 3648.3432982062955, 3980.228798184838, 4070.3690927906223, 4140.192662430857, 3987.9830643186383, 4102.251491500701, 4385.342198670161, 4523.864284071483, 4663.0, 5116.309298244763, 5292.682439053669, 5544.889102217933, 5544.889102218014, 5597.6641401516035, 1168.64940517846, 1191.618682021762, 1329.9069065165345, 1347.730453909222, 1363.88161707399, 894.4052205283414, 939.4269495607674, 961.5048250151178, 963.494165563085, 970.9126178643955, 4217.219814334358, 4559.886002849889, 4601.976990115095, 4852.4662287148385, 4921.9015619688125, 4618.030213655103, 4674.195482710346, 4733.455652599995, 5357.8392648220615, 5391.838244852661, 4250.787460626956, 4428.689991700615, 4733.639116227517, 4790.592799612003, 4857.659963802571, 2326.5230627721276, 2461.3231361375765, 2730.986345068262, 2835.6597208335133, 2889.2632378743288, 4740.597049250783, 5584.811391456407, 5650.943490565124, 5708.143778433268, 5709.314882214161, 5002.424721157114, 5446.972765136149, 5538.778448862081, 5620.330343087001, 5942.524598032147, 5822.679752613647, 5846.970765146147, 5877.970610146922, 5885.499732522783, 5903.173555702291, 4961.479044700254, 5233.816816411408, 5293.629445938747, 5504.184058636665, 5670.319561652628, 4774.976125119352, 4843.951560484491, 5466.91799623014, 5472.534834538949, 5653.265075540154, 5834.358220595816, 5839.182514448065, 5856.603170161381, 5863.384344643921, 5872.708004239061, 3396.1133036918422, 3690.4279836625365, 3837.9616203837604, 3839.0210496323257, 3861.768293902375, 4751.738654374042, 5464.754086066057, 5641.827896259887, 5687.402822703557, 5717.771289148387, 4958.652894297365, 5099.5410413063, 5459.099248624091, 5508.283923089973, 5565.749541270571, 5445.50990410865, 5518.3377994639295, 5524.309461317335, 5542.642052697115, 5560.833175004756, 4389.23188442015, 5020.924686129638, 5134.563562097114, 5408.323959505062, 5423.538999185113, 5530.474604912527, 5532.3637781654015, 5536.307961504812, 5545.833624991256, 5547.8890422192135, 5402.838389746224, 5405.675659460445, 5427.991643092081, 5457.890842183214, 5490.505854473116], "value": 4018.674142113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.4421795217686, 756.7086671631421, 778.6334982106403, 835.7367429259783, 840.4831028917216, 15083.396664133435, 15641.983271087385, 15713.214339283018, 15725.52823415298, 15757.684846303067, 14139.434422623108, 14589.708205835877, 14594.197319147443, 14602.561923142297, 14632.780508292357, 675.3347952267013, 696.4498046543225, 846.5132548784443, 873.2577309287087, 878.8462019146656, 868.4962721621445, 873.5108339329964, 874.7813046738315, 877.4735158904638, 878.8462019146656, 934.145257089762, 940.7977284883749, 941.4680705401427, 942.5098948546752, 946.2902822882838, 720.7657511308793, 937.779621788879, 939.2720641502822, 939.671115109713, 942.0250041207318, 548.1257394455837, 672.5426709837309, 685.5167107189419, 693.1128155960379, 697.1390332938817, 15032.774508382383, 15052.0, 15053.172075535842, 15060.849391506054, 15065.472708455261, 12791.424385902657, 13704.314784260847, 13713.314334283246, 13729.588112356645, 13763.587092387244, 12279.508819647204, 12355.75288494225, 12456.501739930392, 12520.373981300989, 12685.302308373035, 4420.182266280748, 4423.911521769579, 4592.2193227151465, 4616.976915115437, 4619.769011549409, 101169.42398744058, 102736.48631756793, 102873.0, 104109.47945260302, 105846.0, 9392.342536022414, 10935.83596246057, 11014.39420831862, 11014.834777478345, 11305.0, 21719.022643981058, 22032.669509957363, 22107.77892221057, 22141.335759927228, 22234.55530889373, 7012.439004879598, 7680.846383072419, 7690.692372305045, 7727.806804829925, 7764.0, 4032.5362583303295, 4098.4876890388705, 4101.487314085739, 4116.1767646470225, 4130.56629053951, 3386.661333866643, 3454.6199918008997, 3473.531073305122, 3482.268723568079, 3483.2511010132944, 537.5968023982014, 625.6089943785134, 627.3475585391142, 642.8971364581645, 643.4177069751788, 599.3526990849872, 637.37537213531, 673.743977288637, 680.126252175254, 682.0860047536225, 9843.163331116935, 10112.164805506693, 10372.429516376671, 10501.477285793517, 10594.0, 470.3274317725617, 598.7156100852061, 606.3693758491208, 634.552640388525, 674.9865002699919, 4988.9750551247735, 5080.517350851663, 5143.099957507496, 5145.099607568661, 5155.123628983082, 103760.96239037513, 105176.47411762892, 107434.92565074406, 108165.0, 110291.0, 76921.46157076827, 93961.06038939524, 94486.58270125954, 96317.0, 96603.99999999859, 79405.0, 81293.0, 82489.0, 82840.75738863922, 84535.73196402058, 9654.42014973463, 9694.660686875926, 9713.902860971582, 9723.0, 9746.805063898824, 40870.59129408668, 51171.74414127906, 51751.74124129355, 52524.0, 53103.0, 71117.28882711107, 72477.6376118116, 73957.0, 75124.4775940336, 82962.58518707368, 13807.723845523235, 14074.014818962574, 14123.64690882695, 14361.066530675473, 14464.421423142956, 14316.427342906167, 14497.202653853717, 14824.166811640769, 14953.850461495245, 15012.849871501145, 10653.30753501051, 12284.0, 12701.36493175356, 13482.797758033441, 14223.288835557974, 13272.867271327163, 13691.726165476435, 13905.235212063433, 13942.930285348508, 14399.640008999859, 11281.379524126252, 13337.866621333662, 13633.795493067413, 13756.0, 14080.78878816798, 11119.27724697892, 11254.718632034264, 11515.30908145514, 11552.768944621228, 11688.766224675288, 8980.371374003757, 9024.774380640274, 10799.082078023142, 11373.658790236306, 11600.6519804406], "value": 7569.495870287438, "unit": "score"}], "name": "dromaeo_css", "value": 7216.186146420237, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 114.0, 106.0, 112.0, 113.0, 113.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 108.0, 150.0, 110.0, 112.0, 111.0, 112.0, 130.0, 111.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 176.0, 156.0, 247.0, 265.0, 192.0, 180.0, 184.0, 183.0, 186.0], "value": 195.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 85.0, 61.0, 59.0, 58.0, 59.0, 74.0, 59.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 84.0, 74.0, 91.0, 97.0, 117.0, 94.0, 68.0, 83.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 88.0, 89.0, 88.0, 86.0, 91.0, 88.0, 89.0, 91.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 130.0, 76.0, 130.0, 130.0, 126.0, 87.0, 84.0, 88.0, 86.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 54.0, 54.0, 56.0, 55.0, 54.0, 56.0, 56.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 70.0, 79.0, 73.0, 72.0, 71.0, 71.0, 71.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 87.0, 86.0, 86.0, 88.0, 86.0, 90.0, 86.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 150.0, 135.0, 145.0, 151.0, 162.0, 166.0, 198.0, 189.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 171.0, 177.0, 176.0, 232.0, 175.0, 178.0, 177.0, 178.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 67.0, 69.0, 69.0, 68.0, 68.0, 68.0, 78.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1490.5, "alertThreshold": 2.0}]} 04:49:46 INFO - Return code: 0 04:49:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:49:46 INFO - # TBPL SUCCESS # 04:49:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:46 INFO - 2017-01-12 04:49:46.379 screenresolution[1659:10246] starting screenresolution argv=screenresolution get 04:49:46 INFO - 2017-01-12 04:49:46.381 screenresolution[1659:10246] Display 0: 1600x1200x32@60 04:49:46 INFO - 2017-01-12 04:49:46.387 screenresolution[1660:10249] starting screenresolution argv=screenresolution list 04:49:46 INFO - Available Modes on Display 0 04:49:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:46 INFO - Intel Iris: 04:49:46 INFO - Chipset Model: Intel Iris 04:49:46 INFO - Type: GPU 04:49:46 INFO - Bus: Built-In 04:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:46 INFO - Vendor: Intel (0x8086) 04:49:46 INFO - Device ID: 0x0a2e 04:49:46 INFO - Revision ID: 0x0009 04:49:46 INFO - Displays: 04:49:46 INFO - Display: 04:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:46 INFO - Display Serial Number: 1600x1200 60 04:49:46 INFO - Main Display: Yes 04:49:46 INFO - Mirror: Off 04:49:46 INFO - Online: Yes 04:49:46 INFO - Rotation: Supported 04:49:46 INFO - Return code: 0 04:49:46 INFO - Running post-action listener: _resource_record_post_action 04:49:46 INFO - [mozharness: 2017-01-12 12:49:46.635215Z] Finished run-tests step (success) 04:49:46 INFO - Running post-run listener: _resource_record_post_run 04:49:46 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37757952; Write bytes: 552663040; Read time: 237; Write time: 1669 04:49:46 INFO - TinderboxPrint: CPU usage
53.6% 04:49:46 INFO - TinderboxPrint: I/O read bytes / time
37,757,952 / 237 04:49:46 INFO - TinderboxPrint: I/O write bytes / time
552,663,040 / 1,669 04:49:46 INFO - TinderboxPrint: CPU idle
2,231.9 (46.4%) 04:49:46 INFO - TinderboxPrint: CPU system
961.0 (20.0%) 04:49:46 INFO - TinderboxPrint: CPU user
1,616.7 (33.6%) 04:49:46 INFO - TinderboxPrint: Swap in / out
289,079,296 / 0 04:49:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243458560; Write bytes: 235761664; Read time: 18104; Write time: 497 04:49:46 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35767296; Write bytes: 299571200; Read time: 219; Write time: 1123 04:49:47 INFO - Running post-run listener: _upload_blobber_files 04:49:47 INFO - Blob upload gear active. 04:49:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:47 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 04:49:47 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'] 04:49:47 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 04:49:47 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:48 INFO - (blobuploader) - INFO - Done attempting. 04:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:49 INFO - (blobuploader) - INFO - Done attempting. 04:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:50 INFO - (blobuploader) - INFO - Done attempting. 04:49:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:49:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:53 INFO - (blobuploader) - INFO - Done attempting. 04:49:53 INFO - (blobuploader) - INFO - Iteration through files over. 04:49:53 INFO - Return code: 0 04:49:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:49:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:49:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dd772b6c63e9ab798329f73c61d7b9386d3dc0578487a49f683d4219b7a59dd954a27685976c73470c4d21dffe56786b8873ad4162d9e3b3b0ba3b8ed27a486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc5981e434fc1e36ce35dc71411d1a2d4391e550aefa744ec861deb94a77ae24bb5672cb1546e61fd9c6f1c433ef1d20d20f37d11ecd614a3bbbdda8a9121175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4cbab1a6c5d17bb069f51337a2e0105eec48285c2bab266d2f2a172d2f6561bfd5960541bff7510760018d6451f7fbbb4b96f6b95570aeb6059be5815434fb05", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/566ccb68b02eee630fbb804642961b6f60a0b67fb57355038b9150cc40205c7ab0936b6927e272e3012b06d01d19b154f331bb557952b969399224661219dea6"} 04:49:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:49:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:49:53 INFO - Contents: 04:49:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dd772b6c63e9ab798329f73c61d7b9386d3dc0578487a49f683d4219b7a59dd954a27685976c73470c4d21dffe56786b8873ad4162d9e3b3b0ba3b8ed27a486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc5981e434fc1e36ce35dc71411d1a2d4391e550aefa744ec861deb94a77ae24bb5672cb1546e61fd9c6f1c433ef1d20d20f37d11ecd614a3bbbdda8a9121175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4cbab1a6c5d17bb069f51337a2e0105eec48285c2bab266d2f2a172d2f6561bfd5960541bff7510760018d6451f7fbbb4b96f6b95570aeb6059be5815434fb05", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/566ccb68b02eee630fbb804642961b6f60a0b67fb57355038b9150cc40205c7ab0936b6927e272e3012b06d01d19b154f331bb557952b969399224661219dea6"} 04:49:53 INFO - Running post-run listener: copy_logs_to_upload_dir 04:49:53 INFO - Copying logs to upload dir... 04:49:53 INFO - mkdir: /builds/slave/test/build/upload/logs 04:49:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.643858 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-12 04:49:54.045882) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:54.053170) ========= 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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/0dd772b6c63e9ab798329f73c61d7b9386d3dc0578487a49f683d4219b7a59dd954a27685976c73470c4d21dffe56786b8873ad4162d9e3b3b0ba3b8ed27a486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc5981e434fc1e36ce35dc71411d1a2d4391e550aefa744ec861deb94a77ae24bb5672cb1546e61fd9c6f1c433ef1d20d20f37d11ecd614a3bbbdda8a9121175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4cbab1a6c5d17bb069f51337a2e0105eec48285c2bab266d2f2a172d2f6561bfd5960541bff7510760018d6451f7fbbb4b96f6b95570aeb6059be5815434fb05", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/566ccb68b02eee630fbb804642961b6f60a0b67fb57355038b9150cc40205c7ab0936b6927e272e3012b06d01d19b154f331bb557952b969399224661219dea6"} build_url:https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dd772b6c63e9ab798329f73c61d7b9386d3dc0578487a49f683d4219b7a59dd954a27685976c73470c4d21dffe56786b8873ad4162d9e3b3b0ba3b8ed27a486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc5981e434fc1e36ce35dc71411d1a2d4391e550aefa744ec861deb94a77ae24bb5672cb1546e61fd9c6f1c433ef1d20d20f37d11ecd614a3bbbdda8a9121175", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4cbab1a6c5d17bb069f51337a2e0105eec48285c2bab266d2f2a172d2f6561bfd5960541bff7510760018d6451f7fbbb4b96f6b95570aeb6059be5815434fb05", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/566ccb68b02eee630fbb804642961b6f60a0b67fb57355038b9150cc40205c7ab0936b6927e272e3012b06d01d19b154f331bb557952b969399224661219dea6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:54.094226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:54.094564) ========= 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.dnOfXMIgK7/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.ggOFgOEIFo/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005100 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:54.143672) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 04:49:54.147104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 04:49:54.147503) ========= ========= Total master_lag: 0.94 =========