builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0201 starttime: 1484235008.35 results: success (0) buildid: 20170112060215 builduid: 37ee7dc7add0497da43b989463a075a2 revision: 1289b60bd5ed221f6713497fcdc8167ae3896916 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.356290) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.357000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.357401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.385662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.386109) ========= 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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.8M=0s 2017-01-12 07:30:08 (39.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.558631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.558963) ========= 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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.025751 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:08.607557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 07:30:08.607924) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1289b60bd5ed221f6713497fcdc8167ae3896916 --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 1289b60bd5ed221f6713497fcdc8167ae3896916 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:08,707 truncating revision to first 12 chars 2017-01-12 07:30:08,707 Setting DEBUG logging. 2017-01-12 07:30:08,707 attempt 1/10 2017-01-12 07:30:08,707 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1289b60bd5ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 07:30:09,523 unpacking tar archive at: mozilla-central-1289b60bd5ed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.088845 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 07:30:09.720521) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:09.720936) ========= script_repo_revision: 1289b60bd5ed221f6713497fcdc8167ae3896916 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:09.721353) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:09.721620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 07:30:09.743652) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-12 07:30:09.744119) ========= /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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:09 INFO - MultiFileLogger online at 20170112 07:30:09 in /builds/slave/test 07:30:09 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 07:30:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:09 INFO - {'append_to_log': False, 07:30:09 INFO - 'base_work_dir': '/builds/slave/test', 07:30:09 INFO - 'blob_upload_branch': 'Firefox', 07:30:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:09 INFO - 'branch': 'Firefox', 07:30:09 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:09 INFO - 'config_files': ('talos/mac_config.py',), 07:30:09 INFO - 'default_actions': ('clobber', 07:30:09 INFO - 'read-buildbot-config', 07:30:09 INFO - 'download-and-extract', 07:30:09 INFO - 'populate-webroot', 07:30:09 INFO - 'create-virtualenv', 07:30:09 INFO - 'install', 07:30:09 INFO - 'run-tests'), 07:30:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:09 INFO - 'download_minidump_stackwalk': True, 07:30:09 INFO - 'download_symbols': 'ondemand', 07:30:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:09 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:09 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:09 INFO - 'installer_path': 'installer.exe', 07:30:09 INFO - 'log_level': 'info', 07:30:09 INFO - 'log_name': 'talos', 07:30:09 INFO - 'log_to_console': True, 07:30:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:30:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:30:09 INFO - 'opt_config_files': (), 07:30:09 INFO - 'pip_index': False, 07:30:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:09 INFO - 'cmd': ('bash', 07:30:09 INFO - '-c', 07:30:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:09 INFO - 'enabled': True, 07:30:09 INFO - 'halt_on_failure': False, 07:30:09 INFO - 'name': 'check_screen_resolution'},), 07:30:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:09 INFO - 'cmd': ('bash', 07:30:09 INFO - '-c', 07:30:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:09 INFO - 'enabled': True, 07:30:09 INFO - 'halt_on_failure': False, 07:30:09 INFO - 'name': 'check_screen_resolution'},), 07:30:09 INFO - 'run_cmd_checks_enabled': True, 07:30:09 INFO - 'sps_profile': False, 07:30:09 INFO - 'sps_profile_interval': 0, 07:30:09 INFO - 'suite': 'dromaeojs', 07:30:09 INFO - 'system_bits': '32', 07:30:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:09 INFO - 'title': 't-yosemite-r7-0201', 07:30:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:09 INFO - 'use_talos_json': True, 07:30:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:09 INFO - 'work_dir': 'build'} 07:30:09 INFO - [mozharness: 2017-01-12 15:30:09.947465Z] Running clobber step. 07:30:09 INFO - Running pre-action listener: _resource_record_pre_action 07:30:09 INFO - Running main action method: clobber 07:30:09 INFO - rmtree: /builds/slave/test/build 07:30:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:12 INFO - Running post-action listener: _resource_record_post_action 07:30:12 INFO - [mozharness: 2017-01-12 15:30:12.662665Z] Finished clobber step (success) 07:30:12 INFO - [mozharness: 2017-01-12 15:30:12.662800Z] Running read-buildbot-config step. 07:30:12 INFO - Running pre-action listener: _resource_record_pre_action 07:30:12 INFO - Running main action method: read_buildbot_config 07:30:12 INFO - Using buildbot properties: 07:30:12 INFO - { 07:30:12 INFO - "project": "", 07:30:12 INFO - "product": "firefox", 07:30:12 INFO - "script_repo_revision": "production", 07:30:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:30:12 INFO - "repository": "", 07:30:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:30:12 INFO - "buildid": "20170112060215", 07:30:12 INFO - "pgo_build": "False", 07:30:12 INFO - "basedir": "/builds/slave/test", 07:30:12 INFO - "buildnumber": 5, 07:30:12 INFO - "slavename": "t-yosemite-r7-0201", 07:30:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:30:12 INFO - "platform": "yosemite_r7", 07:30:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:30:12 INFO - "branch": "mozilla-central", 07:30:12 INFO - "repo_path": "mozilla-central", 07:30:12 INFO - "revision": "1289b60bd5ed221f6713497fcdc8167ae3896916", 07:30:12 INFO - "stage_platform": "macosx64", 07:30:12 INFO - "builduid": "37ee7dc7add0497da43b989463a075a2", 07:30:12 INFO - "slavebuilddir": "test" 07:30:12 INFO - } 07:30:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:30:12 INFO - Running post-action listener: _resource_record_post_action 07:30:12 INFO - [mozharness: 2017-01-12 15:30:12.666124Z] Finished read-buildbot-config step (success) 07:30:12 INFO - [mozharness: 2017-01-12 15:30:12.666261Z] Running download-and-extract step. 07:30:12 INFO - Running pre-action listener: _resource_record_pre_action 07:30:12 INFO - Running main action method: download_and_extract 07:30:12 INFO - mkdir: /builds/slave/test/build/tests 07:30: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')]} 07:30:12 INFO - trying https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:30:12 INFO - Downloading https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/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 07:30:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/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 07:30:13 INFO - Downloaded 1199 bytes. 07:30:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:30:13 INFO - Using the following test package requirements: 07:30:13 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:30:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:30:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:30:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'jsshell-mac.zip'], 07:30:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:30:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:30:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:30:13 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:30:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:30:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:13 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:30:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:30:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:30:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:30:13 INFO - Fetch https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:30:14 INFO - Expected file size: 16314216 07:30:14 INFO - Obtained file size: 16314216 07:30:20 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 07:30:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:30:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:30:20 INFO - Fetch https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:30:21 INFO - Expected file size: 16314216 07:30:21 INFO - Obtained file size: 16314216 07:30:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:30:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:30:27 INFO - Fetch https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:30:29 INFO - Expected file size: 14403532 07:30:29 INFO - Obtained file size: 14403532 07:30:29 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')]} 07:30:29 INFO - trying https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:30:29 INFO - Downloading https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/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 07:30:32 INFO - Downloaded 70134992 bytes. 07:30:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:30:32 INFO - mkdir: /builds/slave/test/properties 07:30:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:32 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:32 INFO - Contents: 07:30:32 INFO - build_url:https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:30:33 INFO - Running post-action listener: _resource_record_post_action 07:30:33 INFO - Running post-action listener: set_extra_try_arguments 07:30:33 INFO - [mozharness: 2017-01-12 15:30:33.214506Z] Finished download-and-extract step (success) 07:30:33 INFO - [mozharness: 2017-01-12 15:30:33.214631Z] Running populate-webroot step. 07:30:33 INFO - Running pre-action listener: _resource_record_pre_action 07:30:33 INFO - Running main action method: populate_webroot 07:30:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:33 INFO - '--tppagecycles', 07:30:33 INFO - '7'], 07:30:33 INFO - 'tests': ['tsvgm']}}, 07:30:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['damp', 'tps']}, 07:30:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'tests': ['damp', 'tps']}, 07:30:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['dromaeo_dom']}, 07:30:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['basic_compositor_video']}, 07:30:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:30:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['a11yr', 07:30:33 INFO - 'ts_paint', 07:30:33 INFO - 'tpaint', 07:30:33 INFO - 'sessionrestore', 07:30:33 INFO - 'sessionrestore_no_auto_restore', 07:30:33 INFO - 'tabpaint']}, 07:30:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['tsvgx', 07:30:33 INFO - 'tsvgr_opacity', 07:30:33 INFO - 'tart', 07:30:33 INFO - 'tscrollx', 07:30:33 INFO - 'cart', 07:30:33 INFO - 'tsvg_static']}, 07:30:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:33 INFO - 'tsvgr_opacity', 07:30:33 INFO - 'tart', 07:30:33 INFO - 'tscrollx', 07:30:33 INFO - 'cart', 07:30:33 INFO - 'tsvg_static']}, 07:30:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'talos_options': ['--disable-e10s'], 07:30:33 INFO - 'tests': ['tp5o']}, 07:30:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:30:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'talos_options': ['--disable-e10s', 07:30:33 INFO - '--xperf_path', 07:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:33 INFO - 'tests': ['tp5n']}, 07:30:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:30:33 INFO - 'talos_options': ['--xperf_path', 07:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:33 INFO - 'tests': ['tp5n']}}, 07:30:33 INFO - 'talos.zip': {'path': '', 07:30:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:33 INFO - Running post-action listener: _resource_record_post_action 07:30:33 INFO - [mozharness: 2017-01-12 15:30:33.233352Z] Finished populate-webroot step (success) 07:30:33 INFO - [mozharness: 2017-01-12 15:30:33.233492Z] Running create-virtualenv step. 07:30:33 INFO - Running pre-action listener: _resource_record_pre_action 07:30:33 INFO - Running main action method: create_virtualenv 07:30:33 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30:33 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 07:30:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:33 INFO - Using real prefix '/tools/python27' 07:30:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:34 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:37 INFO - Installing pip.................done. 07:30:37 INFO - Return code: 0 07:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:30:37 INFO - Reading from file tmpfile_stdout 07:30:37 INFO - Output received: 07:30:37 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) 07:30:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:37 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:37 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 07:30:37 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 07:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:37 INFO - 'HOME': '/Users/cltbld', 07:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:37 INFO - 'LOGNAME': 'cltbld', 07:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:37 INFO - 'MOZ_NO_REMOTE': '1', 07:30:37 INFO - 'NO_EM_RESTART': '1', 07:30:37 INFO - 'PAGER': '/bin/cat', 07:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:37 INFO - 'PWD': '/builds/slave/test', 07:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:37 INFO - 'SHELL': '/bin/bash', 07:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:37 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:37 INFO - 'USER': 'cltbld', 07:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:37 INFO - 'XPC_FLAGS': '0x0', 07:30:37 INFO - 'XPC_SERVICE_NAME': '0', 07:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:37 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 07:30:37 INFO - Cleaning up... 07:30:37 INFO - Return code: 0 07:30:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:37 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:37 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 07:30:37 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 07:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:37 INFO - 'HOME': '/Users/cltbld', 07:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:37 INFO - 'LOGNAME': 'cltbld', 07:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:37 INFO - 'MOZ_NO_REMOTE': '1', 07:30:37 INFO - 'NO_EM_RESTART': '1', 07:30:37 INFO - 'PAGER': '/bin/cat', 07:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:37 INFO - 'PWD': '/builds/slave/test', 07:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:37 INFO - 'SHELL': '/bin/bash', 07:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:37 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:37 INFO - 'USER': 'cltbld', 07:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:37 INFO - 'XPC_FLAGS': '0x0', 07:30:37 INFO - 'XPC_SERVICE_NAME': '0', 07:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:38 INFO - Downloading/unpacking psutil>=3.1.1 07:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:30:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:41 INFO - Installing collected packages: psutil 07:30:41 INFO - Running setup.py install for psutil 07:30:41 INFO - building 'psutil._psutil_osx' extension 07:30:41 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 07:30:42 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 07:30:42 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 07:30:42 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 07:30:42 INFO - building 'psutil._psutil_posix' extension 07:30:42 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 07:30:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:30:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:30:42 INFO - ^ 07:30:42 INFO - 1 warning generated. 07:30:42 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 07:30:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:42 INFO - Successfully installed psutil 07:30:42 INFO - Cleaning up... 07:30:43 INFO - Return code: 0 07:30:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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')]} 07:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:30: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 mozsystemmonitor==0.3 07:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:43 INFO - 'HOME': '/Users/cltbld', 07:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:43 INFO - 'LOGNAME': 'cltbld', 07:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:43 INFO - 'MOZ_NO_REMOTE': '1', 07:30:43 INFO - 'NO_EM_RESTART': '1', 07:30:43 INFO - 'PAGER': '/bin/cat', 07:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:43 INFO - 'PWD': '/builds/slave/test', 07:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:43 INFO - 'SHELL': '/bin/bash', 07:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:43 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:43 INFO - 'USER': 'cltbld', 07:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:43 INFO - 'XPC_FLAGS': '0x0', 07:30:43 INFO - 'XPC_SERVICE_NAME': '0', 07:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:30:46 INFO - Installing collected packages: mozsystemmonitor 07:30:46 INFO - Running setup.py install for mozsystemmonitor 07:30:46 INFO - Successfully installed mozsystemmonitor 07:30:46 INFO - Cleaning up... 07:30:46 INFO - Return code: 0 07:30:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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')]} 07:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:46 INFO - 'HOME': '/Users/cltbld', 07:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:46 INFO - 'LOGNAME': 'cltbld', 07:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:46 INFO - 'MOZ_NO_REMOTE': '1', 07:30:46 INFO - 'NO_EM_RESTART': '1', 07:30:46 INFO - 'PAGER': '/bin/cat', 07:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:46 INFO - 'PWD': '/builds/slave/test', 07:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:46 INFO - 'SHELL': '/bin/bash', 07:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:46 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:46 INFO - 'USER': 'cltbld', 07:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:46 INFO - 'XPC_FLAGS': '0x0', 07:30:46 INFO - 'XPC_SERVICE_NAME': '0', 07:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:46 INFO - Downloading/unpacking jsonschema==2.5.1 07:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:30:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:49 INFO - Downloading functools32-3.2.3-2.tar.gz 07:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:30:49 INFO - warning: no files found matching '*.txt' 07:30:49 INFO - no previously-included directories found matching 'build' 07:30:49 INFO - no previously-included directories found matching 'dist' 07:30:49 INFO - no previously-included directories found matching '.git*' 07:30:49 INFO - Installing collected packages: jsonschema, functools32 07:30:49 INFO - Running setup.py install for functools32 07:30:49 INFO - warning: no files found matching '*.txt' 07:30:49 INFO - no previously-included directories found matching 'build' 07:30:49 INFO - no previously-included directories found matching 'dist' 07:30:49 INFO - no previously-included directories found matching '.git*' 07:30:49 INFO - Successfully installed jsonschema functools32 07:30:49 INFO - Cleaning up... 07:30:49 INFO - Return code: 0 07:30:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:49 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 07:30:49 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 07:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:49 INFO - 'HOME': '/Users/cltbld', 07:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:49 INFO - 'LOGNAME': 'cltbld', 07:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:49 INFO - 'MOZ_NO_REMOTE': '1', 07:30:49 INFO - 'NO_EM_RESTART': '1', 07:30:49 INFO - 'PAGER': '/bin/cat', 07:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:49 INFO - 'PWD': '/builds/slave/test', 07:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:49 INFO - 'SHELL': '/bin/bash', 07:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:49 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:49 INFO - 'USER': 'cltbld', 07:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:49 INFO - 'XPC_FLAGS': '0x0', 07:30:49 INFO - 'XPC_SERVICE_NAME': '0', 07:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:30:50 INFO - Cleaning up... 07:30:50 INFO - Return code: 0 07:30:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:50 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 07:30:50 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 07:30:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:50 INFO - 'HOME': '/Users/cltbld', 07:30:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:50 INFO - 'LOGNAME': 'cltbld', 07:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:50 INFO - 'MOZ_NO_REMOTE': '1', 07:30:50 INFO - 'NO_EM_RESTART': '1', 07:30:50 INFO - 'PAGER': '/bin/cat', 07:30:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:50 INFO - 'PWD': '/builds/slave/test', 07:30:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:50 INFO - 'SHELL': '/bin/bash', 07:30:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:50 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:50 INFO - 'USER': 'cltbld', 07:30:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:50 INFO - 'XPC_FLAGS': '0x0', 07:30:50 INFO - 'XPC_SERVICE_NAME': '0', 07:30:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:50 INFO - Downloading/unpacking blobuploader==1.2.4 07:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:52 INFO - Downloading blobuploader-1.2.4.tar.gz 07:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:30:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:30:52 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 07:30:52 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:30:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:54 INFO - Downloading docopt-0.6.1.tar.gz 07:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:30:54 INFO - Installing collected packages: blobuploader, requests, docopt 07:30:54 INFO - Running setup.py install for blobuploader 07:30:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:30:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:30:54 INFO - Running setup.py install for requests 07:30:54 INFO - Running setup.py install for docopt 07:30:55 INFO - Successfully installed blobuploader requests docopt 07:30:55 INFO - Cleaning up... 07:30:55 INFO - Return code: 0 07:30:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:55 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')]} 07:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:55 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')]} 07:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:55 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:55 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 07:30:55 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 07:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:55 INFO - 'HOME': '/Users/cltbld', 07:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:55 INFO - 'LOGNAME': 'cltbld', 07:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:55 INFO - 'MOZ_NO_REMOTE': '1', 07:30:55 INFO - 'NO_EM_RESTART': '1', 07:30:55 INFO - 'PAGER': '/bin/cat', 07:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:55 INFO - 'PWD': '/builds/slave/test', 07:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:55 INFO - 'SHELL': '/bin/bash', 07:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:55 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:55 INFO - 'USER': 'cltbld', 07:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:55 INFO - 'XPC_FLAGS': '0x0', 07:30:55 INFO - 'XPC_SERVICE_NAME': '0', 07:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-eGjoqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-8jS4na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-54GmjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ihCQWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Eywgtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-C8z5mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Q0VwEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-x8b6iB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-1DG3Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-bIBEtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4Z4nGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-M7i0T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-C3Adcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-HJ09Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-FT86Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:30:56 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ljVYNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:30:57 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xaf14c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:30:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:30:57 INFO - Running setup.py install for manifestparser 07:30:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:30:57 INFO - Running setup.py install for mozcrash 07:30:57 INFO - Running setup.py install for mozdebug 07:30:57 INFO - Running setup.py install for mozdevice 07:30:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:30:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:30:57 INFO - Running setup.py install for mozfile 07:30:57 INFO - Running setup.py install for mozhttpd 07:30:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Running setup.py install for mozinfo 07:30:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Running setup.py install for mozInstall 07:30:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Running setup.py install for mozleak 07:30:58 INFO - Running setup.py install for mozlog 07:30:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Running setup.py install for moznetwork 07:30:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:30:58 INFO - Running setup.py install for mozprocess 07:30:58 INFO - Running setup.py install for mozprofile 07:30:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:30:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:30:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:30:59 INFO - Running setup.py install for mozrunner 07:30:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:30:59 INFO - Running setup.py install for mozscreenshot 07:30:59 INFO - Running setup.py install for moztest 07:30:59 INFO - Running setup.py install for mozversion 07:30:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:30:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:30:59 INFO - Cleaning up... 07:30:59 INFO - Return code: 0 07:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:59 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')]} 07:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 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')]} 07:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:30:59 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 07:30:59 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 07:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:59 INFO - 'HOME': '/Users/cltbld', 07:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:59 INFO - 'LOGNAME': 'cltbld', 07:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:59 INFO - 'MOZ_NO_REMOTE': '1', 07:30:59 INFO - 'NO_EM_RESTART': '1', 07:30:59 INFO - 'PAGER': '/bin/cat', 07:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:59 INFO - 'PWD': '/builds/slave/test', 07:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:59 INFO - 'SHELL': '/bin/bash', 07:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:30:59 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:59 INFO - 'USER': 'cltbld', 07:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:59 INFO - 'XPC_FLAGS': '0x0', 07:30:59 INFO - 'XPC_SERVICE_NAME': '0', 07:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-3G3lLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-gFSH_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-3kv_wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-wBZGfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-jz7H80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-jHQfEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-BCpE7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-YpELOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-0x9KHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:00 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)) 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Br4tRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-bvjc6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4txEEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-_detwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-t2Rz9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Q_ZlHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4RdDWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:01 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)) 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:01 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pSqRVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:01 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)) 07:31:01 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)) 07:31:01 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)) 07:31:01 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)) 07:31:01 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)) 07:31:01 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)) 07:31:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:01 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 07:31:01 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 07:31:01 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 07:31:01 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 07:31:01 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 07:31:01 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 07:31:04 INFO - Downloading blessings-1.6.tar.gz 07:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:04 INFO - Installing collected packages: blessings 07:31:04 INFO - Running setup.py install for blessings 07:31:04 INFO - Successfully installed blessings 07:31:04 INFO - Cleaning up... 07:31:04 INFO - Return code: 0 07:31:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:05 INFO - Reading from file tmpfile_stdout 07:31:05 INFO - Current package versions: 07:31:05 INFO - blessings == 1.6 07:31:05 INFO - blobuploader == 1.2.4 07:31:05 INFO - docopt == 0.6.1 07:31:05 INFO - functools32 == 3.2.3-2 07:31:05 INFO - jsonschema == 2.5.1 07:31:05 INFO - manifestparser == 1.1 07:31:05 INFO - mozInstall == 1.12 07:31:05 INFO - mozcrash == 1.0 07:31:05 INFO - mozdebug == 0.1 07:31:05 INFO - mozdevice == 0.49 07:31:05 INFO - mozfile == 1.2 07:31:05 INFO - mozhttpd == 0.7 07:31:05 INFO - mozinfo == 0.9 07:31:05 INFO - mozleak == 0.1 07:31:05 INFO - mozlog == 3.4 07:31:05 INFO - moznetwork == 0.27 07:31:05 INFO - mozprocess == 0.24 07:31:05 INFO - mozprofile == 0.28 07:31:05 INFO - mozrunner == 6.13 07:31:05 INFO - mozscreenshot == 0.1 07:31:05 INFO - mozsystemmonitor == 0.3 07:31:05 INFO - moztest == 0.8 07:31:05 INFO - mozversion == 1.4 07:31:05 INFO - psutil == 3.1.1 07:31:05 INFO - requests == 1.2.3 07:31:05 INFO - wsgiref == 0.1.2 07:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:05 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:31:05 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 07:31:05 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 07:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:05 INFO - 'HOME': '/Users/cltbld', 07:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:05 INFO - 'LOGNAME': 'cltbld', 07:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:05 INFO - 'MOZ_NO_REMOTE': '1', 07:31:05 INFO - 'NO_EM_RESTART': '1', 07:31:05 INFO - 'PAGER': '/bin/cat', 07:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:05 INFO - 'PWD': '/builds/slave/test', 07:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:05 INFO - 'SHELL': '/bin/bash', 07:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:31:05 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:05 INFO - 'USER': 'cltbld', 07:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:05 INFO - 'XPC_FLAGS': '0x0', 07:31:05 INFO - 'XPC_SERVICE_NAME': '0', 07:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 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)) 07:31:05 INFO - Cleaning up... 07:31:05 INFO - Return code: 0 07:31:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:05 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 0x1062fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd553d5d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, '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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 07:31:05 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 07:31:05 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 07:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:05 INFO - 'HOME': '/Users/cltbld', 07:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:05 INFO - 'LOGNAME': 'cltbld', 07:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:05 INFO - 'MOZ_NO_REMOTE': '1', 07:31:05 INFO - 'NO_EM_RESTART': '1', 07:31:05 INFO - 'PAGER': '/bin/cat', 07:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:05 INFO - 'PWD': '/builds/slave/test', 07:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:05 INFO - 'SHELL': '/bin/bash', 07:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:31:05 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:05 INFO - 'USER': 'cltbld', 07:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:05 INFO - 'XPC_FLAGS': '0x0', 07:31:05 INFO - 'XPC_SERVICE_NAME': '0', 07:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:05 INFO - Cleaning up... 07:31:05 INFO - Return code: 0 07:31:05 INFO - Running post-action listener: _resource_record_post_action 07:31:05 INFO - Running post-action listener: _start_resource_monitoring 07:31:05 INFO - Starting resource monitoring. 07:31:05 INFO - [mozharness: 2017-01-12 15:31:05.733369Z] Finished create-virtualenv step (success) 07:31:05 INFO - [mozharness: 2017-01-12 15:31:05.734375Z] Running install step. 07:31:05 INFO - Running pre-action listener: _resource_record_pre_action 07:31:05 INFO - Running main action method: install 07:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:05 INFO - Reading from file tmpfile_stdout 07:31:05 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:06 INFO - Reading from file tmpfile_stdout 07:31:06 INFO - Output received: 07:31:06 INFO - Usage: mozinstall [options] installer 07:31:06 INFO - Options: 07:31:06 INFO - -h, --help show this help message and exit 07:31:06 INFO - -d DEST, --destination=DEST 07:31:06 INFO - Directory to install application into. [default: 07:31:06 INFO - "/builds/slave/test"] 07:31:06 INFO - --app=APP Application being installed. [default: firefox] 07:31:06 INFO - mkdir: /builds/slave/test/build/application 07:31:06 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'] 07:31:06 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 07:31:27 INFO - Reading from file tmpfile_stdout 07:31:27 INFO - Output received: 07:31:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:31:27 INFO - Running post-action listener: _resource_record_post_action 07:31:27 INFO - [mozharness: 2017-01-12 15:31:27.951824Z] Finished install step (success) 07:31:27 INFO - [mozharness: 2017-01-12 15:31:27.951955Z] Running run-tests step. 07:31:27 INFO - Running pre-action listener: _resource_record_pre_action 07:31:27 INFO - Running main action method: run_tests 07:31:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:31:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:31:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:31:27 INFO - 2017-01-12 07:31:27.966 screenresolution[1635:7707] starting screenresolution argv=screenresolution get 07:31:27 INFO - 2017-01-12 07:31:27.969 screenresolution[1635:7707] Display 0: 1600x1200x32@60 07:31:27 INFO - 2017-01-12 07:31:27.975 screenresolution[1636:7710] starting screenresolution argv=screenresolution list 07:31:27 INFO - Available Modes on Display 0 07:31:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:31:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:31:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:31:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:31:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:31:28 INFO - Intel Iris: 07:31:28 INFO - Chipset Model: Intel Iris 07:31:28 INFO - Type: GPU 07:31:28 INFO - Bus: Built-In 07:31:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:31:28 INFO - Vendor: Intel (0x8086) 07:31:28 INFO - Device ID: 0x0a2e 07:31:28 INFO - Revision ID: 0x0009 07:31:28 INFO - Displays: 07:31:28 INFO - Display: 07:31:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:31:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:31:28 INFO - Display Serial Number: 1600x1200 60 07:31:28 INFO - Main Display: Yes 07:31:28 INFO - Mirror: Off 07:31:28 INFO - Online: Yes 07:31:28 INFO - Rotation: Supported 07:31:28 INFO - Return code: 0 07:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:28 INFO - Python 2.7.3 07:31:28 INFO - Return code: 0 07:31:28 INFO - grabbing minidump binary from tooltool 07:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:28 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 0x1060d8d30>, '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 0x105fffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060cdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31:28 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 07:31:28 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 07:31:28 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 07:31:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:28 INFO - Return code: 0 07:31:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:31:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:31:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 07:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0201 --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 07:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kRcgwrzErY/Render', 07:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:28 INFO - 'HOME': '/Users/cltbld', 07:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:28 INFO - 'LOGNAME': 'cltbld', 07:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:28 INFO - 'MOZ_NO_REMOTE': '1', 07:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:28 INFO - 'NO_EM_RESTART': '1', 07:31:28 INFO - 'PAGER': '/bin/cat', 07:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:28 INFO - 'PWD': '/builds/slave/test', 07:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:28 INFO - 'SHELL': '/bin/bash', 07:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Iy2EeR0iuh/Listeners', 07:31:28 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 07:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:28 INFO - 'USER': 'cltbld', 07:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:28 INFO - 'XPC_FLAGS': '0x0', 07:31:28 INFO - 'XPC_SERVICE_NAME': '0', 07:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 07:31:28 INFO - mozversion application_buildid: 20170112060215 07:31:28 INFO - mozversion application_changeset: 1289b60bd5ed221f6713497fcdc8167ae3896916 07:31:28 INFO - mozversion application_display_name: Nightly 07:31:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:28 INFO - mozversion application_name: Firefox 07:31:28 INFO - mozversion application_remotingname: firefox 07:31:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:31:28 INFO - mozversion application_vendor: Mozilla 07:31:28 INFO - mozversion application_version: 53.0a1 07:31:28 INFO - mozversion platform_buildid: 20170112060215 07:31:28 INFO - mozversion platform_changeset: 1289b60bd5ed221f6713497fcdc8167ae3896916 07:31:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:31:28 INFO - mozversion platform_version: 53.0a1 07:31:28 INFO - using testdate: 1484235088 07:31:28 INFO - actual date: 1484235088 07:31:28 INFO - starting webserver on 'localhost:49251' 07:31:28 INFO - SUITE-START | Running 2 tests 07:31:28 INFO - TEST-START | dromaeo_css 07:31:28 INFO - operating with platform_type : mac_ 07:31:28 INFO - Initialising browser for dromaeo_css test... 07:31:28 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpVqawlB/profile http://localhost:49251/getInfo.html) 07:31:30 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 07:31:30 INFO - PROCESS | 1646 | colorDepth:24 07:31:30 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 07:31:30 INFO - PROCESS | 1646 | __metrics 07:31:30 INFO - PROCESS | 1646 | 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" 07:31:30 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:31 INFO - TEST-INFO | 1646: exit 0 07:31:31 INFO - Browser initialized. 07:31:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:31:31 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpVqawlB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:43 INFO - PROCESS | 1648 | RSS: Main: 235040768 07:31:43 INFO - PROCESS | 1648 | 07:34:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 07:34:49 INFO - PROCESS | 1648 | RSS: Main: 446750720 07:34:49 INFO - PROCESS | 1648 | 07:36:32 INFO - PROCESS | 1648 | ************************* 07:36:32 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 07:36:32 INFO - PROCESS | 1648 | 07:36:32 INFO - PROCESS | 1648 | Full message: ReferenceError: fetch is not defined 07:36:32 INFO - PROCESS | 1648 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:36:32 INFO - PROCESS | 1648 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:36:32 INFO - PROCESS | 1648 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:36:32 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:36:32 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:36:32 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:36:32 INFO - PROCESS | 1648 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:36:32 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:36:32 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:36:32 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:36:32 INFO - PROCESS | 1648 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:36:32 INFO - PROCESS | 1648 | 07:36:32 INFO - PROCESS | 1648 | ************************* 07:37:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 07:37:53 INFO - PROCESS | 1648 | RSS: Main: 371052544 07:37:53 INFO - PROCESS | 1648 | 07:40:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 07:40:55 INFO - PROCESS | 1648 | RSS: Main: 338538496 07:40:55 INFO - PROCESS | 1648 | 07:43:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 07:43:58 INFO - PROCESS | 1648 | RSS: Main: 344145920 07:43:58 INFO - PROCESS | 1648 | 07:47:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 07:47:03 INFO - PROCESS | 1648 | RSS: Main: 330366976 07:47:03 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 07:50:06 INFO - PROCESS | 1648 | RSS: Main: 457953280 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | __start_tp_report 07:50:06 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 07:50:06 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 07:50:06 INFO - PROCESS | 1648 | |i|pagename|runs| 07:50:06 INFO - PROCESS | 1648 | |0;dojo.html;2831.900883469079;3329.2842038961626;3346.1969127409416;3347.5146103814945;3409.5397121388605;5599.916001259984;5681.147827825823;5697.458741419564;5702.258706368171;5731.426857314266;5710.800121995741;5726.68503232322;5812.622179558326;5874.118882167677;5942.940570594293;5173.974130129344;5184.792608295664;5215.1395019821875;5238.13570760824;5306.70813105279;5322.281491998569;5336;5342.439043900393;5343.412224655285;5353.464653534635;5158.871028224287;5186.533212010937;5285.656432331886;5300.734963251822;5391.460853914617;4385.6710746694125;5174.896502069938;5214.139666954948;5248.71132087735;5266.657667251617;5231.136862417697;5234.607404444682;5253.185756207794;5264.210368444726;5296.205569164617;5785.334686511063;5796.1595568642315;5809.912851307234;5816.563757718146;5851.882962340772;6132.795736484409;6195.721192546346;6213.56505044647;6261.123442718023;6355.491560675135;6255.06174073888;6320.462760665349;6326.398992095744;6371.681415929185;6378;5945.316288626776;5949.8215053548465;5950.5834591578605;5953.878823745867;6009.188759517497;6243.469305109116;6246.187995560548;6270.592411493239;6361.713722882481;6362.936370636235;6113.263474096151;6137.631742095399;6148.016317389196;6251.12484252205;6260.436560709558;6161.691915404212;6341.714622841983;6350.809475715643;6351.682415879188;6369.394907483782;6150.046742754791;6153.661548614869;6169.105479705418;6237.376262373817;6277.089821975788;6339.714712837841;6343.651099189497;6353.5234857385885;6366.649834259068;6375.075614036032;4953.2074868020945;4997.700137991659;5012.3233363495465;5018.096742586296;5029.471905449948;3716.5168528091176;4428.822847086145;4455.465344158657;4507.549245075466;4508.752018638941;5713.485786279171;5768.163616275617;5774.133879918046;5779.190913272145;5779.566532510079;6325.114483972247;6342.334054924258;6346.873062538723;6347.238331400169;6349.714262858182;4839;5692.715364231772;5744.971275143681;5766.0197766379815;5772.989726797794;5465.508104270635;5473.397926228112;5474.835754927359;5476.616636835463;5477.808276710296;6237.5321850860455;6264.467520260829;6276.905846412216;6282.308946015934;6299.433051025429;6340.778072767431;6354.110424540476;6365.4589359903075;6371.7132729028235;6384.170057892445;6320;6342.080398342307;6345.365463453618;6350.079238510482;6355.904661430175;6115.362036198588;6184.134221209124;6189.659568723763;6304.369563043659;6310.368963103653;4954.529319714621;6295.842603934938;6359.96820015897;6360.236771587439;6389.9680501597195;6075.787347442818;6194.969025154845;6230.50155987529;6236.126942228001;6246.156768836331;5453.018456677836;5907.852303692442;6025.548083893638;6192;6221.875562488815;6003.90994135097;6004.939950600438;6011.128386383863;6013.939860601338;6019.247594050744;6111.591738512325;6115.696722738907;6120.599512025131;6136.079588061826;6165.290991536031;6030.969845150747;6081.422264884829;6082.635041897501;6105.9694701526205;6111.755529778759 07:50:06 INFO - PROCESS | 1648 | |1;ext.html;445.5967349548658;456.0445865910917;486.3774368807926;487.85354417119794;492.40910906911716;24298.75701242987;40575.59424405755;41871.79064104683;42042.57957420425;43872.56127438725;42474;42954.7852260739;42961;43095;43104.568954310445;9235.538223088852;9465.905340946605;9466.148046675798;9468.81062378752;9470.10034046768;8597.871031934526;8808.603612837438;11134.777304453906;11406.258593191458;11422.485988130513;11330.773384532346;11458.369789661565;11458.541658333657;11466;11470.08239340855;8515.57422128891;11419.200655954086;11517.942410287937;11537.5961841336;11543.01884339832;8481.872771908425;10890.945545272223;11182.608608698738;11201.327920324806;11371.943140284246;40673.79663101695;41168.970775730544;41185.794071029755;41199.7940010301;41236.587634123876;4044.4337792709043;4047.797610119512;4068.8575899843363;4075.103477235033;4161.126163505667;27188.864055679795;28954.565681514796;29363.41273174546;30555.388892222032;31044.689553104632;3139.764517661162;3275.8853440129483;3282.917927051819;3285.047336272502;3326.76712630116;469662;475014;475574.99999999657;478214;480670;59513.70243148757;61099.38900611026;61430;61505.69247153735;61643;66842.33157668458;67186;67205;67213;67217;15856.286467109008;16298.51104466868;16318.75521867173;16326.346946122023;16409.91795041017;11298.265612735146;11552.364619945984;12491.937540312421;12573.99408047354;12594;15895.364185432685;18310.81689183118;18359.54101147482;18578.071096445394;18761.90619046896;5642.830715078554;6177.567570270038;6589.077529145923;6871.415929645936;6929.925861491477;838.446625227347;875.2844424927086;881.352206128495;905.3843386497114;918.3112665500875;24520.632190517157;29844.701552984625;30333.99055983745;30528.389432211232;30758.38483230323;1034.3948789957808;1057.2704833664748;1066.8666416697913;1085.5115198160702;1092.847001419802;15043.774343384857;15508.302126404338;15660.451884183998;15865;15918;540678;545556;548262;548645.5135448674;550576;59018.70490647605;59329.40670593325;59335;59421.108683369785;59465.10802337968;1290.535407253388;1416.9433222671184;1458.7082583483345;1470.9997201902595;1476.2618690654672;28285.57571636386;29079;29229.707702923548;29584.55623165611;30031.54952675668;51643;51663.48336516737;51725.96548069093;51818.48181518287;51918.22122668087;384790;387334;387463.1880521738;387830.1216987907;388286;39337;39658;40120;40282.395764064146;40675.59324406718;36525.63474365223;44775.77612112049;45823.77088114538;46056.769716151204;46410.76794616006;37928.81035594915;38015.239695206496;38740.80629596834;38758.526060413584;39017.60982390253;37735;37963.2407351857;38018;38976.41535377028;39311.2137757249;33907.49138762871;36656;36721.63278367289;37408.438873417464;37731.81134094312;28065.71934280631;31878.362432751677;31899.202519937186;31902.68097318997;31952.201194970312;23722.288331350086;23855.40361490907;24062.15782447606;24291.271261862174;24321.51356972886 07:50:06 INFO - PROCESS | 1648 | |2;jquery.html;303.18745761359577;373.2106594552522;374.0592410088627;377.4621164840103;398.7069503914624;15247.008944418605;15857.76213356799;16203.108829014414;20430.69353959692;21090;20473.078711457983;20576.485587860312;20961;21174.89412552935;21320.57358852826;4789.3294938708605;5567.777288908449;5790.826275211751;5797.811448653016;5808.4191580842;4974.900501989958;5005.849824505271;5752.2234498342605;5865.560082993771;5868.618539794899;5737.913931291034;5800.854978625547;5817.069268916975;5821.243238379005;5823.184754134425;5904.2029326040865;6095;6096.451319381277;6108.664023478706;6113.419225173601;5821.854453638693;5830.055821810001;5871.32479764824;5906.409359064102;5960.880782384372;13344.733105337937;13359.866401336056;13365.66585835362;13539.39072741729;13569.118007329591;2602.2323414592743;2624.39638883058;2641.4320921001886;2686.408990022192;2713.2945434187054;10192.898071019343;10244.846327305097;10530.684079477627;12851.100422970396;14314.856851431561;1801.8107552101335;1862.1992543206416;1879.6334714730633;1886.9150888210063;1890.3572785252943;121605;125460;126954;129048;129718.3514082433;11151.776964460665;11620.477078531487;11629;11648.184627076193;11849.763004739856;15298.464553740565;15299.541013769605;15325.846741532665;15349.15579643108;15353;8290.834183316421;8553.44402613828;8573.957130214309;8661.437006594551;8692.608832602422;8336.390042727788;8375.916240837514;8430.957845210734;8609.268212202032;8613.956930215309;4654.371659825948;4757.429108506932;4758.5241475852135;4818.71087734737;4829.6860704054125;1936.8934708591162;2020.7069974853857;2021.5956808638332;2029.665105257616;2036.521417466894;726.4696771356939;867.4795122926193;871.7620978210956;873.2315562305131;900.3787386703285;14662.486812961495;18496;19286.324978625682;20206;20600.866952317767;807.249258189889;889.7597648634833;910.8724778531011;912.119804388768;918.1415376622879;10218.489075546342;10509.737256568495;10550.208734344955;10556.419396933243;10636.787264254826;371295;531116;655039;657861;684346;110622;139405;141645;148879;159203;977.7653363192925;991.5984026469291;1014.8629934958834;1015.5531566110888;1017.1303535477208;18595.721064183777;19282.517937051685;19310.903445482683;19361.515962100497;19384.418467445998;60682.69658651679;61013;61577;61990.69004654948;62110.068348973895;89001.21997560142;89848;91041.54479227826;91661;92950;8124.837503249783;8128.796780080545;8317.085120636604;8396;8677.652893884175;9521.904780952378;10597.788044238918;12660.430280637209;12920.483180672667;13322.800157997444;10907.454627268764;11167.664970050912;11305.208635395444;11614.070874329864;11668.124890633406;11062.834057488983;11066.667999960015;11218.439078046229;11325.943370283096;11480.598179063692;10862.619808306672;10954.890451095387;11141.387223702774;11664.591739289084;11778.823317650413;7125.928740712734;7339.935709322225;7480.962595186989;8185.181481852005;8541.487510749375;8403.285720713808;10309.381437113798;11123.332600044023;11595;11604.88395116038 07:50:06 INFO - PROCESS | 1648 | |3;mootools.html;1343.5566263133164;1387.1538361599426;1444.4077928049496;1458.1615571046643;1460.1677044084863;7570.432217583682;7957.721479748196;7960.243776841198;7972.282494575487;7978.4016198785175;7022.473314501407;7233.348998590126;7460.6642701078445;7717.807054823632;7761.301482866541;2206.58074965756;2275.248058994573;2276.84062115652;2279.076973825603;2285.451491642002;2229.2977712020743;2268.9092436302526;2271.5911135995516;2272.7727227277305;2276.203328834903;1634.4197809777536;2042.121887588344;2180.7274090738656;2188.8139508141826;2215.9778402215934;2235.6311208650636;2240.4510894830787;2245.214175038723;2251.1670681847822;2252.200468833557;2167.3389616167065;2214.2914267434426;2218.4453886528368;2259.051198496635;2262.423082114067;7739.8452030959625;7845.097813751435;7855.057393112806;7861.764147075597;7868.016497937758;4784.234522476422;4829.203181475087;4923.335349727777;4931.284963680247;4933.950660493385;3122.67211942745;3273.6562660920736;3277.9344413111844;3333.4833100869405;3345.832708364572;1079.5304042741432;1103.8951299626522;1104.4312179227704;1120.7001931398706;1188.2810899405822;9861.852072218922;9984.301098923079;10028.097471227551;10029.699109026667;10064.144547713453;4131.669466442678;4399.450068741407;4474.284114541658;4514.0971805639;4524.819007239674;8942.284617230605;8945.373823832399;8970.41692289999;8987.236084932723;9035;5057.190849464068;5057.848264552069;5059.62052846038;5059.873503162376;5063.417706963673;3850.249201405699;4103.753774773523;4169.583041695806;4517.88705282364;4561.110583436198;3688.354537955847;3700.703943684499;3774.7735135891935;3800.9809950950066;3812.4662547243406;1218.670958841108;1231.347385885482;1232.6548566401423;1241.3544956622686;1242.192574826352;819.2239245200467;841.4530555139086;860.1355637584313;910.7905181808221;924.5793164110314;5274.446183150714;5366.168243922199;5374.328208973879;5616.438356164433;5624.987502249553;897.5063714956748;917.554985831871;927.2859897878595;963.6097380560884;983.6213057972589;3404.548724918055;3477.9660678941436;3557.181848174934;3560.7507474477056;3589.2642008388216;8928.776780580538;9172.12864777845;9228.630854765868;9247.537623118817;9255.444673319622;9113.36206465541;9133.315001374924;9223.985361610217;9309.162175404246;9315.673951411669;7243.311885370881;7403.259674032553;7413.1104267488245;7415.443841711785;7421.554706717507;4724.480307166277;4777.259524773665;4821.927671085006;5064.043802492323;5254.369475662945;6741.730330786713;8634.352423568384;8636.395452318277;8650.048494665707;8652.653893844428;7350.889736653848;8380.874286885579;8619.094995025433;8725.30197584179;8735.43219690718;6320.209973753281;6526.379993900572;6538.444232240123;6742.9662851685425;6876.931230687629;7257.818554535967;7450.403967682681;7493;7587.430942679211;7591.924080759121;6741.831454213684;6845.760398386033;6858.314168583302;6892.483063770337;6990.580565166107;6678;6724.361185687352;6751.088603038527;6791.388775010273;6810.250872404131;5891.410858914074;5927.733252003584;5930.614510056833;5961.701914904324;5962.016267315845;4632.606228470483;4714.4442334029;4720.362751028704;4743.388431798937;4888.560029597354;4699.600533954652;4807.95192048075;4832.927506087482;4847.660663753503;4870.805167793249 07:50:06 INFO - PROCESS | 1648 | |4;prototype.html;329.5386458957461;331.19684764446214;335.8387973772589;339.7316120264991;347.86781023211205;5020.573251273641;5370.301860758106;5420.430854760242;5442.374126975399;5488.231647569333;4875.195592727205;5054.519820617035;5092.592592592593;5214.452482489341;5339.359276886779;3302.801831890082;3431.450967845139;4131.380292956065;4132.483439570054;4141.544430112685;4116.135611521569;4136.27615167346;4177.540470548237;4185.811638476261;4188.497380314367;3725.7578257413184;3883.796023232793;4017.995501124719;4122.196171746511;4144.958550414487;3578.320119177348;3617.403128483797;4093.16090201508;4097.795110244506;4117.085555216823;3573.5533058367705;3603.837827297778;3608;3618.7466877318598;3636;4551.998560316725;4559.726416415026;5166.948330516722;5197.636165468419;5311.867203319948;4716.787744551469;4737.857864264078;4758.167320718895;4760.952390476085;4802.542169637855;4551.817927282905;4972;5133.460986596391;5655.688937108457;5656.218291238247;3759.59385992962;4052.0882801369753;4054.4729185205733;4055.9797201013807;4063.735857169304;5527.695976721238;5537.1140617501005;5710.428957104298;5839.970800146015;5863.827234553107;3801.2967600993907;4056.1887622475624;4059.878203653895;4089;4091.0590564170407;3334.433146365124;3931.5478719947573;4087.9151632667554;4125.731827431207;4135.958640413617;3620.4931309616672;3856.8071596420627;4499.807550998988;4577.633789296849;4765.9046819064115;4956.3804524434445;5373.462653734673;5608.242887210256;5615.0454422748235;5619.971900140555;1191.523390643732;1282.5254655777235;1315.7565850317721;1336.5990202939079;1338.4445455136204;787.629813987425;793.6230290611913;815.3558688635911;884.7390019944132;905.9547022648841;4035.556088830226;4106.3635136553885;4778.354922085474;4869.768393935004;4890.290907818347;4839.903201935942;4887.315775791392;5356.4107948125675;5452.609584555913;5509.685931908608;4125.587441255911;4164.875053748392;4600.401947746772;4879.902401951941;4897.7795999180125;2403.495265994126;2661.418424128592;2663.866806659698;2686.0978713847308;2845.38824152804;5057.508035129646;5688.748475335502;5711.771529138788;5714.1714851148945;5730.713464326767;5260.868478288074;5592.412796656374;5622.228997865659;5635.943640563624;5636.882888640426;4777.205334878996;5072.949270507322;5381.596380271495;5509.752061157177;5681.715914204273;4829.589484893683;4923.409190897115;5124.590032797441;5384.326959130109;5445.564354851523;4745.430548334222;5097.949020509747;5694.345150307602;5722.656640601577;5726.559768220291;5008.57427118699;5224.216367544898;5720.056190728483;5912.9061123690535;5972.522198224218;3549.1304630365726;3616.029264051151;3761.6050314716563;3771.811409429463;3776.4894042383266;5515.310586176728;5694;5720.599558031065;5785.39253378406;5846.503047241032;5635.549156067423;5644.6613203207935;5654.15187721845;5669.2220811802745;5694.857628559319;4970.6023518117745;5611.915821262766;5649.915251271316;5675.404082571271;5675.744591493475;4787.521247875185;5516.021348471941;5521;5534.335879694462;5538.556915446835;5063.518965698253;5124.872528043825;5526.91709624364;5534.395025442737;5538.806141785019;4791.5687588117235;5044.9243261350375;5392.921415716951;5447.836564903059;5513.436252142773 07:50:06 INFO - PROCESS | 1648 | |5;yui.html;632.6615260835454;764.5489161394776;797.7367468735316;807.6567458829995;812.48407261389;15394.15332156731;15950.441734539294;15996.440124595641;16010.67978640428;16032.519024429272;14654.926725366358;14806.555803325917;14824.629384265372;14828.332725027372;14847.1091734496;685.8662560800645;699.3216579917472;712.2912701861654;713.1192976673804;858.540680735808;706.015108923052;709.163187438822;846.6147902704255;873.8645509945968;880.0143838900409;924.4869097650801;931.3992474853716;931.8509038553833;933.6685476655791;939.8778158839341;891.4784850862221;923.1507013547553;935.2144198872945;935.471458625881;939.8167357365317;694.8019814352886;697.1564407067455;698.4517154034058;699.6711545573572;701.5930760159096;12081.275123492618;12561.371931403393;14793.11241325524;15343.846561534354;15398.53804385859;14228.644283892985;14377.712445751027;14410.639734006734;14413.71172576553;14470.27648617565;10471.68584942453;11380.772384552345;12123.151379403445;12139.210951288138;12344.81482777759;3590.964090359089;3714.220013797106;3797.4493698413853;3868.052327179859;3918.1967696622123;85014;101231.99999999926;102599.91115680829;104699.23879278683;105424;10325.535350909227;10660.520276587573;10702.94648526768;10731.83902241467;11036.944815275872;21785.673214901788;22013.339599812032;22050.228242011774;22078.116875324806;22116;7620.1998790127345;7626.885596716053;7644.80887977805;7683.923160768432;7860.84278314442;3464.3071385722956;3645.7265705072227;4008.719389642697;4010.6991975601945;4052.615001574846;3351.5307856900054;3415.863365465403;3424.2124311408515;3439.2949445363643;3452.4993875887853;620.7516993202755;625.0904933322097;631.438020162047;636.147562266565;638.0110828216339;668.3416834418094;682.7951614515625;707.9156038045533;718.647862547348;732;10034.598616055277;10101.797964040825;10121.991920339226;10443.530041148166;10594.788104237872;688.3942130924854;690.7444245629149;691.5781373362245;701.9894701579481;711.3562226185311;3909.8465952544066;4700.576948074621;4723.622110231173;4725.810967561259;4740.1230772307235;93699.53150234341;93765.5311723437;94184;95182;95282;78793.60603196947;81701;81989;83395;83657.58171209105;67148;68650;69547;75324.49351012839;77212;10835.891641083488;11053.723656908642;11237.604125346825;11367.943160284145;11401.828972565252;47152.22782177486;48096;49045.75477122592;49340.75329623329;49610.503894962036;45170.774146130374;49016.5098349012;59084.704576478565;59556.40443595508;60717.39282607118;12874.871251287368;13031.934840325737;13053.804192937303;13177.341132943506;13439.328033598085;12643.494260229489;14476.638084047561;14506.347214375168;14875.404983800527;15295.388184472497;13482.19106853592;13759.17444953306;13976.237604016556;14143.151410915378;14211.841206360954;13602.591922242349;13608.387622556811;13731.519396821062;13744.931275343559;13913.791293130409;13092.672683182996;13270.535531256359;13454.46182152703;13483;13947.232902190477;10726.624568140078;11041.56459660239;11229.606963756229;11334.60328888485;11461.770764584828;9939.665918723511;10558;11005.559777608805;11011.28426652265;11053.226274160732 07:50:06 INFO - PROCESS | 1648 | __end_tp_report 07:50:06 INFO - PROCESS | 1648 | __start_cc_report 07:50:06 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2344 07:50:06 INFO - PROCESS | 1648 | __end_cc_report 07:50:06 INFO - PROCESS | 1648 | __startTimestamp1484236206814__endTimestamp 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | ------- Summary: start ------- 07:50:06 INFO - PROCESS | 1648 | Number of tests: 6 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5778.54 Median:6019.25 stddev:685.00 (11.4%) stddev-sans-first:646.93 07:50:06 INFO - PROCESS | 1648 | Values: 2831.9 3329.3 3346.2 3347.5 3409.5 5599.9 5681.1 5697.5 5702.3 5731.4 5710.8 5726.7 5812.6 5874.1 5942.9 5174.0 5184.8 5215.1 5238.1 5306.7 5322.3 5336.0 5342.4 5343.4 5353.5 5158.9 5186.5 5285.7 5300.7 5391.5 4385.7 5174.9 5214.1 5248.7 5266.7 5231.1 5234.6 5253.2 5264.2 5296.2 5785.3 5796.2 5809.9 5816.6 5851.9 6132.8 6195.7 6213.6 6261.1 6355.5 6255.1 6320.5 6326.4 6371.7 6378.0 5945.3 5949.8 5950.6 5953.9 6009.2 6243.5 6246.2 6270.6 6361.7 6362.9 6113.3 6137.6 6148.0 6251.1 6260.4 6161.7 6341.7 6350.8 6351.7 6369.4 6150.0 6153.7 6169.1 6237.4 6277.1 6339.7 6343.7 6353.5 6366.6 6375.1 4953.2 4997.7 5012.3 5018.1 5029.5 3716.5 4428.8 4455.5 4507.5 4508.8 5713.5 5768.2 5774.1 5779.2 5779.6 6325.1 6342.3 6346.9 6347.2 6349.7 4839.0 5692.7 5745.0 5766.0 5773.0 5465.5 5473.4 5474.8 5476.6 5477.8 6237.5 6264.5 6276.9 6282.3 6299.4 6340.8 6354.1 6365.5 6371.7 6384.2 6320.0 6342.1 6345.4 6350.1 6355.9 6115.4 6184.1 6189.7 6304.4 6310.4 4954.5 6295.8 6360.0 6360.2 6390.0 6075.8 6195.0 6230.5 6236.1 6246.2 5453.0 5907.9 6025.5 6192.0 6221.9 6003.9 6004.9 6011.1 6013.9 6019.2 6111.6 6115.7 6120.6 6136.1 6165.3 6031.0 6081.4 6082.6 6106.0 6111.8 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:62335.42 Median:213102.00 stddev:126008.16 (59.1%) stddev-sans-first:126275.97 07:50:06 INFO - PROCESS | 1648 | Values: 445.6 456.0 486.4 487.9 492.4 24298.8 40575.6 41871.8 42042.6 43872.6 42474.0 42954.8 42961.0 43095.0 43104.6 9235.5 9465.9 9466.1 9468.8 9470.1 8597.9 8808.6 11134.8 11406.3 11422.5 11330.8 11458.4 11458.5 11466.0 11470.1 8515.6 11419.2 11517.9 11537.6 11543.0 8481.9 10890.9 11182.6 11201.3 11371.9 40673.8 41169.0 41185.8 41199.8 41236.6 4044.4 4047.8 4068.9 4075.1 4161.1 27188.9 28954.6 29363.4 30555.4 31044.7 3139.8 3275.9 3282.9 3285.0 3326.8 469662.0 475014.0 475575.0 478214.0 480670.0 59513.7 61099.4 61430.0 61505.7 61643.0 66842.3 67186.0 67205.0 67213.0 67217.0 15856.3 16298.5 16318.8 16326.3 16409.9 11298.3 11552.4 12491.9 12574.0 12594.0 15895.4 18310.8 18359.5 18578.1 18761.9 5642.8 6177.6 6589.1 6871.4 6929.9 838.4 875.3 881.4 905.4 918.3 24520.6 29844.7 30334.0 30528.4 30758.4 1034.4 1057.3 1066.9 1085.5 1092.8 15043.8 15508.3 15660.5 15865.0 15918.0 540678.0 545556.0 548262.0 548645.5 550576.0 59018.7 59329.4 59335.0 59421.1 59465.1 1290.5 1416.9 1458.7 1471.0 1476.3 28285.6 29079.0 29229.7 29584.6 30031.5 51643.0 51663.5 51726.0 51818.5 51918.2 384790.0 387334.0 387463.2 387830.1 388286.0 39337.0 39658.0 40120.0 40282.4 40675.6 36525.6 44775.8 45823.8 46056.8 46410.8 37928.8 38015.2 38740.8 38758.5 39017.6 37735.0 37963.2 38018.0 38976.4 39311.2 33907.5 36656.0 36721.6 37408.4 37731.8 28065.7 31878.4 31899.2 31902.7 31952.2 23722.3 23855.4 24062.2 24291.3 24321.5 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35286.34 Median:11961.40 stddev:99792.40 (834.3%) stddev-sans-first:100037.77 07:50:06 INFO - PROCESS | 1648 | Values: 303.2 373.2 374.1 377.5 398.7 15247.0 15857.8 16203.1 20430.7 21090.0 20473.1 20576.5 20961.0 21174.9 21320.6 4789.3 5567.8 5790.8 5797.8 5808.4 4974.9 5005.8 5752.2 5865.6 5868.6 5737.9 5800.9 5817.1 5821.2 5823.2 5904.2 6095.0 6096.5 6108.7 6113.4 5821.9 5830.1 5871.3 5906.4 5960.9 13344.7 13359.9 13365.7 13539.4 13569.1 2602.2 2624.4 2641.4 2686.4 2713.3 10192.9 10244.8 10530.7 12851.1 14314.9 1801.8 1862.2 1879.6 1886.9 1890.4 121605.0 125460.0 126954.0 129048.0 129718.4 11151.8 11620.5 11629.0 11648.2 11849.8 15298.5 15299.5 15325.8 15349.2 15353.0 8290.8 8553.4 8574.0 8661.4 8692.6 8336.4 8375.9 8431.0 8609.3 8614.0 4654.4 4757.4 4758.5 4818.7 4829.7 1936.9 2020.7 2021.6 2029.7 2036.5 726.5 867.5 871.8 873.2 900.4 14662.5 18496.0 19286.3 20206.0 20600.9 807.2 889.8 910.9 912.1 918.1 10218.5 10509.7 10550.2 10556.4 10636.8 371295.0 531116.0 655039.0 657861.0 684346.0 110622.0 139405.0 141645.0 148879.0 159203.0 977.8 991.6 1014.9 1015.6 1017.1 18595.7 19282.5 19310.9 19361.5 19384.4 60682.7 61013.0 61577.0 61990.7 62110.1 89001.2 89848.0 91041.5 91661.0 92950.0 8124.8 8128.8 8317.1 8396.0 8677.7 9521.9 10597.8 12660.4 12920.5 13322.8 10907.5 11167.7 11305.2 11614.1 11668.1 11062.8 11066.7 11218.4 11325.9 11480.6 10862.6 10954.9 11141.4 11664.6 11778.8 7125.9 7339.9 7481.0 8185.2 8541.5 8403.3 10309.4 11123.3 11595.0 11604.9 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5106.80 Median:5063.73 stddev:2722.75 (53.8%) stddev-sans-first:2715.69 07:50:06 INFO - PROCESS | 1648 | Values: 1343.6 1387.2 1444.4 1458.2 1460.2 7570.4 7957.7 7960.2 7972.3 7978.4 7022.5 7233.3 7460.7 7717.8 7761.3 2206.6 2275.2 2276.8 2279.1 2285.5 2229.3 2268.9 2271.6 2272.8 2276.2 1634.4 2042.1 2180.7 2188.8 2216.0 2235.6 2240.5 2245.2 2251.2 2252.2 2167.3 2214.3 2218.4 2259.1 2262.4 7739.8 7845.1 7855.1 7861.8 7868.0 4784.2 4829.2 4923.3 4931.3 4934.0 3122.7 3273.7 3277.9 3333.5 3345.8 1079.5 1103.9 1104.4 1120.7 1188.3 9861.9 9984.3 10028.1 10029.7 10064.1 4131.7 4399.5 4474.3 4514.1 4524.8 8942.3 8945.4 8970.4 8987.2 9035.0 5057.2 5057.8 5059.6 5059.9 5063.4 3850.2 4103.8 4169.6 4517.9 4561.1 3688.4 3700.7 3774.8 3801.0 3812.5 1218.7 1231.3 1232.7 1241.4 1242.2 819.2 841.5 860.1 910.8 924.6 5274.4 5366.2 5374.3 5616.4 5625.0 897.5 917.6 927.3 963.6 983.6 3404.5 3478.0 3557.2 3560.8 3589.3 8928.8 9172.1 9228.6 9247.5 9255.4 9113.4 9133.3 9224.0 9309.2 9315.7 7243.3 7403.3 7413.1 7415.4 7421.6 4724.5 4777.3 4821.9 5064.0 5254.4 6741.7 8634.4 8636.4 8650.0 8652.7 7350.9 8380.9 8619.1 8725.3 8735.4 6320.2 6526.4 6538.4 6743.0 6876.9 7257.8 7450.4 7493.0 7587.4 7591.9 6741.8 6845.8 6858.3 6892.5 6990.6 6678.0 6724.4 6751.1 6791.4 6810.3 5891.4 5927.7 5930.6 5961.7 5962.0 4632.6 4714.4 4720.4 4743.4 4888.6 4699.6 4808.0 4832.9 4847.7 4870.8 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4462.78 Median:4910.59 stddev:1357.04 (27.6%) stddev-sans-first:1324.91 07:50:06 INFO - PROCESS | 1648 | Values: 329.5 331.2 335.8 339.7 347.9 5020.6 5370.3 5420.4 5442.4 5488.2 4875.2 5054.5 5092.6 5214.5 5339.4 3302.8 3431.5 4131.4 4132.5 4141.5 4116.1 4136.3 4177.5 4185.8 4188.5 3725.8 3883.8 4018.0 4122.2 4145.0 3578.3 3617.4 4093.2 4097.8 4117.1 3573.6 3603.8 3608.0 3618.7 3636.0 4552.0 4559.7 5166.9 5197.6 5311.9 4716.8 4737.9 4758.2 4761.0 4802.5 4551.8 4972.0 5133.5 5655.7 5656.2 3759.6 4052.1 4054.5 4056.0 4063.7 5527.7 5537.1 5710.4 5840.0 5863.8 3801.3 4056.2 4059.9 4089.0 4091.1 3334.4 3931.5 4087.9 4125.7 4136.0 3620.5 3856.8 4499.8 4577.6 4765.9 4956.4 5373.5 5608.2 5615.0 5620.0 1191.5 1282.5 1315.8 1336.6 1338.4 787.6 793.6 815.4 884.7 906.0 4035.6 4106.4 4778.4 4869.8 4890.3 4839.9 4887.3 5356.4 5452.6 5509.7 4125.6 4164.9 4600.4 4879.9 4897.8 2403.5 2661.4 2663.9 2686.1 2845.4 5057.5 5688.7 5711.8 5714.2 5730.7 5260.9 5592.4 5622.2 5635.9 5636.9 4777.2 5072.9 5381.6 5509.8 5681.7 4829.6 4923.4 5124.6 5384.3 5445.6 4745.4 5097.9 5694.3 5722.7 5726.6 5008.6 5224.2 5720.1 5912.9 5972.5 3549.1 3616.0 3761.6 3771.8 3776.5 5515.3 5694.0 5720.6 5785.4 5846.5 5635.5 5644.7 5654.2 5669.2 5694.9 4970.6 5611.9 5649.9 5675.4 5675.7 4787.5 5516.0 5521.0 5534.3 5538.6 5063.5 5124.9 5526.9 5534.4 5538.8 4791.6 5044.9 5392.9 5447.8 5513.4 07:50:06 INFO - PROCESS | 1648 | 07:50:06 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19349.13 Median:3679.97 stddev:26874.46 (730.3%) stddev-sans-first:26913.10 07:50:06 INFO - PROCESS | 1648 | Values: 632.7 764.5 797.7 807.7 812.5 15394.2 15950.4 15996.4 16010.7 16032.5 14654.9 14806.6 14824.6 14828.3 14847.1 685.9 699.3 712.3 713.1 858.5 706.0 709.2 846.6 873.9 880.0 924.5 931.4 931.9 933.7 939.9 891.5 923.2 935.2 935.5 939.8 694.8 697.2 698.5 699.7 701.6 12081.3 12561.4 14793.1 15343.8 15398.5 14228.6 14377.7 14410.6 14413.7 14470.3 10471.7 11380.8 12123.2 12139.2 12344.8 3591.0 3714.2 3797.4 3868.1 3918.2 85014.0 101232.0 102599.9 104699.2 105424.0 10325.5 10660.5 10702.9 10731.8 11036.9 21785.7 22013.3 22050.2 22078.1 22116.0 7620.2 7626.9 7644.8 7683.9 7860.8 3464.3 3645.7 4008.7 4010.7 4052.6 3351.5 3415.9 3424.2 3439.3 3452.5 620.8 625.1 631.4 636.1 638.0 668.3 682.8 707.9 718.6 732.0 10034.6 10101.8 10122.0 10443.5 10594.8 688.4 690.7 691.6 702.0 711.4 3909.8 4700.6 4723.6 4725.8 4740.1 93699.5 93765.5 94184.0 95182.0 95282.0 78793.6 81701.0 81989.0 83395.0 83657.6 67148.0 68650.0 69547.0 75324.5 77212.0 10835.9 11053.7 11237.6 11367.9 11401.8 47152.2 48096.0 49045.8 49340.8 49610.5 45170.8 49016.5 59084.7 59556.4 60717.4 12874.9 13031.9 13053.8 13177.3 13439.3 12643.5 14476.6 14506.3 14875.4 15295.4 13482.2 13759.2 13976.2 14143.2 14211.8 13602.6 13608.4 13731.5 13744.9 13913.8 13092.7 13270.5 13454.5 13483.0 13947.2 10726.6 11041.6 11229.6 11334.6 11461.8 9939.7 10558.0 11005.6 11011.3 11053.2 07:50:06 INFO - PROCESS | 1648 | -------- Summary: end -------- 07:50:06 INFO - PROCESS | 1648 | 07:50:07 INFO - TEST-INFO | 1648: exit 0 07:50:07 INFO - TEST-OK | dromaeo_css | took 1118907ms 07:50:07 INFO - TEST-START | kraken 07:50:07 INFO - operating with platform_type : mac_ 07:50:07 INFO - Initialising browser for kraken test... 07:50:07 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmp4Rolmq/profile http://localhost:49251/getInfo.html) 07:50:09 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 07:50:09 INFO - PROCESS | 1655 | colorDepth:24 07:50:09 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 07:50:09 INFO - PROCESS | 1655 | __metrics 07:50:10 INFO - PROCESS | 1655 | 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" 07:50:10 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:10 INFO - TEST-INFO | 1655: exit 0 07:50:10 INFO - Browser initialized. 07:50:10 INFO - Running cycle 1/1 for kraken test... 07:50:10 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmp4Rolmq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:22 INFO - PROCESS | 1656 | RSS: Main: 235663360 07:50:22 INFO - PROCESS | 1656 | 07:50:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:26 INFO - PROCESS | 1656 | RSS: Main: 287490048 07:50:26 INFO - PROCESS | 1656 | 07:50:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 07:50:29 INFO - PROCESS | 1656 | RSS: Main: 288055296 07:50:29 INFO - PROCESS | 1656 | 07:50:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 07:50:33 INFO - PROCESS | 1656 | RSS: Main: 380973056 07:50:33 INFO - PROCESS | 1656 | 07:50:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 07:50:36 INFO - PROCESS | 1656 | RSS: Main: 286679040 07:50:36 INFO - PROCESS | 1656 | 07:50:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:50:39 INFO - PROCESS | 1656 | RSS: Main: 288960512 07:50:39 INFO - PROCESS | 1656 | 07:50:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 07:50:42 INFO - PROCESS | 1656 | RSS: Main: 329691136 07:50:42 INFO - PROCESS | 1656 | 07:50:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 07:50:46 INFO - PROCESS | 1656 | RSS: Main: 330104832 07:50:46 INFO - PROCESS | 1656 | 07:50:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 07:50:49 INFO - PROCESS | 1656 | RSS: Main: 326979584 07:50:49 INFO - PROCESS | 1656 | 07:50:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:50:51 INFO - PROCESS | 1656 | RSS: Main: 305229824 07:50:51 INFO - PROCESS | 1656 | 07:50:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:50:54 INFO - PROCESS | 1656 | RSS: Main: 306778112 07:50:54 INFO - PROCESS | 1656 | 07:50:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:50:57 INFO - PROCESS | 1656 | RSS: Main: 309133312 07:50:57 INFO - PROCESS | 1656 | 07:51:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:00 INFO - PROCESS | 1656 | RSS: Main: 322179072 07:51:00 INFO - PROCESS | 1656 | 07:51:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:04 INFO - PROCESS | 1656 | RSS: Main: 320880640 07:51:04 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 07:51:06 INFO - PROCESS | 1656 | RSS: Main: 365211648 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | __start_tp_report 07:51:06 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 07:51:06 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 07:51:06 INFO - PROCESS | 1656 | |i|pagename|runs| 07:51:06 INFO - PROCESS | 1656 | |0;ai-astar;114;115;115;115;115;116;115;113;115;114 07:51:06 INFO - PROCESS | 1656 | |1;audio-beat-detection;145;112;110;148;123;141;121;110;127;112 07:51:06 INFO - PROCESS | 1656 | |2;audio-dft;181;182;183;184;185;182;197;262;181;184 07:51:06 INFO - PROCESS | 1656 | |3;audio-fft;78;59;59;85;61;74;81;50;91;74 07:51:06 INFO - PROCESS | 1656 | |4;audio-oscillator;88;69;87;72;88;97;113;84;70;82 07:51:06 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;85;131;87;89;86;91;87;91;85 07:51:06 INFO - PROCESS | 1656 | |6;imaging-darkroom;108;150;95;95;95;95;97;91;133;150 07:51:06 INFO - PROCESS | 1656 | |7;imaging-desaturate;86;85;85;88;85;89;88;84;85;86 07:51:06 INFO - PROCESS | 1656 | |8;json-parse-financial;56;55;54;53;54;54;55;55;54;54 07:51:06 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;72;64;79;72;76;70;69;70;70 07:51:06 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;87;88;87;87;89;91;86;88;77;91 07:51:06 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;141;129;182;154;150;130;143;144;219;185 07:51:06 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;174;210;218;233;222;175;179;175;173;177 07:51:06 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;67;68;67;67;68;67;66;66;66 07:51:06 INFO - PROCESS | 1656 | __end_tp_report 07:51:06 INFO - PROCESS | 1656 | __start_cc_report 07:51:06 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1981 07:51:06 INFO - PROCESS | 1656 | __end_cc_report 07:51:06 INFO - PROCESS | 1656 | __startTimestamp1484236266701__endTimestamp 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | ------- Summary: start ------- 07:51:06 INFO - PROCESS | 1656 | Number of tests: 14 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 07:51:06 INFO - PROCESS | 1656 | Values: 114.0 115.0 115.0 115.0 115.0 116.0 115.0 113.0 115.0 114.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:125.00 stddev:14.90 (11.9%) stddev-sans-first:13.91 07:51:06 INFO - PROCESS | 1656 | Values: 145.0 112.0 110.0 148.0 123.0 141.0 121.0 110.0 127.0 112.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:192.10 Median:184.00 stddev:25.00 (13.6%) stddev-sans-first:26.19 07:51:06 INFO - PROCESS | 1656 | Values: 181.0 182.0 183.0 184.0 185.0 182.0 197.0 262.0 181.0 184.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:71.20 Median:76.00 stddev:13.30 (17.5%) stddev-sans-first:13.88 07:51:06 INFO - PROCESS | 1656 | Values: 78.0 59.0 59.0 85.0 61.0 74.0 81.0 50.0 91.0 74.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:85.50 stddev:13.37 (15.6%) stddev-sans-first:14.14 07:51:06 INFO - PROCESS | 1656 | Values: 88.0 69.0 87.0 72.0 88.0 97.0 113.0 84.0 70.0 82.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.00 stddev:13.84 (15.7%) stddev-sans-first:14.64 07:51:06 INFO - PROCESS | 1656 | Values: 89.0 85.0 131.0 87.0 89.0 86.0 91.0 87.0 91.0 85.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:95.00 stddev:23.93 (25.2%) stddev-sans-first:25.36 07:51:06 INFO - PROCESS | 1656 | Values: 108.0 150.0 95.0 95.0 95.0 95.0 97.0 91.0 133.0 150.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.76 07:51:06 INFO - PROCESS | 1656 | Values: 86.0 85.0 85.0 88.0 85.0 89.0 88.0 84.0 85.0 86.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:0.84 (1.5%) stddev-sans-first:0.67 07:51:06 INFO - PROCESS | 1656 | Values: 56.0 55.0 54.0 53.0 54.0 54.0 55.0 55.0 54.0 54.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:4.50 (6.3%) stddev-sans-first:4.27 07:51:06 INFO - PROCESS | 1656 | Values: 65.0 72.0 64.0 79.0 72.0 76.0 70.0 69.0 70.0 70.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.00 stddev:3.93 (4.5%) stddev-sans-first:4.17 07:51:06 INFO - PROCESS | 1656 | Values: 87.0 88.0 87.0 87.0 89.0 91.0 86.0 88.0 77.0 91.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:157.70 Median:152.00 stddev:28.75 (18.9%) stddev-sans-first:29.85 07:51:06 INFO - PROCESS | 1656 | Values: 141.0 129.0 182.0 154.0 150.0 130.0 143.0 144.0 219.0 185.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.60 Median:194.50 stddev:24.07 (12.4%) stddev-sans-first:24.46 07:51:06 INFO - PROCESS | 1656 | Values: 174.0 210.0 218.0 233.0 222.0 175.0 179.0 175.0 173.0 177.0 07:51:06 INFO - PROCESS | 1656 | 07:51:06 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 07:51:06 INFO - PROCESS | 1656 | Values: 66.0 67.0 68.0 67.0 67.0 68.0 67.0 66.0 66.0 66.0 07:51:06 INFO - PROCESS | 1656 | -------- Summary: end -------- 07:51:06 INFO - PROCESS | 1656 | 07:51:07 INFO - TEST-INFO | 1656: exit 0 07:51:07 INFO - TEST-OK | kraken | took 60141ms 07:51:07 INFO - SUITE-END | took 1179s 07:51:07 INFO - Completed test suite (00:19:39) 07:51:07 INFO - javascript benchmark 07:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2831.900883469079, 3329.2842038961626, 3346.1969127409416, 3347.5146103814945, 3409.5397121388605, 5599.916001259984, 5681.147827825823, 5697.458741419564, 5702.258706368171, 5731.426857314266, 5710.800121995741, 5726.68503232322, 5812.622179558326, 5874.118882167677, 5942.940570594293, 5173.974130129344, 5184.792608295664, 5215.1395019821875, 5238.13570760824, 5306.70813105279, 5322.281491998569, 5336.0, 5342.439043900393, 5343.412224655285, 5353.464653534635, 5158.871028224287, 5186.533212010937, 5285.656432331886, 5300.734963251822, 5391.460853914617, 4385.6710746694125, 5174.896502069938, 5214.139666954948, 5248.71132087735, 5266.657667251617, 5231.136862417697, 5234.607404444682, 5253.185756207794, 5264.210368444726, 5296.205569164617, 5785.334686511063, 5796.1595568642315, 5809.912851307234, 5816.563757718146, 5851.882962340772, 6132.795736484409, 6195.721192546346, 6213.56505044647, 6261.123442718023, 6355.491560675135, 6255.06174073888, 6320.462760665349, 6326.398992095744, 6371.681415929185, 6378.0, 5945.316288626776, 5949.8215053548465, 5950.5834591578605, 5953.878823745867, 6009.188759517497, 6243.469305109116, 6246.187995560548, 6270.592411493239, 6361.713722882481, 6362.936370636235, 6113.263474096151, 6137.631742095399, 6148.016317389196, 6251.12484252205, 6260.436560709558, 6161.691915404212, 6341.714622841983, 6350.809475715643, 6351.682415879188, 6369.394907483782, 6150.046742754791, 6153.661548614869, 6169.105479705418, 6237.376262373817, 6277.089821975788, 6339.714712837841, 6343.651099189497, 6353.5234857385885, 6366.649834259068, 6375.075614036032, 4953.2074868020945, 4997.700137991659, 5012.3233363495465, 5018.096742586296, 5029.471905449948, 3716.5168528091176, 4428.822847086145, 4455.465344158657, 4507.549245075466, 4508.752018638941, 5713.485786279171, 5768.163616275617, 5774.133879918046, 5779.190913272145, 5779.566532510079, 6325.114483972247, 6342.334054924258, 6346.873062538723, 6347.238331400169, 6349.714262858182, 4839.0, 5692.715364231772, 5744.971275143681, 5766.0197766379815, 5772.989726797794, 5465.508104270635, 5473.397926228112, 5474.835754927359, 5476.616636835463, 5477.808276710296, 6237.5321850860455, 6264.467520260829, 6276.905846412216, 6282.308946015934, 6299.433051025429, 6340.778072767431, 6354.110424540476, 6365.4589359903075, 6371.7132729028235, 6384.170057892445, 6320.0, 6342.080398342307, 6345.365463453618, 6350.079238510482, 6355.904661430175, 6115.362036198588, 6184.134221209124, 6189.659568723763, 6304.369563043659, 6310.368963103653, 4954.529319714621, 6295.842603934938, 6359.96820015897, 6360.236771587439, 6389.9680501597195, 6075.787347442818, 6194.969025154845, 6230.50155987529, 6236.126942228001, 6246.156768836331, 5453.018456677836, 5907.852303692442, 6025.548083893638, 6192.0, 6221.875562488815, 6003.90994135097, 6004.939950600438, 6011.128386383863, 6013.939860601338, 6019.247594050744, 6111.591738512325, 6115.696722738907, 6120.599512025131, 6136.079588061826, 6165.290991536031, 6030.969845150747, 6081.422264884829, 6082.635041897501, 6105.9694701526205, 6111.755529778759], "value": 5732.111338927122, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.5967349548658, 456.0445865910917, 486.3774368807926, 487.85354417119794, 492.40910906911716, 24298.75701242987, 40575.59424405755, 41871.79064104683, 42042.57957420425, 43872.56127438725, 42474.0, 42954.7852260739, 42961.0, 43095.0, 43104.568954310445, 9235.538223088852, 9465.905340946605, 9466.148046675798, 9468.81062378752, 9470.10034046768, 8597.871031934526, 8808.603612837438, 11134.777304453906, 11406.258593191458, 11422.485988130513, 11330.773384532346, 11458.369789661565, 11458.541658333657, 11466.0, 11470.08239340855, 8515.57422128891, 11419.200655954086, 11517.942410287937, 11537.5961841336, 11543.01884339832, 8481.872771908425, 10890.945545272223, 11182.608608698738, 11201.327920324806, 11371.943140284246, 40673.79663101695, 41168.970775730544, 41185.794071029755, 41199.7940010301, 41236.587634123876, 4044.4337792709043, 4047.797610119512, 4068.8575899843363, 4075.103477235033, 4161.126163505667, 27188.864055679795, 28954.565681514796, 29363.41273174546, 30555.388892222032, 31044.689553104632, 3139.764517661162, 3275.8853440129483, 3282.917927051819, 3285.047336272502, 3326.76712630116, 469662.0, 475014.0, 475574.99999999657, 478214.0, 480670.0, 59513.70243148757, 61099.38900611026, 61430.0, 61505.69247153735, 61643.0, 66842.33157668458, 67186.0, 67205.0, 67213.0, 67217.0, 15856.286467109008, 16298.51104466868, 16318.75521867173, 16326.346946122023, 16409.91795041017, 11298.265612735146, 11552.364619945984, 12491.937540312421, 12573.99408047354, 12594.0, 15895.364185432685, 18310.81689183118, 18359.54101147482, 18578.071096445394, 18761.90619046896, 5642.830715078554, 6177.567570270038, 6589.077529145923, 6871.415929645936, 6929.925861491477, 838.446625227347, 875.2844424927086, 881.352206128495, 905.3843386497114, 918.3112665500875, 24520.632190517157, 29844.701552984625, 30333.99055983745, 30528.389432211232, 30758.38483230323, 1034.3948789957808, 1057.2704833664748, 1066.8666416697913, 1085.5115198160702, 1092.847001419802, 15043.774343384857, 15508.302126404338, 15660.451884183998, 15865.0, 15918.0, 540678.0, 545556.0, 548262.0, 548645.5135448674, 550576.0, 59018.70490647605, 59329.40670593325, 59335.0, 59421.108683369785, 59465.10802337968, 1290.535407253388, 1416.9433222671184, 1458.7082583483345, 1470.9997201902595, 1476.2618690654672, 28285.57571636386, 29079.0, 29229.707702923548, 29584.55623165611, 30031.54952675668, 51643.0, 51663.48336516737, 51725.96548069093, 51818.48181518287, 51918.22122668087, 384790.0, 387334.0, 387463.1880521738, 387830.1216987907, 388286.0, 39337.0, 39658.0, 40120.0, 40282.395764064146, 40675.59324406718, 36525.63474365223, 44775.77612112049, 45823.77088114538, 46056.769716151204, 46410.76794616006, 37928.81035594915, 38015.239695206496, 38740.80629596834, 38758.526060413584, 39017.60982390253, 37735.0, 37963.2407351857, 38018.0, 38976.41535377028, 39311.2137757249, 33907.49138762871, 36656.0, 36721.63278367289, 37408.438873417464, 37731.81134094312, 28065.71934280631, 31878.362432751677, 31899.202519937186, 31902.68097318997, 31952.201194970312, 23722.288331350086, 23855.40361490907, 24062.15782447606, 24291.271261862174, 24321.51356972886], "value": 19919.782097027222, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.18745761359577, 373.2106594552522, 374.0592410088627, 377.4621164840103, 398.7069503914624, 15247.008944418605, 15857.76213356799, 16203.108829014414, 20430.69353959692, 21090.0, 20473.078711457983, 20576.485587860312, 20961.0, 21174.89412552935, 21320.57358852826, 4789.3294938708605, 5567.777288908449, 5790.826275211751, 5797.811448653016, 5808.4191580842, 4974.900501989958, 5005.849824505271, 5752.2234498342605, 5865.560082993771, 5868.618539794899, 5737.913931291034, 5800.854978625547, 5817.069268916975, 5821.243238379005, 5823.184754134425, 5904.2029326040865, 6095.0, 6096.451319381277, 6108.664023478706, 6113.419225173601, 5821.854453638693, 5830.055821810001, 5871.32479764824, 5906.409359064102, 5960.880782384372, 13344.733105337937, 13359.866401336056, 13365.66585835362, 13539.39072741729, 13569.118007329591, 2602.2323414592743, 2624.39638883058, 2641.4320921001886, 2686.408990022192, 2713.2945434187054, 10192.898071019343, 10244.846327305097, 10530.684079477627, 12851.100422970396, 14314.856851431561, 1801.8107552101335, 1862.1992543206416, 1879.6334714730633, 1886.9150888210063, 1890.3572785252943, 121605.0, 125460.0, 126954.0, 129048.0, 129718.3514082433, 11151.776964460665, 11620.477078531487, 11629.0, 11648.184627076193, 11849.763004739856, 15298.464553740565, 15299.541013769605, 15325.846741532665, 15349.15579643108, 15353.0, 8290.834183316421, 8553.44402613828, 8573.957130214309, 8661.437006594551, 8692.608832602422, 8336.390042727788, 8375.916240837514, 8430.957845210734, 8609.268212202032, 8613.956930215309, 4654.371659825948, 4757.429108506932, 4758.5241475852135, 4818.71087734737, 4829.6860704054125, 1936.8934708591162, 2020.7069974853857, 2021.5956808638332, 2029.665105257616, 2036.521417466894, 726.4696771356939, 867.4795122926193, 871.7620978210956, 873.2315562305131, 900.3787386703285, 14662.486812961495, 18496.0, 19286.324978625682, 20206.0, 20600.866952317767, 807.249258189889, 889.7597648634833, 910.8724778531011, 912.119804388768, 918.1415376622879, 10218.489075546342, 10509.737256568495, 10550.208734344955, 10556.419396933243, 10636.787264254826, 371295.0, 531116.0, 655039.0, 657861.0, 684346.0, 110622.0, 139405.0, 141645.0, 148879.0, 159203.0, 977.7653363192925, 991.5984026469291, 1014.8629934958834, 1015.5531566110888, 1017.1303535477208, 18595.721064183777, 19282.517937051685, 19310.903445482683, 19361.515962100497, 19384.418467445998, 60682.69658651679, 61013.0, 61577.0, 61990.69004654948, 62110.068348973895, 89001.21997560142, 89848.0, 91041.54479227826, 91661.0, 92950.0, 8124.837503249783, 8128.796780080545, 8317.085120636604, 8396.0, 8677.652893884175, 9521.904780952378, 10597.788044238918, 12660.430280637209, 12920.483180672667, 13322.800157997444, 10907.454627268764, 11167.664970050912, 11305.208635395444, 11614.070874329864, 11668.124890633406, 11062.834057488983, 11066.667999960015, 11218.439078046229, 11325.943370283096, 11480.598179063692, 10862.619808306672, 10954.890451095387, 11141.387223702774, 11664.591739289084, 11778.823317650413, 7125.928740712734, 7339.935709322225, 7480.962595186989, 8185.181481852005, 8541.487510749375, 8403.285720713808, 10309.381437113798, 11123.332600044023, 11595.0, 11604.88395116038], "value": 9494.163824838228, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.5566263133164, 1387.1538361599426, 1444.4077928049496, 1458.1615571046643, 1460.1677044084863, 7570.432217583682, 7957.721479748196, 7960.243776841198, 7972.282494575487, 7978.4016198785175, 7022.473314501407, 7233.348998590126, 7460.6642701078445, 7717.807054823632, 7761.301482866541, 2206.58074965756, 2275.248058994573, 2276.84062115652, 2279.076973825603, 2285.451491642002, 2229.2977712020743, 2268.9092436302526, 2271.5911135995516, 2272.7727227277305, 2276.203328834903, 1634.4197809777536, 2042.121887588344, 2180.7274090738656, 2188.8139508141826, 2215.9778402215934, 2235.6311208650636, 2240.4510894830787, 2245.214175038723, 2251.1670681847822, 2252.200468833557, 2167.3389616167065, 2214.2914267434426, 2218.4453886528368, 2259.051198496635, 2262.423082114067, 7739.8452030959625, 7845.097813751435, 7855.057393112806, 7861.764147075597, 7868.016497937758, 4784.234522476422, 4829.203181475087, 4923.335349727777, 4931.284963680247, 4933.950660493385, 3122.67211942745, 3273.6562660920736, 3277.9344413111844, 3333.4833100869405, 3345.832708364572, 1079.5304042741432, 1103.8951299626522, 1104.4312179227704, 1120.7001931398706, 1188.2810899405822, 9861.852072218922, 9984.301098923079, 10028.097471227551, 10029.699109026667, 10064.144547713453, 4131.669466442678, 4399.450068741407, 4474.284114541658, 4514.0971805639, 4524.819007239674, 8942.284617230605, 8945.373823832399, 8970.41692289999, 8987.236084932723, 9035.0, 5057.190849464068, 5057.848264552069, 5059.62052846038, 5059.873503162376, 5063.417706963673, 3850.249201405699, 4103.753774773523, 4169.583041695806, 4517.88705282364, 4561.110583436198, 3688.354537955847, 3700.703943684499, 3774.7735135891935, 3800.9809950950066, 3812.4662547243406, 1218.670958841108, 1231.347385885482, 1232.6548566401423, 1241.3544956622686, 1242.192574826352, 819.2239245200467, 841.4530555139086, 860.1355637584313, 910.7905181808221, 924.5793164110314, 5274.446183150714, 5366.168243922199, 5374.328208973879, 5616.438356164433, 5624.987502249553, 897.5063714956748, 917.554985831871, 927.2859897878595, 963.6097380560884, 983.6213057972589, 3404.548724918055, 3477.9660678941436, 3557.181848174934, 3560.7507474477056, 3589.2642008388216, 8928.776780580538, 9172.12864777845, 9228.630854765868, 9247.537623118817, 9255.444673319622, 9113.36206465541, 9133.315001374924, 9223.985361610217, 9309.162175404246, 9315.673951411669, 7243.311885370881, 7403.259674032553, 7413.1104267488245, 7415.443841711785, 7421.554706717507, 4724.480307166277, 4777.259524773665, 4821.927671085006, 5064.043802492323, 5254.369475662945, 6741.730330786713, 8634.352423568384, 8636.395452318277, 8650.048494665707, 8652.653893844428, 7350.889736653848, 8380.874286885579, 8619.094995025433, 8725.30197584179, 8735.43219690718, 6320.209973753281, 6526.379993900572, 6538.444232240123, 6742.9662851685425, 6876.931230687629, 7257.818554535967, 7450.403967682681, 7493.0, 7587.430942679211, 7591.924080759121, 6741.831454213684, 6845.760398386033, 6858.314168583302, 6892.483063770337, 6990.580565166107, 6678.0, 6724.361185687352, 6751.088603038527, 6791.388775010273, 6810.250872404131, 5891.410858914074, 5927.733252003584, 5930.614510056833, 5961.701914904324, 5962.016267315845, 4632.606228470483, 4714.4442334029, 4720.362751028704, 4743.388431798937, 4888.560029597354, 4699.600533954652, 4807.95192048075, 4832.927506087482, 4847.660663753503, 4870.805167793249], "value": 4188.866840105599, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.5386458957461, 331.19684764446214, 335.8387973772589, 339.7316120264991, 347.86781023211205, 5020.573251273641, 5370.301860758106, 5420.430854760242, 5442.374126975399, 5488.231647569333, 4875.195592727205, 5054.519820617035, 5092.592592592593, 5214.452482489341, 5339.359276886779, 3302.801831890082, 3431.450967845139, 4131.380292956065, 4132.483439570054, 4141.544430112685, 4116.135611521569, 4136.27615167346, 4177.540470548237, 4185.811638476261, 4188.497380314367, 3725.7578257413184, 3883.796023232793, 4017.995501124719, 4122.196171746511, 4144.958550414487, 3578.320119177348, 3617.403128483797, 4093.16090201508, 4097.795110244506, 4117.085555216823, 3573.5533058367705, 3603.837827297778, 3608.0, 3618.7466877318598, 3636.0, 4551.998560316725, 4559.726416415026, 5166.948330516722, 5197.636165468419, 5311.867203319948, 4716.787744551469, 4737.857864264078, 4758.167320718895, 4760.952390476085, 4802.542169637855, 4551.817927282905, 4972.0, 5133.460986596391, 5655.688937108457, 5656.218291238247, 3759.59385992962, 4052.0882801369753, 4054.4729185205733, 4055.9797201013807, 4063.735857169304, 5527.695976721238, 5537.1140617501005, 5710.428957104298, 5839.970800146015, 5863.827234553107, 3801.2967600993907, 4056.1887622475624, 4059.878203653895, 4089.0, 4091.0590564170407, 3334.433146365124, 3931.5478719947573, 4087.9151632667554, 4125.731827431207, 4135.958640413617, 3620.4931309616672, 3856.8071596420627, 4499.807550998988, 4577.633789296849, 4765.9046819064115, 4956.3804524434445, 5373.462653734673, 5608.242887210256, 5615.0454422748235, 5619.971900140555, 1191.523390643732, 1282.5254655777235, 1315.7565850317721, 1336.5990202939079, 1338.4445455136204, 787.629813987425, 793.6230290611913, 815.3558688635911, 884.7390019944132, 905.9547022648841, 4035.556088830226, 4106.3635136553885, 4778.354922085474, 4869.768393935004, 4890.290907818347, 4839.903201935942, 4887.315775791392, 5356.4107948125675, 5452.609584555913, 5509.685931908608, 4125.587441255911, 4164.875053748392, 4600.401947746772, 4879.902401951941, 4897.7795999180125, 2403.495265994126, 2661.418424128592, 2663.866806659698, 2686.0978713847308, 2845.38824152804, 5057.508035129646, 5688.748475335502, 5711.771529138788, 5714.1714851148945, 5730.713464326767, 5260.868478288074, 5592.412796656374, 5622.228997865659, 5635.943640563624, 5636.882888640426, 4777.205334878996, 5072.949270507322, 5381.596380271495, 5509.752061157177, 5681.715914204273, 4829.589484893683, 4923.409190897115, 5124.590032797441, 5384.326959130109, 5445.564354851523, 4745.430548334222, 5097.949020509747, 5694.345150307602, 5722.656640601577, 5726.559768220291, 5008.57427118699, 5224.216367544898, 5720.056190728483, 5912.9061123690535, 5972.522198224218, 3549.1304630365726, 3616.029264051151, 3761.6050314716563, 3771.811409429463, 3776.4894042383266, 5515.310586176728, 5694.0, 5720.599558031065, 5785.39253378406, 5846.503047241032, 5635.549156067423, 5644.6613203207935, 5654.15187721845, 5669.2220811802745, 5694.857628559319, 4970.6023518117745, 5611.915821262766, 5649.915251271316, 5675.404082571271, 5675.744591493475, 4787.521247875185, 5516.021348471941, 5521.0, 5534.335879694462, 5538.556915446835, 5063.518965698253, 5124.872528043825, 5526.91709624364, 5534.395025442737, 5538.806141785019, 4791.5687588117235, 5044.9243261350375, 5392.921415716951, 5447.836564903059, 5513.436252142773], "value": 4035.675278927564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.6615260835454, 764.5489161394776, 797.7367468735316, 807.6567458829995, 812.48407261389, 15394.15332156731, 15950.441734539294, 15996.440124595641, 16010.67978640428, 16032.519024429272, 14654.926725366358, 14806.555803325917, 14824.629384265372, 14828.332725027372, 14847.1091734496, 685.8662560800645, 699.3216579917472, 712.2912701861654, 713.1192976673804, 858.540680735808, 706.015108923052, 709.163187438822, 846.6147902704255, 873.8645509945968, 880.0143838900409, 924.4869097650801, 931.3992474853716, 931.8509038553833, 933.6685476655791, 939.8778158839341, 891.4784850862221, 923.1507013547553, 935.2144198872945, 935.471458625881, 939.8167357365317, 694.8019814352886, 697.1564407067455, 698.4517154034058, 699.6711545573572, 701.5930760159096, 12081.275123492618, 12561.371931403393, 14793.11241325524, 15343.846561534354, 15398.53804385859, 14228.644283892985, 14377.712445751027, 14410.639734006734, 14413.71172576553, 14470.27648617565, 10471.68584942453, 11380.772384552345, 12123.151379403445, 12139.210951288138, 12344.81482777759, 3590.964090359089, 3714.220013797106, 3797.4493698413853, 3868.052327179859, 3918.1967696622123, 85014.0, 101231.99999999926, 102599.91115680829, 104699.23879278683, 105424.0, 10325.535350909227, 10660.520276587573, 10702.94648526768, 10731.83902241467, 11036.944815275872, 21785.673214901788, 22013.339599812032, 22050.228242011774, 22078.116875324806, 22116.0, 7620.1998790127345, 7626.885596716053, 7644.80887977805, 7683.923160768432, 7860.84278314442, 3464.3071385722956, 3645.7265705072227, 4008.719389642697, 4010.6991975601945, 4052.615001574846, 3351.5307856900054, 3415.863365465403, 3424.2124311408515, 3439.2949445363643, 3452.4993875887853, 620.7516993202755, 625.0904933322097, 631.438020162047, 636.147562266565, 638.0110828216339, 668.3416834418094, 682.7951614515625, 707.9156038045533, 718.647862547348, 732.0, 10034.598616055277, 10101.797964040825, 10121.991920339226, 10443.530041148166, 10594.788104237872, 688.3942130924854, 690.7444245629149, 691.5781373362245, 701.9894701579481, 711.3562226185311, 3909.8465952544066, 4700.576948074621, 4723.622110231173, 4725.810967561259, 4740.1230772307235, 93699.53150234341, 93765.5311723437, 94184.0, 95182.0, 95282.0, 78793.60603196947, 81701.0, 81989.0, 83395.0, 83657.58171209105, 67148.0, 68650.0, 69547.0, 75324.49351012839, 77212.0, 10835.891641083488, 11053.723656908642, 11237.604125346825, 11367.943160284145, 11401.828972565252, 47152.22782177486, 48096.0, 49045.75477122592, 49340.75329623329, 49610.503894962036, 45170.774146130374, 49016.5098349012, 59084.704576478565, 59556.40443595508, 60717.39282607118, 12874.871251287368, 13031.934840325737, 13053.804192937303, 13177.341132943506, 13439.328033598085, 12643.494260229489, 14476.638084047561, 14506.347214375168, 14875.404983800527, 15295.388184472497, 13482.19106853592, 13759.17444953306, 13976.237604016556, 14143.151410915378, 14211.841206360954, 13602.591922242349, 13608.387622556811, 13731.519396821062, 13744.931275343559, 13913.791293130409, 13092.672683182996, 13270.535531256359, 13454.46182152703, 13483.0, 13947.232902190477, 10726.624568140078, 11041.56459660239, 11229.606963756229, 11334.60328888485, 11461.770764584828, 9939.665918723511, 10558.0, 11005.559777608805, 11011.28426652265, 11053.226274160732], "value": 7389.222249449603, "unit": "score"}], "name": "dromaeo_css", "value": 7166.154937716913, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 115.0, 116.0, 115.0, 113.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 110.0, 148.0, 123.0, 141.0, 121.0, 110.0, 127.0, 112.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 183.0, 184.0, 185.0, 182.0, 197.0, 262.0, 181.0, 184.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 85.0, 61.0, 74.0, 81.0, 50.0, 91.0, 74.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 87.0, 72.0, 88.0, 97.0, 113.0, 84.0, 70.0, 82.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 131.0, 87.0, 89.0, 86.0, 91.0, 87.0, 91.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 150.0, 95.0, 95.0, 95.0, 95.0, 97.0, 91.0, 133.0, 150.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 88.0, 85.0, 89.0, 88.0, 84.0, 85.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 64.0, 79.0, 72.0, 76.0, 70.0, 69.0, 70.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 87.0, 89.0, 91.0, 86.0, 88.0, 77.0, 91.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 129.0, 182.0, 154.0, 150.0, 130.0, 143.0, 144.0, 219.0, 185.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 210.0, 218.0, 233.0, 222.0, 175.0, 179.0, 175.0, 173.0, 177.0], "value": 193.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1507.3, "alertThreshold": 2.0}]} 07:51:07 INFO - Return code: 0 07:51:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:08 INFO - # TBPL SUCCESS # 07:51:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:08 INFO - 2017-01-12 07:51:08.029 screenresolution[1660:10093] starting screenresolution argv=screenresolution get 07:51:08 INFO - 2017-01-12 07:51:08.031 screenresolution[1660:10093] Display 0: 1600x1200x32@60 07:51:08 INFO - 2017-01-12 07:51:08.038 screenresolution[1661:10096] starting screenresolution argv=screenresolution list 07:51:08 INFO - Available Modes on Display 0 07:51:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:08 INFO - Intel Iris: 07:51:08 INFO - Chipset Model: Intel Iris 07:51:08 INFO - Type: GPU 07:51:08 INFO - Bus: Built-In 07:51:08 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:08 INFO - Vendor: Intel (0x8086) 07:51:08 INFO - Device ID: 0x0a2e 07:51:08 INFO - Revision ID: 0x0009 07:51:08 INFO - Displays: 07:51:08 INFO - Display: 07:51:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:08 INFO - Display Serial Number: 1600x1200 60 07:51:08 INFO - Main Display: Yes 07:51:08 INFO - Mirror: Off 07:51:08 INFO - Online: Yes 07:51:08 INFO - Rotation: Supported 07:51:08 INFO - Return code: 0 07:51:08 INFO - Running post-action listener: _resource_record_post_action 07:51:08 INFO - [mozharness: 2017-01-12 15:51:08.275776Z] Finished run-tests step (success) 07:51:08 INFO - Running post-run listener: _resource_record_post_run 07:51:08 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32361472; Write bytes: 551596032; Read time: 203; Write time: 1655 07:51:08 INFO - TinderboxPrint: CPU usage
53.8% 07:51:08 INFO - TinderboxPrint: I/O read bytes / time
32,361,472 / 203 07:51:08 INFO - TinderboxPrint: I/O write bytes / time
551,596,032 / 1,655 07:51:08 INFO - TinderboxPrint: CPU idle
2,224.2 (46.2%) 07:51:08 INFO - TinderboxPrint: CPU system
963.8 (20.0%) 07:51:08 INFO - TinderboxPrint: CPU user
1,621.7 (33.7%) 07:51:08 INFO - TinderboxPrint: Swap in / out
279,515,136 / 0 07:51:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249717248; Write bytes: 243640320; Read time: 18093; Write time: 512 07:51:08 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 28994560; Write bytes: 296511488; Read time: 180; Write time: 1110 07:51:08 INFO - Running post-run listener: _upload_blobber_files 07:51:08 INFO - Blob upload gear active. 07:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:09 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:10 INFO - (blobuploader) - INFO - Done attempting. 07:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:11 INFO - (blobuploader) - INFO - Done attempting. 07:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:12 INFO - (blobuploader) - INFO - Done attempting. 07:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:51:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:15 INFO - (blobuploader) - INFO - Done attempting. 07:51:15 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:15 INFO - Return code: 0 07:51:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebe3518198730a032b3059148ce722ec7fc1921cd36f20f7bf5d77599bd9176ff6593255bf33f4c71cfca504eaee58406067ed100033c79747422d12c0945c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61d2b5a12b4bbc807d5cf4e9dd187c106efe4533f16c6f51e54338c75d044666c56a6c48ddb623792fef348dbfa04885bf5fb5e00e83f3fdd6c0c4dcfbfd15b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c53f24c071208119a7b0dcba3fd2bcdb505e301da2e6e84eb510490b0ee814998566d56213bc11bd89041b14c5a4bf894be7ac5d279cd5f68d2cf10826195b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4594c7551a3a950eeda4b8e9ecfafa7cba5f55cbb966d13b9603491fbff3bfcbd48638f59f4d4f20d41aea3d446183327dd31512a9b60ac23e0081b17f1a31d"} 07:51:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:15 INFO - Contents: 07:51:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebe3518198730a032b3059148ce722ec7fc1921cd36f20f7bf5d77599bd9176ff6593255bf33f4c71cfca504eaee58406067ed100033c79747422d12c0945c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61d2b5a12b4bbc807d5cf4e9dd187c106efe4533f16c6f51e54338c75d044666c56a6c48ddb623792fef348dbfa04885bf5fb5e00e83f3fdd6c0c4dcfbfd15b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c53f24c071208119a7b0dcba3fd2bcdb505e301da2e6e84eb510490b0ee814998566d56213bc11bd89041b14c5a4bf894be7ac5d279cd5f68d2cf10826195b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4594c7551a3a950eeda4b8e9ecfafa7cba5f55cbb966d13b9603491fbff3bfcbd48638f59f4d4f20d41aea3d446183327dd31512a9b60ac23e0081b17f1a31d"} 07:51:15 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:15 INFO - Copying logs to upload dir... 07:51:15 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.928340 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-12 07:51:15.716900) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 07:51:15.720419) ========= 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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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/1ebe3518198730a032b3059148ce722ec7fc1921cd36f20f7bf5d77599bd9176ff6593255bf33f4c71cfca504eaee58406067ed100033c79747422d12c0945c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61d2b5a12b4bbc807d5cf4e9dd187c106efe4533f16c6f51e54338c75d044666c56a6c48ddb623792fef348dbfa04885bf5fb5e00e83f3fdd6c0c4dcfbfd15b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c53f24c071208119a7b0dcba3fd2bcdb505e301da2e6e84eb510490b0ee814998566d56213bc11bd89041b14c5a4bf894be7ac5d279cd5f68d2cf10826195b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4594c7551a3a950eeda4b8e9ecfafa7cba5f55cbb966d13b9603491fbff3bfcbd48638f59f4d4f20d41aea3d446183327dd31512a9b60ac23e0081b17f1a31d"} build_url:https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/IM2Y4zTwTXe3VLOu837Qgw/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/1ebe3518198730a032b3059148ce722ec7fc1921cd36f20f7bf5d77599bd9176ff6593255bf33f4c71cfca504eaee58406067ed100033c79747422d12c0945c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61d2b5a12b4bbc807d5cf4e9dd187c106efe4533f16c6f51e54338c75d044666c56a6c48ddb623792fef348dbfa04885bf5fb5e00e83f3fdd6c0c4dcfbfd15b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c53f24c071208119a7b0dcba3fd2bcdb505e301da2e6e84eb510490b0ee814998566d56213bc11bd89041b14c5a4bf894be7ac5d279cd5f68d2cf10826195b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4594c7551a3a950eeda4b8e9ecfafa7cba5f55cbb966d13b9603491fbff3bfcbd48638f59f4d4f20d41aea3d446183327dd31512a9b60ac23e0081b17f1a31d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 07:51:15.759142) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:51:15.759464) ========= 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.kRcgwrzErY/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.Iy2EeR0iuh/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005301 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 07:51:15.807713) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 07:51:15.808051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 07:51:15.808369) ========= ========= Total master_lag: 0.19 =========